Opstarten van DS214+ traag

[HPP]

Hi

Mijn DS214+ start op in 5 minuten en 25 seconden. Zijn er mede forum 214+ gebruikers die dit ook hebben?
Configuratie: 2 x Seagate IronWolf 4Tb in Raid 0. DSM 6.2-23511 (bèta). Beide netwerkpoorten aangesloten, maar niet gecombineerd. Via switch naar router.

Ik heb er al een andere schijf in geplaatst, maar geen verschil in opstarttijd. Schijven zijn 100% no errors.

Ben erg benieuwd naar jullie boot tijden.

groet,

Dick

[rodio]

op mijn 213+ met 2 schijven DSM 6.1.4 update5 kan ik na 2m37 inloggen.

mijn 916 reboot alleen als het nodig is, dus die test ik niet 

[HPP]

Dank je wel! Jouw tijden zijn bijna de helft van die van mij!
Ik hoop op nog andere 214+ gebruikers anders stuur ik voor de zekerheid een ticket naar Synology

fijn weekend!

Dick

[Ben(V)]

Ik heb een DS414 wat precies dezelfde hardware is als een DS214+ maar dan met vier bay's
Ik kan na 2 min en 40 seconden weer inloggen.

Het meest waarschijnlijke is dat je twee netwerk aansluitingen die je zomaar op je router aansluit timeout problemen oplevert.
Configureer ze in een bond en probeer het nog eens.

Een andere oorzaak kan natuurlijk aan bepaalde packages liggen die lang duren om af te sluiten, maar ik vermoed niet dat het dat is want volgens mij stuurt DSM gewoon een kill naar alle processen en dat is nagenoeg tijdloos.

[HPP]

Hi Ben,

ik heb de bond er juist afgegooid, maar nog wel de beide kabels erin zitten. Daarvan ga ik er morgen eens 1 uit verwijderen en testen wat dat de boot tijd is.
ik zal het resultaat weer terugkoppelen.


Toch maar even snel getest met 1 poort. weer 5 minuut 20 seconden.!

Dick

[Ben(V)]

Een niet gebruikte aansluiting geeft timeout problemen bij het opstarten.
Het kan geen kwaad maar linux probeert hem te configureren en dat gaat niet dus veel time-outs kosten en dus langer opstarten.

Gewoon in een bond configureren is het beste.
Geen lacp kiezen tenzij je een managed switch hebt die dat ondersteund.

[HPP]

Ik maak er weer een bond van (Adaptive Load Balancing)
en ga dan weer klokken.

Blijft vreemd!

Dick

[HPP]

Met een bond (adaptive) weer 5 minuut 17 seconden!
Ga morgen kijken hoe lang het booten kost achter een andere switch. Misschien nog eens een ander ip adres proberen.

het feit dat een 414 er 2 minuten over doet, geeft me wel hoop!

Ben nog steeds benieuwd in welke tijden andere gebruikers hebben.

Fijn weekend!

Dick

[Briolet]

Als ik in het "/var/log/messages" kijk, staan daar een paar paginas met alles wat er met het opstarten gebeurt, incl tijdcodes.

Daar kun je ook het een en ander vinden qua timing

[HPP]

Hallo,

het bestand /var/log/messages /volume1/Logs/Systeem/messages.log kon ik niet vinden. De hele dir eigenlijk niet. Ik vond in /var/log wel een file "messages.
Hier stond o.a. dit in:

2018-01-25T00:01:50-08:00 DiskStation kernel: [  148.755178] Initializing Armada-XP CPU power management  (WFI)
2018-01-25T00:01:50-08:00 DiskStation kernel: [  148.756354] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
2018-01-25T00:01:50-08:00 DiskStation kernel: [  158.317589] ata1: link is slow to respond, please be patient (ready=0)
2018-01-25T00:01:50-08:00 DiskStation kernel: [  162.817589] ata1: SRST failed (errno=-16)
2018-01-25T00:01:50-08:00 DiskStation kernel: [  162.821611] ata1: SRST fail, set srst fail flag
2018-01-25T00:01:50-08:00 DiskStation kernel: [  163.993821] ata1: link reset sucessfully clear error flags
2018-01-25T00:01:50-08:00 DiskStation kernel: [  167.146318] Delay 5 seconds to wait for disk 2 ready.
2018-01-25T00:01:50-08:00 DiskStation kernel: [  177.697589] ata2: link is slow to respond, please be patient (ready=0)
2018-01-25T00:01:50-08:00 DiskStation kernel: [  182.197588] ata2: SRST failed (errno=-16)
2018-01-25T00:01:50-08:00 DiskStation kernel: [  182.201610] ata2: SRST fail, set srst fail flag
2018-01-25T00:01:50-08:00 DiskStation kernel: [  183.553818] ata2: link reset sucessfully clear error flags
2018-01-25T00:01:50-08:00 DiskStation kernel: [  185.847589] ata6: failed to resume link (SControl 4)
2018-01-25T00:01:50-08:00 DiskStation kernel: [  185.867485] SPI Serial flash detected @ 0xf0000000, 8192KB (128sec x 64KB)
2018-01-25T00:01:50-08:00 DiskStation kernel: [  185.959564] vender Mac0 address :
2018-01-25T00:01:50-08:00 DiskStation kernel: [  185.964459] vender Mac1 address :
2018-01-25T00:01:50-08:00 DiskStation kernel: [  185.969358] vender Mac2 doesn't set ucSum:0x00 Buf:0x00 Sum:0.
2018-01-25T00:01:50-08:00 DiskStation kernel: [  185.975209] vender Mac3 doesn't set ucSum:0x00 Buf:0x00 Sum:0.

