Cron jobs disappear - Merlin 386.1b5 AX86U

[dev_null]

This was posted in the Merlin Beta thread, but because there is so much action, it may have been missed. Posting it separately in the event it's not a beta-version issue.

I have an interesting issue. My AX86U running 386.1b5 is erasing cron entries without any other symptoms. I only noticed this because I didn't get an expected overnight report. This happened once on b4 but the device was brand new to me and I assumed I mis-configured something.

These cron jobs are added through services-start. The device is an AX86U running 386.1b5 with about 35 devices connected. Temps are in the 50-60C range. The device uptime does not change - so it's not the device rebooting. There is nothing I can see in the logs. This has not happened on my other devices even on the 386.1 beta software (AC66U_B1/AC68U).

Anyone else notice disappearing cron jobs? Any potential solutions?

EDIT: narrowed the time down based on when NTPmerlin stopped recording. The logs around that time are below. In this example the cron jobs stopped executing after 21:15 and before 21:30 (the ChkWAN executed at 21:15 but not at 21:30; same for NTPmerlin); previously they disappeared about 2 hours later.

Spoiler

Jan 27 21:15:01 RT-AX86U-C328 (ChkWAN.sh): 10915 v1.11 Monitoring WAN connection using 2 target PING hosts (72.251.239.206 162.249.171.198) (Tries=3)
Jan 27 21:15:01 RT-AX86U-C328 (ChkWAN.sh): 10915 Monitoring pass 1 out of 3
Jan 27 21:15:01 RT-AX86U-C328 (ChkWAN.sh): 10915 Monitoring WAN connection OK.....(Successful ping to '72.251.239.206'); Terminating due to ACTIVE cron schedule
Jan 27 21:21:28 RT-AX86U-C328 kernel: Init chrdev /dev/idp with major 190
Jan 27 21:21:28 RT-AX86U-C328 kernel: tdts: tcp_conn_max = 8000
Jan 27 21:21:28 RT-AX86U-C328 kernel: tdts: tcp_conn_timeout = 300 sec
Jan 27 21:21:30 RT-AX86U-C328 kernel: SHN Release Version: 2.0.1 4b635f32
Jan 27 21:21:30 RT-AX86U-C328 kernel: UDB Core Version: 0.2.18
Jan 27 21:21:30 RT-AX86U-C328 kernel: Init chrdev /dev/idpfw with major 191
Jan 27 21:21:30 RT-AX86U-C328 kernel: IDPfw: flush fc
Jan 27 21:21:30 RT-AX86U-C328 kernel: IDPfw: IDPfw is ready
Jan 27 21:21:30 RT-AX86U-C328 kernel: sizeof forward pkt param = 280
Jan 27 21:21:30 RT-AX86U-C328 BWDPI: fun bitmap = 3
Jan 27 21:21:30 RT-AX86U-C328 kernel: CONSOLE: 034460.978 wl1.0: wlc_send_bar: for b0:ca:68:15:b2:5a seq 0x12 tid 1
Jan 27 21:21:41 RT-AX86U-C328 BWDPI: force to flush flowcache entries
Jan 27 21:21:41 RT-AX86U-C328 kernel: IDPfw: Exit IDPfw
Jan 27 21:21:41 RT-AX86U-C328 kernel: mod epilog takes 0 jiffies
Jan 27 21:21:41 RT-AX86U-C328 kernel: IDPfw: Exit IDPfw
Jan 27 21:21:41 RT-AX86U-C328 kernel: Exit chrdev /dev/idpfw with major 191
Jan 27 21:21:41 RT-AX86U-C328 kernel: Exit chrdev /dev/idp with major 190
Jan 27 21:21:41 RT-AX86U-C328 custom_script: Running /jffs/scripts/nat-start
Jan 27 21:21:41 RT-AX86U-C328 custom_script: Running /jffs/scripts/firewall-start (args: eth0)
Jan 27 21:21:41 RT-AX86U-C328 CakeQOS-Merlin: Starting - ( 105Mbit | 5Mbit | diffserv4 | docsis wash | docsis ack-filter )
Jan 27 21:21:41 RT-AX86U-C328 kernel: Cpuidle Host Clock divider is disabled
Jan 27 21:21:42 RT-AX86U-C328 kernel: CONSOLE: 034472.863 wl1.0: wlc_send_bar: for 7c:a1:ae:c4:42:b5 seq 0x17 tid 1
Jan 27 21:22:33 RT-AX86U-C328 kernel: Init chrdev /dev/idp with major 190
Jan 27 21:22:33 RT-AX86U-C328 kernel: tdts: tcp_conn_max = 8000
Jan 27 21:22:33 RT-AX86U-C328 kernel: tdts: tcp_conn_timeout = 300 sec
Jan 27 21:22:35 RT-AX86U-C328 kernel: SHN Release Version: 2.0.1 4b635f32
Jan 27 21:22:35 RT-AX86U-C328 kernel: UDB Core Version: 0.2.18
Jan 27 21:22:35 RT-AX86U-C328 kernel: Init chrdev /dev/idpfw with major 191
Jan 27 21:22:35 RT-AX86U-C328 kernel: IDPfw: flush fc
Jan 27 21:22:35 RT-AX86U-C328 kernel: IDPfw: IDPfw is ready
Jan 27 21:22:35 RT-AX86U-C328 kernel: sizeof forward pkt param = 280
Jan 27 21:22:35 RT-AX86U-C328 BWDPI: fun bitmap = 3
Jan 27 21:22:49 RT-AX86U-C328 BWDPI: force to flush flowcache entries
Jan 27 21:22:49 RT-AX86U-C328 kernel: IDPfw: Exit IDPfw
Jan 27 21:22:49 RT-AX86U-C328 kernel: mod epilog takes 0 jiffies
Jan 27 21:22:49 RT-AX86U-C328 kernel: IDPfw: Exit IDPfw
Jan 27 21:22:50 RT-AX86U-C328 kernel: Exit chrdev /dev/idpfw with major 191
Jan 27 21:22:50 RT-AX86U-C328 kernel: Exit chrdev /dev/idp with major 190
Jan 27 21:22:50 RT-AX86U-C328 custom_script: Running /jffs/scripts/nat-start
Jan 27 21:22:50 RT-AX86U-C328 custom_script: Running /jffs/scripts/firewall-start (args: eth0)
Jan 27 21:22:50 RT-AX86U-C328 CakeQOS-Merlin: Starting - ( 105Mbit | 5Mbit | diffserv4 | docsis wash | docsis ack-filter )
Jan 27 21:22:50 RT-AX86U-C328 kernel: Cpuidle Host Clock divider is disabled
Jan 27 21:23:15 RT-AX86U-C328 kernel: Init chrdev /dev/idp with major 190
Jan 27 21:23:15 RT-AX86U-C328 kernel: tdts: tcp_conn_max = 8000
Jan 27 21:23:15 RT-AX86U-C328 kernel: tdts: tcp_conn_timeout = 300 sec
Jan 27 21:23:17 RT-AX86U-C328 kernel: SHN Release Version: 2.0.1 4b635f32
Jan 27 21:23:17 RT-AX86U-C328 kernel: UDB Core Version: 0.2.18
Jan 27 21:23:17 RT-AX86U-C328 kernel: Init chrdev /dev/idpfw with major 191
Jan 27 21:23:17 RT-AX86U-C328 kernel: IDPfw: flush fc
Jan 27 21:23:17 RT-AX86U-C328 kernel: IDPfw: IDPfw is ready
Jan 27 21:23:17 RT-AX86U-C328 kernel: sizeof forward pkt param = 280
Jan 27 21:23:17 RT-AX86U-C328 BWDPI: fun bitmap = 3
Jan 27 21:25:38 RT-AX86U-C328 BWDPI: force to flush flowcache entries
Jan 27 21:25:38 RT-AX86U-C328 kernel: IDPfw: Exit IDPfw
Jan 27 21:25:38 RT-AX86U-C328 kernel: mod epilog takes 0 jiffies
Jan 27 21:25:38 RT-AX86U-C328 kernel: IDPfw: Exit IDPfw
Jan 27 21:25:38 RT-AX86U-C328 kernel: Exit chrdev /dev/idpfw with major 191
Jan 27 21:25:38 RT-AX86U-C328 kernel: Exit chrdev /dev/idp with major 190
Jan 27 21:25:38 RT-AX86U-C328 custom_script: Running /jffs/scripts/nat-start
Jan 27 21:25:38 RT-AX86U-C328 custom_script: Running /jffs/scripts/firewall-start (args: eth0)
Jan 27 21:25:38 RT-AX86U-C328 CakeQOS-Merlin: Starting - ( 105Mbit | 5Mbit | diffserv4 | docsis wash | docsis ack-filter )
Jan 27 21:25:38 RT-AX86U-C328 kernel: Cpuidle Host Clock divider is disabled
Jan 27 21:27:19 RT-AX86U-C328 hostapd: eth6: STA fc:f8:ae:be:7a:c1 IEEE 802.11: associated
Jan 27 21:27:19 RT-AX86U-C328 kernel: br0: received packet on eth6.0 with own address as source address
Jan 27 21:27:19 RT-AX86U-C328 kernel: CFG80211-ERROR) wl_cfg80211_change_station : WLC_SCB_AUTHORIZE sta_flags_mask not set
Jan 27 21:27:19 RT-AX86U-C328 hostapd: eth6: STA fc:f8:ae:be:7a:c1 RADIUS: starting accounting session BCFC4DA2ED18043A
Jan 27 21:27:19 RT-AX86U-C328 hostapd: eth6: STA fc:f8:ae:be:7a:c1 WPA: pairwise key handshake completed (RSN)
Jan 27 21:27:52 RT-AX86U-C328 kernel: Init chrdev /dev/idp with major 190
Jan 27 21:27:52 RT-AX86U-C328 kernel: tdts: tcp_conn_max = 8000
Jan 27 21:27:52 RT-AX86U-C328 kernel: tdts: tcp_conn_timeout = 300 sec
Jan 27 21:27:53 RT-AX86U-C328 kernel: SHN Release Version: 2.0.1 4b635f32
Jan 27 21:27:53 RT-AX86U-C328 kernel: UDB Core Version: 0.2.18
Jan 27 21:27:53 RT-AX86U-C328 kernel: Init chrdev /dev/idpfw with major 191
Jan 27 21:27:53 RT-AX86U-C328 kernel: IDPfw: flush fc
Jan 27 21:27:53 RT-AX86U-C328 kernel: IDPfw: IDPfw is ready
Jan 27 21:27:53 RT-AX86U-C328 kernel: sizeof forward pkt param = 280

 

