"Отвал" buddy 5 от mesh при проблеме HDD на контроллере

[vasek00]

Обновился на 37С01 на KN2710 (Контроллер) в 20-00, в схеме есть buddy 5 подключен по wifi. HDD разбит на три раздела NTFS-Ext4-Swap, подключен к USB3 роутера (USB2 пустой), кабель родной от Box короткий и толстый, проблем на нем (как и на его напарнике кабель и Box при работе 24/7 не замечено до этого).

Работает ТВ на проводе DLNA к KN2710, далее такая вот картина. На ТВ вылет из DLNA. Роутер не зависал, но Baddy 5 потерялся. Перегруз контроллера восстановил работу.

  Показать контент

[I] Dec  1 21:44:00 kernel: usb 2-1: reset SuperSpeed USB device number 2 using xhci-mtk
[I] Dec  1 21:44:00 kernel: usb 2-1: reset SuperSpeed USB device number 2 using xhci-mtk
[W] Dec  1 21:44:06 kernel: xhci-mtk 1a0c0000.usb: Timeout while waiting for setup device command
[W] Dec  1 21:44:11 kernel: xhci-mtk 1a0c0000.usb: Timeout while waiting for setup device command
[E] Dec  1 21:44:11 kernel: usb 2-1: device not accepting address 2, error -62
[E] Dec  1 21:44:15 kernel: usb usb2-port1: Cannot enable. Maybe the USB cable is bad?
[E] Dec  1 21:44:24 kernel: Core::Syslog: last message repeated 5 times.
[I] Dec  1 21:44:24 kernel: usb 2-1: USB disconnect, device number 2
[I] Dec  1 21:44:24 kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=0x00
[I] Dec  1 21:44:24 kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 26 fc 12 d0 00 08 00 00
[I] Dec  1 21:44:24 ndm: Core::FileSystem::Repository: unregistering "fee743b7-......-43b70a91d701:" filesystem... 
[E] Dec  1 21:44:24 kernel: blk_update_request: I/O error, dev sda, sector 654054096
[E] Dec  1 21:44:24 kernel: tntfs error (device sda1, pid 14): ntfs_bio_end_io_read(): Bio read I/O error (error 5).
[C] Dec  1 21:44:24 kernel: EXT4-fs error (device sda3): __ext4_get_inode_loc:4425: inode #446465: block 1785859: comm syslog-ng: unable to read itable block
[E] Dec  1 21:44:24 kernel: EXT4-fs (sda3): previous I/O error to superblock detected
[E] Dec  1 21:44:24 kernel: EXT4-fs (sda3): previous I/O error to superblock detected
[C] Dec  1 21:44:24 kernel: EXT4-fs error (device sda3): __ext4_get_inode_loc:4425: inode #446465: block 1785859: comm ndm: unable to read itable block
[E] Dec  1 21:44:24 kernel: tntfs error (device sda1, pid 7905): ntfs_bio_end_io_read(): Bio read I/O error (error 5).
[E] Dec  1 21:44:24 kernel: Core::Syslog: last message repeated 2 times.
[E] Dec  1 21:44:24 kernel: EXT4-fs (sda3): previous I/O error to superblock detected
[C] Dec  1 21:44:24 kernel: EXT4-fs error (device sda3) in ext4_reserve_inode_write:5548: IO failure
[C] Dec  1 21:44:24 kernel: EXT4-fs error (device sda3): __ext4_get_inode_loc:4425: inode #446465: block 1785859: comm ndm: unable to read itable block
[C] Dec  1 21:44:24 kernel: Core::Syslog: last message repeated 3 times.
[E] Dec  1 21:44:24 kernel: EXT4-fs (sda3): previous I/O error to superblock detected
[E] Dec  1 21:44:24 kernel: Core::Syslog: last message repeated 4 times.
[C] Dec  1 21:44:24 kernel: EXT4-fs error (device sda3): __ext4_get_inode_loc:4425: inode #446465: block 1785859: comm ndm: unable to read itable block
[E] Dec  1 21:44:24 kernel: EXT4-fs (sda3): previous I/O error to superblock detected
[E] Dec  1 21:44:24 kernel: EXT4-fs (sda3): previous I/O error to superblock detected
[I] Dec  1 21:44:24 ndm: Opkg::Manager: disk unmounted. 
[I] Dec  1 21:44:24 ndnproxy: stopped. 
[I] Dec  1 21:44:24 ndm: Dns::Manager: RPC-only mode disabled. 
[E] Dec  1 21:44:24 kernel: Aborting journal on device sda3-8.
[E] Dec  1 21:44:24 kernel: Buffer I/O error on dev sda3, logical block 537, lost sync page write
[E] Dec  1 21:44:24 kernel: JBD2: Error -5 detected when updating journal superblock for sda3-8.
[C] Dec  1 21:44:24 kernel: EXT4-fs error (device sda3): ext4_journal_check_start:56: Detected aborted journal