mijn serienr en de mac addressen heb ik gedelete uit deze log. Ik zie errors zoals SRST failed, maar dan houdt mijn kennis op.

(het zijn overigens 2 splinternieuwe schijven en de test schijf had hetzelfde euvel van slow boot)

Wie kan helpen? Ik hoop niet dat de DS end of life is..

groet,

Dick

[Briolet]

Dat was idd de log-file die ik bedoelde. Het 2e pad was naar mijn share en hoort er niet bij. (Ik zal het wissen in mijn vorige bericht om meer verwarring te voorkomen.

Het loggen van fouten is normaal en wil niet zeggen dat iets echt defect is. Hoewel je er als ict-er bij Synology natuurlijk wel moet zorgen dat ook niet-fatale fouten vermeden worden.

Het opstart proces begint inderdaad met het loggen van de 'kernel' taken. Deze heeft een eigen tijdcode die bij [0.00000] begint.  Bij mij eindigt dit deel na ruim 1 minuut. (71.915172 seconden). In jou log zie ik dat dit deel al veel trager gaat.

Het laatste stuk an mijn kernel log ziet er zo uit:

Code: [Selecteer]

kernel: [    0.000000] Disk Index Map: 020006
kernel: [    0.000000] Synology Hardware Version: DS415+-j
kernel: [    0.000000] Serial Number: xxxxxxxx
kernel: [    0.110240] ACPI: All ACPI Tables successfully acquired
kernel: [    0.397747] raid6: sse2x1   gen()   707 MB/s
kernel: [    0.419579] raid6: sse2x1   xor()  2437 MB/s
kernel: [    0.441441] raid6: sse2x2   gen()  1195 MB/s
kernel: [    0.463272] raid6: sse2x2   xor()  2902 MB/s
kernel: [    0.485097] raid6: sse2x4   gen()  2066 MB/s
kernel: [    0.506923] raid6: sse2x4   xor()  2625 MB/s
kernel: [    0.511707] raid6: using algorithm sse2x4 gen() (2066 MB/s)
kernel: [    0.517951] raid6: .... xor() 2625 MB/s, rmw enabled
kernel: [    0.523514] raid6: using ssse3x2 recovery algorithm
kernel: [    0.551852] ACPI: Executed 1 blocks of module-level executable AML code
kernel: [    0.567982] ACPI: SSDT 0x000000007F339918 0044E (v01 PmRef  Cpu0Ist  00003000 INTL 20130117)
kernel: [    0.577688] ACPI: Dynamic OEM Table Load:
kernel: [    0.582195] ACPI: SSDT 0x0000000000000000 0044E (v01 PmRef  Cpu0Ist  00003000 INTL 20130117)
kernel: [    0.591776] ACPI: SSDT 0x000000007F339D98 001FA (v01 PmRef  Cpu0Cst  00003001 INTL 20130117)
kernel: [    0.601485] ACPI: Dynamic OEM Table Load:
kernel: [    0.605991] ACPI: SSDT 0x0000000000000000 001FA (v01 PmRef  Cpu0Cst  00003001 INTL 20130117)
kernel: [    0.620692] ACPI: SSDT 0x000000007F33BA18 0047A (v01 PmRef  ApIst    00003000 INTL 20130117)
kernel: [    0.630506] ACPI: Dynamic OEM Table Load:
kernel: [    0.635011] ACPI: SSDT 0x0000000000000000 0047A (v01 PmRef  ApIst    00003000 INTL 20130117)
kernel: [    0.647415] ACPI: SSDT 0x000000007F33AD98 00119 (v01 PmRef  ApCst    00003000 INTL 20130117)
kernel: [    0.657144] ACPI: Dynamic OEM Table Load:
kernel: [    0.661642] ACPI: SSDT 0x0000000000000000 00119 (v01 PmRef  ApCst    00003000 INTL 20130117)
kernel: [    0.680857] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S4_] (20130328/hwxface-568)
kernel: [    0.956410] ACPI: Enabled 1 GPEs in block 00 to 3F
kernel: [    3.063659] bounce pool size: 64 pages
kernel: [    3.072749] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
kernel: [   12.471490] ata1: link is slow to respond, please be patient (ready=0)
kernel: [   15.579346] Delay 5 seconds to wait for disk 1 ready.
kernel: [   28.946984] ata5: link is slow to respond, please be patient (ready=0)
kernel: [   33.597662] ata5: COMRESET failed (errno=-16)
kernel: [   33.602547] ata5: COMRESET fail, set COMRESET fail flag
kernel: [   36.728403] ata5: link reset sucessfully clear error flags
kernel: [   37.005538] Delay 5 seconds to wait for disk 2 ready.
kernel: [   50.367328] ata6: link is slow to respond, please be patient (ready=0)
kernel: [   59.255331] i8042: No controller found
kernel: [   59.721230] avoton_synobios: module license 'Synology Inc.' taints kernel.
kernel: [   59.737353] 2018-1-22 8:28:20 UTC
kernel: [   59.745871] Brand: Synology
kernel: [   59.749013] Model: DS-415+
kernel: [   59.752052] This is default settings: set group disks wakeup number to 1, spinup time deno 1
kernel: [   59.786290] synobios: unload
kernel: [   59.845432] md: invalid raid superblock magic on sda5
kernel: [   59.851100] md: sda5 does not have a valid v0.90 superblock, not importing!
kernel: [   59.915962] md: invalid raid superblock magic on sdb5
kernel: [   59.928955] md: sdb5 does not have a valid v0.90 superblock, not importing!
kernel: [   59.970059] md: invalid raid superblock magic on sdc5
kernel: [   59.975723] md: sdc5 does not have a valid v0.90 superblock, not importing!
kernel: [   59.999278] md: sda2 has different UUID to sda1
kernel: [   60.007969] md: sdb2 has different UUID to sda1
kernel: [   60.016666] md: sdc2 has different UUID to sda1
kernel: [   60.058270] Got empty serial number. Generate serial number from product.
kernel: [   61.065863] Got empty serial number. Generate serial number from product.
kernel: [   65.657353] usb 2-1.3: ep 0x81 - rounding interval to 64 microframes, ep desc says 80 microframes
kernel: [   71.915172] zram: module is from the staging directory, the quality is unknown, you have been warned.