[Matthew Patrick]

I used to get this too when I had too many cron jobs (more than 20) from the addons I had. Then I tried disabling each and nothing seems to be working. Until I removed a lot of em so I just now use some addons with about 10 cron jobs and it works just fine. Idk if it's the same but how many cron jobs did you have before its gone? (AC86U) . Don't know if it's still happening now tho

 

[dave14305]

What is going on at 21:21? Are you viewing the Adaptive QoS Bandwidth Monitoring page at these times when these kernel messages show up? This seems to be restarting the firewall which stops and starts CAKE. CAKE will delete and re-add its cron job during this process. It shouldn't necessarily be at fault based on the straightforward command used in the script, but it would have been the last thing to mess with cron.

Are there by chance any extra files in the crontabs directory?

ls -la /var/spool/cron/crontabs

 

[dev_null]

What is going on at 21:21? Are you viewing the Adaptive QoS Bandwidth Monitoring page at these times when these kernel messages show up? This seems to be restarting the firewall which stops and starts CAKE. CAKE will delete and re-add its cron job during this process. It shouldn't necessarily be at fault based on the straightforward command used in the script, but it would have been the last thing to mess with cron.

Are there by chance any extra files in the crontabs directory?

ls -la /var/spool/cron/crontabs

To my recollection, I was in bed at 21:21 (and I was certainly asleep at 23:00 when it happened previously), so I don't think I was doing anything to trigger this.

