Ripshod
Very Senior Member
Strange.My cru script only added logging. It would not fix anything itself.
Strange.My cru script only added logging. It would not fix anything itself.
When you say the jobs "clashed", what did you mean with that? Did they both try to get concurrently loaded, and hung? Is this where a change in the lock mechanism would help?Strange.
Yes, both try loading at the same time. I presume cron can't multi-task. Moving the two cronjobs apart by a couple of minutes definitely seems to have stabilised things. I can't believe I only noticed this a week ago, after posting my list ages agoWhen you say the jobs "clashed", what did you mean with that? Did they both try to get concurrently loaded, and hung? Is this where a change in the lock mechanism would help?
Yes, both try loading at the same time. I presume cron can't multi-task. Moving the two cronjobs apart by a couple of minutes definitely seems to have stabilised things. I can't believe I only noticed this a week ago, after posting my list ages ago
Yes, both try loading at the same time. I presume cron can't multi-task. Moving the two cronjobs apart by a couple of minutes definitely seems to have stabilised things. I can't believe I only noticed this a week ago, after posting my list ages ago
crond
doesn't multitask anything, it's scheduler. Every minute it sequentially reads the crontab and then spawns separate processes for any jobs that need to run. Multiple processes running at the same time is a normal function of Linux. If there are problems with two jobs running at the same time then that's an issue with the jobs themselves (or something it calls, e.g. nvram
).cru
is not the same as crond
. I seem to recall that one of the problems appeared to be multiple copies of cru
running at roughly the same time, hence the speculation there's a locking problem with cru
.Feb 16 14:00:22 ripshod cru[5782]: Action: a, JobID: Diversion_RotateLogs, Args: a Diversion_RotateLogs 20 5 * * * /bin/sh /opt/share/diversion/file/rotate-logs.div
Feb 16 14:00:22 ripshod cru[5782]: Calling PID: 5258, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Feb 16 14:00:22 ripshod cru[5782]: Jobs before: 7, after: 8
Feb 16 14:00:22 ripshod cru[5810]: Action: a, JobID: Diversion_CountAds, Args: a Diversion_CountAds 20 17 * * * diversion count_ads count
Feb 16 14:00:22 ripshod cru[5810]: Calling PID: 5258, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Feb 16 14:00:22 ripshod cru[5810]: Jobs before: 8, after: 9
Feb 16 14:00:22 ripshod cru[5839]: Action: a, JobID: Diversion_WeeklyStats, Args: a Diversion_WeeklyStats 30 1 * * Sat /bin/sh /opt/share/diversion/file/stats.div
Feb 16 14:00:22 ripshod cru[5839]: Calling PID: 5258, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Feb 16 14:00:22 ripshod cru[5839]: Jobs before: 9, after: 10
Feb 16 14:00:22 ripshod Diversion: restarted Dnsmasq to apply settings
Feb 16 14:00:22 ripshod stubby[5902]: Read config from file /etc/stubby/stubby.yml
Feb 16 14:00:22 ripshod stubby[5902]: Stubby version: Stubby 0.4.2
Feb 16 14:00:22 ripshod uiDivStats: dnsmasq has restarted, restarting taildns
25 18 * * * sh /jffs/scripts/firewall banmalware #Skynet_banmalware#
20 1 * * Mon sh /jffs/scripts/firewall update #Skynet_autoupdate#
0 * * * * sh /jffs/scripts/firewall save #Skynet_save#
34 */12 * * * sh /jffs/scripts/firewall debug genstats #Skynet_genstats#
55 */12 * * * sh /jffs/scripts/firewall debug genstats #Skynet_genstats#
This has been a real tough one to pin down... I wish someone would find a smoking gun...I started this thread when I saw ALL my cronjobs disappear.
I've been watching them, but it hasn't happened since.
I haven't installed the logging script to watch the "cru" command, but a few people have done and there hasn't been any feedback from them yet so I assume it hasn't been seen by them either.
Can you post exactly what that entry looked like?My entry that got deleted was the last line in the list.
I was looking through the skynet code, and I'm just not seeing how your entry would get deleted. The beginning number changes because it randomly picks a number between 28 and 58 (or so). But why it decided to delete that entry and add a new one is mysterious to me.Does the above point to Skynet as the culprit, or is this just coincidence?
As far as I can see that specific crontab entry is updated every time the webUI page is installed. Which is every time Skynet is started or restarted.I was looking through the skynet code, and I'm just not seeing how your entry would get deleted. The beginning number changes because it randomly picks a number between 28 and 58 (or so). But why it decided to delete that entry and add a new one is mysterious to me.
Yes:Can you post exactly what that entry looked like?
1-59/10 * * * * /jffs/scripts/op-cron hc-nextdns #hcNextDNS#
Or so I thought. It happened again last night. Right now I only have 4 outof 19 crons still running. And I caught it in the logs.I've had four cron jobs miss since I started tracing. None of them were deliberately removed by "cru d", and 3 of them were skynet.
Since I moved the BACKUPMON job a few minutes later I've had no issues. There's nothing written down as I found my answer.
Mar 6 21:37:56 ripshod cru[11191]: Action: d, JobID: Diversion_LocalBackup, Args: d Diversion_LocalBackup
Mar 6 21:37:56 ripshod cru[11191]: Calling PID: 11144, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:37:56 ripshod cru[11191]: Jobs before: 19, after: 19
Mar 6 21:37:56 ripshod cru[11265]: Action: a, JobID: Diversion_UpdateBL, Args: a Diversion_UpdateBL 00 2 * * Sat /bin/sh /opt/share/diversion/file/update-bl.div reset
Mar 6 21:37:56 ripshod cru[11265]: Calling PID: 11144, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:37:56 ripshod cru[11265]: Jobs before: 19, after: 19
Mar 6 21:37:56 ripshod cru[11349]: Action: d, JobID: Diversion_UpdateBLTwo, Args: d Diversion_UpdateBLTwo
Mar 6 21:37:56 ripshod cru[11349]: Calling PID: 11144, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:37:56 ripshod cru[11349]: Jobs before: 19, after: 19
Mar 6 21:37:56 ripshod cru[11420]: Action: a, JobID: Diversion_RotateLogs, Args: a Diversion_RotateLogs 20 5 * * * /bin/sh /opt/share/diversion/file/rotate-logs.div
Mar 6 21:37:56 ripshod cru[11420]: Calling PID: 11144, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:37:56 ripshod cru[11420]: Jobs before: 19, after: 19
Mar 6 21:37:56 ripshod cru[11470]: Action: d, JobID: Skynet_genstats, Args: d Skynet_genstats
Mar 6 21:37:56 ripshod cru[11470]: Calling PID: 2175, CMD: sh/jffs/scripts/firewallstartskynetloc=/tmp/mnt/asus/skynet
Mar 6 21:37:56 ripshod cru[11492]: Action: a, JobID: Diversion_CountAds, Args: a Diversion_CountAds 20 17 * * * diversion count_ads count
Mar 6 21:37:56 ripshod cru[11492]: Calling PID: 11144, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:37:56 ripshod cru[11470]: Jobs before: 19, after: 19
Mar 6 21:37:56 ripshod cru[11492]: Jobs before: 19, after: 19
Mar 6 21:37:56 ripshod cru[11568]: Action: a, JobID: Skynet_genstats, Args: a Skynet_genstats 31 */12 * * * sh /jffs/scripts/firewall debug genstats
Mar 6 21:37:56 ripshod cru[11568]: Calling PID: 2175, CMD: sh/jffs/scripts/firewallstartskynetloc=/tmp/mnt/asus/skynet
Mar 6 21:37:56 ripshod cru[11574]: Action: a, JobID: Diversion_WeeklyStats, Args: a Diversion_WeeklyStats 30 1 * * Sat /bin/sh /opt/share/diversion/file/stats.div
Mar 6 21:37:56 ripshod cru[11574]: Calling PID: 11144, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:37:56 ripshod cru[11568]: Jobs before: 19, after: 1
Mar 6 21:37:56 ripshod cru[11574]: Jobs before: 19, after: 1
Mar 6 21:37:59 ripshod cru[14991]: Action: d, JobID: Diversion_LocalBackup, Args: d Diversion_LocalBackup
Mar 6 21:37:59 ripshod cru[14991]: Calling PID: 14977, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:37:59 ripshod cru[14991]: Jobs before: 1, after: 1
Mar 6 21:37:59 ripshod cru[15019]: Action: a, JobID: Diversion_UpdateBL, Args: a Diversion_UpdateBL 00 2 * * Sat /bin/sh /opt/share/diversion/file/update-bl.div reset
Mar 6 21:37:59 ripshod cru[15019]: Calling PID: 14977, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:37:59 ripshod cru[15019]: Jobs before: 1, after: 2
Mar 6 21:37:59 ripshod cru[15060]: Action: d, JobID: Diversion_UpdateBLTwo, Args: d Diversion_UpdateBLTwo
Mar 6 21:37:59 ripshod cru[15060]: Calling PID: 14977, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:37:59 ripshod cru[15060]: Jobs before: 2, after: 2
Mar 6 21:37:59 ripshod cru[15095]: Action: a, JobID: Diversion_RotateLogs, Args: a Diversion_RotateLogs 20 5 * * * /bin/sh /opt/share/diversion/file/rotate-logs.div
Mar 6 21:37:59 ripshod cru[15095]: Calling PID: 14977, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:37:59 ripshod cru[15095]: Jobs before: 2, after: 3
Mar 6 21:37:59 ripshod cru[15139]: Action: a, JobID: Diversion_CountAds, Args: a Diversion_CountAds 20 17 * * * diversion count_ads count
Mar 6 21:37:59 ripshod cru[15139]: Calling PID: 14977, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:37:59 ripshod cru[15139]: Jobs before: 3, after: 4
Mar 6 21:37:59 ripshod cru[15168]: Action: a, JobID: Diversion_WeeklyStats, Args: a Diversion_WeeklyStats 30 1 * * Sat /bin/sh /opt/share/diversion/file/stats.div
Mar 6 21:37:59 ripshod cru[15168]: Calling PID: 14977, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:37:59 ripshod cru[15168]: Jobs before: 4, after: 4
Mar 6 21:38:01 ripshod cru[16052]: Action: d, JobID: Diversion_LocalBackup, Args: d Diversion_LocalBackup
Mar 6 21:38:01 ripshod cru[16052]: Calling PID: 16010, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:38:01 ripshod cru[16052]: Jobs before: 4, after: 4
Mar 6 21:38:01 ripshod cru[16116]: Action: a, JobID: Diversion_UpdateBL, Args: a Diversion_UpdateBL 00 2 * * Sat /bin/sh /opt/share/diversion/file/update-bl.div reset
Mar 6 21:38:01 ripshod cru[16116]: Calling PID: 16010, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:38:01 ripshod cru[16116]: Jobs before: 4, after: 4
Mar 6 21:38:01 ripshod cru[16189]: Action: d, JobID: Diversion_UpdateBLTwo, Args: d Diversion_UpdateBLTwo
Mar 6 21:38:01 ripshod cru[16189]: Calling PID: 16010, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:38:01 ripshod cru[16189]: Jobs before: 4, after: 4
Mar 6 21:38:01 ripshod cru[16250]: Action: a, JobID: Diversion_RotateLogs, Args: a Diversion_RotateLogs 20 5 * * * /bin/sh /opt/share/diversion/file/rotate-logs.div
Mar 6 21:38:01 ripshod cru[16250]: Calling PID: 16010, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:38:01 ripshod cru[16250]: Jobs before: 4, after: 4
Mar 6 21:38:01 ripshod cru[16314]: Action: a, JobID: Diversion_CountAds, Args: a Diversion_CountAds 20 17 * * * diversion count_ads count
Mar 6 21:38:01 ripshod cru[16314]: Calling PID: 16010, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:38:01 ripshod cru[16314]: Jobs before: 4, after: 4
Mar 6 21:38:01 ripshod cru[16381]: Action: a, JobID: Diversion_WeeklyStats, Args: a Diversion_WeeklyStats 30 1 * * Sat /bin/sh /opt/share/diversion/file/stats.div
Mar 6 21:38:01 ripshod cru[16381]: Calling PID: 16010, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:38:01 ripshod cru[16381]: Jobs before: 4, after: 4
Mar 6 21:38:03 ripshod cru[18385]: Action: d, JobID: Diversion_LocalBackup, Args: d Diversion_LocalBackup
Mar 6 21:38:03 ripshod cru[18385]: Calling PID: 18371, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:38:03 ripshod cru[18385]: Jobs before: 4, after: 4
Mar 6 21:38:03 ripshod cru[18430]: Action: a, JobID: Diversion_UpdateBL, Args: a Diversion_UpdateBL 00 2 * * Sat /bin/sh /opt/share/diversion/file/update-bl.div reset
Mar 6 21:38:03 ripshod cru[18430]: Calling PID: 18371, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:38:03 ripshod cru[18430]: Jobs before: 4, after: 4
Mar 6 21:38:03 ripshod cru[18484]: Action: d, JobID: Diversion_UpdateBLTwo, Args: d Diversion_UpdateBLTwo
Mar 6 21:38:03 ripshod cru[18484]: Calling PID: 18371, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:38:03 ripshod cru[18484]: Jobs before: 4, after: 4
Mar 6 21:38:03 ripshod cru[18533]: Action: a, JobID: Diversion_RotateLogs, Args: a Diversion_RotateLogs 20 5 * * * /bin/sh /opt/share/diversion/file/rotate-logs.div
Mar 6 21:38:03 ripshod cru[18533]: Calling PID: 18371, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:38:04 ripshod cru[18533]: Jobs before: 4, after: 4
Mar 6 21:38:04 ripshod cru[18584]: Action: a, JobID: Diversion_CountAds, Args: a Diversion_CountAds 20 17 * * * diversion count_ads count
Mar 6 21:38:04 ripshod cru[18584]: Calling PID: 18371, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:38:04 ripshod cru[18584]: Jobs before: 4, after: 4
Mar 6 21:38:04 ripshod cru[18639]: Action: a, JobID: Diversion_WeeklyStats, Args: a Diversion_WeeklyStats 30 1 * * Sat /bin/sh /opt/share/diversion/file/stats.div
Mar 6 21:38:04 ripshod cru[18639]: Calling PID: 18371, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
*/10 * * * * /jffs/scripts/YazFi check #YazFi#
00 2 * * Sat /bin/sh /opt/share/diversion/file/update-bl.div reset #Diversion_UpdateBL#
20 5 * * * /bin/sh /opt/share/diversion/file/rotate-logs.div #Diversion_RotateLogs#
20 17 * * * diversion count_ads count #Diversion_CountAds#
30 1 * * Sat /bin/sh /opt/share/diversion/file/stats.div #Diversion_WeeklyStats#
Mar 6 21:37:55 ripshod Diversion: restarted Dnsmasq to apply settings
Mar 6 21:37:55 ripshod stubby[10907]: Read config from file /etc/stubby/stubby.yml
Mar 6 21:37:55 ripshod stubby[10907]: Stubby version: Stubby 0.4.2
Mar 6 21:37:55 ripshod uiDivStats: dnsmasq has restarted, restarting taildns
Mar 6 21:37:55 ripshod custom_script: Running /jffs/scripts/service-event (args: restart dnsmasq)
Mar 6 21:37:56 ripshod custom_config: Appending content of /jffs/configs/dnsmasq.conf.add.
Mar 6 21:37:56 ripshod custom_script: Running /jffs/scripts/dnsmasq.postconf (args: /etc/dnsmasq.conf)
Mar 6 21:37:56 ripshod Diversion: restarted Dnsmasq to apply settings
Mar 6 21:37:56 ripshod stubby[11754]: Read config from file /etc/stubby/stubby.yml
Mar 6 21:37:56 ripshod stubby[11754]: Stubby version: Stubby 0.4.2
Mar 6 21:37:56 ripshod uiDivStats: dnsmasq has restarted, restarting taildns
Mar 6 21:37:56 ripshod YazFi_[12353]: Firewall restarted - sleeping 10s before running YazFi
Mar 6 21:37:58 ripshod Gilly: dnsmasq tainted detected, restarting dnsmasq
Mar 6 21:37:56 ripshod cru[11568]: Action: a, JobID: Skynet_genstats, Args: a Skynet_genstats 31 */12 * * * sh /jffs/scripts/firewall debug genstats
Mar 6 21:37:56 ripshod cru[11568]: Calling PID: 2175, CMD: sh/jffs/scripts/firewallstartskynetloc=/tmp/mnt/asus/skynet
Mar 6 21:37:56 ripshod cru[11574]: Action: a, JobID: Diversion_WeeklyStats, Args: a Diversion_WeeklyStats 30 1 * * Sat /bin/sh /opt/share/diversion/file/stats.div
Mar 6 21:37:56 ripshod cru[11574]: Calling PID: 11144, CMD: /bin/sh/jffs/scripts/dnsmasq.postconf/etc/dnsmasq.conf
Mar 6 21:37:56 ripshod cru[11568]: Jobs before: 19, after: 1
Mar 6 21:37:56 ripshod cru[11574]: Jobs before: 19, after: 1
Welcome To SNBForums
SNBForums is a community for anyone who wants to learn about or discuss the latest in wireless routers, network storage and the ins and outs of building and maintaining a small network.
If you'd like to post a question, simply register and have at it!
While you're at it, please check out SmallNetBuilder for product reviews and our famous Router Charts, Ranker and plenty more!