Few days ago my router started restarting. Restarts are unwanted, unplanned etc. Uptime is about 15 to 23 hours. I have not any logs now, because syslog writes to tmpfs. I changed it to persistent storage, so I will see.
I noticed this behavior in Fridays night. Router was much more stable before, just I2C bus was freezing unless power off the router (it was two times per week, no log entry just I2C bus no response).
Do anybody have similar behavior?
Please check the router casing around rear reset button. There may have been a problem in the manufacturing which could make the button unstable and susceptible to random presses.
This is not this problem… Reset button is correctly in hole, when pressed noisy and clear click is audible and no action is performed before this click. By the way, router is in stable position, last restart was before one hour, when nobody was home.
I noticed it just today. First reboot I noticed was at cca 4 am and second one at cca 9 pm. I setupped my log to go to drive too. I will see what I can get from it. I am not sure what could cause it, but I encountered it only on router I have at home. Work router seems to be not affected. So I will see.
If you have some output from log, please post it. Thank you.
Yesterday around 11pm I noted, that my turris has restarted for no reason. Before I had uptime of several days. I’ll see what happens now.
I had similar problem last Friday - I came home and the router was restarting every 2-10 min. Even browsing was unusable… I had to factory reset it, that helped. Question is, for how long.
i had about 5 unwanted resets this morning.
it became stable as i was connecting a serial console to get log output.
It really puts a big question mark on Omnia’s stability…
Two unwanted restarts today and I2C blocked whole day (No weather measuring whole day! I am really angry!) I2C was blocked during unwanted restart until I restarted router by unplugging them from power.
By the way, when I become home, I found router with ALL LEDs turned on with green light (like when “rainbow” is restarted).
Same for me, random restarts when I don’t expect it. Frustrating!
cynerd from Turris Team said:
If you have some output from log, please post it. Thank you.
Guys do you have some output, which would help Turis Team to find the cause?
I can understand and I think we all can understand that it is frustrating, but if they don’t have cause, it’s usually hard to find cause.
is there easy way how to put them 2.5k lines long text?
By the way, there is extremely nothing interesting about restarts in the log…
unwanted restart, few lines before, few lines after boot:
2016-11-23T10:56:01+01:00 info cron[30464]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
2016-11-23T10:56:01+01:00 info sshd[30477]: Accepted publickey for root from 172.16.1.2 port 59888 ssh2: RSA SHA256:8+AkLcbWSZnjB0oy6m4G+aXBaFiRXRj6pjm1ivS5Rsw
2016-11-23T10:56:01+01:00 info sshd[30477]: Received disconnect from 172.16.1.2 port 59888:11: disconnected by user
2016-11-23T10:56:01+01:00 info sshd[30477]: Disconnected from 172.16.1.2 port 59888
2016-11-23T10:56:01+01:00 info sshd[30492]: Accepted publickey for root from 172.16.1.2 port 59890 ssh2: RSA SHA256:8+AkLcbWSZnjB0oy6m4G+aXBaFiRXRj6pjm1ivS5Rsw
2016-11-23T10:56:01+01:00 info sshd[30492]: Received disconnect from 172.16.1.2 port 59890:11: disconnected by user
2016-11-23T10:56:01+01:00 info sshd[30492]: Disconnected from 172.16.1.2 port 59890
2016-11-23T10:57:01+01:00 info cron[30794]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
2016-11-23T10:57:01+01:00 info sshd[30809]: Accepted publickey for root from 172.16.1.2 port 59900 ssh2: RSA SHA256:8+AkLcbWSZnjB0oy6m4G+aXBaFiRXRj6pjm1ivS5Rsw
2016-11-23T10:57:01+01:00 info sshd[30809]: Received disconnect from 172.16.1.2 port 59900:11: disconnected by user
2016-11-23T10:57:01+01:00 info sshd[30809]: Disconnected from 172.16.1.2 port 59900
2016-11-23T10:58:01+01:00 info cron[31102]: (root) CMD (nethist_stats.lua)
2016-11-23T10:58:01+01:00 info cron[31101]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
2016-11-23T10:58:01+01:00 info sshd[31118]: Accepted publickey for root from 172.16.1.2 port 59908 ssh2: RSA SHA256:8+AkLcbWSZnjB0oy6m4G+aXBaFiRXRj6pjm1ivS5Rsw
2016-11-23T10:58:01+01:00 info sshd[31118]: Received disconnect from 172.16.1.2 port 59908:11: disconnected by user
2016-11-23T10:58:01+01:00 info sshd[31118]: Disconnected from 172.16.1.2 port 59908
2016-11-23T10:59:01+01:00 info cron[31396]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
2016-11-23T10:59:02+01:00 info sshd[31411]: Accepted publickey for root from 172.16.1.2 port 59916 ssh2: RSA SHA256:8+AkLcbWSZnjB0oy6m4G+aXBaFiRXRj6pjm1ivS5Rsw
2016-11-23T10:59:02+01:00 info sshd[31411]: Received disconnect from 172.16.1.2 port 59916:11: disconnected by user
2016-11-23T10:59:02+01:00 info sshd[31411]: Disconnected from 172.16.1.2 port 59916
2016-11-23T12:00:40+01:00 notice syslog-ng[2341]: syslog-ng starting up; version='3.0.9'
2016-11-23T12:00:40+01:00 info kernel[]: [ 6.719150] hub 5-0:1.0: USB hub found
2016-11-23T12:00:40+01:00 info kernel[]: [ 6.722925] hub 5-0:1.0: 1 port detected
2016-11-23T12:00:40+01:00 info kernel[]: [ 6.728279] usbcore: registered new interface driver usb-storage
2016-11-23T12:00:40+01:00 info kernel[]: [ 7.040751] usb 5-1: new SuperSpeed USB device number 2 using xhci-hcd
2016-11-23T12:00:40+01:00 info kernel[]: [ 7.060849] usb 5-1: LPM exit latency is zeroed, disabling LPM.
2016-11-23T12:00:40+01:00 info kernel[]: [ 7.067254] usb-storage 5-1:1.0: USB Mass Storage device detected
2016-11-23T12:00:40+01:00 info kernel[]: [ 7.073479] scsi host2: usb-storage 5-1:1.0
2016-11-23T12:00:40+01:00 info kernel[]: 7.620873] init: - preinit -
2016-11-23T12:00:40+01:00 notice kernel[]: [ 8.071027] scsi 2:0:0:0: Direct-Access SAMSUNG HD204UI 1AQ1 PQ: 0 ANSI: 6
2016-11-23T12:00:40+01:00 notice kernel[]: [ 8.079693] sd 2:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
2016-11-23T11:00:40+01:00 notice firewall[]: Reloading firewall due to ifup of wan6 (wwan0)
2016-11-23T11:00:41+01:00 notice firewall[]: Reloading firewall due to ifup of wan (wwan0)
2016-11-23T12:00:40+01:00 info dnsmasq[2304]: started, version 2.73 cachesize 150
2016-11-23T12:00:42+01:00 warning dnsmasq[2304]: overflow: 12 log entries lost
2016-11-23T12:00:42+01:00 info dnsmasq-dhcp[2304]: DHCPOFFER(br-lan) 172.16.1.3 b8:27:eb:31:95:e0
2016-11-23T12:00:42+01:00 info dnsmasq-dhcp[2304]: DHCPREQUEST(br-lan) 172.16.1.3 b8:27:eb:31:95:e0
2016-11-23T12:00:42+01:00 info dnsmasq-dhcp[2304]: DHCPACK(br-lan) 172.16.1.3 b8:27:eb:31:95:e0 xbmc
2016-11-23T12:00:45+01:00 notice kernel[]: [ 8.087647] sd 2:0:0:0: [sda] Write Protect is off
2016-11-23T12:00:45+01:00 debug kernel[]: [ 8.092470] sd 2:0:0:0: [sda] Mode Sense: 1f 00 00 08
2016-11-23T12:00:45+01:00 notice kernel[]: [ 8.092682] random: procd urandom read with 21 bits of entropy available
2016-11-23T12:00:45+01:00 err kernel[]: [ 8.099514] sd 2:0:0:0: [sda] No Caching mode page found
2016-11-23T12:00:45+01:00 err kernel[]: [ 8.104865] sd 2:0:0:0: [sda] Assuming drive cache: write through
2016-11-23T12:00:45+01:00 info kernel[]: [ 8.218148] sda: sda1 sda2 sda3 < sda5 sda6 sda7 > sda4
I2C still blocked out, just after reboot:
2016-11-23T11:01:06+01:00 emerge sfpswitch.py[1812]: Write error: Operation timed out
2016-11-23T12:01:06+01:00 err kernel[]: [ 56.500739] i2c i2c-0: mv64xxx: I2C bus locked, block: 1, time_left: 0
2016-11-23T11:01:12+01:00 emerg sfpswitch.py[1812]: Write error: Operation not permitted
2016-11-23T12:01:12+01:00 err kernel[]: [ 62.500737] i2c i2c-0: mv64xxx: I2C bus locked, block: 1, time_left: 0
2016-11-23T11:01:16+01:00 emerg sfpswitch.py[1812]: Write error: Operation not permitted
2016-11-23T12:01:16+01:00 err kernel[]: [ 66.500737] i2c i2c-0: mv64xxx: I2C bus locked, block: 1, time_left: 0
2016-11-23T11:01:20+01:00 emerg sfpswitch.py[1812]: Write error: Operation not permitted
2016-11-23T12:01:20+01:00 err kernel[]: [ 70.500736] i2c i2c-0: mv64xxx: I2C bus locked, block: 1, time_left: 0
2016-11-23T11:01:24+01:00 emerg sfpswitch.py[1812]: Write error: Operation not permitted
2016-11-23T12:01:24+01:00 err kernel[]: [ 74.500736] i2c i2c-0: mv64xxx: I2C bus locked, block: 1, time_left: 0
2016-11-23T11:01:28+01:00 emerg sfpswitch.py[1812]: Write error: Operation timed out
2016-11-23T12:01:28+01:00 err kernel[]: [ 78.500737] i2c i2c-0: mv64xxx: I2C bus locked, block: 1, time_left: 0
2016-11-23T11:01:28+01:00 emerg sfpswitch.py[1812]: setting up led Auto-configuration for PCI2
2016-11-23T12:01:30+01:00 err kernel[]: [ 80.500737] i2c i2c-0: mv64xxx: I2C bus locked, block: 1, time_left: 0
2016-11-23T11:01:32+01:00 emerg sfpswitch.py[1812]: sh: write error: Operation timed out
2016-11-23T12:01:32+01:00 err kernel[]: [ 82.500738] i2c i2c-0: mv64xxx: I2C bus locked, block: 1, time_left: 0
2016-11-23T11:01:32+01:00 emerg sfpswitch.py[1812]: setting up led Auto-configuration for PCI3
2016-11-23T11:01:38+01:00 emerg sfpswitch.py[1812]: sh: write error: Operation timed out
2016-11-23T12:01:38+01:00 err kernel[]: [ 88.500737] i2c i2c-0: mv64xxx: I2C bus locked, block: 1, time_left: 0
2016-11-23T11:01:38+01:00 emerg sfpswitch.py[1812]: setting up led Wan dev indication
2016-11-23T11:01:44+01:00 emerg sfpswitch.py[1812]: sh: write error: Operation timed out
2016-11-23T12:01:44+01:00 err kernel[]: [ 94.500740] i2c i2c-0: mv64xxx: I2C bus locked, block: 1, time_left: 0
2016-11-23T11:01:44+01:00 emerg sfpswitch.py[1812]: setting up led USB modem present
2016-11-23T11:01:50+01:00 emerg sfpswitch.py[1812]: sh: write error: Operation timed out
2016-11-23T12:01:50+01:00 err kernel[]: [ 100.500741] i2c i2c-0: mv64xxx: I2C bus locked, block: 1, time_left: 0
2016-11-23T11:01:50+01:00 emerg sfpswitch.py[1812]: setting up led disk IO
Only interesting thing is kernel panic which did not caused reboot:
2016-11-22T04:00:01+01:00 emerg kernel[]: [42262.013544] Internal error: Oops: 805 [#1] SMP ARM
2016-11-22T03:00:01+01:00 info cron[13993]: (root) CMD ( /usr/bin/notifier)
2016-11-22T03:00:01+01:00 info cron[13994]: (root) CMD (/usr/bin/watchdog.sh)
2016-11-22T03:00:01+01:00 info cron[13997]: (root) CMD (/usr/bin/get-api-crl)
2016-11-22T03:00:01+01:00 info cron[13995]: (root) CMD (nethist_stats.lua)
2016-11-22T03:00:01+01:00 info cron[13996]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.018347] Modules linked in: qcserial option iptable_nat ath9k usb_wwan rndis_host qmi_wwan pppoe nf_nat_pptp nf_nat_ipv4 nf_nat_amanda nf_conntrack_pptp nf_conntrack_netlink nf_conntrack_ipv6 nf_conntrack_ipv4 nf_conntrack_amanda ipt_REJECT ipt_MASQUERADE ebtable_nat ebtable_filter ebtable_broute cdc_ether ath9k_common armada_thermal xt_time xt_tcpudp xt_state xt_nat xt_multiport xt_mark xt_mac xt_limit xt_id xt_conntrack xt_comment xt_TCPMSS xt_REDIRECT xt_LOG xt_CT usbserial usbnet ums_usbat ums_sddr55 ums_sddr09 ums_karma ums_jumpshot ums_isd200 ums_freecom ums_datafab ums_cypress ums_alauda ts_kmp ts_fsm ts_bm thermal_sys pppox ppp_mppe ppp_async nfnetlink nf_reject_ipv4 nf_nat_tftp nf_nat_snmp_basic nf_nat_sip nf_nat_redirect nf_nat_proto_gre nf_nat_masquerade_ipv4 nf_nat_irc nf_nat_h323 nf_nat_ftp nf_nat nf_log_ipv4 nf_defrag_ipv6 nf_defrag_ipv4 nf_conntrack_tftp nf_conntrack_snmp nf_conntrack_sip nf_conntrack_rtcache nf_conntrack_proto_gre nf_conntrack_irc nf_conntrack_h323 nf_conntrack_ftp nf_conntrack_broadcast nf_conntrack mvsdio iptable_raw iptable_mangle iptable_filter ip_tables hwmon ebtables ebt_vlan ebt_stp ebt_redirect ebt_pkttype ebt_mark_m ebt_mark ebt_limit ebt_among ebt_802_3 crc_ccitt cdc_wdm ath9k_hw fuse sch_teql sch_tbf sch_sfq sch_red sch_prio sch_pie sch_netem sch_htb sch_gred sch_fq sch_dsmark sch_codel em_text em_nbyte em_meta em_cmp cls_basic act_police act_ipt act_skbedit act_mirred em_u32 cls_u32 cls_tcindex cls_flow cls_route cls_fw sch_hfsc sch_ingress ath10k_pci ath10k_core ath mac80211 cfg80211 compat ledtrig_usbdev ledtrig_oneshot xt_LED ledtrig_morse ledtrig_heartbeat ledtrig_gpio ip6t_REJECT nf_reject_ipv6 nf_log_ipv6 nf_log_common ip6table_raw ip6table_mangle ip6table_filter ip6_tables x_tables pppoatm ppp_generic slhc nfsd nfsv3 nfs msdos ip_gre gre sit ip6_tunnel tunnel6 tunnel4 ip_tunnel veth tun snd_compress snd_pcm_oss snd_mixer_oss snd_pcm snd_timer snd_rawmidi snd_seq_device snd_hwdep snd input_core soundcore rxkad loop vfat fat udf crc_itu_t ntfs lockd sunrpc grace minix isofs hfsplus hfs cramfs configfs cifs autofs4 kafs af_rxrpc dns_resolver dm_crypt dm_mirror dm_region_hash dm_log dm_mod br2684 atm multipath fscache raid456 async_raid6_recov async_pq async_xor async_memcpy async_tx raid10 raid1 raid0 linear md_mod nls_utf8 nls_koi8_r nls_cp1255 nls_iso8859_6 nls_iso8859_2 nls_iso8859_15 nls_iso8859_13 nls_iso8859_1 nls_cp932 nls_cp866 nls_cp864 nls_cp862 nls_cp852 nls_cp850 nls_cp775 nls_cp437 nls_cp1251 nls_cp1250 jitterentropy_rng xts algif_skcipher algif_hash af_alg sha512_generic sha256_generic sha1_generic seqiv pcbc cryptosoft cryptodev ocf md5 md4 marvell_cesa hmac gf128mul fcrypt ecb des_generic ctr cmac ccm cbc authenc usb_storage xhci_plat_hcd xhci_pci xhci_hcd orion_wdt uhci_hcd ledtrig_transient ahci_mvebu ahci ehci_orion ehci_platform ehci_hcd sd_mod ahci_platform libahci_platform libahci libata scsi_mod xfs libcrc32c jfs f2fs usbcore nls_base usb_common mii aead crypto_null
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.282707] CPU: 0 PID: 13978 Comm: sshd Not tainted 4.4.13-05df79f63527051ea0071350f86faf76-9 #1
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.291599] Hardware name: Marvell Armada 380/385 (Device Tree)
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.297531] task: ef1f9300 ti: c2ff6000 task.ti: c2ff6000
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.302978] PC is at swcr_newsession+0x264/0x898 [cryptosoft]
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.308748] LR is at swcr_newsession+0x1e8/0x898 [cryptosoft]
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.314506] pc : [<bf249bac>] lr : [<bf249b30>] psr: 20000013
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.314506] sp : c2ff7d18 ip : 00000000 fp : c2ff7d54
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.326009] r10: 02000000 r9 : eda25200 r8 : bf24ae40
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.331244] r7 : bf24ab70 r6 : c2ff7e10 r5 : 00000001 r4 : eb201b84
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.337785] r3 : 00000000 r2 : 00000000 r1 : 0000000c r0 : 00000010
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.344328] Flags: nzCv IRQs on FIQs on Mode SVC_32 ISA ARM Segment none
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.351478] Control: 10c5387d Table: 1ec1804a DAC: 00000051
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.357236] Process sshd (pid: 13978, stack limit = 0xc2ff6210)
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.363167] Stack: (0xc2ff7d18 to 0xc2ff8000)
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.367533] 7d00: c000a5e0 00000000
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.375730] 7d20: bf24a340 c06baebc e7125918 eda25200 c2ff7e10 bf23d980 20000013 00000000
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.383928] 7d40: eda25200 02000000 c2ff7d94 c2ff7d58 bf2392d0 bf249954 e72c9740 c2ff7db8
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.392125] 7d60: 024000c0 00000001 c2ff7d94 bf244d40 c01c6365 beddc86c e72c9740 c2ff7dec
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.400322] 7d80: c2ff7e10 c2ff7ed8 c2ff7f04 c2ff7d98 bf242e4c bf2390b8 e70d2210 2e24359f
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.408520] 7da0: c2ff7dd4 efd7616c 00000051 00000000 c2ff7dd4 c2ff7dc4 c00a2a14 c005b510
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.416717] 7dc0: e664407c 00010001 00000100 00000000 c00a362c c00a29e8 00000000 00000000
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.424914] 7de0: 00000000 b6e10000 eee424c8 00000000 00000000 00000000 00000000 00000000
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.433111] 7e00: 00000000 00000000 00000000 00000000 0000000c 00000080 00000000 e72c9840
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.441307] 7e20: 00000000 00000000 00000000 00000000 00000000 c00a344c de9c05f8 000005f8
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.449505] 7e40: 00000040 0000017e efb46f00 fffffdfa efc5f190 e6644000 0000000c 00000000
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.457702] 7e60: 00000010 b6ebd568 00000000 00000000 00000000 00000000 00000000 00000000
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.465899] 7e80: 00000000 00000000 0000007a e6644040 00000000 c2ff7fb0 ef1f9300 ec9b3900
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.474096] 7ea0: 80000007 b6e16184 ec9b3938 e70d2210 c2ff7efc c2ff7ec0 c0020fa0 c005d154
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.482293] 7ec0: c2973015 c2ff7fb0 00000000 00000054 ee91caf8 00000007 c0020db4 ea484b80
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.490490] 7ee0: ea484b80 beddc86c c01c6365 ee91caf8 beddc86c 00000000 c2ff7f14 c2ff7f08
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.498688] 7f00: bf2443ac bf24231c c2ff7f7c c2ff7f18 c00f43dc bf2443a8 c2973000 00000003
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.506885] 7f20: c2ff7f4c c2ff7f30 c00ff5e8 c0012fc4 ea484b80 ea484b80 00000000 00000001
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.515082] 7f40: c2ff7f7c c2ff7f50 c00f2a18 c00ff5c4 c00e1c8c ea484b80 ea484b80 beddc86c
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.523279] 7f60: c01c6365 00000006 c2ff6000 00000000 c2ff7fa4 c2ff7f80 c00f445c c00f3d3c
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.531476] 7f80: beddc858 0000000c 00000005 b6ebd450 00000036 c0009ea4 00000000 c2ff7fa8
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.539673] 7fa0: c0009ce0 c00f442c 0000000c 00000005 00000006 c01c6365 beddc86c beddc858
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.547870] 7fc0: 0000000c 00000005 b6ebd450 00000036 00000000 00000006 beddc884 beddc86c
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.556067] 7fe0: b6ed2a84 beddc840 b6e2b17c b6f088c8 20000010 00000006 2fffd861 2fffdc61
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.564260] Backtrace:
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.566745] [<bf249948>] (swcr_newsession [cryptosoft]) from [<bf2392d0>] (crypto_newsession+0x224/0x2fc [ocf])
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.576855] r10:02000000 r9:eda25200 r8:00000000 r7:20000013 r6:bf23d980 r5:c2ff7e10
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.584754] r4:eda25200
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.587320] [<bf2390ac>] (crypto_newsession [ocf]) from [<bf242e4c>] (cryptodev_cb+0xbd0/0x2120 [cryptodev])
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.597169] r10:c2ff7ed8 r9:c2ff7e10 r8:c2ff7dec r7:e72c9740 r6:beddc86c r5:c01c6365
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.605067] r4:bf244d40
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.607632] [<bf242310>] (cryptodev_cb [cryptodev]) from [<bf2443ac>] (cryptodev_unlocked_ioctl+0x10/0x14 [cryptodev])
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.618351] r10:00000000 r9:beddc86c r8:ee91caf8 r7:c01c6365 r6:beddc86c r5:ea484b80
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.626249] r4:ea484b80
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.628811] [<bf24439c>] (cryptodev_unlocked_ioctl [cryptodev]) from [<c00f43dc>] (do_vfs_ioctl+0x6ac/0x6f0)
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.638663] [<c00f3d30>] (do_vfs_ioctl) from [<c00f445c>] (SyS_ioctl+0x3c/0x64)
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.645987] r10:00000000 r9:c2ff6000 r8:00000006 r7:c01c6365 r6:beddc86c r5:ea484b80
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.653884] r4:ea484b80
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.656434] [<c00f4420>] (SyS_ioctl) from [<c0009ce0>] (ret_fast_syscall+0x0/0x3c)
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.664018] r8:c0009ea4 r7:00000036 r6:b6ebd450 r5:00000005 r4:0000000c r3:beddc858
2016-11-22T04:00:02+01:00 emerg kernel[]: [42262.671830] Code: e5810010 e50b3038 e5961000 e5943000 (e5831014)
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.678057] ath10k_pci 0000:02:00.0: SWBA overrun on vdev 0, skipped old beacon
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.685448] ath10k_pci 0000:02:00.0: SWBA overrun on vdev 0, skipped old beacon
2016-11-22T04:00:02+01:00 warning kernel[]: [42262.692848] ath10k_pci 0000:02:00.0: SWBA overrun on vdev 0, skipped old beacondo you all have crytodev enabled? also OCF module installed?
LE - fyi - 2 reboots today
another reboot today at 11am, after 3 days running without problem
actually I installed kmod-crypto-ocf before the strange restarts, could it be related? But I just measured the openssl speed, that’s it.
According to the log above it seems related.
I uninstalled mine and rebooted.
When you log into luci and go to Services > Watchcat, by default it is set to reboot on connection loss. Try deleting that rule and see if that helps.
I don’t have any rule in watchcat
I found some rules in watchcat. I deleted it and router rebooted few minutes after --> no change.
@maurer : please, where you see relation between crypto* and ocf in log?
@maurer: Edit: OK, I am almost blind, I see it now… BUT! This bug did not caused restart. Router restarted few hours before and few hours after.
Are the logs saved in a specific file?
The Kernel logs reset at every reboot, how can I save them in a different location?
My kernel log is full of these entries:
[ 110.803204] turris-00000000: IN=eth1 OUT=br-lan MAC=d8:58:d7:00:5a:fa:70:ca:9b:a7:9a:d9:86:dd SRC=2607:f8b0:4007:080c:0000:0000:0000:200e DST=2600:8801:d800:02b3:6d9c:0d87:554d:2bec LEN=135 TC=0 HOPLIMIT=58 FLOWLBL=0 PROTO=TCP SPT=443 DPT=38530 WINDOW=236 RES=0x00 ACK PSH URGP=0
[ 110.803279] turris-00000000: IN=eth1 OUT=br-lan MAC=d8:58:d7:00:5a:fa:70:ca:9b:a7:9a:d9:86:dd SRC=2607:f8b0:4007:080c:0000:0000:0000:200e DST=2600:8801:d800:02b3:6d9c:0d87:554d:2bec LEN=72 TC=0 HOPLIMIT=58 FLOWLBL=0 PROTO=TCP SPT=443 DPT=38530 WINDOW=236 RES=0x00 ACK FIN URGP=0
[ 117.358432] turris-00000000: IN=eth1 OUT=br-lan MAC=d8:58:d7:00:5a:fa:70:ca:9b:a7:9a:d9:86:dd SRC=2607:f8b0:4007:080c:0000:0000:0000:2003 DST=2600:8801:d800:02b3:e457:6d8b:94ca:1593 LEN=115 TC=0 HOPLIMIT=58 FLOWLBL=0 PROTO=TCP SPT=443 DPT=63986 WINDOW=238 RES=0x00 ACK PSH URGP=0
[ 117.358506] turris-00000000: IN=eth1 OUT=br-lan MAC=d8:58:d7:00:5a:fa:70:ca:9b:a7:9a:d9:86:dd SRC=2607:f8b0:4007:080c:0000:0000:0000:2003 DST=2600:8801:d800:02b3:e457:6d8b:94ca:1593 LEN=60 TC=0 HOPLIMIT=58 FLOWLBL=0 PROTO=TCP SPT=443 DPT=63986 WINDOW=238 RES=0x00 ACK FIN URGP=0
[ 118.347435] turris-00000000: IN=eth1 OUT=br-lan MAC=d8:58:d7:00:5a:fa:70:ca:9b:a7:9a:d9:86:dd SRC=2607:f8b0:4007:0806:0000:0000:0000:2004 DST=2600:8801:d800:02b3:e457:6d8b:94ca:1593 LEN=115 TC=0 HOPLIMIT=58 FLOWLBL=0 PROTO=TCP SPT=443 DPT=63992 WINDOW=238 RES=0x00 ACK PSH URGP=0
[ 118.347517] turris-00000000: IN=eth1 OUT=br-lan MAC=d8:58:d7:00:5a:fa:70:ca:9b:a7:9a:d9:86:dd SRC=2607:f8b0:4007:0806:0000:0000:0000:2004 DST=2600:8801:d800:02b3:e457:6d8b:94ca:1593 LEN=60 TC=0 HOPLIMIT=58 FLOWLBL=0 PROTO=TCP SPT=443 DPT=63992 WINDOW=238 RES=0x00 ACK FIN URGP=0
[ 121.237984] turris-00000000: IN=eth1 OUT=br-lan MAC=d8:58:d7:00:5a:fa:70:ca:9b:a7:9a:d9:86:dd SRC=2607:f8b0:4007:0806:0000:0000:0000:200a DST=2600:8801:d800:02b3:e457:6d8b:94ca:1593 LEN=60 TC=0 HOPLIMIT=58 FLOWLBL=0 PROTO=TCP SPT=80 DPT=64016 WINDOW=230 RES=0x00 ACK FIN URGP=0
fyi
root@turris:~# uptime
12:04:50 up 1 day, 19:09, load average: 0.00, 0.00, 0.00
…since removed ocf kmod…
Me too:
# uptime
00:24:43 up 2 days, 5:29, load average: 0.02, 0.12, 0.49
…since removed ocf.