I don't think I had cake-qos installed when it happened previously, but my recall could be faulty. Cake was one of the add-ons that I specifically purchased the AX86U to support, but early on I was ensuring my previous setup was stable.

Here's the output from the command above:

username@RT-AX86U-C328:/tmp/home/root# ls -la /var/spool/cron/crontabs
drwxr-xr-x    2 username root            60 Jan 28 18:45 .
drwxr-xr-x    3 username root            60 Dec 31  1969 ..
-rw-rw-rw-    1 username root          1445 Jan 28 18:45 username

@dave14305 : if I load the cron jobs via the firewall-start script, I suppose that would reload them, and there'd be no downside, save perhaps immediately re-running the job when it was reloaded instead of waiting for the scheduled cycle. I would of course also need to ensure that the entries are the same as in the services-start script, which triggers them to begin with...

@Matthew Patrick : interesting observation. I have 19 cronjobs when I run cru l. Some are from scripts, some are mine. I can try to consolidate jobs into scripts to see if I can lower that number, just in case.

 

[Matthew Patrick]

To my recollection, I was in bed at 21:21 (and I was certainly asleep at 23:00 when it happened previously), so I don't think I was doing anything to trigger this.

I don't think I had cake-qos installed when it happened previously, but my recall could be faulty. Cake was one of the add-ons that I specifically purchased the AX86U to support, but early on I was ensuring my previous setup was stable.