Ik zie er ook "slow responding ata link" berichten staan. Daar staat hij bij mij 2x 10 seconden te wachten. Bij jou is dat dezelfde tijd.

De precieze betekenis van het meeste ontgaat me ook. Maar via de tijdcodes kun je wel zien of er iets is waar hij héél lang op moet wachten. En of dit in het Kernel deel zit of het proces erna.

Het kernel deel heeft volgens mij vooral betrekking op de hardware. Het stuk erna op de softwareprocessen.

[HPP]

Hi Briolet,

Bedankt natuurlijk weer voor je reactie! ik zag bij jou ook die error -16 staan in je logfile. en wat me ook opviel (ik heb mijn messages als attachment er bij gedaan) zijn jouw veel hogere raid 6 waardes. 707 b.v. t.o.v. mijn 162 MB/s. Alsof je SSD's in je machine hebt zitten!

ik denk dat ik maar een ticket stuur naar Synology samen met mijn debug.dat file.

Op zich loopt de 214 verder goed, zij het dat ik wel vind dat de disk i/o erg varieert (ik test dan met Nas Performance tester (400 Mb 5x).  Soms prima zoals 110 read, maar ook wel eens 82 met ondergrens van 63 MB/s. Zonder dat er naar mijn weten processen lopen.

ik hou jullie op de hoogte! Straks nog even een vraag over Moments plaatsen!

Dick

[Briolet]

Ik heb wel een iets nieuwer model nas met 4 slots en 3 schijven. Ik heb eigenlijk geen idee of hij een error logt, als hij een leeg slot tegenkomt.

En raid6 ?? In elk geval niet qua schijven bij mij. 2625 MB/s moet een of ander interne test van geheugen of een kaart zijn.

Voor een goed vergelijk zou je jouw log moeten vergelijken met een identiek model.

[HPP]

Zojuist Ticket aan gemaakt voor Synology!

[HPP]

Even een terugkoppeling na een weekje testen met Synolgyen diagnostics draaien:

mijn bootprocess tijd is met 50% verminderd. van ruim 5 minuten naar 2 minuut 20! Ik heb gevraagd naar oorzaak en de changes, maar die heb ik niet gekregen.

Synology schrijft wel: We are glad that things are working well for you now.
This issue will be fixed in DSM 6.2 official release.

@Briolet: jij had ook van die ata 16 errors in je logfile. Misschien iets voor jou om attent te zijn bij een nieuwe versie.

Top support vanuit Synology gekregen!