[I] Dec  1 21:44:24 ndm: Http::Manager: disabled WebDAV passthrough. 
[I] Dec  1 21:44:25 ndm: Http::Nginx: loaded SSL certificate for "p....1.keenetic.pro". 
[I] Dec  1 21:44:25 ndm: Http::Nginx: loaded SSL certificate for "0....1.keenetic.io". 
[I] Dec  1 21:44:25 ndm: Core::Server: started Session /var/run/ndm.core.socket. 
[I] Dec  1 21:44:25 ndm: Core::Session: client disconnected. 
[I] Dec  1 21:44:25 ndm: Http::Manager: updated configuration. 
[I] Dec  1 21:44:25 ndm: Core::Server: started Session /var/run/ndm.core.socket. 
[I] Dec  1 21:44:25 ndm: Core::Session: client disconnected. 
[I] Dec  1 21:44:27 ndm: Storage::Manager: "Media0": unregistered partition "fee743b7-......-43b70a91d701:". 
[I] Dec  1 21:44:27 ndm: Storage::Manager: "Media0": removed partition "fee743b7-......-43b70a91d701:". 
[I] Dec  1 21:44:27 ndm: Core::FileSystem::Repository: unregistering "00000000-0000-0000-0000-000000000000:" filesystem... 
[E] Dec  1 21:44:27 kernel: tntfs error (device sda1, pid 511): ntfs_end_buffer_async_read(): Buffer I/O error, logical block 17756, failed page read.
[E] Dec  1 21:44:27 kernel: tntfs error (device sda1, pid 511): ntfs_end_buffer_async_read(): Buffer I/O error, logical block 17757, failed page read.
[E] Dec  1 21:44:27 kernel: tntfs error (device sda1, pid 511): ntfs_end_buffer_async_read(): Buffer I/O error, logical block 17758, failed page read.
[E] Dec  1 21:44:27 kernel: tntfs error (device sda1, pid 511): ntfs_end_buffer_async_read(): Buffer I/O error, logical block 17759, failed page read.
[E] Dec  1 21:44:27 kernel: tntfs error (device sda1, pid 511): ntfs_mft_record_map(): Failed to map page containing mft record 0x5 (error 5).
[C] Dec  1 21:44:27 ndm: WebDav::Server: system failed [0xcffd032a], unable to clean a temporary directory: input/output error. 
[C] Dec  1 21:44:27 ndm: WebDav::Server: system failed [0xcffd0335], unable to create a temporary directory: input/output error. 
[I] Dec  1 21:44:27 ndm: WebDav::Server: has no temporary directory, unable to start. 
[I] Dec  1 21:44:27 kernel: ndm (7899): drop_caches: 3
[E] Dec  1 21:44:27 ndm: Core::FileSystem::Native: unable to disable swap: no device found. 
[I] Dec  1 21:44:27 ndm: Storage::Manager: "Media0": unregistered partition "00000000-0000-0000-0000-000000000000:". 
[I] Dec  1 21:44:27 ndm: Storage::Manager: "Media0": removed partition "00000000-0000-0000-0000-000000000000:". 
[I] Dec  1 21:44:27 ndm: Core::FileSystem::Repository: unregistering "01........0:" filesystem... 
[I] Dec  1 21:44:27 ndm: Storage::Manager: "Media0": unregistered partition "01.........0:". 
[I] Dec  1 21:44:27 ndm: Storage::Manager: "Media0": removed partition "01........0:". 
[C] Dec  1 21:44:27 ndm: Storage::Manager: "Media0": a media was unsafely unplugged without ejecting. 
[I] Dec  1 21:44:27 ndm: Storage::Manager: removed "Media0", port 1. 
[I] Dec  1 21:44:27 ndm: WebDav::Server: has no temporary directory, unable to start. 
[E] Dec  1 21:44:28 kernel: usb usb2-port1: Cannot enable. Maybe the USB cable is bad?
[E] Dec  1 21:44:32 kernel: usb usb2-port1: Cannot enable. Maybe the USB cable is bad?
[E] Dec  1 21:44:36 kernel: Core::Syslog: last message repeated 2 times.
[W] Dec  1 21:44:41 kernel: xhci-mtk 1....0.usb: Timeout while waiting for setup device command
[I] Dec  1 21:44:46 ndm: Network::InternetChecker: Internet access lost (status: 0x0001). 
[W] Dec  1 21:44:47 kernel: xhci-mtk 1....0.usb: Timeout while waiting for setup device command
[E] Dec  1 21:44:47 kernel: usb 2-1: device not accepting address 6, error -62
[E] Dec  1 21:44:47 kernel: usb usb2-port1: unable to enumerate USB device
[E] Dec  1 21:49:31 ndm: Core::Ndss: [8254] cannot connect to the server. 
[E] Dec  1 21:51:03 ndm: Core::Ndss: [8823] cannot connect to the server. 