Here's the output from the command above:

username@RT-AX86U-C328:/tmp/home/root# ls -la /var/spool/cron/crontabs
drwxr-xr-x    2 username root            60 Jan 28 18:45 .
drwxr-xr-x    3 username root            60 Dec 31  1969 ..
-rw-rw-rw-    1 username root          1445 Jan 28 18:45 username

@dave14305 : if I load the cron jobs via the firewall-start script, I suppose that would reload them, and there'd be no downside, save perhaps immediately re-running the job when it was reloaded instead of waiting for the scheduled cycle. I would of course also need to ensure that the entries are the same as in the services-start script, which triggers them to begin with...

@Matthew Patrick : interesting observation. I have 19 cronjobs when I run cru l. Some are from scripts, some are mine. I can try to consolidate jobs into scripts to see if I can lower that number, just in case.

Yeah i don't have a proper evidence that it's the amount. But I remember it happening on mine too when it was 19 . Usually 20 . If you can. Try removing some of your own cron jobs and see if it still happens... I didn't find a workaround back then. I just know that it does happen when I have 19 20 21 etc cron jobs. But 13 cron jobs is safe (i know because mine was about 13 and it's fine.) As far as I can remember. Now I'm running with just 10 cron jobs tho

 

[dave14305]

I suggest reviewing the jobs to ensure none of them are long-running when another script (e.g. like CAKE) tries to update crontabs. It might also help to increase cron's logging level when you really want to watch the behavior.

service stop_crond
nohup crond -l 7 >/dev/null 2>&1 &

The logging level goes up and the number goes down (8 is least verbose, 0 is most verbose).

To reset to normal just run service restart_crond

 

[dev_null]

I suggest reviewing the jobs to ensure none of them are long-running when another script (e.g. like CAKE) tries to update crontabs. It might also help to increase cron's logging level when you really want to watch the behavior.

service stop_crond
nohup crond -l 7 >/dev/null 2>&1 &

The logging level goes up and the number goes down (8 is least verbose, 0 is most verbose).

To reset to normal just run service restart_crond

Thanks, I'll give it a go.

Your statement made me realize that I may have left diversion > follow log running when I left. Otherwise none of the others should have been long-running unless it hung for some reason. Will watch with a more verbose log level and see what's up.

 

[dev_null]

service stop_crond
nohup crond -l 7 >/dev/null 2>&1 &

The logging level goes up and the number goes down (8 is least verbose, 0 is most verbose).

Just a follow-up: I had to do a killall crond to get the existing cron (log level 9) to die and for log level 2 to kick in. Not sure why, but log level 9 just persisted even with service stop.

 

[elorimer]

@Matthew Patrick : interesting observation. I have 19 cronjobs when I run cru l. Some are from scripts, some are mine. I can try to consolidate jobs into scripts to see if I can lower that number, just in case.

I have 26 with my 86U, one of which is mine, an overnight pixelserv report. From time to time mine gets deleted, something I associate with updating the addons rather than the way they work.. I could be wrong about that.

 

[dev_null]

It happened again last night, about an hour after I ran the update to 386.1 (final). It *does* seem to be related to nat-start execution, but it seems odd that nat-start would kill existing cron entries. I've posted the relevant section of the log in the next message.

