[code] d9886e4b-ff3d-43e7-80e1-cfdd2d3d1756: Loaded. | pid: 2467 | LD_PRELOAD:
d9886e4b-ff3d-43e7-80e1-cfdd2d3d1756: sdwdate_main…
d9886e4b-ff3d-43e7-80e1-cfdd2d3d1756: Running sdwdate…
d9886e4b-ff3d-43e7-80e1-cfdd2d3d1756: sdwdate_preparation: Setting CURL to curl.anondist-orig.
d9886e4b-ff3d-43e7-80e1-cfdd2d3d1756: sdwdate_preparation: who_ami is set to user.
d9886e4b-ff3d-43e7-80e1-cfdd2d3d1756: dispatching pre (SDW_MODE: startup): /usr/lib/timesync/timesync_pre --autostart --mode $SDW_MODE
d9886e4b-ff3d-43e7-80e1-cfdd2d3d1756: dispatching prerequisite (SDW_MODE: startup) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
d9886e4b-ff3d-43e7-80e1-cfdd2d3d1756: DISPATCH_PREREQUISITE exited 2 | Tor Bootstrap Result: Tor’s Control Port could not be reached. Did you start Gateway beforehand? Please run whonixcheck on Gateway. | waiting…
d9886e4b-ff3d-43e7-80e1-cfdd2d3d1756: sdwdate (not timesync!): signal SIGTERM received. Cleaning up…
d9886e4b-ff3d-43e7-80e1-cfdd2d3d1756: sdwdate (not timesync!): signal SIGTERM received. Exiting.
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: Loaded. | pid: 2352 | LD_PRELOAD:
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: sdwdate_main…
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: Running sdwdate…
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: sdwdate_preparation: Setting CURL to curl.anondist-orig.
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: sdwdate_preparation: who_ami is set to user.
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: dispatching pre (SDW_MODE: startup): /usr/lib/timesync/timesync_pre --autostart --mode $SDW_MODE
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: dispatching prerequisite (SDW_MODE: startup) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: DISPATCH_PREREQUISITE exited 2 | Tor Bootstrap Result: Tor’s Control Port could not be reached. Did you start Gateway beforehand? Please run whonixcheck on Gateway. | waiting…
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: DISPATCH_PREREQUISITE exited 0, continuing…
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: getUrlDateDiff: https://boum.org
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 15
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 30
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: https://boum.org (took 3s) => diff = 250 second(s)
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: getUrlDateDiff: https://wikileaks.org
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 45
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 60
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: https://wikileaks.org (took 5s) => diff = 252 second(s)
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: getUrlDateDiff: https://privacyfoundation.ch
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 65
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 80
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: https://privacyfoundation.ch (took 5s) => diff = 252 second(s)
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: Results summary: one: 250 | two: 252 | three: 252 | second(s)
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: Min: 250 | Max: 252 | Median diff: 252 second(s) [252000000000 nanosecond(s)]
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: Time before setting using date
: 1424542793.179394291 [Sat Feb 21 18:19:53 UTC 2015]
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: Made up random extra -0.013273145 second[s] [-13273145 nanosecond(s)].
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: Setting time to 1424543044.986726855…
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: Time after setting using date
: 1424543044.987220057 [Sat Feb 21 18:24:04 UTC 2015]
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: dispatching post_success (SDW_MODE: startup): /usr/lib/timesync/timesync_post_success --autostart --mode $SDW_MODE
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: Sleeping for 43 minutes.
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: sdwdate (not timesync!): signal SIGTERM received. Cleaning up…
8fe198c4-4e0b-4dc8-9fe1-873046a8a703: sdwdate (not timesync!): signal SIGTERM received. Exiting.
3fefb348-e46a-40be-936e-194d982498b3: Loaded. | pid: 2330 | LD_PRELOAD:
3fefb348-e46a-40be-936e-194d982498b3: sdwdate_main…
3fefb348-e46a-40be-936e-194d982498b3: Running sdwdate…
3fefb348-e46a-40be-936e-194d982498b3: sdwdate_preparation: Setting CURL to curl.anondist-orig.
3fefb348-e46a-40be-936e-194d982498b3: sdwdate_preparation: who_ami is set to user.
3fefb348-e46a-40be-936e-194d982498b3: dispatching pre (SDW_MODE: startup): /usr/lib/timesync/timesync_pre --autostart --mode $SDW_MODE
3fefb348-e46a-40be-936e-194d982498b3: dispatching prerequisite (SDW_MODE: startup) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
3fefb348-e46a-40be-936e-194d982498b3: DISPATCH_PREREQUISITE exited 0, continuing…
3fefb348-e46a-40be-936e-194d982498b3: getUrlDateDiff: https://www.immerda.ch
3fefb348-e46a-40be-936e-194d982498b3: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 15
3fefb348-e46a-40be-936e-194d982498b3: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 30
3fefb348-e46a-40be-936e-194d982498b3: https://www.immerda.ch (took 3s) => diff = 139 second(s)
3fefb348-e46a-40be-936e-194d982498b3: getUrlDateDiff: https://wikileaks.org
3fefb348-e46a-40be-936e-194d982498b3: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 45
3fefb348-e46a-40be-936e-194d982498b3: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 60
3fefb348-e46a-40be-936e-194d982498b3: https://wikileaks.org (took 15s) => diff = 116 second(s)
3fefb348-e46a-40be-936e-194d982498b3: getUrlDateDiff: https://www.apache.org
3fefb348-e46a-40be-936e-194d982498b3: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 65
3fefb348-e46a-40be-936e-194d982498b3: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 80
3fefb348-e46a-40be-936e-194d982498b3: https://www.apache.org (took 3s) => diff = 265 second(s)
3fefb348-e46a-40be-936e-194d982498b3: Results summary: one: 139 | two: 116 | three: 265 | second(s)
3fefb348-e46a-40be-936e-194d982498b3: Min: 116 | Max: 265 | Median diff: 139 second(s) [139000000000 nanosecond(s)]
3fefb348-e46a-40be-936e-194d982498b3: Time before setting using date
: 1424543927.896552121 [Sat Feb 21 18:38:47 UTC 2015]
3fefb348-e46a-40be-936e-194d982498b3: Made up random extra +0.025409909 second[s] [+25409909 nanosecond(s)].
3fefb348-e46a-40be-936e-194d982498b3: Setting time to 1424544066.025409909…
3fefb348-e46a-40be-936e-194d982498b3: Time after setting using date
: 1424544066.026493701 [Sat Feb 21 18:41:06 UTC 2015]
3fefb348-e46a-40be-936e-194d982498b3: dispatching post_success (SDW_MODE: startup): /usr/lib/timesync/timesync_post_success --autostart --mode $SDW_MODE
3fefb348-e46a-40be-936e-194d982498b3: Sleeping for 38 minutes.
3fefb348-e46a-40be-936e-194d982498b3: Running sdwdate…
3fefb348-e46a-40be-936e-194d982498b3: sdwdate_preparation: Setting CURL to curl.anondist-orig.
3fefb348-e46a-40be-936e-194d982498b3: sdwdate_preparation: who_ami is set to user.
3fefb348-e46a-40be-936e-194d982498b3: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --mode $SDW_MODE
3fefb348-e46a-40be-936e-194d982498b3: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
3fefb348-e46a-40be-936e-194d982498b3: DISPATCH_PREREQUISITE exited 0, continuing…
3fefb348-e46a-40be-936e-194d982498b3: getUrlDateDiff: https://tachanka.org
3fefb348-e46a-40be-936e-194d982498b3: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 15
3fefb348-e46a-40be-936e-194d982498b3: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 30
3fefb348-e46a-40be-936e-194d982498b3: https://tachanka.org (took 4s) => diff = 1 second(s)
3fefb348-e46a-40be-936e-194d982498b3: getUrlDateDiff: https://cve.mitre.org
3fefb348-e46a-40be-936e-194d982498b3: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 45
3fefb348-e46a-40be-936e-194d982498b3: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 60
3fefb348-e46a-40be-936e-194d982498b3: https://cve.mitre.org (took 4s) => diff = -16 second(s)
3fefb348-e46a-40be-936e-194d982498b3: getUrlDateDiff: https://www.centos.org
3fefb348-e46a-40be-936e-194d982498b3: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 65
3fefb348-e46a-40be-936e-194d982498b3: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 80
3fefb348-e46a-40be-936e-194d982498b3: https://www.centos.org (took 7s) => diff = 789 second(s)
3fefb348-e46a-40be-936e-194d982498b3: Results summary: one: 1 | two: -16 | three: 789 | second(s)
3fefb348-e46a-40be-936e-194d982498b3: Min: -16 | Max: 789 | Median diff: 1 second(s) [1000000000 nanosecond(s)]
3fefb348-e46a-40be-936e-194d982498b3: Made up random extra +0.707133477 second[s] [+707133477 nanosecond(s)].
3fefb348-e46a-40be-936e-194d982498b3: Launching into background: sudo /usr/lib/sclockadj --no-verbose --no-debug --no-first-wait --move-min 500000 --move-max 500000 --wait-min 1000000000 --wait-max 1000000000 --add 1707133477
3fefb348-e46a-40be-936e-194d982498b3: Started subshell for sclockadj with pid: 27113
3fefb348-e46a-40be-936e-194d982498b3: dispatching post_success (SDW_MODE: daemon): /usr/lib/timesync/timesync_post_success --autostart --mode $SDW_MODE
3fefb348-e46a-40be-936e-194d982498b3: sclockadj reports: Time before running sclockadj: 1424546372.170959416 [Sat Feb 21 19:19:32 UTC 2015]
3fefb348-e46a-40be-936e-194d982498b3: Sleeping for 14 minutes.
3fefb348-e46a-40be-936e-194d982498b3: sdwdate (not timesync!): signal SIGTERM received. Cleaning up…
3fefb348-e46a-40be-936e-194d982498b3: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 27113 still running. Terminating…
3fefb348-e46a-40be-936e-194d982498b3: sclockadj reports: executing: sudo /usr/lib/sclockadj_kill_helper 27121
3fefb348-e46a-40be-936e-194d982498b3: sclockadj reports: sclockadj_kill_helper_output: kill -sigterm 27121
3fefb348-e46a-40be-936e-194d982498b3: sclockadj reports: Exiting…
3fefb348-e46a-40be-936e-194d982498b3: sclockadj reports: was running for 609 s [~ 10.15 min] [~ 0.16 h].
3fefb348-e46a-40be-936e-194d982498b3: sclockadj reports: Time after running sclockadj: 1424546981.535731265 [Sat Feb 21 19:29:41 UTC 2015]
3fefb348-e46a-40be-936e-194d982498b3: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 27113 Terminated.
3fefb348-e46a-40be-936e-194d982498b3: sdwdate (not timesync!): signal SIGTERM received. Exiting.
ab468866-0756-4767-a728-266acda09213: Loaded. | pid: 2408 | LD_PRELOAD:
ab468866-0756-4767-a728-266acda09213: sdwdate_main…
ab468866-0756-4767-a728-266acda09213: Running sdwdate…
ab468866-0756-4767-a728-266acda09213: sdwdate_preparation: Setting CURL to curl.anondist-orig.
ab468866-0756-4767-a728-266acda09213: sdwdate_preparation: who_ami is set to user.
ab468866-0756-4767-a728-266acda09213: dispatching pre (SDW_MODE: startup): /usr/lib/timesync/timesync_pre --autostart --mode $SDW_MODE
ab468866-0756-4767-a728-266acda09213: dispatching prerequisite (SDW_MODE: startup) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
ab468866-0756-4767-a728-266acda09213: DISPATCH_PREREQUISITE exited 2 | Tor is not yet fully bootstrapped. 63 % done. Tor reports: NOTICE BOOTSTRAP PROGRESS=63 TAG=loading_descriptors SUMMARY=“Loading relay descriptors” | waiting…
ab468866-0756-4767-a728-266acda09213: DISPATCH_PREREQUISITE exited 0, continuing…
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://www.privacyinternational.org
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 15
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 30
ab468866-0756-4767-a728-266acda09213: https://www.privacyinternational.org (took 4s) => diff = -28 second(s)
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://wikileaks.org
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 45
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 60
ab468866-0756-4767-a728-266acda09213: https://wikileaks.org (took 11s) => diff = -88 second(s)
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://www.ccc.de
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 65
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 80
ab468866-0756-4767-a728-266acda09213: https://www.ccc.de (took 4s) => diff = -61 second(s)
ab468866-0756-4767-a728-266acda09213: Results summary: one: -28 | two: -88 | three: -61 | second(s)
ab468866-0756-4767-a728-266acda09213: Min: -88 | Max: -28 | Median diff: -61 second(s) [-61000000000 nanosecond(s)]
ab468866-0756-4767-a728-266acda09213: Time before setting using date
: 1425122633.139549048 [Sat Feb 28 11:23:53 UTC 2015]
ab468866-0756-4767-a728-266acda09213: Made up random extra -0.498747740 second[s] [-498747740 nanosecond(s)].
ab468866-0756-4767-a728-266acda09213: Setting time to 1425122571.501252260…
ab468866-0756-4767-a728-266acda09213: Time after setting using date
: 1425122571.501990184 [Sat Feb 28 11:22:51 UTC 2015]
ab468866-0756-4767-a728-266acda09213: dispatching post_success (SDW_MODE: startup): /usr/lib/timesync/timesync_post_success --autostart --mode $SDW_MODE
ab468866-0756-4767-a728-266acda09213: Sleeping for 12 minutes.
ab468866-0756-4767-a728-266acda09213: Running sdwdate…
ab468866-0756-4767-a728-266acda09213: sdwdate_preparation: Setting CURL to curl.anondist-orig.
ab468866-0756-4767-a728-266acda09213: sdwdate_preparation: who_ami is set to user.
ab468866-0756-4767-a728-266acda09213: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --mode $SDW_MODE
ab468866-0756-4767-a728-266acda09213: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
ab468866-0756-4767-a728-266acda09213: DISPATCH_PREREQUISITE exited 0, continuing…
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://squat.net
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 15
ab468866-0756-4767-a728-266acda09213: No file could be downloaded from https://squat.net. (curl_exit_code: 60 | curl_status_message: [60] - [Peer certificate cannot be authenticated with known CA certificates.] - Is your clock much too slow or fast by chance?)
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://tachanka.org
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 15
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 30
ab468866-0756-4767-a728-266acda09213: https://tachanka.org (took 5s) => diff = 0 second(s)
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://wikileaks.org
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 45
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 60
ab468866-0756-4767-a728-266acda09213: https://wikileaks.org (took 3s) => diff = -26 second(s)
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://www.torservers.net
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 65
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 80
ab468866-0756-4767-a728-266acda09213: https://www.torservers.net (took 5s) => diff = 0 second(s)
ab468866-0756-4767-a728-266acda09213: Results summary: one: 0 | two: -26 | three: 0 | second(s)
ab468866-0756-4767-a728-266acda09213: Min: -26 | Max: 0 | Median diff: 0 second(s) [0 nanosecond(s)]
ab468866-0756-4767-a728-266acda09213: No need to set clock.
ab468866-0756-4767-a728-266acda09213: dispatching post_success (SDW_MODE: daemon): /usr/lib/timesync/timesync_post_success --autostart --mode $SDW_MODE
ab468866-0756-4767-a728-266acda09213: Sleeping for 20 minutes.
ab468866-0756-4767-a728-266acda09213: Running sdwdate…
ab468866-0756-4767-a728-266acda09213: sdwdate_preparation: Setting CURL to curl.anondist-orig.
ab468866-0756-4767-a728-266acda09213: sdwdate_preparation: who_ami is set to user.
ab468866-0756-4767-a728-266acda09213: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --mode $SDW_MODE
ab468866-0756-4767-a728-266acda09213: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
ab468866-0756-4767-a728-266acda09213: DISPATCH_PREREQUISITE exited 0, continuing…
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://chavez.indymedia.org
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 15
ab468866-0756-4767-a728-266acda09213: No file could be downloaded from https://chavez.indymedia.org. (curl_exit_code: 7 | curl_status_message: [7] - [Failed to connect to host.])
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://sarava.org
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 15
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 30
ab468866-0756-4767-a728-266acda09213: https://sarava.org (took 2s) => diff = 1 second(s)
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://grc.com
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 45
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 60
ab468866-0756-4767-a728-266acda09213: https://grc.com (took 4s) => diff = 41 second(s)
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://privacyfoundation.ch
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 65
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 80
ab468866-0756-4767-a728-266acda09213: https://privacyfoundation.ch (took 6s) => diff = 0 second(s)
ab468866-0756-4767-a728-266acda09213: Results summary: one: 1 | two: 41 | three: 0 | second(s)
ab468866-0756-4767-a728-266acda09213: Min: 0 | Max: 41 | Median diff: 1 second(s) [1000000000 nanosecond(s)]
ab468866-0756-4767-a728-266acda09213: Made up random extra -0.774802388 second[s] [-774802388 nanosecond(s)].
ab468866-0756-4767-a728-266acda09213: Launching into background: sudo /usr/lib/sclockadj --no-verbose --no-debug --no-first-wait --move-min 500000 --move-max 500000 --wait-min 1000000000 --wait-max 1000000000 --add 225197612
ab468866-0756-4767-a728-266acda09213: Started subshell for sclockadj with pid: 730
ab468866-0756-4767-a728-266acda09213: dispatching post_success (SDW_MODE: daemon): /usr/lib/timesync/timesync_post_success --autostart --mode $SDW_MODE
ab468866-0756-4767-a728-266acda09213: sclockadj reports: Time before running sclockadj: 1425124529.217972854 [Sat Feb 28 11:55:29 UTC 2015]
ab468866-0756-4767-a728-266acda09213: Sleeping for 13 minutes.
ab468866-0756-4767-a728-266acda09213: sclockadj reports: was running for 451 s [~ 7.51 min] [~ 0.12 h].
ab468866-0756-4767-a728-266acda09213: sclockadj reports: Time after running sclockadj: 1425124982.370645040 [Sat Feb 28 12:03:02 UTC 2015]
ab468866-0756-4767-a728-266acda09213: sclockadj reports: exit code: 0
ab468866-0756-4767-a728-266acda09213: Running sdwdate…
ab468866-0756-4767-a728-266acda09213: sdwdate_preparation: Setting CURL to curl.anondist-orig.
ab468866-0756-4767-a728-266acda09213: sdwdate_preparation: who_ami is set to user.
ab468866-0756-4767-a728-266acda09213: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --mode $SDW_MODE
ab468866-0756-4767-a728-266acda09213: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
ab468866-0756-4767-a728-266acda09213: DISPATCH_PREREQUISITE exited 0, continuing…
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://www.1984.is
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 15
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 30
ab468866-0756-4767-a728-266acda09213: https://www.1984.is (took 3s) => diff = -1 second(s)
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://grc.com
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 45
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 60
ab468866-0756-4767-a728-266acda09213: https://grc.com (took 3s) => diff = 40 second(s)
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://rsf.org
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 65
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 80
ab468866-0756-4767-a728-266acda09213: https://rsf.org (took 3s) => diff = -1 second(s)
ab468866-0756-4767-a728-266acda09213: Results summary: one: -1 | two: 40 | three: -1 | second(s)
ab468866-0756-4767-a728-266acda09213: Min: -1 | Max: 40 | Median diff: -1 second(s) [-1000000000 nanosecond(s)]
ab468866-0756-4767-a728-266acda09213: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 730 no longer running. Exit code: 0
ab468866-0756-4767-a728-266acda09213: Made up random extra +0.609885313 second[s] [+609885313 nanosecond(s)].
ab468866-0756-4767-a728-266acda09213: Launching into background: sudo /usr/lib/sclockadj --no-verbose --no-debug --no-first-wait --move-min 500000 --move-max 500000 --wait-min 1000000000 --wait-max 1000000000 --subtract 390114687
ab468866-0756-4767-a728-266acda09213: Started subshell for sclockadj with pid: 10594
ab468866-0756-4767-a728-266acda09213: dispatching post_success (SDW_MODE: daemon): /usr/lib/timesync/timesync_post_success --autostart --mode $SDW_MODE
ab468866-0756-4767-a728-266acda09213: sclockadj reports: Time before running sclockadj: 1425125323.283086823 [Sat Feb 28 12:08:43 UTC 2015]
ab468866-0756-4767-a728-266acda09213: Sleeping for 26 minutes.
ab468866-0756-4767-a728-266acda09213: sclockadj reports: was running for 781 s [~ 13.01 min] [~ 0.21 h].
ab468866-0756-4767-a728-266acda09213: sclockadj reports: Time after running sclockadj: 1425126104.392933924 [Sat Feb 28 12:21:44 UTC 2015]
ab468866-0756-4767-a728-266acda09213: sclockadj reports: exit code: 0
ab468866-0756-4767-a728-266acda09213: Running sdwdate…
ab468866-0756-4767-a728-266acda09213: sdwdate_preparation: Setting CURL to curl.anondist-orig.
ab468866-0756-4767-a728-266acda09213: sdwdate_preparation: who_ami is set to user.
ab468866-0756-4767-a728-266acda09213: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --mode $SDW_MODE
ab468866-0756-4767-a728-266acda09213: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
ab468866-0756-4767-a728-266acda09213: DISPATCH_PREREQUISITE exited 0, continuing…
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://db.debian.org
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 15
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 30
ab468866-0756-4767-a728-266acda09213: https://db.debian.org (took 4s) => diff = 0 second(s)
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://wikileaks.org
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 45
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 60
ab468866-0756-4767-a728-266acda09213: https://wikileaks.org (took 4s) => diff = 1 second(s)
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://www.centos.org
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 65
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 80
ab468866-0756-4767-a728-266acda09213: https://www.centos.org (took 6s) => diff = 822 second(s)
ab468866-0756-4767-a728-266acda09213: Results summary: one: 0 | two: 1 | three: 822 | second(s)
ab468866-0756-4767-a728-266acda09213: Min: 0 | Max: 822 | Median diff: 1 second(s) [1000000000 nanosecond(s)]
ab468866-0756-4767-a728-266acda09213: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 10594 no longer running. Exit code: 0
ab468866-0756-4767-a728-266acda09213: Made up random extra -0.101653711 second[s] [-101653711 nanosecond(s)].
ab468866-0756-4767-a728-266acda09213: Launching into background: sudo /usr/lib/sclockadj --no-verbose --no-debug --no-first-wait --move-min 500000 --move-max 500000 --wait-min 1000000000 --wait-max 1000000000 --add 898346289
ab468866-0756-4767-a728-266acda09213: Started subshell for sclockadj with pid: 15729
ab468866-0756-4767-a728-266acda09213: dispatching post_success (SDW_MODE: daemon): /usr/lib/timesync/timesync_post_success --autostart --mode $SDW_MODE
ab468866-0756-4767-a728-266acda09213: sclockadj reports: Time before running sclockadj: 1425126900.414494330 [Sat Feb 28 12:35:00 UTC 2015]
ab468866-0756-4767-a728-266acda09213: Sleeping for 19 minutes.
ab468866-0756-4767-a728-266acda09213: Running sdwdate…
ab468866-0756-4767-a728-266acda09213: sdwdate_preparation: Setting CURL to curl.anondist-orig.
ab468866-0756-4767-a728-266acda09213: sdwdate_preparation: who_ami is set to user.
ab468866-0756-4767-a728-266acda09213: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --mode $SDW_MODE
ab468866-0756-4767-a728-266acda09213: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
ab468866-0756-4767-a728-266acda09213: DISPATCH_PREREQUISITE exited 0, continuing…
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://www.privacyinternational.org
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 15
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 30
ab468866-0756-4767-a728-266acda09213: https://www.privacyinternational.org (took 3s) => diff = 33 second(s)
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://lkml.org
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 45
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 60
ab468866-0756-4767-a728-266acda09213: https://lkml.org (took 4s) => diff = -2 second(s)
ab468866-0756-4767-a728-266acda09213: getUrlDateDiff: https://www.calyxinstitute.org
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 65
ab468866-0756-4767-a728-266acda09213: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/msgcollector/msgcollector --icon /usr/share/icons/anon-icon-pack/timesync.ico --identifier timesync --whoami “$who_ami” --progressbaridx timesync --progressx 80
ab468866-0756-4767-a728-266acda09213: https://www.calyxinstitute.org (took 3s) => diff = -1 second(s)
ab468866-0756-4767-a728-266acda09213: Results summary: one: 33 | two: -2 | three: -1 | second(s)
ab468866-0756-4767-a728-266acda09213: Min: -2 | Max: 33 | Median diff: -1 second(s) [-1000000000 nanosecond(s)]
ab468866-0756-4767-a728-266acda09213: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 15729 still running. Terminating…
ab468866-0756-4767-a728-266acda09213: sclockadj reports: executing: sudo /usr/lib/sclockadj_kill_helper 15737
ab468866-0756-4767-a728-266acda09213: sclockadj reports: sclockadj_kill_helper_output: kill -sigterm 15737
ab468866-0756-4767-a728-266acda09213: sclockadj reports: was running for 1155 s [~ 19.25 min] [~ 0.32 h].
ab468866-0756-4767-a728-266acda09213: sclockadj reports: Time after running sclockadj: 1425128055.009685642 [Sat Feb 28 12:54:15 UTC 2015]
ab468866-0756-4767-a728-266acda09213: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 15729 Terminated.
ab468866-0756-4767-a728-266acda09213: Made up random extra -0.984453567 second[s] [-984453567 nanosecond(s)].
ab468866-0756-4767-a728-266acda09213: Launching into background: sudo /usr/lib/sclockadj --no-verbose --no-debug --no-first-wait --move-min 500000 --move-max 500000 --wait-min 1000000000 --wait-max 1000000000 --subtract 1984453567
ab468866-0756-4767-a728-266acda09213: Started subshell for sclockadj with pid: 19793
ab468866-0756-4767-a728-266acda09213: dispatching post_success (SDW_MODE: daemon): /usr/lib/timesync/timesync_post_success --autostart --mode $SDW_MODE
ab468866-0756-4767-a728-266acda09213: sclockadj reports: Exiting…
ab468866-0756-4767-a728-266acda09213: sclockadj reports: Time before running sclockadj: 1425128055.022678824 [Sat Feb 28 12:54:15 UTC 2015]
ab468866-0756-4767-a728-266acda09213: Sleeping for 47 minutes.
ab468866-0756-4767-a728-266acda09213: sdwdate (not timesync!): signal SIGTERM received. Cleaning up…
ab468866-0756-4767-a728-266acda09213: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 19793 still running. Terminating…
ab468866-0756-4767-a728-266acda09213: sclockadj reports: executing: sudo /usr/lib/sclockadj_kill_helper 19803
ab468866-0756-4767-a728-266acda09213: sclockadj reports: sclockadj_kill_helper_output: kill -sigterm 19803
ab468866-0756-4767-a728-266acda09213: sclockadj reports: Exiting…
ab468866-0756-4767-a728-266acda09213: sclockadj reports: was running for 517 s [~ 8.61 min] [~ 0.14 h].
ab468866-0756-4767-a728-266acda09213: sclockadj reports: Time after running sclockadj: 1425128572.400452475 [Sat Feb 28 13:02:52 UTC 2015]
ab468866-0756-4767-a728-266acda09213: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 19793 Terminated.
ab468866-0756-4767-a728-266acda09213: sdwdate (not timesync!): signal SIGTERM received. Exiting.
25248: Running sdwdate… pid: 25248 | LD_PRELOAD:
25248: sdwdate_preparation: Setting CURL to curl.anondist-orig.
25248: sdwdate_preparation: who_ami is set to user.
25248: dispatching pre (SDW_MODE: startup): /usr/lib/timesync/timesync_pre --autostart --mode $SDW_MODE
25248: error_message: ############################################
Error detected! Please report this bug!
BASH_SOURCE: /usr/lib/sdwdate/modules.d/sdwdate
SDW_EXIT_ON_ERROR: 1
SDW_MODE: startup
SDWDATE_SUBSHELL_SCLOCKADJ_EXIT_CODE:
exit_code: 1
error_cause: sdwdate_error_handler signal ERR detected with BASH_COMMAND:
/usr/lib/timesync/timesync_pre --autostart --mode $SDW_MODE
############################################
25248: dispatching post_error (SDW_MODE: startup) (SDW_EXIT_ON_ERROR: 1): /usr/lib/timesync/timesync_post_error “$error_message” & disown
25248: dispatching post_error done.
25248: sdwdate (not timesync!): Error detected. Cleaning up…
25248: sdwdate (not timesync!): signal ERR received. Cleaning up…
25248: sdwdate (not timesync!): signal ERR received. Exiting.
25248: sdwdate (not timesync!): Error detected. Exiting.
25248: error_message: ############################################
Error detected! Please report this bug!
BASH_SOURCE: /usr/lib/sdwdate/modules.d/sdwdate
SDW_EXIT_ON_ERROR: 1
SDW_MODE: startup
SDWDATE_SUBSHELL_SCLOCKADJ_EXIT_CODE:
exit_code: 1
error_cause: sdwdate_error_handler signal ERR detected with BASH_COMMAND:
eval_output=“$(eval ${1+”$@“} 2>&1)”
############################################
25248: dispatching post_error (SDW_MODE: startup) (SDW_EXIT_ON_ERROR: 1): /usr/lib/timesync/timesync_post_error “$error_message” & disown
25248: dispatching post_error done.
25248: sdwdate (not timesync!): Error detected. Cleaning up…
25248: sdwdate (not timesync!): signal ERR received. Cleaning up…
25248: sdwdate (not timesync!): signal ERR received. Exiting.
25248: sdwdate (not timesync!): Error detected. Exiting.
2512: Running sdwdate… pid: 2512 | LD_PRELOAD:
2512: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2512: sdwdate_preparation: who_ami is set to user.
2512: dispatching pre (SDW_MODE: startup): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2512: dispatching prerequisite (SDW_MODE: startup) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2512: DISPATCH_PREREQUISITE exited 0, continuing…
2512: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2512: getUrlDateDiff: https://boum.org | Sat Feb 28 13:11:15 UTC 2015
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2512: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2512: https://boum.org (took 66s) => diff = -193 second(s)
2512: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2512: getUrlDateDiff: https://www.gnu.org | Sat Feb 28 13:11:25 UTC 2015
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2512: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2512: https://www.gnu.org (took 6s) => diff = -186 second(s)
2512: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2512: getUrlDateDiff: https://schneier.com | Sat Feb 28 13:11:30 UTC 2015
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2512: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2512: https://schneier.com (took 5s) => diff = -187 second(s)
2512: Results summary: one: -193 | two: -186 | three: -187 | second(s)
2512: Min: -193 | Max: -186 | Median diff: -187 second(s) [-187000000000 nanosecond(s)]
2512: local unixtime : 1425129096 | local time : Sat Feb 28 13:11:36 UTC 2015
2512: remote unixtime: 1425128909 | remote time: Sat Feb 28 13:08:29 UTC 2015
2512: Made up random extra -0.027342129 second[s] [-27342129 nanosecond(s)].
2512: Time before setting: 1425129096.117993542 [Sat Feb 28 13:11:36 UTC 2015]
2512: Setting time using date
to 1425128908.972657871…
2512: Time after setting using date
: 1425128908.973546807 [Sat Feb 28 13:08:28 UTC 2015]
2512: dispatching post_success (SDW_MODE: startup): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2512: dispatching post_success done.
2512: Sleeping for 39 minutes. (RANDOMIZE: 1)
2512: Running sdwdate… pid: 2512 | LD_PRELOAD:
2512: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2512: sdwdate_preparation: who_ami is set to user.
2512: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2512: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2512: DISPATCH_PREREQUISITE exited 0, continuing…
2512: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2512: getUrlDateDiff: https://mail.riseup.net | Sat Feb 28 13:47:32 UTC 2015
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2512: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2512: https://mail.riseup.net (took 4s) => diff = 1 second(s)
2512: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2512: getUrlDateDiff: https://wikileaks.org | Sat Feb 28 13:47:36 UTC 2015
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2512: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2512: https://wikileaks.org (took 3s) => diff = 1 second(s)
2512: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2512: getUrlDateDiff: https://www.startpage.com | Sat Feb 28 13:47:40 UTC 2015
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2512: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2512: https://www.startpage.com (took 4s) => diff = -1 second(s)
2512: Results summary: one: 1 | two: 1 | three: -1 | second(s)
2512: Min: -1 | Max: 1 | Median diff: 1 second(s) [1000000000 nanosecond(s)]
2512: local unixtime : 1425131263 | local time : Sat Feb 28 13:47:43 UTC 2015
2512: remote unixtime: 1425131264 | remote time: Sat Feb 28 13:47:44 UTC 2015
2512: Made up random extra -0.635362514 second[s] [-635362514 nanosecond(s)].
2512: Launching into background: sudo INLINEDIR=/var/cache/sdwdate/sclockadj /usr/lib/sdwdate/sclockadj --no-verbose --no-debug --no-first-wait --move-min 500000 --move-max 500000 --wait-min 1000000000 --wait-max 1000000000 --add 364637486
2512: Started subshell for sclockadj with pid: 12619
2512: sdwdate_subshell_read: start
2512: dispatching post_success (SDW_MODE: daemon): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2512: sdwdate_subshell_read: sclockadj reports: sudo: unable to execute /usr/lib/sdwdate/sclockadj_debug_helper: Permission denied
2512: sdwdate_subshell_read: sclockadj reports: Time before running sclockadj: 1425131263.231807353 [Sat Feb 28 13:47:43 UTC 2015]
2512: sdwdate_subshell_read: sclockadj reports: sdwdate_subshell_wait: Waiting for pid to finish SDWDATE_SCLOCKADJ_COMMAND_PID: 12635
2512: sdwdate_subshell_read: sclockadj reports: /usr/lib/ruby/1.9.1/fileutils.rb:244:in mkdir': Permission denied - /var/cache/sdwdate/sclockadj/.ruby_inline (Errno::EACCES) 2512: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:244:in
fu_mkdir’
2512: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:221:in block (2 levels) in mkdir_p' 2512: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:219:in
reverse_each’
2512: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:219:in block in mkdir_p' 2512: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:205:in
each’
2512: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:205:in mkdir_p' 2512: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/vendor_ruby/inline.rb:544:in
build’
2512: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/vendor_ruby/inline.rb:843:in inline' 2512: sdwdate_subshell_read: sclockadj reports: from /usr/lib/sdwdate/sclockadj:189:in
class:Cinline’
2512: sdwdate_subshell_read: sclockadj reports: from /usr/lib/sdwdate/sclockadj:187:in `’
2512: sdwdate_subshell_read: sclockadj reports: sdwdate_subshell_wait: SDWDATE_SCLOCKADJ_COMMAND_PID: 12635 | SDWDATE_SCLOCKADJ_COMMAND_EXIT_CODE: 1
2512: sdwdate_subshell_read: sclockadj reports: was running for 0 s [~ 0.00 min] [~ 0.00 h].
2512: sdwdate_subshell_read: sclockadj reports: Time after running sclockadj: 1425131263.835395992 [Sat Feb 28 13:47:43 UTC 2015]
2512: sdwdate_subshell_read: sclockadj reports: exit code: 1
2512: sdwdate_subshell_read: end
2512: dispatching post_success done.
2512: Sleeping for 20 minutes. (RANDOMIZE: 1)
2512: Running sdwdate… pid: 2512 | LD_PRELOAD:
2512: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2512: sdwdate_preparation: who_ami is set to user.
2512: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2512: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2512: DISPATCH_PREREQUISITE exited 0, continuing…
2512: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2512: getUrlDateDiff: https://freedom.press | Sat Feb 28 14:07:46 UTC 2015
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2512: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2512: https://freedom.press (took 4s) => diff = 0 second(s)
2512: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2512: getUrlDateDiff: https://lkml.org | Sat Feb 28 14:07:51 UTC 2015
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2512: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2512: https://lkml.org (took 4s) => diff = -1 second(s)
2512: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2512: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2512: getUrlDateDiff: https://www.centos.org | Sat Feb 28 14:07:55 UTC 2015
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2512: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2512: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2512: https://www.centos.org (took 5s) => diff = 823 second(s)
2512: Results summary: one: 0 | two: -1 | three: 823 | second(s)
2512: Min: -1 | Max: 823 | Median diff: 0 second(s) [0 nanosecond(s)]
2512: local unixtime : 1425132480 | local time : Sat Feb 28 14:08:00 UTC 2015
2512: remote unixtime: 1425132480 | remote time: Sat Feb 28 14:08:00 UTC 2015
2512: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 12619 no longer running. Exit code: 1
2512: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 12619 exited with unexpected non-zero exit code: 1
2512: error_message: ############################################
Error detected! Please report this bug!
BASH_SOURCE: /usr/lib/sdwdate/modules.d/sdwdate
SDW_EXIT_ON_ERROR: 1
SDW_MODE: daemon
SDWDATE_SUBSHELL_SCLOCKADJ_EXIT_CODE: 1
exit_code: 127
error_cause: sdwdate_error_handler signal ERR detected with BASH_COMMAND:
sdwdate_error “$FUNCNAME: subshell for sclockadj exited with unexpected non-zero exit code: $SDWDATE_SUBSHELL_SCLOCKADJ_EXIT_CODE”
################### ||| Shortened log msg string length!
2512: dispatching post_error (SDW_MODE: daemon) (SDW_EXIT_ON_ERROR: 1): /usr/lib/timesync/timesync_post_error --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” “$error_message” & disown
2512: dispatching post_error done.
2512: sdwdate (not timesync!): Error detected. Cleaning up…
2512: sdwdate (not timesync!): signal ERR received. Cleaning up…
2512: sdwdate (not timesync!): signal ERR received. Exiting.
2512: sdwdate (not timesync!): Error detected. Exiting.
2478: Running sdwdate… pid: 2478 | LD_PRELOAD:
2478: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2478: sdwdate_preparation: who_ami is set to user.
2478: dispatching pre (SDW_MODE: startup): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2478: dispatching prerequisite (SDW_MODE: startup) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2478: DISPATCH_PREREQUISITE exited 0, continuing…
2478: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2478: getUrlDateDiff: https://tachanka.org | Sat Feb 28 14:34:54 UTC 2015
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2478: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2478: https://tachanka.org (took 63s) => diff = 50 second(s)
2478: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2478: getUrlDateDiff: https://opentechfund.org | Sat Feb 28 14:34:59 UTC 2015
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2478: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2478: https://opentechfund.org (took 4s) => diff = 50 second(s)
2478: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2478: getUrlDateDiff: https://privacyfoundation.ch | Sat Feb 28 14:35:03 UTC 2015
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2478: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2478: https://privacyfoundation.ch (took 3s) => diff = 51 second(s)
2478: Results summary: one: 50 | two: 50 | three: 51 | second(s)
2478: Min: 50 | Max: 51 | Median diff: 50 second(s) [50000000000 nanosecond(s)]
2478: local unixtime : 1425134106 | local time : Sat Feb 28 14:35:06 UTC 2015
2478: remote unixtime: 1425134156 | remote time: Sat Feb 28 14:35:56 UTC 2015
2478: Made up random extra -0.070179925 second[s] [-70179925 nanosecond(s)].
2478: Time before setting: 1425134106.874069457 [Sat Feb 28 14:35:06 UTC 2015]
2478: Setting time using date
to 1425134155.929820075…
2478: Time after setting using date
: 1425134155.930714961 [Sat Feb 28 14:35:55 UTC 2015]
2478: dispatching post_success (SDW_MODE: startup): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2478: dispatching post_success done.
2478: Sleeping for 26 minutes. (RANDOMIZE: 1)
2478: Running sdwdate… pid: 2478 | LD_PRELOAD:
2478: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2478: sdwdate_preparation: who_ami is set to user.
2478: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2478: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2478: DISPATCH_PREREQUISITE exited 0, continuing…
2478: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2478: getUrlDateDiff: https://debian.org | Sat Feb 28 15:01:59 UTC 2015
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2478: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2478: https://debian.org (took 5s) => diff = 1 second(s)
2478: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2478: getUrlDateDiff: https://www.tarsnap.com | Sat Feb 28 15:02:05 UTC 2015
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2478: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2478: https://www.tarsnap.com (took 4s) => diff = 127 second(s)
2478: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2478: getUrlDateDiff: https://schneier.com | Sat Feb 28 15:02:09 UTC 2015
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2478: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2478: https://schneier.com (took 4s) => diff = 2 second(s)
2478: Results summary: one: 1 | two: 127 | three: 2 | second(s)
2478: Min: 1 | Max: 127 | Median diff: 2 second(s) [2000000000 nanosecond(s)]
2478: local unixtime : 1425135733 | local time : Sat Feb 28 15:02:13 UTC 2015
2478: remote unixtime: 1425135735 | remote time: Sat Feb 28 15:02:15 UTC 2015
2478: Made up random extra +0.199354194 second[s] [+199354194 nanosecond(s)].
2478: Launching into background: sudo INLINEDIR=/var/cache/sdwdate/sclockadj /usr/lib/sdwdate/sclockadj --no-verbose --no-debug --no-first-wait --move-min 500000 --move-max 500000 --wait-min 1000000000 --wait-max 1000000000 --add 2199354194
2478: Started subshell for sclockadj with pid: 16064
2478: sdwdate_subshell_read: start
2478: dispatching post_success (SDW_MODE: daemon): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2478: sdwdate_subshell_read: sclockadj reports: sudo: unable to execute /usr/lib/sdwdate/sclockadj_debug_helper: Permission denied
2478: sdwdate_subshell_read: sclockadj reports: Time before running sclockadj: 1425135733.949862186 [Sat Feb 28 15:02:13 UTC 2015]
2478: sdwdate_subshell_read: sclockadj reports: sdwdate_subshell_wait: Waiting for pid to finish SDWDATE_SCLOCKADJ_COMMAND_PID: 16080
2478: sdwdate_subshell_read: sclockadj reports: /usr/lib/ruby/1.9.1/fileutils.rb:244:in mkdir': Permission denied - /var/cache/sdwdate/sclockadj/.ruby_inline (Errno::EACCES) 2478: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:244:in
fu_mkdir’
2478: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:221:in block (2 levels) in mkdir_p' 2478: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:219:in
reverse_each’
2478: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:219:in block in mkdir_p' 2478: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:205:in
each’
2478: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:205:in mkdir_p' 2478: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/vendor_ruby/inline.rb:544:in
build’
2478: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/vendor_ruby/inline.rb:843:in inline' 2478: sdwdate_subshell_read: sclockadj reports: from /usr/lib/sdwdate/sclockadj:189:in
class:Cinline’
2478: sdwdate_subshell_read: sclockadj reports: from /usr/lib/sdwdate/sclockadj:187:in `’
2478: sdwdate_subshell_read: sclockadj reports: sdwdate_subshell_wait: SDWDATE_SCLOCKADJ_COMMAND_PID: 16080 | SDWDATE_SCLOCKADJ_COMMAND_EXIT_CODE: 1
2478: sdwdate_subshell_read: sclockadj reports: was running for 1 s [~ 0.01 min] [~ 0.00 h].
2478: sdwdate_subshell_read: sclockadj reports: Time after running sclockadj: 1425135734.609162449 [Sat Feb 28 15:02:14 UTC 2015]
2478: sdwdate_subshell_read: sclockadj reports: exit code: 1
2478: sdwdate_subshell_read: end
2478: dispatching post_success done.
2478: Sleeping for 57 minutes. (RANDOMIZE: 1)
2478: Running sdwdate… pid: 2478 | LD_PRELOAD:
2478: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2478: sdwdate_preparation: who_ami is set to user.
2478: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2478: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2478: DISPATCH_PREREQUISITE exited 0, continuing…
2478: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2478: getUrlDateDiff: https://squat.net | Sat Feb 28 15:59:18 UTC 2015
2478: No file could be downloaded from https://squat.net. (took 7s) (curl_exit_code: 60 | curl_status_message: [60] - [Peer certificate cannot be authenticated with known CA certificates.] - Is your clock much too slow or fast by chance?)
2478: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 1)
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2478: getUrlDateDiff: https://boum.org | Sat Feb 28 15:59:24 UTC 2015
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2478: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2478: https://boum.org (took 4s) => diff = 0 second(s)
2478: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2478: getUrlDateDiff: https://wikileaks.org | Sat Feb 28 15:59:28 UTC 2015
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2478: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2478: https://wikileaks.org (took 3s) => diff = 1 second(s)
2478: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2478: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2478: getUrlDateDiff: https://www.centos.org | Sat Feb 28 15:59:32 UTC 2015
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2478: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2478: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2478: https://www.centos.org (took 4s) => diff = 824 second(s)
2478: Results summary: one: 0 | two: 1 | three: 824 | second(s)
2478: Min: 0 | Max: 824 | Median diff: 1 second(s) [1000000000 nanosecond(s)]
2478: local unixtime : 1425139176 | local time : Sat Feb 28 15:59:36 UTC 2015
2478: remote unixtime: 1425139177 | remote time: Sat Feb 28 15:59:37 UTC 2015
2478: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 16064 no longer running. Exit code: 1
2478: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 16064 exited with unexpected non-zero exit code: 1
2478: error_message: ############################################
Error detected! Please report this bug!
BASH_SOURCE: /usr/lib/sdwdate/modules.d/sdwdate
SDW_EXIT_ON_ERROR: 1
SDW_MODE: daemon
SDWDATE_SUBSHELL_SCLOCKADJ_EXIT_CODE: 1
exit_code: 127
error_cause: sdwdate_error_handler signal ERR detected with BASH_COMMAND:
sdwdate_error “$FUNCNAME: subshell for sclockadj exited with unexpected non-zero exit code: $SDWDATE_SUBSHELL_SCLOCKADJ_EXIT_CODE”
################### ||| Shortened log msg string length!
2478: dispatching post_error (SDW_MODE: daemon) (SDW_EXIT_ON_ERROR: 1): /usr/lib/timesync/timesync_post_error --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” “$error_message” & disown
2478: dispatching post_error done.
2478: sdwdate (not timesync!): Error detected. Cleaning up…
2478: sdwdate (not timesync!): signal ERR received. Cleaning up…
2478: sdwdate (not timesync!): signal ERR received. Exiting.
2478: sdwdate (not timesync!): Error detected. Exiting.
2479: Running sdwdate… pid: 2479 | LD_PRELOAD:
2479: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2479: sdwdate_preparation: who_ami is set to user.
2479: dispatching pre (SDW_MODE: startup): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2479: dispatching prerequisite (SDW_MODE: startup) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2479: DISPATCH_PREREQUISITE exited 2 | Tor is not yet fully bootstrapped. 15 % done. Tor reports: NOTICE BOOTSTRAP PROGRESS=15 TAG=onehop_create SUMMARY=“Establishing an encrypted directory connection” | waiting…
2479: DISPATCH_PREREQUISITE exited 0, continuing…
2479: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2479: getUrlDateDiff: https://www.torproject.org | Sun Mar 1 21:12:12 UTC 2015
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2479: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2479: https://www.torproject.org (took 47s) => diff = -51 second(s)
2479: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2479: getUrlDateDiff: https://defectivebydesign.org | Sun Mar 1 21:12:20 UTC 2015
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2479: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2479: https://defectivebydesign.org (took 10s) => diff = -48 second(s)
2479: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2479: getUrlDateDiff: https://schneier.com | Sun Mar 1 21:12:32 UTC 2015
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2479: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2479: https://schneier.com (took 5s) => diff = -47 second(s)
2479: Results summary: one: -51 | two: -48 | three: -47 | second(s)
2479: Min: -51 | Max: -47 | Median diff: -48 second(s) [-48000000000 nanosecond(s)]
2479: local unixtime : 1425244358 | local time : Sun Mar 1 21:12:38 UTC 2015
2479: remote unixtime: 1425244310 | remote time: Sun Mar 1 21:11:50 UTC 2015
2479: Made up random extra +0.660374771 second[s] [+660374771 nanosecond(s)].
2479: Time before setting: 1425244358.026917010 [Sun Mar 1 21:12:38 UTC 2015]
2479: Setting time using date
to 1425244310.660374771…
2479: Time after setting using date
: 1425244310.661185809 [Sun Mar 1 21:11:50 UTC 2015]
2479: dispatching post_success (SDW_MODE: startup): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2479: dispatching post_success done.
2479: Sleeping for 15 minutes. (RANDOMIZE: 1)
2479: Running sdwdate… pid: 2479 | LD_PRELOAD:
2479: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2479: sdwdate_preparation: who_ami is set to user.
2479: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2479: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2479: DISPATCH_PREREQUISITE exited 0, continuing…
2479: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2479: getUrlDateDiff: https://squat.net | Sun Mar 1 21:26:54 UTC 2015
2479: No file could be downloaded from https://squat.net. (took 3s) (curl_exit_code: 60 | curl_status_message: [60] - [Peer certificate cannot be authenticated with known CA certificates.] - Is your clock much too slow or fast by chance?)
2479: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 1)
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2479: getUrlDateDiff: https://db.debian.org | Sun Mar 1 21:26:56 UTC 2015
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2479: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2479: https://db.debian.org (took 5s) => diff = 0 second(s)
2479: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2479: getUrlDateDiff: https://en.wikipedia.org | Sun Mar 1 21:27:01 UTC 2015
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2479: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2479: https://en.wikipedia.org (took 4s) => diff = 1 second(s)
2479: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2479: getUrlDateDiff: https://en.bitcoin.it | Sun Mar 1 21:27:05 UTC 2015
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2479: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2479: https://en.bitcoin.it (took 4s) => diff = 1 second(s)
2479: Results summary: one: 0 | two: 1 | three: 1 | second(s)
2479: Min: 0 | Max: 1 | Median diff: 1 second(s) [1000000000 nanosecond(s)]
2479: local unixtime : 1425245229 | local time : Sun Mar 1 21:27:09 UTC 2015
2479: remote unixtime: 1425245230 | remote time: Sun Mar 1 21:27:10 UTC 2015
2479: Made up random extra +0.045250841 second[s] [+45250841 nanosecond(s)].
2479: Launching into background: sudo INLINEDIR=/var/cache/sdwdate/sclockadj /usr/lib/sdwdate/sclockadj --no-verbose --no-debug --no-first-wait --move-min 500000 --move-max 500000 --wait-min 1000000000 --wait-max 1000000000 --add 1045250841
2479: Started subshell for sclockadj with pid: 14538
2479: sdwdate_subshell_read: start
2479: dispatching post_success (SDW_MODE: daemon): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2479: sdwdate_subshell_read: sclockadj reports: sudo: unable to execute /usr/lib/sdwdate/sclockadj_debug_helper: Permission denied
2479: sdwdate_subshell_read: sclockadj reports: Time before running sclockadj: 1425245229.760245807 [Sun Mar 1 21:27:09 UTC 2015]
2479: sdwdate_subshell_read: sclockadj reports: sdwdate_subshell_wait: Waiting for pid to finish SDWDATE_SCLOCKADJ_COMMAND_PID: 14557
2479: sdwdate_subshell_read: sclockadj reports: /usr/lib/ruby/1.9.1/fileutils.rb:244:in mkdir': Permission denied - /var/cache/sdwdate/sclockadj/.ruby_inline (Errno::EACCES) 2479: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:244:in
fu_mkdir’
2479: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:221:in block (2 levels) in mkdir_p' 2479: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:219:in
reverse_each’
2479: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:219:in block in mkdir_p' 2479: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:205:in
each’
2479: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:205:in mkdir_p' 2479: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/vendor_ruby/inline.rb:544:in
build’
2479: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/vendor_ruby/inline.rb:843:in inline' 2479: sdwdate_subshell_read: sclockadj reports: from /usr/lib/sdwdate/sclockadj:189:in
class:Cinline’
2479: sdwdate_subshell_read: sclockadj reports: from /usr/lib/sdwdate/sclockadj:187:in `’
2479: sdwdate_subshell_read: sclockadj reports: sdwdate_subshell_wait: SDWDATE_SCLOCKADJ_COMMAND_PID: 14557 | SDWDATE_SCLOCKADJ_COMMAND_EXIT_CODE: 1
2479: sdwdate_subshell_read: sclockadj reports: was running for 1 s [~ 0.01 min] [~ 0.00 h].
2479: sdwdate_subshell_read: sclockadj reports: Time after running sclockadj: 1425245230.285157110 [Sun Mar 1 21:27:10 UTC 2015]
2479: sdwdate_subshell_read: sclockadj reports: exit code: 1
2479: sdwdate_subshell_read: end
2479: dispatching post_success done.
2479: Sleeping for 37 minutes. (RANDOMIZE: 1)
2479: Running sdwdate… pid: 2479 | LD_PRELOAD:
2479: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2479: sdwdate_preparation: who_ami is set to user.
2479: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2479: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2479: DISPATCH_PREREQUISITE exited 0, continuing…
2479: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2479: getUrlDateDiff: https://epic.org | Sun Mar 1 22:04:15 UTC 2015
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2479: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2479: https://epic.org (took 5s) => diff = 1 second(s)
2479: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2479: getUrlDateDiff: https://www.tarsnap.com | Sun Mar 1 22:04:20 UTC 2015
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2479: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2479: https://www.tarsnap.com (took 5s) => diff = 129 second(s)
2479: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2479: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2479: getUrlDateDiff: https://www.accessnow.org | Sun Mar 1 22:04:25 UTC 2015
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2479: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2479: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2479: https://www.accessnow.org (took 4s) => diff = -298 second(s)
2479: Results summary: one: 1 | two: 129 | three: -298 | second(s)
2479: Min: -298 | Max: 129 | Median diff: 1 second(s) [1000000000 nanosecond(s)]
2479: local unixtime : 1425247469 | local time : Sun Mar 1 22:04:29 UTC 2015
2479: remote unixtime: 1425247470 | remote time: Sun Mar 1 22:04:30 UTC 2015
2479: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 14538 no longer running. Exit code: 1
2479: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 14538 exited with unexpected non-zero exit code: 1
2479: error_message: ############################################
Error detected! Please report this bug!
BASH_SOURCE: /usr/lib/sdwdate/modules.d/sdwdate
SDW_EXIT_ON_ERROR: 1
SDW_MODE: daemon
SDWDATE_SUBSHELL_SCLOCKADJ_EXIT_CODE: 1
exit_code: 127
error_cause: sdwdate_error_handler signal ERR detected with BASH_COMMAND:
sdwdate_error “$FUNCNAME: subshell for sclockadj exited with unexpected non-zero exit code: $SDWDATE_SUBSHELL_SCLOCKADJ_EXIT_CODE”
################### ||| Shortened log msg string length!
2479: dispatching post_error (SDW_MODE: daemon) (SDW_EXIT_ON_ERROR: 1): /usr/lib/timesync/timesync_post_error --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” “$error_message” & disown
2479: dispatching post_error done.
2479: sdwdate (not timesync!): Error detected. Cleaning up…
2479: sdwdate (not timesync!): signal ERR received. Cleaning up…
2479: sdwdate (not timesync!): signal ERR received. Exiting.
2479: sdwdate (not timesync!): Error detected. Exiting.
2477: Running sdwdate… pid: 2477 | LD_PRELOAD:
2477: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2477: sdwdate_preparation: who_ami is set to user.
2477: dispatching pre (SDW_MODE: startup): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2477: dispatching prerequisite (SDW_MODE: startup) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2477: DISPATCH_PREREQUISITE exited 0, continuing…
2477: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2477: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2477: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2477: getUrlDateDiff: https://tachanka.org | Tue Mar 3 20:25:08 UTC 2015
2477: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2477: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2477: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2477: https://tachanka.org (took 57s) => diff = 87 second(s)
2477: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2477: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2477: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2477: getUrlDateDiff: https://www.tarsnap.com | Tue Mar 3 20:25:16 UTC 2015
2477: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2477: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2477: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2477: https://www.tarsnap.com (took 5s) => diff = 222 second(s)
2477: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2477: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2477: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2477: getUrlDateDiff: https://www.apache.org | Tue Mar 3 20:25:22 UTC 2015
2477: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2477: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2477: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2477: https://www.apache.org (took 4s) => diff = 215 second(s)
2477: Results summary: one: 87 | two: 222 | three: 215 | second(s)
2477: Min: 87 | Max: 222 | Median diff: 215 second(s) [215000000000 nanosecond(s)]
2477: local unixtime : 1425414326 | local time : Tue Mar 3 20:25:26 UTC 2015
2477: remote unixtime: 1425414541 | remote time: Tue Mar 3 20:29:01 UTC 2015
2477: Made up random extra +0.729521883 second[s] [+729521883 nanosecond(s)].
2477: Time before setting: 1425414326.395609665 [Tue Mar 3 20:25:26 UTC 2015]
2477: Setting time using date
to 1425414541.729521883…
2477: Time after setting using date
: 1425414541.730370082 [Tue Mar 3 20:29:01 UTC 2015]
2477: dispatching post_success (SDW_MODE: startup): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2477: dispatching post_success done.
2477: Sleeping for 45 minutes. (RANDOMIZE: 1)
2477: sdwdate (not timesync!): signal SIGTERM received. Cleaning up…
2477: sdwdate (not timesync!): signal SIGTERM received. Exiting.
2492: Running sdwdate… pid: 2492 | LD_PRELOAD:
2492: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2492: sdwdate_preparation: who_ami is set to user.
2492: dispatching pre (SDW_MODE: startup): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2492: dispatching prerequisite (SDW_MODE: startup) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2492: DISPATCH_PREREQUISITE exited 0, continuing…
2492: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2492: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2492: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2492: getUrlDateDiff: https://sarava.org | Sun Mar 8 21:24:42 UTC 2015
2492: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2492: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2492: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2492: https://sarava.org (took 59s) => diff = -26 second(s)
2492: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2492: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2492: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2492: getUrlDateDiff: https://cve.mitre.org | Sun Mar 8 21:24:49 UTC 2015
2492: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2492: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2492: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2492: https://cve.mitre.org (took 5s) => diff = -248 second(s)
2492: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2492: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2492: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2492: getUrlDateDiff: https://www.piratenpartei.de | Sun Mar 8 21:24:55 UTC 2015
2492: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2492: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2492: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2492: https://www.piratenpartei.de (took 5s) => diff = -82 second(s)
2492: Results summary: one: -26 | two: -248 | three: -82 | second(s)
2492: Min: -248 | Max: -26 | Median diff: -82 second(s) [-82000000000 nanosecond(s)]
2492: local unixtime : 1425849900 | local time : Sun Mar 8 21:25:00 UTC 2015
2492: remote unixtime: 1425849818 | remote time: Sun Mar 8 21:23:38 UTC 2015
2492: Made up random extra -0.402262339 second[s] [-402262339 nanosecond(s)].
2492: Time before setting: 1425849900.302529281 [Sun Mar 8 21:25:00 UTC 2015]
2492: Setting time using date
to 1425849817.597737661…
2492: Time after setting using date
: 1425849817.598701306 [Sun Mar 8 21:23:37 UTC 2015]
2492: dispatching post_success (SDW_MODE: startup): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2492: dispatching post_success done.
2492: Sleeping for 52 minutes. (RANDOMIZE: 1)
2492: sdwdate (not timesync!): signal SIGTERM received. Cleaning up…
2492: sdwdate (not timesync!): signal SIGTERM received. Exiting.
2459: Running sdwdate… pid: 2459 | LD_PRELOAD:
2459: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2459: sdwdate_preparation: who_ami is set to user.
2459: dispatching pre (SDW_MODE: startup): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2459: dispatching prerequisite (SDW_MODE: startup) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2459: DISPATCH_PREREQUISITE exited 0, continuing…
2459: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2459: getUrlDateDiff: https://chavez.indymedia.org | Mon Mar 9 17:13:46 UTC 2015
2459: No file could be downloaded from https://chavez.indymedia.org. (took 61s) (curl_exit_code: 7 | curl_status_message: [7] - [Failed to connect to host.])
2459: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 1)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2459: getUrlDateDiff: https://www.torproject.org | Mon Mar 9 17:13:49 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://www.torproject.org (took 3s) => diff = 62 second(s)
2459: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2459: getUrlDateDiff: https://pressfreedomfoundation.org | Mon Mar 9 17:13:52 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://pressfreedomfoundation.org (took 2s) => diff = 64 second(s)
2459: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2459: getUrlDateDiff: https://schneier.com | Mon Mar 9 17:13:55 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://schneier.com (took 4s) => diff = 64 second(s)
2459: Results summary: one: 62 | two: 64 | three: 64 | second(s)
2459: Min: 62 | Max: 64 | Median diff: 64 second(s) [64000000000 nanosecond(s)]
2459: local unixtime : 1425921239 | local time : Mon Mar 9 17:13:59 UTC 2015
2459: remote unixtime: 1425921303 | remote time: Mon Mar 9 17:15:03 UTC 2015
2459: Made up random extra +0.583966757 second[s] [+583966757 nanosecond(s)].
2459: Time before setting: 1425921239.803221836 [Mon Mar 9 17:13:59 UTC 2015]
2459: Setting time using date
to 1425921303.583966757…
2459: Time after setting using date
: 1425921303.584763710 [Mon Mar 9 17:15:03 UTC 2015]
2459: dispatching post_success (SDW_MODE: startup): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2459: dispatching post_success done.
2459: Sleeping for 11 minutes. (RANDOMIZE: 1)
2459: Running sdwdate… pid: 2459 | LD_PRELOAD:
2459: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2459: sdwdate_preparation: who_ami is set to user.
2459: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2459: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2459: DISPATCH_PREREQUISITE exited 0, continuing…
2459: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2459: getUrlDateDiff: https://www.eff.org | Mon Mar 9 17:26:06 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://www.eff.org (took 6s) => diff = 0 second(s)
2459: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2459: getUrlDateDiff: https://cve.mitre.org | Mon Mar 9 17:26:12 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://cve.mitre.org (took 3s) => diff = -226 second(s)
2459: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2459: getUrlDateDiff: https://privacyfoundation.ch | Mon Mar 9 17:26:15 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://privacyfoundation.ch (took 3s) => diff = 0 second(s)
2459: Results summary: one: 0 | two: -226 | three: 0 | second(s)
2459: Min: -226 | Max: 0 | Median diff: 0 second(s) [0 nanosecond(s)]
2459: local unixtime : 1425921979 | local time : Mon Mar 9 17:26:19 UTC 2015
2459: remote unixtime: 1425921979 | remote time: Mon Mar 9 17:26:19 UTC 2015
2459: No need to set clock.
2459: dispatching post_success (SDW_MODE: daemon): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2459: dispatching post_success done.
2459: Sleeping for 37 minutes. (RANDOMIZE: 1)
2459: Running sdwdate… pid: 2459 | LD_PRELOAD:
2459: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2459: sdwdate_preparation: who_ami is set to user.
2459: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2459: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2459: DISPATCH_PREREQUISITE exited 0, continuing…
2459: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2459: getUrlDateDiff: https://www.immerda.ch | Mon Mar 9 18:03:22 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://www.immerda.ch (took 4s) => diff = 0 second(s)
2459: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2459: getUrlDateDiff: https://www.tarsnap.com | Mon Mar 9 18:03:27 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://www.tarsnap.com (took 4s) => diff = 142 second(s)
2459: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2459: getUrlDateDiff: https://www.wauland.de | Mon Mar 9 18:03:31 UTC 2015
2459: No file could be downloaded from https://www.wauland.de. (took 3s) (curl_exit_code: 60 | curl_status_message: [60] - [Peer certificate cannot be authenticated with known CA certificates.] - Is your clock much too slow or fast by chance?)
2459: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 1)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2459: getUrlDateDiff: https://www.calyxinstitute.org | Mon Mar 9 18:03:34 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://www.calyxinstitute.org (took 5s) => diff = 0 second(s)
2459: Results summary: one: 0 | two: 142 | three: 0 | second(s)
2459: Min: 0 | Max: 142 | Median diff: 0 second(s) [0 nanosecond(s)]
2459: local unixtime : 1425924218 | local time : Mon Mar 9 18:03:38 UTC 2015
2459: remote unixtime: 1425924218 | remote time: Mon Mar 9 18:03:38 UTC 2015
2459: No need to set clock.
2459: dispatching post_success (SDW_MODE: daemon): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2459: dispatching post_success done.
2459: Sleeping for 25 minutes. (RANDOMIZE: 1)
2459: Running sdwdate… pid: 2459 | LD_PRELOAD:
2459: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2459: sdwdate_preparation: who_ami is set to user.
2459: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2459: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2459: DISPATCH_PREREQUISITE exited 0, continuing…
2459: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2459: getUrlDateDiff: https://boum.org | Mon Mar 9 18:28:42 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://boum.org (took 4s) => diff = -2 second(s)
2459: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2459: getUrlDateDiff: https://www.kernel.org | Mon Mar 9 18:28:47 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://www.kernel.org (took 3s) => diff = 0 second(s)
2459: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2459: getUrlDateDiff: https://en.bitcoin.it | Mon Mar 9 18:28:51 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://en.bitcoin.it (took 3s) => diff = 0 second(s)
2459: Results summary: one: -2 | two: 0 | three: 0 | second(s)
2459: Min: -2 | Max: 0 | Median diff: 0 second(s) [0 nanosecond(s)]
2459: local unixtime : 1425925734 | local time : Mon Mar 9 18:28:54 UTC 2015
2459: remote unixtime: 1425925734 | remote time: Mon Mar 9 18:28:54 UTC 2015
2459: No need to set clock.
2459: dispatching post_success (SDW_MODE: daemon): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2459: dispatching post_success done.
2459: Sleeping for 17 minutes. (RANDOMIZE: 1)
2459: Running sdwdate… pid: 2459 | LD_PRELOAD:
2459: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2459: sdwdate_preparation: who_ami is set to user.
2459: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2459: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2459: DISPATCH_PREREQUISITE exited 0, continuing…
2459: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2459: getUrlDateDiff: https://www.torproject.org | Mon Mar 9 18:45:57 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://www.torproject.org (took 2s) => diff = 1 second(s)
2459: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2459: getUrlDateDiff: https://www.kernel.org | Mon Mar 9 18:45:59 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://www.kernel.org (took 3s) => diff = 1 second(s)
2459: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2459: getUrlDateDiff: https://www.torservers.net | Mon Mar 9 18:46:02 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://www.torservers.net (took 5s) => diff = 0 second(s)
2459: Results summary: one: 1 | two: 1 | three: 0 | second(s)
2459: Min: 0 | Max: 1 | Median diff: 1 second(s) [1000000000 nanosecond(s)]
2459: local unixtime : 1425926767 | local time : Mon Mar 9 18:46:07 UTC 2015
2459: remote unixtime: 1425926768 | remote time: Mon Mar 9 18:46:08 UTC 2015
2459: Made up random extra +0.251084028 second[s] [+251084028 nanosecond(s)].
2459: Launching into background: sudo INLINEDIR=/var/cache/sdwdate/sclockadj /usr/lib/sdwdate/sclockadj --no-verbose --no-debug --no-first-wait --move-min 500000 --move-max 500000 --wait-min 1000000000 --wait-max 1000000000 --add 1251084028
2459: Started subshell for sclockadj with pid: 23268
2459: sdwdate_subshell_read: start
2459: dispatching post_success (SDW_MODE: daemon): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2459: sdwdate_subshell_read: sclockadj reports: sudo: unable to execute /usr/lib/sdwdate/sclockadj_debug_helper: Permission denied
2459: sdwdate_subshell_read: sclockadj reports: Time before running sclockadj: 1425926767.469582802 [Mon Mar 9 18:46:07 UTC 2015]
2459: sdwdate_subshell_read: sclockadj reports: sdwdate_subshell_wait: Waiting for pid to finish SDWDATE_SCLOCKADJ_COMMAND_PID: 23284
2459: sdwdate_subshell_read: sclockadj reports: /usr/lib/ruby/1.9.1/fileutils.rb:244:in mkdir': Permission denied - /var/cache/sdwdate/sclockadj/.ruby_inline (Errno::EACCES) 2459: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:244:in
fu_mkdir’
2459: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:221:in block (2 levels) in mkdir_p' 2459: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:219:in
reverse_each’
2459: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:219:in block in mkdir_p' 2459: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:205:in
each’
2459: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/1.9.1/fileutils.rb:205:in mkdir_p' 2459: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/vendor_ruby/inline.rb:544:in
build’
2459: sdwdate_subshell_read: sclockadj reports: from /usr/lib/ruby/vendor_ruby/inline.rb:843:in inline' 2459: sdwdate_subshell_read: sclockadj reports: from /usr/lib/sdwdate/sclockadj:189:in
class:Cinline’
2459: sdwdate_subshell_read: sclockadj reports: from /usr/lib/sdwdate/sclockadj:187:in `’
2459: sdwdate_subshell_read: sclockadj reports: sdwdate_subshell_wait: SDWDATE_SCLOCKADJ_COMMAND_PID: 23284 | SDWDATE_SCLOCKADJ_COMMAND_EXIT_CODE: 1
2459: sdwdate_subshell_read: sclockadj reports: was running for 0 s [~ 0.00 min] [~ 0.00 h].
2459: sdwdate_subshell_read: sclockadj reports: Time after running sclockadj: 1425926767.993905383 [Mon Mar 9 18:46:07 UTC 2015]
2459: sdwdate_subshell_read: sclockadj reports: exit code: 1
2459: sdwdate_subshell_read: end
2459: dispatching post_success done.
2459: Sleeping for 27 minutes. (RANDOMIZE: 1)
2459: Running sdwdate… pid: 2459 | LD_PRELOAD:
2459: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2459: sdwdate_preparation: who_ami is set to user.
2459: dispatching pre (SDW_MODE: daemon): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2459: dispatching prerequisite (SDW_MODE: daemon) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2459: DISPATCH_PREREQUISITE exited 0, continuing…
2459: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2459: getUrlDateDiff: https://www.1984.is | Mon Mar 9 19:13:10 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://www.1984.is (took 3s) => diff = 0 second(s)
2459: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2459: getUrlDateDiff: https://pressfreedomfoundation.org | Mon Mar 9 19:13:14 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://pressfreedomfoundation.org (took 3s) => diff = 0 second(s)
2459: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: daemon) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2459: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2459: getUrlDateDiff: https://schneier.com | Mon Mar 9 19:13:17 UTC 2015
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2459: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2459: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2459: https://schneier.com (took 15s) => diff = 0 second(s)
2459: Results summary: one: 0 | two: 0 | three: 0 | second(s)
2459: Min: 0 | Max: 0 | Median diff: 0 second(s) [0 nanosecond(s)]
2459: local unixtime : 1425928412 | local time : Mon Mar 9 19:13:32 UTC 2015
2459: remote unixtime: 1425928412 | remote time: Mon Mar 9 19:13:32 UTC 2015
2459: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 23268 no longer running. Exit code: 1
2459: sdwdate_terminate_sclockadj: subshell for sclockadj with pid 23268 exited with unexpected non-zero exit code: 1
2459: error_message: ############################################
Error detected! Please report this bug!
BASH_SOURCE: /usr/lib/sdwdate/modules.d/sdwdate
SDW_EXIT_ON_ERROR: 1
SDW_MODE: daemon
SDWDATE_SUBSHELL_SCLOCKADJ_EXIT_CODE: 1
exit_code: 127
error_cause: sdwdate_error_handler signal ERR detected with BASH_COMMAND:
sdwdate_error “$FUNCNAME: subshell for sclockadj exited with unexpected non-zero exit code: $SDWDATE_SUBSHELL_SCLOCKADJ_EXIT_CODE”
################### ||| Shortened log msg string length!
2459: dispatching post_error (SDW_MODE: daemon) (SDW_EXIT_ON_ERROR: 1): /usr/lib/timesync/timesync_post_error --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” “$error_message” & disown
2459: dispatching post_error done.
2459: sdwdate (not timesync!): Error detected. Cleaning up…
2459: sdwdate (not timesync!): signal ERR received. Cleaning up…
2459: sdwdate (not timesync!): signal ERR received. Exiting.
2459: sdwdate (not timesync!): Error detected. Exiting.
2488: Running sdwdate… pid: 2488 | LD_PRELOAD:
2488: sdwdate_preparation: Setting CURL to curl.anondist-orig.
2488: sdwdate_preparation: who_ami is set to user.
2488: dispatching pre (SDW_MODE: startup): /usr/lib/timesync/timesync_pre --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2488: dispatching prerequisite (SDW_MODE: startup) (CURL: curl.anondist-orig) (LD_PRELOAD: ): /usr/lib/anon-shared-helper-scripts/te_pe_tb_check
2488: DISPATCH_PREREQUISITE exited 2 | Tor is not yet fully bootstrapped. 90 % done. Tor reports: NOTICE BOOTSTRAP PROGRESS=90 TAG=circuit_create SUMMARY=“Establishing a Tor circuit” | waiting…
2488: DISPATCH_PREREQUISITE exited 0, continuing…
2488: allowed_member_failures: 5 (debugging information: temp: 5.10 | SDWDATE_POOL_ONE array_length: 15 | array_length_remember: 0)
2488: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 15
2488: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_ONE] done.
2488: getUrlDateDiff: https://debian.org | Mon Mar 9 19:47:39 UTC 2015
2488: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 30
2488: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_ONE] done.
2488: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2488: https://debian.org (took 45s) => diff = 118 second(s)
2488: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_TWO array_length: 17 | array_length_remember: 0)
2488: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 45
2488: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_TWO] done.
2488: getUrlDateDiff: https://fsfe.org | Mon Mar 9 19:47:44 UTC 2015
2488: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 60
2488: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_TWO] done.
2488: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2488: https://fsfe.org (took 3s) => diff = 119 second(s)
2488: allowed_member_failures: 6 (debugging information: temp: 5.78 | SDWDATE_POOL_THREE array_length: 17 | array_length_remember: 0)
2488: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] (SDW_MODE: startup) (CURL: curl.anondist-orig): /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 65
2488: dispatching SDWDATE_CURL_DISPATCH_PRE[SDWDATE_POOL_THREE] done.
2488: getUrlDateDiff: https://www.ccc.de | Mon Mar 9 19:47:47 UTC 2015
2488: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE]: /usr/lib/timesync/timesync_progress --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami” --progressx 80
2488: dispatching SDWDATE_CURL_DISPATCH_POST[SDWDATE_POOL_THREE] done.
2488: WEB_DATE_FROM_TOOL_STRING_LENGTH: 31
2488: https://www.ccc.de (took 6s) => diff = 119 second(s)
2488: Results summary: one: 118 | two: 119 | three: 119 | second(s)
2488: Min: 118 | Max: 119 | Median diff: 119 second(s) [119000000000 nanosecond(s)]
2488: local unixtime : 1425930473 | local time : Mon Mar 9 19:47:53 UTC 2015
2488: remote unixtime: 1425930592 | remote time: Mon Mar 9 19:49:52 UTC 2015
2488: Made up random extra -0.690008242 second[s] [-690008242 nanosecond(s)].
2488: Time before setting: 1425930473.322386695 [Mon Mar 9 19:47:53 UTC 2015]
2488: Setting time using date
to 1425930591.309991758…
2488: Time after setting using date
: 1425930591.310817268 [Mon Mar 9 19:49:51 UTC 2015]
2488: dispatching post_success (SDW_MODE: startup): /usr/lib/timesync/timesync_post_success --autostart --identifier “timesync” --progressbaridx “$ID” --mode “$SDW_MODE” --whoami “$who_ami”
2488: dispatching post_success done.
2488: Sleeping for 48 minutes. (RANDOMIZE: 1)
[/CODE]