• TS219 : HDD #2 not seen

    From Ewen Nedelec@21:1/5 to All on Thu Mar 24 09:00:01 2022
    Hi,

    I have a QNAP TS219 running Debian 10, installed thanks to the excellent tutorial of Martin Michlmayr.
    It has been successfully running for a while with only one 120GB SSD in
    tray #1.
    I am now trying to add another HDD (WD Red 2TB) in tray #2 to store
    additional data.

    But this second disk is not recognized.
    The tray#1 led blinks briefly early during the boot and then remains off.
    The disk does not appear in lsblk output and the dmesg states "ata1:
    SATA link down (SStatus 0 SControl F300)"
    I have tried another HDD (Seagate 4TB) : same symptom
    I have tried to swap the trays : same symptom (except the leds are
    inverted) -> this should eliminate hardware issues I think?

    Does anyone have idea of what I could further investigate?

    Thanks in advance for your support

    Best

    Ewen


    Full dmesg : ----------------------------------------------------------------------------------------------------
    [    0.000000] Booting Linux on physical CPU 0x0
    [    0.000000] Linux version 4.19.0-19-marvell (debian-kernel@lists.debian.org) (gcc version 8.3.0 (Debian 8.3.0-6)) #1
    Debian 4.19.232-1 (2022-03-07)
    [    0.000000] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=0005397f
    [    0.000000] CPU: VIVT data cache, VIVT instruction cache
    [    0.000000] OF: fdt: Machine model: QNAP TS219 family
    [    0.000000] Memory policy: Data cache writeback
    [    0.000000] On node 0 totalpages: 131072
    [    0.000000]   Normal zone: 1024 pages used for memmap
    [    0.000000]   Normal zone: 0 pages reserved
    [    0.000000]   Normal zone: 131072 pages, LIFO batch:31
    [    0.000000] random: get_random_bytes called from
    start_kernel+0x78/0x4ac with crng_init=0
    [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
    [    0.000000] pcpu-alloc: [0] 0
    [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 130048
    [    0.000000] Kernel command line: console=ttyS0,115200 root=/dev/ram initrd=0xa00000,0x900000 ramdisk=32768
    [    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
    [    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
    [    0.000000] Memory: 504420K/524288K available (3991K kernel code,
    342K rwdata, 1040K rodata, 312K init, 219K bss, 19868K reserved, 0K cma-reserved, 0K highmem)
    [    0.000000] Virtual kernel memory layout:
                       vector  : 0xffff0000 - 0xffff1000   (   4 kB)
                       fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
                       vmalloc : 0xe0800000 - 0xff800000   ( 496 MB)
                       lowmem  : 0xc0000000 - 0xe0000000   ( 512 MB)
                       pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
                       modules : 0xbf000000 - 0xbfe00000   (  14 MB)
                         .text : 0x(ptrval) - 0x(ptrval)   (3993 kB)
                         .init : 0x(ptrval) - 0x(ptrval)   ( 312 kB)
                         .data : 0x(ptrval) - 0x(ptrval)   ( 343 kB)
                          .bss : 0x(ptrval) - 0x(ptrval)   ( 220 kB)
    [    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [    0.000000] ftrace: allocating 19000 entries in 56 pages
    [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
    [    0.000000] clocksource: orion_clocksource: mask: 0xffffffff
    max_cycles: 0xffffffff, max_idle_ns: 9556302233 ns
    [    0.000008] sched_clock: 32 bits at 200MHz, resolution 5ns, wraps
    every 10737418237ns
    [    0.000040] Switching to timer-based delay loop, resolution 5ns
    [    0.000130] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=800000)
    [    0.000153] pid_max: default: 32768 minimum: 301
    [    0.000410] Security Framework initialized
    [    0.000429] Yama: disabled by default; enable with sysctl kernel.yama.* [    0.000547] AppArmor: AppArmor initialized
    [    0.000671] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) [    0.000693] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.001631] CPU: Testing write buffer coherency: ok
    [    0.002605] Setting up static identity map for 0x8200 - 0x823c
    [    0.002840] mvebu-soc-id: MVEBU SoC ID=0x6281, Rev=0x2
    [    0.004648] devtmpfs: initialized
    [    0.007742] VFP support v0.3: not present
    [    0.007894] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
    [    0.007919] futex hash table entries: 256 (order: -1, 3072 bytes)
    [    0.008231] pinctrl core: initialized pinctrl subsystem
    [    0.009321] NET: Registered protocol family 16
    [    0.009727] DMA: preallocated 256 KiB pool for atomic coherent allocations
    [    0.010339] audit: initializing netlink subsys (disabled)
    [    0.011109] cpuidle: using governor ladder
    [    0.011125] cpuidle: using governor menu
    [    0.011457] Feroceon L2: Enabling L2
    [    0.011499] Feroceon L2: Cache support initialised.
    [    0.011730] [Firmware Info]: /ocp@f1000000/ethernet-controller@72000/ethernet0-port@0:
    local-mac-address is not set
    [    0.015563] No ATAGs?
    [    0.016077] audit: type=2000 audit(0.008:1): state=initialized audit_enabled=0 res=1
    [    0.019847] clocksource: Switched to clocksource orion_clocksource [    0.042938] VFS: Disk quotas dquot_6.6.0
    [    0.043015] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
    [    0.043496] AppArmor: AppArmor Filesystem Enabled
    [    0.044624] NET: Registered protocol family 2
    [    0.044793] IP idents hash table entries: 8192 (order: 4, 65536 bytes) [    0.045605] tcp_listen_portaddr_hash hash table entries: 512 (order:
    0, 4096 bytes)
    [    0.045635] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
    [    0.045688] TCP bind hash table entries: 4096 (order: 2, 16384 bytes) [    0.045738] TCP: Hash tables configured (established 4096 bind 4096) [    0.045829] UDP hash table entries: 256 (order: 0, 4096 bytes)
    [    0.045853] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes) [    0.046027] NET: Registered protocol family 1
    [    0.046063] PCI: CLS 0 bytes, default 32
    [    0.046281] Unpacking initramfs...
    [    0.583932] Freeing initrd memory: 9216K
    [    0.584765] Initialise system trusted keyrings
    [    0.584815] Key type blacklist registered
    [    0.584984] workingset: timestamp_bits=30 max_order=17 bucket_order=0 [    0.589679] zbud: loaded
    [    0.591409] Key type asymmetric registered
    [    0.591422] Asymmetric key parser 'x509' registered
    [    0.591473] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
    [    0.591625] io scheduler noop registered
    [    0.591912] io scheduler cfq registered (default)
    [    0.593387] kirkwood-pinctrl f1010000.pin-controller: registered
    pinctrl driver
    [    0.594968] mvebu-pcie mbus@f1000000:pcie@82000000: PCI host bridge
    to bus 0000:00
    [    0.594987] pci_bus 0000:00: root bus resource [bus 00-ff]
    [    0.595000] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xefffffff]
    [    0.595012] pci_bus 0000:00: root bus resource [io 0x1000-0xeffff] [    0.595094] pci 0000:00:01.0: [11ab:6281] type 01 class 0x060400
    [    0.596190] PCI: bus0: Fast back to back transfers disabled
    [    0.596209] pci 0000:00:01.0: bridge configuration invalid ([bus
    00-00]), reconfiguring
    [    0.596341] pci 0000:01:00.0: [197b:2363] type 00 class 0x010601
    [    0.596430] pci 0000:01:00.0: reg 0x24: [mem 0x40000000-0x40001fff] [    0.596446] pci 0000:01:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref] [    0.596509] pci 0000:01:00.0: PME# supported from D3hot
    [    0.596677] pci 0000:01:00.1: [197b:2363] type 00 class 0x010185
    [    0.596707] pci 0000:01:00.1: [Firmware Bug]: reg 0x10: invalid BAR (can't size)
    [    0.596724] pci 0000:01:00.1: [Firmware Bug]: reg 0x14: invalid BAR (can't size)
    [    0.596740] pci 0000:01:00.1: [Firmware Bug]: reg 0x18: invalid BAR (can't size)
    [    0.596755] pci 0000:01:00.1: [Firmware Bug]: reg 0x1c: invalid BAR (can't size)
    [    0.596770] pci 0000:01:00.1: [Firmware Bug]: reg 0x20: invalid BAR (can't size)
    [    0.597855] PCI: bus1: Fast back to back transfers disabled
    [    0.597873] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 [    0.598059] pci 0000:00:01.0: BAR 8: assigned [mem 0xe0000000-0xe00fffff] [    0.598078] pci 0000:01:00.0: BAR 6: assigned [mem
    0xe0000000-0xe000ffff pref]
    [    0.598091] pci 0000:01:00.0: BAR 5: assigned [mem 0xe0010000-0xe0011fff] [    0.598106] pci 0000:00:01.0: PCI bridge to [bus 01]
    [    0.598120] pci 0000:00:01.0:   bridge window [mem 0xe0000000-0xe00fffff]
    [    0.598198] pcieport 0000:00:01.0: enabling device (0140 -> 0142)
    [    0.598242] pci 0000:01:00.0: async suspend disabled to avoid multi-function power-on ordering issue
    [    0.598271] pci 0000:01:00.1: async suspend disabled to avoid multi-function power-on ordering issue
    [    0.598688] mv_xor f1060800.xor: Marvell shared XOR driver
    [    0.628714] mv_xor f1060800.xor: Marvell XOR (Registers Mode): ( xor
    cpy intr )
    [    0.628902] mv_xor f1060900.xor: Marvell shared XOR driver
    [    0.656717] mv_xor f1060900.xor: Marvell XOR (Registers Mode): ( xor
    cpy intr )
    [    0.657050] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled [    0.657990] console [ttyS0] disabled
    [    0.658051] f1012000.serial: ttyS0 at MMIO 0xf1012000 (irq = 27, base_baud = 12500000) is a 16550A
    [    1.314948] console [ttyS0] enabled
    [    1.319036] f1012100.serial: ttyS1 at MMIO 0xf1012100 (irq = 28, base_baud = 12500000) is a 16550A
    [    1.328055] random: fast init done
    [    1.332163] i2c /dev entries driver
    [    1.336983] ledtrig-cpu: registered to indicate activity on CPUs
    [    1.343969] registered taskstats version 1
    [    1.348104] Loading compiled-in X.509 certificates
    [    1.378835] Loaded X.509 cert 'Debian Secure Boot CA: 6ccece7e4c6c0d1f6149f3dd27dfcc5cbb419ea1'
    [    1.412109] Loaded X.509 cert 'Debian Secure Boot Signer 2021 -
    linux: 4b6ef5abca669825178e052c84667ccbc0531f8c'
    [    1.422440] AppArmor: AppArmor sha1 policy hashing enabled
    [    1.429258] hctosys: unable to open rtc device (rtc0)
    [    1.438279] Freeing unused kernel memory: 312K
    [    1.442769] This architecture does not have kernel memory protection. [    1.449248] Run /init as init process
    [    1.902774] rtc-s35390a 0-0030: rtc core: registered rtc-s35390a as rtc0 [    1.979219] SCSI subsystem initialized
    [    2.036987] libata version 3.00 loaded.
    [    2.039331] sata_mv f1080000.sata: version 1.28
    [    2.039581] sata_mv f1080000.sata: slots 32 ports 2
    [    2.053967] scsi host0: sata_mv
    [    2.059661] scsi host1: sata_mv
    [    2.063804] ata1: SATA max UDMA/133 irq 35
    [    2.067948] ata2: SATA max UDMA/133 irq 35
    [    2.386186] ata1: SATA link down (SStatus 0 SControl F300)
    [    2.863873] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl F300) [    2.894185] ata2.00: ATA-8: KINGSTON SH100S3120G, 503ABBF0, max UDMA/133 [    2.900937] ata2.00: 234441648 sectors, multi 16: LBA48 NCQ (depth 31/32) [    2.930175] ata2.00: configured for UDMA/133
    [    2.934811] scsi 1:0:0:0: Direct-Access     ATA      KINGSTON SH100S3
    BBF0 PQ: 0 ANSI: 5
    [    2.973711] sd 1:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB)
    [    2.984210] sd 1:0:0:0: [sda] Write Protect is off
    [    2.989062] sd 1:0:0:0: [sda] Mode Sense: 00 3a 00 00
    [    2.989152] sd 1:0:0:0: [sda] Write cache: enabled, read cache:
    enabled, doesn't support DPO or FUA
    [    3.002380]  sda: sda1 sda2 sda3 < sda5 >
    [    3.010314] sd 1:0:0:0: [sda] Attached SCSI disk
    [    3.465341] EXT4-fs (sda2): mounted filesystem with ordered data
    mode. Opts: (null)
    [    3.805978] systemd[1]: System time before build time, advancing clock. [    3.838993] systemd[1]: Inserted module 'autofs4'
    [    3.885114] NET: Registered protocol family 10
    [    3.990796] Segment Routing with IPv6
    [    4.014481] systemd[1]: systemd 241 running in system mode. (+PAM
    +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP
    +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN
    -PCRE2 default-hierarchy=hybrid)
    [    4.036502] systemd[1]: Detected architecture arm.
    [    4.064452] systemd[1]: Set hostname to <qnap219-1>.
    [    5.139615] systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP
    firewall (IPAddressDeny=any), but the local system does not support
    BPF/cgroup based firewalling.
    [    5.156797] systemd[1]: Proceeding WITHOUT firewalling in effect!
    (This warning is only shown for the first loaded unit using IP firewalling.) [    5.526875] random: systemd: uninitialized urandom read (16 bytes read) [    5.545589] random: systemd: uninitialized urandom read (16 bytes read) [    5.553511] systemd[1]: Listening on Journal Socket.
    [    5.572104] random: systemd: uninitialized urandom read (16 bytes read) [    5.579442] systemd[1]: Listening on Syslog Socket.
    [    5.600771] systemd[1]: Listening on fsck to fsckd communication Socket. [    5.631420] systemd[1]: Mounting Kernel Debug File System...
    [    5.657332] systemd[1]: Listening on Journal Audit Socket.
    [    5.826718] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro
    [    6.322179] input: gpio_keys as /devices/platform/gpio_keys/input/input0 [    7.152427] systemd-journald[141]: Received request to flush runtime journal from PID 1
    [    7.830156] ahci 0000:01:00.0: version 3.0
    [    7.840469] ahci 0000:01:00.0: AHCI 0001.0000 32 slots 2 ports 3 Gbps
    0x3 impl SATA mode
    [    7.848634] ahci 0000:01:00.0: flags: 64bit ncq pm led clo pmp pio
    slum part
    [    7.939622] orion_wdt: Initial timeout 21 sec
    [    8.003723] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4 [    8.060007] scsi host2: ahci
    [    8.068504] mv643xx_eth_port mv643xx_eth_port.0: DMA mask not set
    [    8.095675] scsi host3: ahci
    [    8.111970] marvell-cesa f1030000.crypto: CESA device successfully registered
    [    8.132258] ata3: SATA max UDMA/133 abar m8192@0xe0010000 port
    0xe0010100 irq 40
    [    8.139724] ata4: SATA max UDMA/133 abar m8192@0xe0010000 port
    0xe0010180 irq 40
    [    8.180058] usbcore: registered new interface driver usbfs
    [    8.207968] sd 1:0:0:0: Attached scsi generic sg0 type 0
    [    8.224695] usbcore: registered new interface driver hub
    [    8.256852] usbcore: registered new device driver usb
    [    8.462820] ata3: SATA link down (SStatus 0 SControl 300)
    [    8.468498] ata4: SATA link down (SStatus 0 SControl 300)
    [    8.482730] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [    8.568924] m25p80 spi0.0: m25p128 (16384 Kbytes)
    [    8.595022] ehci-orion: EHCI orion driver
    [    8.648138] orion-ehci f1050000.ehci: EHCI Host Controller
    [    8.675919] rtc-mv f1010300.rtc: internal RTC not ticking
    [    8.711971] orion-ehci f1050000.ehci: new USB bus registered,
    assigned bus number 1
    [    8.795225] orion-ehci f1050000.ehci: irq 32, io mem 0xf1050000
    [    8.887919] orion-ehci f1050000.ehci: USB 2.0 started, EHCI 1.00
    [    8.927349] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
    [    8.935702] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    [    8.942987] usb usb1: Product: EHCI Host Controller
    [    8.947906] usb usb1: Manufacturer: Linux 4.19.0-19-marvell ehci_hcd [    8.954312] usb usb1: SerialNumber: f1050000.ehci
    [    8.975887] 6 fixed-partitions partitions found on MTD device spi0.0 [    8.982293] Creating 6 MTD partitions on "spi0.0":
    [    9.000609] 0x000000000000-0x000000080000 : "U-Boot"
    [    9.018803] 0x000000200000-0x000000400000 : "Kernel"
    [    9.029017] 0x000000400000-0x000000d00000 : "RootFS1"
    [    9.041354] 0x000000d00000-0x000001000000 : "RootFS2"
    [    9.050489] hub 1-0:1.0: USB hub found
    [    9.055819] 0x000000080000-0x0000000c0000 : "U-Boot Config"
    [    9.064347] hub 1-0:1.0: 1 port detected
    [    9.116319] 0x0000000c0000-0x000000200000 : "NAS Config"
    [    9.228723] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address 00:08:9b:bd:31:90
    [    9.363914] usb 1-1: new high-speed USB device number 2 using orion-ehci [    9.573741] usb 1-1: New USB device found, idVendor=05e3,
    idProduct=0608, bcdDevice= 9.01
    [    9.581999] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
    [    9.589203] usb 1-1: Product: USB2.0 Hub
    [    9.637649] hub 1-1:1.0: USB hub found
    [    9.652268] hub 1-1:1.0: 4 ports detected
    [   10.328102] Adding 499708k swap on /dev/sda5.  Priority:-2 extents:1 across:499708k SSFS
    [   11.495822] EXT4-fs (sda1): mounting ext2 file system using the ext4 subsystem
    [   11.514953] EXT4-fs (sda1): mounted filesystem without journal. Opts: (null)
    [   11.878022] audit: type=1400 audit(1550139126.068:2):
    apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/cups-browsed" pid=220 comm="apparmor_parser"
    [   11.929541] audit: type=1400 audit(1550139126.120:3):
    apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe" pid=221 comm="apparmor_parser"
    [   11.997249] audit: type=1400 audit(1550139126.120:4):
    apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe//kmod" pid=221 comm="apparmor_parser"
    [   12.095210] audit: type=1400 audit(1550139126.284:5):
    apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/cups/backend/cups-pdf" pid=224 comm="apparmor_parser"
    [   12.156055] audit: type=1400 audit(1550139126.284:6):
    apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/cupsd" pid=224 comm="apparmor_parser"
    [   12.223923] audit: type=1400 audit(1550139126.284:7):
    apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/cupsd//third_party" pid=224 comm="apparmor_parser"
    [   12.309906] audit: type=1400 audit(1550139126.384:8):
    apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/man" pid=225 comm="apparmor_parser"
    [   12.399625] audit: type=1400 audit(1550139126.384:9):
    apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_filter" pid=225 comm="apparmor_parser"
    [   12.478421] audit: type=1400 audit(1550139126.384:10):
    apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_groff" pid=225 comm="apparmor_parser"
    [   13.519422] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
    [   13.727676] NET: Registered protocol family 17
    [   15.067905] random: crng init done
    [   15.071333] random: 7 urandom warning(s) missed due to ratelimiting
    [   16.658086] mv643xx_eth_port mv643xx_eth_port.0 eth0: link up, 1000
    Mb/s, full duplex, flow control disabled
    [   16.668030] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [33441.768928] lvm2-activation-generator: lvmconfig failed
    [33441.808580] lvm2-activation-generator: Activation generator failed. [33444.348791] lvm2-activation-generator: lvmconfig failed
    [33444.396450] lvm2-activation-generator: Activation generator failed. ----------------------------------------------------------------------------------------------------

    --- SoupGate-Win32 v1.05
    * Origin: fsxNet Usenet Gateway (21:1/5)