More specifically it's this sequence of logged events:

Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: wakeup dt=10
Jan 30 22:15:22 RT-AX86U-C328 BWDPI: force to flush flowcache entries
Jan 30 22:15:22 RT-AX86U-C328 kernel: IDPfw: Exit IDPfw
Jan 30 22:15:22 RT-AX86U-C328 kernel: mod epilog takes 0 jiffies
Jan 30 22:15:22 RT-AX86U-C328 kernel: IDPfw: Exit IDPfw
Jan 30 22:15:22 RT-AX86U-C328 kernel: Exit chrdev /dev/idpfw with major 191
Jan 30 22:15:22 RT-AX86U-C328 kernel: Exit chrdev /dev/idp with major 190
Jan 30 22:15:22 RT-AX86U-C328 custom_script: Running /jffs/scripts/nat-start
Jan 30 22:15:22 RT-AX86U-C328 custom_script: Running /jffs/scripts/firewall-start (args: eth0)
Jan 30 22:15:23 RT-AX86U-C328 CakeQOS-Merlin: Starting - ( 105Mbit | 5Mbit | diffserv4 | docsis wash | docsis ack-filter )
Jan 30 22:15:23 RT-AX86U-C328 kernel: Cpuidle Host Clock divider is disabled

Is this a bug or by design? Note: I had virtually the same set-up with my AC66UB1 and never lost cron entries, but I know that was a different platform.

As a workaround, I've copied those cron entries that get removed into nat-start so they are re-called if/when nat-start is invoked. Since they are exact duplicates of start-services, even if they don't get purged, there should be no duplication.

I'll let this run for a week or so before I begin cron job consolidation (to see if the number of cron jobs is the issue).

 

[dev_null]

Cont'd from above...

Key: at 22:15 you can see the system read and load all my cron jobs. At 22:15:22 you can see an event that causes a nat-start (re-start) that erases all the cron entries.

Spoiler

Jan 30 22:15:00 RT-AX86U-C328 crond[10955]: wakeup dt=60
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]: file username:
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line sh /opt/share/diversion/file/stats.div
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line /opt/sbin/logrotate /opt/etc/logrotate.conf >> /opt/tmp/logrotate.daily 2>&1
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line /jffs/scripts/ChkWAN.sh ping=72.251.239.206,162.249.171.198 nowait wan
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  job: 0 /jffs/scripts/ChkWAN.sh ping=72.251.239.206,162.249.171.198 nowait wan
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line sh /opt/share/diversion/file/update-bl.div reset
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line sh /opt/share/diversion/file/update-bl.div
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line sh /opt/share/diversion/file/rotate-logs.div
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line diversion count_ads count
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line sh /opt/share/diversion/file/stats.div
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line /jffs/scripts/ntpmerlin generate
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line sh /jffs/scripts/skynet-stats && sh /jffs/scripts/send-skynet
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line sh /jffs/scripts/skynet-stats && sh /jffs/scripts/send-skynet
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line /jffs/scripts/uiDivStats generate
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line /jffs/scripts/connmon generate
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  job: 0 /jffs/scripts/connmon generate
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line sh /jffs/scripts/vnstat-stats && sh /jffs/scripts/send-vnstat
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line /jffs/scripts/vnstat-ww.sh
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line /jffs/scripts/wicens.sh cron
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line sh /jffs/scripts/firewall banmalware
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line sh /jffs/scripts/firewall update check
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line sh /jffs/scripts/firewall save
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]:  line /jffs/addons/cake-qos/cake-qos checkrun
Jan 30 22:15:00 RT-AX86U-C328 crond[31180]: child running /bin/sh
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]: USER username pid 31180 cmd /jffs/scripts/ChkWAN.sh ping=72.251.239.206,162.249.171.198 nowait wan
Jan 30 22:15:00 RT-AX86U-C328 crond[31182]: child running /bin/sh
Jan 30 22:15:00 RT-AX86U-C328 crond[10955]: USER username pid 31182 cmd /jffs/scripts/connmon generate
Jan 30 22:15:00 RT-AX86U-C328 (ChkWAN.sh): 31181 v1.11 Monitoring WAN connection using 2 target PING hosts (72.251.239.206 162.249.171.198) (Tries=3)
Jan 30 22:15:00 RT-AX86U-C328 (ChkWAN.sh): 31181 Monitoring pass 1 out of 3
Jan 30 22:15:00 RT-AX86U-C328 (ChkWAN.sh): 31181 Monitoring WAN connection OK.....(Successful ping to '72.251.239.206'); Terminating due to ACTIVE cron schedule
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:10 RT-AX86U-C328 crond[10955]: wakeup dt=10
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:20 RT-AX86U-C328 crond[10955]: wakeup dt=10
Jan 30 22:15:22 RT-AX86U-C328 BWDPI: force to flush flowcache entries
Jan 30 22:15:22 RT-AX86U-C328 kernel: IDPfw: Exit IDPfw
Jan 30 22:15:22 RT-AX86U-C328 kernel: mod epilog takes 0 jiffies
Jan 30 22:15:22 RT-AX86U-C328 kernel: IDPfw: Exit IDPfw
Jan 30 22:15:22 RT-AX86U-C328 kernel: Exit chrdev /dev/idpfw with major 191
Jan 30 22:15:22 RT-AX86U-C328 kernel: Exit chrdev /dev/idp with major 190
Jan 30 22:15:22 RT-AX86U-C328 custom_script: Running /jffs/scripts/nat-start
Jan 30 22:15:22 RT-AX86U-C328 custom_script: Running /jffs/scripts/firewall-start (args: eth0)
Jan 30 22:15:23 RT-AX86U-C328 CakeQOS-Merlin: Starting - ( 105Mbit | 5Mbit | diffserv4 | docsis wash | docsis ack-filter )
Jan 30 22:15:23 RT-AX86U-C328 kernel: Cpuidle Host Clock divider is disabled
Jan 30 22:15:30 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:30 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:30 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:30 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:30 RT-AX86U-C328 crond[10955]: wakeup dt=10
Jan 30 22:15:40 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:40 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:40 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:40 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:15:40 RT-AX86U-C328 crond[10955]: wakeup dt=10
Jan 30 22:16:00 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:16:00 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:16:00 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:16:00 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:16:00 RT-AX86U-C328 crond[10955]: user:username entry:(null)
Jan 30 22:16:00 RT-AX86U-C328 crond[10955]: wakeup dt=20
Jan 30 22:16:00 RT-AX86U-C328 crond[10955]: file username:
Jan 30 22:16:00 RT-AX86U-C328 crond[10955]:  line /jffs/addons/cake-qos/cake-qos checkrun
Jan 30 22:16:00 RT-AX86U-C328 crond[10955]:  line sh /jffs/scripts/firewall banmalware
Jan 30 22:16:00 RT-AX86U-C328 crond[10955]:  line sh /jffs/scripts/firewall update check
Jan 30 22:16:00 RT-AX86U-C328 crond[10955]:  line sh /jffs/scripts/firewall save
Jan 30 22:16:00 RT-AX86U-C328 crond[10955]:  line sh /jffs/scripts/firewall debug genstats

 