T:  Bus=01 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#=  1 Spd=480  MxCh= 2
B:  Alloc=  0/800 us ( 0%), #Int=  0, #Iso=  0
D:  Ver= 2.00 Cls=09(hub  ) Sub=00 Prot=01 MxPS=64 #Cfgs=  1
P:  Vendor=1d6b ProdID=0002 Rev= 4.09
S:  Manufacturer=Linux 4.9-ndm-5 xhci-hcd
S:  Product=xHCI Host Controller
S:  SerialNumber=1......0.usb
C:* #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr=  0mA
I:* If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=00 Driver=hub
E:  Ad=81(I) Atr=03(Int.) MxPS=   4 Ivl=256ms

T:  Bus=02 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#=  1 Spd=5000 MxCh= 1
B:  Alloc=  0/800 us ( 0%), #Int=  0, #Iso=  0
D:  Ver= 3.00 Cls=09(hub  ) Sub=00 Prot=03 MxPS= 9 #Cfgs=  1
P:  Vendor=1d6b ProdID=0003 Rev= 4.09
S:  Manufacturer=Linux 4.9-ndm-5 xhci-hcd
S:  Product=xHCI Host Controller
S:  SerialNumber=1......0.usb
C:* #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr=  0mA
I:* If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=00 Driver=hub
E:  Ad=81(I) Atr=03(Int.) MxPS=   4 Ivl=256ms

    <file name="proc:swaps">
        <![CDATA[
Filename				Type		Size	Used	Priority
/dev/zram0                              partition	524284	0	0
/dev/sda2\040(deleted)                  partition	2096124	0	-1


В нормальном состояние 
    <file name="sys:kernel/debug/usb/devices">
        <![CDATA[

.....
T:  Bus=02 Lev=01 Prnt=01 Port=00 Cnt=01 Dev#=  2 Spd=5000 MxCh= 0
D:  Ver= 3.00 Cls=00(>ifc ) Sub=00 Prot=00 MxPS= 9 #Cfgs=  1
P:  Vendor=1f75 ProdID=0621 Rev= 0.36
S:  SerialNumber=2.....0
C:* #Ifs= 1 Cfg#= 1 Atr=80 MxPwr=800mA
I:* If#= 0 Alt= 0 #EPs= 2 Cls=08(stor.) Sub=06 Prot=50 Driver=usb-storage
E:  Ad=81(I) Atr=02(Bulk) MxPS=1024 Ivl=0ms
E:  Ad=02(O) Atr=02(Bulk) MxPS=1024 Ivl=0ms


    <file name="proc:swaps">
        <![CDATA[
Filename				Type		Size	Used	Priority
/dev/zram0                              partition	524284	0	0
/dev/sda2                               partition	2096124	0	-1


/dev/ubi0_0             105968        36    101096   0% /storage
/dev/sda1            481296380 399937700  81358680  83% /tmp/mnt/01......0
/dev/sda3              4671018    328205   4089066   7% /tmp/mnt/fee743b7-........-43b70a91d701
/dev/sda3              4671018    328205   4089066   7% /opt

Baddy 5 вылетел, так как горит красным, перегруз KN2710 в районе 21-53 решил проблемы.

На Baddy

[I] Dec  1 21:44:53 ndm: Network::InternetChecker: Internet access lost (status: 0x0001). 
[I] Dec  1 21:52:03 kernel: br0: port 7(apclii0.1) entered disabled state
[I] Dec  1 21:52:03 kernel: br0: topology change detected, propagating
[I] Dec  1 21:52:03 wmond: WifiMaster1/WifiStation0: (MT7613) had disassociated by AP (reason: due to inactivity). 
[I] Dec  1 21:52:04 kernel: Wi-Fi client: do site survey (flat scan)...
[I] Dec  1 21:52:06 ndm: Network::Interface::Rtx::WifiController: "WifiMaster0/Backhaul0": set metric 0. 
[I] Dec  1 21:52:06 ndm: Network::Interface::Rtx::WifiController: "WifiMaster1/Backhaul0": set metric 0. 
[I] Dec  1 21:52:06 kernel: br0: port 3(rai0) entered disabled state
[I] Dec  1 21:52:06 kernel: br0: port 7(apclii0.1) entered disabled state
...

Так контроллер был перезапущен в 21-51-хх то в 21-52 происходила установка связи.

Интервал времени с 21-44 до 21-52 в лог не попал.

 

 

 

Изменено 2 декабря, 2021 пользователем vasek00