[dev_null]

I have had 3-4 more instances of nat-start but adding the cron jobs to the nat-start script seems to have fixed the issue.

The question remains, however, is this a bug (erasure of cron jobs at nat-restart) and what is the relationship to these other proximal log entries?

Jan 31 19:15:03 RT-AX86U-C328 BWDPI: force to flush flowcache entries
Jan 31 19:15:03 RT-AX86U-C328 kernel: IDPfw: Exit IDPfw
Jan 31 19:15:03 RT-AX86U-C328 kernel: mod epilog takes 0 jiffies
Jan 31 19:15:03 RT-AX86U-C328 kernel: IDPfw: Exit IDPfw
Jan 31 19:15:03 RT-AX86U-C328 kernel: Exit chrdev /dev/idpfw with major 191
Jan 31 19:15:03 RT-AX86U-C328 kernel: Exit chrdev /dev/idp with major 190
Jan 31 19:15:03 RT-AX86U-C328 custom_script: Running /jffs/scripts/nat-start
Jan 31 19:15:03 RT-AX86U-C328 custom_script: Running /jffs/scripts/firewall-start (args: eth0)

 

[Matthew Patrick]

@dev_null so, is it still happening? Even on 386.2 Beta?

 

[dev_null]

@dev_null so, is it still happening? Even on 386.2 Beta?

Not recently. I think it was *which script* the reload happens from: services-start runs infrequently, and nat-start seems to be the most frequent.

But there are debates in other threads about the appropriate place to trigger/retrigger activities - post-mount, etc. - for Vnstat on Merlin the re-triggers are in a couple of places (courtesy of @Jack Yaz and his amazing support), and for other cronjobs I manually add, I've mirrored those.

 

[Matthew Patrick]

Not recently. I think it was *which script* the reload happens from: services-start runs infrequently, and nat-start seems to be the most frequent.

But there are debates in other threads about the appropriate place to trigger/retrigger activities - post-mount, etc. - for Vnstat on Merlin the re-triggers are in a couple of places (courtesy of @Jack Yaz and his amazing support), and for other cronjobs I manually add, I've mirrored those.

I see. Okay then thanks for the info!!