| Name | RFkKDmoFkC6n9Rq4apOajLDm4fhM0noT9bVorHsSDmisVKDmlsn7Yn_0 |
| Workunit | 225560715 |
| Created | 22 Sep 2024, 22:29:05 UTC |
| Sent | 23 Sep 2024, 0:02:28 UTC |
| Report deadline | 1 Oct 2024, 0:02:28 UTC |
| Received | 1 Oct 2024, 4:40:01 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10836655 |
| Run time | 5 hours 22 min 54 sec |
| CPU time | 11 hours 5 min 41 sec |
| Validate state | Valid |
| Credit | 1,025.16 |
| Device peak FLOPS | 26.91 GFLOPS |
| Application version | ATLAS Simulation v3.01 (vbox64_mt_mcore_atlas) x86_64-apple-darwin |
| Peak working set size | 8.71 GB |
| Peak swap size | 71.07 GB |
| Peak disk usage | 423.64 MB |
Stderr output
<core_client_version>7.24.2</core_client_version> <![CDATA[ <stderr_txt> st=1 727 157 722 277 000 000 ns) 2024-09-23 23:53:24 (3583): Guest Log: 01:26:49.754966 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 066 687 188 000ns (GuestNow=1 727 160 766 134 113 000 ns GuestLast=1 727 157 699 446 925 000 ns fSetTimeLastLoop=true ) 2024-09-24 00:54:20 (3583): Guest Log: 01:37:31.604251 timesync vgsvcTimeSyncWorker: Radical host time change: 3 012 808 000 000ns (HostNow=1 727 164 422 698 000 000 ns HostLast=1 727 161 409 890 000 000 ns) 2024-09-24 00:54:30 (3583): Guest Log: 01:37:41.734362 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 024 864 592 000ns (GuestNow=1 727 164 432 838 029 000 ns GuestLast=1 727 161 407 973 437 000 ns fSetTimeLastLoop=true ) 2024-09-24 01:55:14 (3583): Guest Log: 01:48:05.668624 timesync vgsvcTimeSyncWorker: Radical host time change: 3 019 842 000 000ns (HostNow=1 727 168 100 636 000 000 ns HostLast=1 727 165 080 794 000 000 ns) 2024-09-24 01:55:25 (3583): Guest Log: 01:48:15.692428 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 053 887 831 000ns (GuestNow=1 727 168 110 659 979 000 ns GuestLast=1 727 165 056 772 148 000 ns fSetTimeLastLoop=true ) 2024-09-24 02:51:29 (3583): Guest Log: 01:58:57.894956 timesync vgsvcTimeSyncWorker: Radical host time change: 2 721 164 000 000ns (HostNow=1 727 171 443 810 000 000 ns HostLast=1 727 168 722 646 000 000 ns) 2024-09-24 02:51:39 (3583): Guest Log: 01:59:07.948300 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 701 146 890 000ns (GuestNow=1 727 171 453 936 251 000 ns GuestLast=1 727 168 752 789 361 000 ns fSetTimeLastLoop=true ) 2024-09-24 05:01:51 (3583): Stopping VM. 2024-09-24 05:02:30 (3583): Guest Log: 02:09:21.828597 timesync vgsvcTimeSyncWorker: Radical host time change: 7 197 388 000 000ns (HostNow=1 727 179 270 061 000 000 ns HostLast=1 727 172 072 673 000 000 ns) 2024-09-24 05:02:30 (3583): Successfully stopped VM. 2024-09-24 16:00:00 (74892): Detected: vboxwrapper 26206 2024-09-24 16:00:00 (74892): Detected: BOINC client v7.24.2 2024-09-24 16:00:02 (74892): Detected: VirtualBox VboxManage Interface (Version: 7.0.12) 2024-09-24 16:00:02 (74892): Detected: Sandbox Configuration Enabled 2024-09-24 16:00:02 (74892): Guest Log: BIOS: VirtualBox 7.0.12 2024-09-24 16:00:02 (74892): Guest Log: CPUID EDX: 0x178bfbff 2024-09-24 16:00:02 (74892): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-09-24 16:00:02 (74892): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-09-24 16:00:02 (74892): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-09-24 16:00:02 (74892): Guest Log: BIOS: Booting from Hard Disk... 2024-09-24 16:00:02 (74892): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-09-24 16:00:02 (74892): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f 2024-09-24 16:00:02 (74892): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f 2024-09-24 16:00:02 (74892): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-09-24 16:00:02 (74892): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-09-24 16:00:02 (74892): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log 2024-09-24 16:00:02 (74892): Guest Log: 00:00:00.000161 main Log opened 2024-09-23T01:12:55.376791000Z 2024-09-24 16:00:02 (74892): Guest Log: 00:00:00.000280 main OS Product: Linux 2024-09-24 16:00:02 (74892): Guest Log: 00:00:00.000321 main OS Release: 3.10.0-957.27.2.el7.x86_64 2024-09-24 16:00:02 (74892): Guest Log: 00:00:00.000379 main OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019 2024-09-24 16:00:02 (74892): Guest Log: 00:00:00.000437 main Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService 2024-09-24 16:00:02 (74892): Guest Log: 00:00:00.000438 main Process ID: 1309 2024-09-24 16:00:02 (74892): Guest Log: 00:00:00.000438 main Package type: LINUX_64BITS_GENERIC 2024-09-24 16:00:02 (74892): Guest Log: 00:00:00.003370 main 5.2.32 r132073 started. Verbose level = 0 2024-09-24 16:00:02 (74892): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... 2024-09-24 16:00:02 (74892): Guest Log: [INFO] Mounting shared directory 2024-09-24 16:00:02 (74892): Guest Log: [INFO] Checking for init_data.xml 2024-09-24 16:00:02 (74892): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK 2024-09-24 16:00:02 (74892): Guest Log: [INFO] Detected branch: prod 2024-09-24 16:00:02 (74892): Guest Log: This is the prod version of the ATLAS job wrapper 2024-09-24 16:00:02 (74892): Guest Log: Copying input files 2024-09-24 16:00:02 (74892): Guest Log: 00:00:10.007598 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 754 721 069 000ns (GuestNow=1 727 057 730 104 617 000 ns GuestLast=1 727 053 975 383 548 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:00:02 (74892): Guest Log: Copied input files into RunAtlas. 2024-09-24 16:00:02 (74892): Guest Log: This VM did not configure a local http proxy via BOINC. 2024-09-24 16:00:02 (74892): Guest Log: Small home clusters do not require a local http proxy but it is suggested if 2024-09-24 16:00:02 (74892): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks. 2024-09-24 16:00:02 (74892): Guest Log: Further information can be found at the LHC@home message board. 2024-09-24 16:00:02 (74892): Guest Log: Running cvmfs_config stat atlas.cern.ch 2024-09-24 16:00:02 (74892): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE 2024-09-24 16:00:02 (74892): Guest Log: 2.6.3.0 1605 0 29984 137542 3 1 3975360 4096000 0 65024 0 0 n/a 1 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1 2024-09-24 16:00:02 (74892): Guest Log: copied the webapp to /var/www 2024-09-24 16:00:02 (74892): Guest Log: ATHENA_PROC_NUMBER=4 2024-09-24 16:00:02 (74892): Guest Log: ATHENA_CORE_NUMBER=4 2024-09-24 16:00:02 (74892): Guest Log: Apptainer command /cvmfs/atlas.cern.ch/repo/containers/sw/apptainer/x86_64-el7/current/bin/apptainer exec -B /cvmfs,/data,/home/atlas/RunAtlas /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 2024-09-24 16:00:02 (74892): Guest Log: *** Starting ATLAS job. (PandaID=6341577803 taskID=41078631) *** 2024-09-24 16:00:03 (74892): Guest Log: 00:12:50.527151 timesync vgsvcTimeSyncWorker: Radical host time change: 75 667 794 000 000ns (HostNow=1 727 135 258 470 000 000 ns HostLast=1 727 059 590 676 000 000 ns) 2024-09-24 16:00:03 (74892): Guest Log: 00:13:00.530046 timesync vgsvcTimeSyncWorker: Radical guest time change: 76 777 859 426 000ns (GuestNow=1 727 135 268 478 059 000 ns GuestLast=1 727 058 490 618 633 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:00:03 (74892): Guest Log: 00:23:02.447794 timesync vgsvcTimeSyncWorker: Radical host time change: 3 025 461 000 000ns (HostNow=1 727 138 886 636 000 000 ns HostLast=1 727 135 861 175 000 000 ns) 2024-09-24 16:00:03 (74892): Guest Log: 00:23:12.449891 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 026 243 674 000ns (GuestNow=1 727 138 896 638 787 000 ns GuestLast=1 727 135 870 395 113 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:00:03 (74892): Guest Log: 00:33:05.709922 timesync vgsvcTimeSyncWorker: Radical host time change: 1 954 219 000 000ns (HostNow=1 727 141 460 664 000 000 ns HostLast=1 727 139 506 445 000 000 ns) 2024-09-24 16:00:03 (74892): Guest Log: 00:33:15.744353 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 980 800 909 000ns (GuestNow=1 727 141 470 698 958 000 ns GuestLast=1 727 139 489 898 049 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:00:03 (74892): Guest Log: 00:44:18.754953 timesync vgsvcTimeSyncWorker: Radical host time change: 1 853 060 000 000ns (HostNow=1 727 144 395 321 000 000 ns HostLast=1 727 142 542 261 000 000 ns) 2024-09-24 16:00:03 (74892): Guest Log: 00:44:28.817655 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 271 678 234 000ns (GuestNow=1 727 144 405 385 721 000 ns GuestLast=1 727 142 133 707 487 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:00:03 (74892): Guest Log: 00:54:32.030268 timesync vgsvcTimeSyncWorker: Radical host time change: 3 608 156 000 000ns (HostNow=1 727 149 784 246 000 000 ns HostLast=1 727 146 176 090 000 000 ns) 2024-09-24 16:00:03 (74892): Guest Log: 00:54:42.045582 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 785 800 173 000ns (GuestNow=1 727 149 794 329 044 000 ns GuestLast=1 727 145 008 528 871 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:00:03 (74892): Guest Log: 01:05:45.556369 timesync vgsvcTimeSyncWorker: Radical host time change: 2 985 330 000 000ns (HostNow=1 727 153 459 500 000 000 ns HostLast=1 727 150 474 170 000 000 ns) 2024-09-24 16:00:03 (74892): Guest Log: 01:05:55.568944 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 011 950 013 000ns (GuestNow=1 727 153 469 651 704 000 ns GuestLast=1 727 150 457 701 691 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:00:03 (74892): Guest Log: 01:16:17.706440 timesync vgsvcTimeSyncWorker: Radical host time change: 3 026 245 000 000ns (HostNow=1 727 157 077 382 000 000 ns HostLast=1 727 154 051 137 000 000 ns) 2024-09-24 16:00:03 (74892): Guest Log: 01:16:27.710565 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 995 639 160 000ns (GuestNow=1 727 157 087 407 616 000 ns GuestLast=1 727 154 091 768 456 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:00:03 (74892): Guest Log: 01:26:39.750168 timesync vgsvcTimeSyncWorker: Radical host time change: 3 033 852 000 000ns (HostNow=1 727 160 756 129 000 000 ns HostLast=1 727 157 722 277 000 000 ns) 2024-09-24 16:00:03 (74892): Guest Log: 01:26:49.754966 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 066 687 188 000ns (GuestNow=1 727 160 766 134 113 000 ns GuestLast=1 727 157 699 446 925 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:00:03 (74892): Guest Log: 01:37:31.604251 timesync vgsvcTimeSyncWorker: Radical host time change: 3 012 808 000 000ns (HostNow=1 727 164 422 698 000 000 ns HostLast=1 727 161 409 890 000 000 ns) 2024-09-24 16:00:03 (74892): Guest Log: 01:37:41.734362 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 024 864 592 000ns (GuestNow=1 727 164 432 838 029 000 ns GuestLast=1 727 161 407 973 437 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:00:03 (74892): Guest Log: 01:48:05.668624 timesync vgsvcTimeSyncWorker: Radical host time change: 3 019 842 000 000ns (HostNow=1 727 168 100 636 000 000 ns HostLast=1 727 165 080 794 000 000 ns) 2024-09-24 16:00:03 (74892): Guest Log: 01:48:15.692428 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 053 887 831 000ns (GuestNow=1 727 168 110 659 979 000 ns GuestLast=1 727 165 056 772 148 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:00:03 (74892): Guest Log: 01:58:57.894956 timesync vgsvcTimeSyncWorker: Radical host time change: 2 721 164 000 000ns (HostNow=1 727 171 443 810 000 000 ns HostLast=1 727 168 722 646 000 000 ns) 2024-09-24 16:00:03 (74892): Guest Log: 01:59:07.948300 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 701 146 890 000ns (GuestNow=1 727 171 453 936 251 000 ns GuestLast=1 727 168 752 789 361 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:00:03 (74892): Guest Log: 02:09:21.828597 timesync vgsvcTimeSyncWorker: Radical host time change: 7 197 388 000 000ns (HostNow=1 727 179 270 061 000 000 ns HostLast=1 727 172 072 673 000 000 ns) 2024-09-24 16:00:03 (74892): Starting VM using VBoxManage interface. (boinc_71ea3e0dec4452e7, slot#6) 2024-09-24 16:00:35 (74892): Successfully started VM. (PID = '74913') 2024-09-24 16:00:35 (74892): Reporting VM Process ID to BOINC. 2024-09-24 16:00:35 (74892): VM state change detected. (old = 'poweredoff', new = 'running') 2024-09-24 16:00:35 (74892): Detected: Web Application Enabled (http://localhost:50754) 2024-09-24 16:00:35 (74892): Status Report: Elapsed Time: '10009.000000' 2024-09-24 16:00:35 (74892): Status Report: CPU Time: '17057.210000' 2024-09-24 16:00:35 (74892): Preference change detected 2024-09-24 16:00:35 (74892): Setting CPU throttle for VM. (80%) 2024-09-24 16:00:35 (74892): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 900 seconds)) 2024-09-24 16:00:42 (74892): Guest Log: 02:09:31.860000 timesync vgsvcTimeSyncWorker: Radical host time change: 39 521 269 000 000ns (HostNow=1 727 218 791 330 000 000 ns HostLast=1 727 179 270 061 000 000 ns) 2024-09-24 16:00:42 (74892): Guest Log: 02:09:31.860320 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 212 402 007 000ns (GuestNow=1 727 179 280 153 757 000 ns GuestLast=1 727 172 067 751 750 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:00:52 (74892): Guest Log: 02:09:41.891825 timesync vgsvcTimeSyncWorker: Radical guest time change: 39 521 210 934 000ns (GuestNow=1 727 218 801 364 691 000 ns GuestLast=1 727 179 280 153 757 000 ns fSetTimeLastLoop=true ) 2024-09-24 16:55:05 (74892): Guest Log: 02:12:32.430704 timesync vgsvcTimeSyncWorker: Radical host time change: 2 971 415 000 000ns (HostNow=1 727 222 103 601 000 000 ns HostLast=1 727 219 132 186 000 000 ns) 2024-09-24 16:55:15 (74892): Guest Log: 02:12:42.451499 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 141 742 281 000ns (GuestNow=1 727 222 113 633 827 000 ns GuestLast=1 727 218 971 891 546 000 ns fSetTimeLastLoop=true ) 2024-09-24 17:49:58 (74892): Guest Log: 02:14:22.843806 timesync vgsvcTimeSyncWorker: Radical host time change: 2 540 300 000 000ns (HostNow=1 727 225 362 749 000 000 ns HostLast=1 727 222 822 449 000 000 ns) 2024-09-24 17:50:09 (74892): Guest Log: 02:14:32.969232 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 158 849 981 000ns (GuestNow=1 727 225 372 875 267 000 ns GuestLast=1 727 222 214 025 286 000 ns fSetTimeLastLoop=true ) 2024-09-24 18:50:45 (74892): Guest Log: 02:26:08.515901 timesync vgsvcTimeSyncWorker: Radical host time change: 2 532 191 000 000ns (HostNow=1 727 229 025 519 000 000 ns HostLast=1 727 226 493 328 000 000 ns) 2024-09-24 18:50:55 (74892): Guest Log: 02:26:18.557386 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 967 536 567 000ns (GuestNow=1 727 229 035 759 374 000 ns GuestLast=1 727 226 068 222 807 000 ns fSetTimeLastLoop=true ) 2024-09-24 20:09:49 (74892): Guest Log: 02:38:10.600950 timesync vgsvcTimeSyncWorker: Radical host time change: 2 998 345 000 000ns (HostNow=1 727 233 769 139 000 000 ns HostLast=1 727 230 770 794 000 000 ns) 2024-09-24 20:09:59 (74892): Guest Log: 02:38:20.635278 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 031 401 614 000ns (GuestNow=1 727 233 779 188 437 000 ns GuestLast=1 727 229 747 786 823 000 ns fSetTimeLastLoop=true ) 2024-09-24 21:10:43 (74892): Guest Log: 02:48:42.796195 timesync vgsvcTimeSyncWorker: Radical host time change: 3 081 728 000 000ns (HostNow=1 727 237 442 159 000 000 ns HostLast=1 727 234 360 431 000 000 ns) 2024-09-24 21:10:53 (74892): Guest Log: 02:48:52.809600 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 050 906 820 000ns (GuestNow=1 727 237 452 214 790 000 ns GuestLast=1 727 234 401 307 970 000 ns fSetTimeLastLoop=true ) 2024-09-24 22:11:47 (74892): Guest Log: 02:49:43.295869 timesync vgsvcTimeSyncWorker: Radical host time change: 3 604 439 000 000ns (HostNow=1 727 241 055 024 000 000 ns HostLast=1 727 237 450 585 000 000 ns) 2024-09-24 22:11:57 (74892): Guest Log: 02:49:53.363678 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 562 390 839 000ns (GuestNow=1 727 241 065 091 705 000 ns GuestLast=1 727 237 502 700 866 000 ns fSetTimeLastLoop=true ) 2024-09-24 23:12:45 (74892): Guest Log: 03:01:26.607570 timesync vgsvcTimeSyncWorker: Radical host time change: 2 954 643 000 000ns (HostNow=1 727 244 738 183 000 000 ns HostLast=1 727 241 783 540 000 000 ns) 2024-09-24 23:12:55 (74892): Guest Log: 03:01:36.609225 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 990 315 554 000ns (GuestNow=1 727 244 748 418 046 000 ns GuestLast=1 727 241 758 102 492 000 ns fSetTimeLastLoop=true ) 2024-09-25 00:13:48 (74892): Guest Log: 03:02:26.939213 timesync vgsvcTimeSyncWorker: Radical host time change: 3 604 023 000 000ns (HostNow=1 727 248 409 944 000 000 ns HostLast=1 727 244 805 921 000 000 ns) 2024-09-25 00:13:59 (74892): Guest Log: 03:02:36.972135 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 621 228 871 000ns (GuestNow=1 727 248 419 976 760 000 ns GuestLast=1 727 244 798 747 889 000 ns fSetTimeLastLoop=true ) 2024-09-25 01:14:54 (74892): Guest Log: 03:14:48.871227 timesync vgsvcTimeSyncWorker: Radical host time change: 2 922 227 000 000ns (HostNow=1 727 252 065 867 000 000 ns HostLast=1 727 249 143 640 000 000 ns) 2024-09-25 01:15:04 (74892): Guest Log: 03:14:58.892415 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 924 101 153 000ns (GuestNow=1 727 252 075 932 722 000 ns GuestLast=1 727 249 151 831 569 000 ns fSetTimeLastLoop=true ) 2024-09-25 03:15:41 (74892): Guest Log: 03:15:39.545515 timesync vgsvcTimeSyncWorker: Radical host time change: 7 256 460 000 000ns (HostNow=1 727 259 330 763 000 000 ns HostLast=1 727 252 074 303 000 000 ns) 2024-09-25 03:15:51 (74892): Guest Log: 03:15:49.565969 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 224 392 814 000ns (GuestNow=1 727 259 340 881 067 000 ns GuestLast=1 727 252 116 488 253 000 ns fSetTimeLastLoop=true ) 2024-09-25 05:31:20 (74892): Guest Log: 03:28:44.757812 timesync vgsvcTimeSyncWorker: Radical host time change: 7 197 797 000 000ns (HostNow=1 727 267 435 015 000 000 ns HostLast=1 727 260 237 218 000 000 ns) 2024-09-25 05:31:27 (74892): Stopping VM. 2024-09-25 05:32:07 (74892): Successfully stopped VM. 2024-09-25 18:07:59 (80127): Detected: vboxwrapper 26206 2024-09-25 18:07:59 (80127): Detected: BOINC client v7.24.2 2024-09-25 18:08:07 (80127): Detected: VirtualBox VboxManage Interface (Version: 7.0.12) 2024-09-25 18:08:07 (80127): Detected: Sandbox Configuration Enabled 2024-09-25 18:08:07 (80127): Guest Log: 02:09:31.860000 timesync vgsvcTimeSyncWorker: Radical host time change: 39 521 269 000 000ns (HostNow=1 727 218 791 330 000 000 ns HostLast=1 727 179 270 061 000 000 ns) 2024-09-25 18:08:07 (80127): Guest Log: 02:09:31.860320 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 212 402 007 000ns (GuestNow=1 727 179 280 153 757 000 ns GuestLast=1 727 172 067 751 750 000 ns fSetTimeLastLoop=true ) 2024-09-25 18:08:07 (80127): Guest Log: 02:09:41.891825 timesync vgsvcTimeSyncWorker: Radical guest time change: 39 521 210 934 000ns (GuestNow=1 727 218 801 364 691 000 ns GuestLast=1 727 179 280 153 757 000 ns fSetTimeLastLoop=true ) 2024-09-25 18:08:07 (80127): Guest Log: 02:12:32.430704 timesync vgsvcTimeSyncWorker: Radical host time change: 2 971 415 000 000ns (HostNow=1 727 222 103 601 000 000 ns HostLast=1 727 219 132 186 000 000 ns) 2024-09-25 18:08:07 (80127): Guest Log: 02:12:42.451499 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 141 742 281 000ns (GuestNow=1 727 222 113 633 827 000 ns GuestLast=1 727 218 971 891 546 000 ns fSetTimeLastLoop=true ) 2024-09-25 18:08:07 (80127): Guest Log: 02:14:22.843806 timesync vgsvcTimeSyncWorker: Radical host time change: 2 540 300 000 000ns (HostNow=1 727 225 362 749 000 000 ns HostLast=1 727 222 822 449 000 000 ns) 2024-09-25 18:08:07 (80127): Guest Log: 02:14:32.969232 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 158 849 981 000ns (GuestNow=1 727 225 372 875 267 000 ns GuestLast=1 727 222 214 025 286 000 ns fSetTimeLastLoop=true ) 2024-09-25 18:08:07 (80127): Guest Log: 02:26:08.515901 timesync vgsvcTimeSyncWorker: Radical host time change: 2 532 191 000 000ns (HostNow=1 727 229 025 519 000 000 ns HostLast=1 727 226 493 328 000 000 ns) 2024-09-25 18:08:07 (80127): Guest Log: 02:26:18.557386 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 967 536 567 000ns (GuestNow=1 727 229 035 759 374 000 ns GuestLast=1 727 226 068 222 807 000 ns fSetTimeLastLoop=true ) 2024-09-25 18:08:07 (80127): Guest Log: 02:38:10.600950 timesync vgsvcTimeSyncWorker: Radical host time change: 2 998 345 000 000ns (HostNow=1 727 233 769 139 000 000 ns HostLast=1 727 230 770 794 000 000 ns) 2024-09-25 18:08:07 (80127): Guest Log: 02:38:20.635278 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 031 401 614 000ns (GuestNow=1 727 233 779 188 437 000 ns GuestLast=1 727 229 747 786 823 000 ns fSetTimeLastLoop=true ) 2024-09-25 18:08:07 (80127): Guest Log: 02:48:42.796195 timesync vgsvcTimeSyncWorker: Radical host time change: 3 081 728 000 000ns (HostNow=1 727 237 442 159 000 000 ns HostLast=1 727 234 360 431 000 000 ns) 2024-09-25 18:08:07 (80127): Guest Log: 02:48:52.809600 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 050 906 820 000ns (GuestNow=1 727 237 452 214 790 000 ns GuestLast=1 727 234 401 307 970 000 ns fSetTimeLastLoop=true ) 2024-09-25 18:08:07 (80127): Guest Log: 02:49:43.295869 timesync vgsvcTimeSyncWorker: Radical host time change: 3 604 439 000 000ns (HostNow=1 727 241 055 024 000 000 ns HostLast=1 727 237 450 585 000 000 ns) 2024-09-25 18:08:07 (80127): Guest Log: 02:49:53.363678 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 562 390 839 000ns (GuestNow=1 727 241 065 091 705 000 ns GuestLast=1 727 237 502 700 866 000 ns fSetTimeLastLoop=true ) 2024-09-25 18:08:07 (80127): Guest Log: 03:01:26.607570 timesync vgsvcTimeSyncWorker: Radical host time change: 2 954 643 000 000ns (HostNow=1 727 244 738 183 000 000 ns HostLast=1 727 241 783 540 000 000 ns) 2024-09-25 18:08:07 (80127): Guest Log: 03:01:36.609225 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 990 315 554 000ns (GuestNow=1 727 244 748 418 046 000 ns GuestLast=1 727 241 758 102 492 000 ns fSetTimeLastLoop=true ) 2024-09-25 18:08:07 (80127): Guest Log: 03:02:26.939213 timesync vgsvcTimeSyncWorker: Radical host time change: 3 604 023 000 000ns (HostNow=1 727 248 409 944 000 000 ns HostLast=1 727 244 805 921 000 000 ns) 2024-09-25 18:08:07 (80127): Guest Log: 03:02:36.972135 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 621 228 871 000ns (GuestNow=1 727 248 419 976 760 000 ns GuestLast=1 727 244 798 747 889 000 ns fSetTimeLastLoop=true ) 2024-09-25 18:08:07 (80127): Guest Log: 03:14:48.871227 timesync vgsvcTimeSyncWorker: Radical host time change: 2 922 227 000 000ns (HostNow=1 727 252 065 867 000 000 ns HostLast=1 727 249 143 640 000 000 ns) 2024-09-25 18:08:07 (80127): Guest Log: 03:14:58.892415 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 924 101 153 000ns (GuestNow=1 727 252 075 932 722 000 ns GuestLast=1 727 249 151 831 569 000 ns fSetTimeLastLoop=true ) 2024-09-25 18:08:07 (80127): Guest Log: 03:15:39.545515 timesync vgsvcTimeSyncWorker: Radical host time change: 7 256 460 000 000ns (HostNow=1 727 259 330 763 000 000 ns HostLast=1 727 252 074 303 000 000 ns) 2024-09-25 18:08:07 (80127): Guest Log: 03:15:49.565969 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 224 392 814 000ns (GuestNow=1 727 259 340 881 067 000 ns GuestLast=1 727 252 116 488 253 000 ns fSetTimeLastLoop=true ) 2024-09-25 18:08:07 (80127): Guest Log: 03:28:44.757812 timesync vgsvcTimeSyncWorker: Radical host time change: 7 197 797 000 000ns (HostNow=1 727 267 435 015 000 000 ns HostLast=1 727 260 237 218 000 000 ns) 2024-09-25 18:08:07 (80127): Starting VM using VBoxManage interface. (boinc_71ea3e0dec4452e7, slot#6) 2024-09-25 18:08:26 (80127): Successfully started VM. (PID = '80145') 2024-09-25 18:08:26 (80127): Reporting VM Process ID to BOINC. 2024-09-25 18:08:26 (80127): Guest Log: 03:28:54.913194 timesync vgsvcTimeSyncWorker: Radical host time change: 45 416 433 000 000ns (HostNow=1 727 312 851 448 000 000 ns HostLast=1 727 267 435 015 000 000 ns) 2024-09-25 18:08:26 (80127): Guest Log: 03:28:54.913471 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 329 578 851 000ns (GuestNow=1 727 267 445 354 661 000 ns GuestLast=1 727 260 115 775 810 000 ns fSetTimeLastLoop=true ) 2024-09-25 18:08:26 (80127): VM state change detected. (old = 'poweredoff', new = 'running') 2024-09-25 18:08:26 (80127): Detected: Web Application Enabled (http://localhost:50754) 2024-09-25 18:08:27 (80127): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 18:08:46 (80127): Status Report: Elapsed Time: '14770.958144' 2024-09-25 18:08:46 (80127): Status Report: CPU Time: '27077.650000' 2024-09-25 18:08:46 (80127): Preference change detected 2024-09-25 18:08:46 (80127): Setting CPU throttle for VM. (80%) 2024-09-25 18:08:47 (80127): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 900 seconds)) 2024-09-25 18:08:47 (80127): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 18:08:50 (80127): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 03:53:15 (80127): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 03:53:21 (80127): Guest Log: 03:29:04.917829 timesync vgsvcTimeSyncWorker: Radical host time change: 35 145 963 000 000ns (HostNow=1 727 347 997 411 000 000 ns HostLast=1 727 312 851 448 000 000 ns) 2024-09-26 03:53:21 (80127): Guest Log: 03:29:04.917938 timesync vgsvcTimeSyncWorker: Radical guest time change: 45 416 207 379 000ns (GuestNow=1 727 312 861 562 040 000 ns GuestLast=1 727 267 445 354 661 000 ns fSetTimeLastLoop=true ) 2024-09-26 03:53:31 (80127): Guest Log: 03:29:14.924599 timesync vgsvcTimeSyncWorker: Radical guest time change: 35 145 857 882 000ns (GuestNow=1 727 348 007 419 922 000 ns GuestLast=1 727 312 861 562 040 000 ns fSetTimeLastLoop=true ) 2024-09-26 04:26:37 (80127): Guest Log: 03:30:45.114334 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 888 150 139 000ns (GuestNow=1 727 349 975 687 080 000 ns GuestLast=1 727 348 087 536 941 000 ns fSetTimeLastLoop=true ) 2024-09-26 04:59:41 (80127): Guest Log: 03:32:46.956748 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 846 251 599 000ns (GuestNow=1 727 351 933 758 348 000 ns GuestLast=1 727 350 087 506 749 000 ns fSetTimeLastLoop=true ) 2024-09-26 05:00:02 (80127): Stopping VM. 2024-09-26 05:00:29 (80127): Successfully stopped VM. 2024-09-26 23:15:07 (85276): Detected: vboxwrapper 26206 2024-09-26 23:15:07 (85276): Detected: BOINC client v7.24.2 2024-09-26 23:15:08 (85276): Detected: VirtualBox VboxManage Interface (Version: 7.0.12) 2024-09-26 23:15:08 (85276): Detected: Sandbox Configuration Enabled 2024-09-26 23:15:10 (85276): Guest Log: 03:28:54.913194 timesync vgsvcTimeSyncWorker: Radical host time change: 45 416 433 000 000ns (HostNow=1 727 312 851 448 000 000 ns HostLast=1 727 267 435 015 000 000 ns) 2024-09-26 23:15:10 (85276): Guest Log: 03:28:54.913471 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 329 578 851 000ns (GuestNow=1 727 267 445 354 661 000 ns GuestLast=1 727 260 115 775 810 000 ns fSetTimeLastLoop=true ) 2024-09-26 23:15:10 (85276): Guest Log: 03:29:04.917829 timesync vgsvcTimeSyncWorker: Radical host time change: 35 145 963 000 000ns (HostNow=1 727 347 997 411 000 000 ns HostLast=1 727 312 851 448 000 000 ns) 2024-09-26 23:15:10 (85276): Guest Log: 03:29:04.917938 timesync vgsvcTimeSyncWorker: Radical guest time change: 45 416 207 379 000ns (GuestNow=1 727 312 861 562 040 000 ns GuestLast=1 727 267 445 354 661 000 ns fSetTimeLastLoop=true ) 2024-09-26 23:15:10 (85276): Guest Log: 03:29:14.924599 timesync vgsvcTimeSyncWorker: Radical guest time change: 35 145 857 882 000ns (GuestNow=1 727 348 007 419 922 000 ns GuestLast=1 727 312 861 562 040 000 ns fSetTimeLastLoop=true ) 2024-09-26 23:15:10 (85276): Guest Log: 03:30:45.114334 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 888 150 139 000ns (GuestNow=1 727 349 975 687 080 000 ns GuestLast=1 727 348 087 536 941 000 ns fSetTimeLastLoop=true ) 2024-09-26 23:15:10 (85276): Guest Log: 03:32:46.956748 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 846 251 599 000ns (GuestNow=1 727 351 933 758 348 000 ns GuestLast=1 727 350 087 506 749 000 ns fSetTimeLastLoop=true ) 2024-09-26 23:15:10 (85276): Starting VM using VBoxManage interface. (boinc_71ea3e0dec4452e7, slot#6) 2024-09-26 23:15:27 (85276): Successfully started VM. (PID = '85295') 2024-09-26 23:15:27 (85276): Reporting VM Process ID to BOINC. 2024-09-26 23:15:27 (85276): VM state change detected. (old = 'poweredoff', new = 'running') 2024-09-26 23:15:27 (85276): Detected: Web Application Enabled (http://localhost:50754) 2024-09-26 23:15:28 (85276): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 23:15:30 (85276): Status Report: Elapsed Time: '15021.155790' 2024-09-26 23:15:30 (85276): Status Report: CPU Time: '27550.080000' 2024-09-26 23:15:30 (85276): Preference change detected 2024-09-26 23:15:30 (85276): Setting CPU throttle for VM. (80%) 2024-09-26 23:15:31 (85276): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 900 seconds)) 2024-09-26 23:15:31 (85276): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 23:15:38 (85276): Guest Log: 03:33:17.669816 timesync vgsvcTimeSyncWorker: Radical host time change: 65 729 705 000 000ns (HostNow=1 727 417 721 158 000 000 ns HostLast=1 727 351 991 453 000 000 ns) 2024-09-26 23:31:44 (85276): Guest Log: 03:33:27.689061 timesync vgsvcTimeSyncWorker: Radical guest time change: 65 767 350 398 000ns (GuestNow=1 727 417 731 499 735 000 ns GuestLast=1 727 351 964 149 337 000 ns fSetTimeLastLoop=true ) 2024-09-26 23:32:01 (85276): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 00:07:21 (85276): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 00:07:25 (85276): Guest Log: 03:33:48.487712 timesync vgsvcTimeSyncWorker: Radical host time change: 2 128 543 000 000ns (HostNow=1 727 420 811 278 000 000 ns HostLast=1 727 418 682 735 000 000 ns) 2024-09-27 00:07:26 (85276): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 00:34:29 (85276): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 00:34:38 (85276): Guest Log: 03:33:58.518944 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 070 020 948 000ns (GuestNow=1 727 420 821 807 184 000 ns GuestLast=1 727 417 751 786 236 000 ns fSetTimeLastLoop=true ) 2024-09-27 01:10:30 (85276): Guest Log: 03:35:29.919848 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 075 259 156 000ns (GuestNow=1 727 424 592 332 786 000 ns GuestLast=1 727 422 517 073 630 000 ns fSetTimeLastLoop=true ) 2024-09-27 02:10:02 (85276): Guest Log: 03:40:23.478848 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 070 263 707 000ns (GuestNow=1 727 428 159 467 987 000 ns GuestLast=1 727 426 089 204 280 000 ns fSetTimeLastLoop=true ) 2024-09-27 02:43:14 (85276): Guest Log: 03:43:04.342648 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 850 539 419 000ns (GuestNow=1 727 430 160 813 098 000 ns GuestLast=1 727 428 310 273 679 000 ns fSetTimeLastLoop=true ) 2024-09-27 03:18:27 (85276): Guest Log: 03:45:45.050709 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 946 845 079 000ns (GuestNow=1 727 432 258 244 770 000 ns GuestLast=1 727 430 311 399 691 000 ns fSetTimeLastLoop=true ) 2024-09-27 03:54:59 (85276): Guest Log: 03:48:45.594226 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 061 975 796 000ns (GuestNow=1 727 434 490 683 375 000 ns GuestLast=1 727 432 428 707 579 000 ns fSetTimeLastLoop=true ) 2024-09-27 04:29:47 (85276): Guest Log: 03:51:05.900514 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 964 268 387 000ns (GuestNow=1 727 436 585 196 901 000 ns GuestLast=1 727 434 620 928 514 000 ns fSetTimeLastLoop=true ) 2024-09-27 05:07:04 (85276): Stopping VM. 2024-09-27 05:07:44 (85276): Successfully stopped VM. 2024-09-27 23:32:57 (90248): Detected: vboxwrapper 26206 2024-09-27 23:32:57 (90248): Detected: BOINC client v7.24.2 2024-09-27 23:33:02 (90248): Detected: VirtualBox VboxManage Interface (Version: 7.0.12) 2024-09-27 23:33:02 (90248): Detected: Sandbox Configuration Enabled 2024-09-27 23:33:03 (90248): Guest Log: 03:33:17.669816 timesync vgsvcTimeSyncWorker: Radical host time change: 65 729 705 000 000ns (HostNow=1 727 417 721 158 000 000 ns HostLast=1 727 351 991 453 000 000 ns) 2024-09-27 23:33:03 (90248): Guest Log: 03:33:27.689061 timesync vgsvcTimeSyncWorker: Radical guest time change: 65 767 350 398 000ns (GuestNow=1 727 417 731 499 735 000 ns GuestLast=1 727 351 964 149 337 000 ns fSetTimeLastLoop=true ) 2024-09-27 23:33:03 (90248): Guest Log: 03:33:48.487712 timesync vgsvcTimeSyncWorker: Radical host time change: 2 128 543 000 000ns (HostNow=1 727 420 811 278 000 000 ns HostLast=1 727 418 682 735 000 000 ns) 2024-09-27 23:33:03 (90248): Guest Log: 03:33:58.518944 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 070 020 948 000ns (GuestNow=1 727 420 821 807 184 000 ns GuestLast=1 727 417 751 786 236 000 ns fSetTimeLastLoop=true ) 2024-09-27 23:33:03 (90248): Guest Log: 03:35:29.919848 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 075 259 156 000ns (GuestNow=1 727 424 592 332 786 000 ns GuestLast=1 727 422 517 073 630 000 ns fSetTimeLastLoop=true ) 2024-09-27 23:33:03 (90248): Guest Log: 03:40:23.478848 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 070 263 707 000ns (GuestNow=1 727 428 159 467 987 000 ns GuestLast=1 727 426 089 204 280 000 ns fSetTimeLastLoop=true ) 2024-09-27 23:33:03 (90248): Guest Log: 03:43:04.342648 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 850 539 419 000ns (GuestNow=1 727 430 160 813 098 000 ns GuestLast=1 727 428 310 273 679 000 ns fSetTimeLastLoop=true ) 2024-09-27 23:33:03 (90248): Guest Log: 03:45:45.050709 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 946 845 079 000ns (GuestNow=1 727 432 258 244 770 000 ns GuestLast=1 727 430 311 399 691 000 ns fSetTimeLastLoop=true ) 2024-09-27 23:33:03 (90248): Guest Log: 03:48:45.594226 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 061 975 796 000ns (GuestNow=1 727 434 490 683 375 000 ns GuestLast=1 727 432 428 707 579 000 ns fSetTimeLastLoop=true ) 2024-09-27 23:33:03 (90248): Guest Log: 03:51:05.900514 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 964 268 387 000ns (GuestNow=1 727 436 585 196 901 000 ns GuestLast=1 727 434 620 928 514 000 ns fSetTimeLastLoop=true ) 2024-09-27 23:33:03 (90248): Starting VM using VBoxManage interface. (boinc_71ea3e0dec4452e7, slot#6) 2024-09-27 23:33:37 (90248): Successfully started VM. (PID = '90266') 2024-09-27 23:33:37 (90248): Reporting VM Process ID to BOINC. 2024-09-27 23:33:37 (90248): VM state change detected. (old = 'poweredoff', new = 'running') 2024-09-27 23:33:37 (90248): Detected: Web Application Enabled (http://localhost:50754) 2024-09-27 23:33:38 (90248): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 23:50:16 (90248): Status Report: Elapsed Time: '16306.449215' 2024-09-27 23:50:16 (90248): Status Report: CPU Time: '30083.470000' 2024-09-27 23:50:16 (90248): Preference change detected 2024-09-27 23:50:16 (90248): Setting CPU throttle for VM. (80%) 2024-09-27 23:50:16 (90248): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 900 seconds)) 2024-09-27 23:50:16 (90248): Guest Log: 03:54:56.859771 timesync vgsvcTimeSyncWorker: Radical host time change: 67 376 865 000 000ns (HostNow=1 727 506 188 613 000 000 ns HostLast=1 727 438 811 748 000 000 ns) 2024-09-27 23:50:16 (90248): Guest Log: 03:54:56.860000 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 015 674 604 000ns (GuestNow=1 727 438 821 789 159 000 ns GuestLast=1 727 436 806 114 555 000 ns fSetTimeLastLoop=true ) 2024-09-27 23:50:16 (90248): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 23:50:21 (90248): Guest Log: 03:55:06.865752 timesync vgsvcTimeSyncWorker: Radical guest time change: 67 376 830 297 000ns (GuestNow=1 727 506 198 619 456 000 ns GuestLast=1 727 438 821 789 159 000 ns fSetTimeLastLoop=true ) 2024-09-28 00:16:14 (90248): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 01:09:25 (90248): Guest Log: 03:58:17.666051 timesync vgsvcTimeSyncWorker: Radical host time change: 3 192 050 000 000ns (HostNow=1 727 510 910 913 000 000 ns HostLast=1 727 507 718 863 000 000 ns) 2024-09-28 01:09:25 (90248): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 01:09:33 (90248): Guest Log: 03:58:27.668336 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 194 521 374 000ns (GuestNow=1 727 510 920 914 294 000 ns GuestLast=1 727 507 726 392 920 000 ns fSetTimeLastLoop=true ) 2024-09-28 02:06:52 (90248): Guest Log: 04:03:29.026944 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 893 745 734 000ns (GuestNow=1 727 514 369 224 177 000 ns GuestLast=1 727 512 475 478 443 000 ns fSetTimeLastLoop=true ) 2024-09-28 02:40:25 (90248): Guest Log: 04:06:29.672102 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 874 317 173 000ns (GuestNow=1 727 516 414 050 922 000 ns GuestLast=1 727 514 539 733 749 000 ns fSetTimeLastLoop=true ) 2024-09-28 03:17:07 (90248): Guest Log: 04:09:20.192648 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 040 388 345 000ns (GuestNow=1 727 518 614 946 887 000 ns GuestLast=1 727 516 574 558 542 000 ns fSetTimeLastLoop=true ) 2024-09-28 03:17:59 (90248): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 03:18:21 (90248): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 03:54:10 (90248): Guest Log: 04:12:30.847609 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 002 351 414 000ns (GuestNow=1 727 520 797 895 097 000 ns GuestLast=1 727 518 795 543 683 000 ns fSetTimeLastLoop=true ) 2024-09-28 04:28:04 (90248): Guest Log: 04:15:11.309467 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 928 239 877 000ns (GuestNow=1 727 522 876 562 768 000 ns GuestLast=1 727 520 948 322 891 000 ns fSetTimeLastLoop=true ) 2024-09-28 05:02:14 (90248): Stopping VM. 2024-09-28 05:02:50 (90248): Successfully stopped VM. 2024-09-28 23:51:08 (94246): Detected: vboxwrapper 26206 2024-09-28 23:51:08 (94246): Detected: BOINC client v7.24.2 2024-09-28 23:51:09 (94246): Detected: VirtualBox VboxManage Interface (Version: 7.0.12) 2024-09-28 23:51:09 (94246): Detected: Sandbox Configuration Enabled 2024-09-28 23:51:10 (94246): Guest Log: 03:54:56.859771 timesync vgsvcTimeSyncWorker: Radical host time change: 67 376 865 000 000ns (HostNow=1 727 506 188 613 000 000 ns HostLast=1 727 438 811 748 000 000 ns) 2024-09-28 23:51:10 (94246): Guest Log: 03:54:56.860000 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 015 674 604 000ns (GuestNow=1 727 438 821 789 159 000 ns GuestLast=1 727 436 806 114 555 000 ns fSetTimeLastLoop=true ) 2024-09-28 23:51:10 (94246): Guest Log: 03:55:06.865752 timesync vgsvcTimeSyncWorker: Radical guest time change: 67 376 830 297 000ns (GuestNow=1 727 506 198 619 456 000 ns GuestLast=1 727 438 821 789 159 000 ns fSetTimeLastLoop=true ) 2024-09-28 23:51:10 (94246): Guest Log: 03:58:17.666051 timesync vgsvcTimeSyncWorker: Radical host time change: 3 192 050 000 000ns (HostNow=1 727 510 910 913 000 000 ns HostLast=1 727 507 718 863 000 000 ns) 2024-09-28 23:51:10 (94246): Guest Log: 03:58:27.668336 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 194 521 374 000ns (GuestNow=1 727 510 920 914 294 000 ns GuestLast=1 727 507 726 392 920 000 ns fSetTimeLastLoop=true ) 2024-09-28 23:51:10 (94246): Guest Log: 04:03:29.026944 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 893 745 734 000ns (GuestNow=1 727 514 369 224 177 000 ns GuestLast=1 727 512 475 478 443 000 ns fSetTimeLastLoop=true ) 2024-09-28 23:51:10 (94246): Guest Log: 04:06:29.672102 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 874 317 173 000ns (GuestNow=1 727 516 414 050 922 000 ns GuestLast=1 727 514 539 733 749 000 ns fSetTimeLastLoop=true ) 2024-09-28 23:51:10 (94246): Guest Log: 04:09:20.192648 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 040 388 345 000ns (GuestNow=1 727 518 614 946 887 000 ns GuestLast=1 727 516 574 558 542 000 ns fSetTimeLastLoop=true ) 2024-09-28 23:51:10 (94246): Guest Log: 04:12:30.847609 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 002 351 414 000ns (GuestNow=1 727 520 797 895 097 000 ns GuestLast=1 727 518 795 543 683 000 ns fSetTimeLastLoop=true ) 2024-09-28 23:51:10 (94246): Guest Log: 04:15:11.309467 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 928 239 877 000ns (GuestNow=1 727 522 876 562 768 000 ns GuestLast=1 727 520 948 322 891 000 ns fSetTimeLastLoop=true ) 2024-09-28 23:51:10 (94246): Starting VM using VBoxManage interface. (boinc_71ea3e0dec4452e7, slot#6) 2024-09-29 00:09:38 (94246): Successfully started VM. (PID = '94264') 2024-09-29 00:09:38 (94246): Reporting VM Process ID to BOINC. 2024-09-29 00:09:38 (94246): VM state change detected. (old = 'poweredoff', new = 'running') 2024-09-29 00:09:38 (94246): Detected: Web Application Enabled (http://localhost:50754) 2024-09-29 00:09:39 (94246): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 00:10:00 (94246): Status Report: Elapsed Time: '17669.672741' 2024-09-29 00:10:00 (94246): Status Report: CPU Time: '32817.730000' 2024-09-29 00:10:00 (94246): Preference change detected 2024-09-29 00:10:00 (94246): Setting CPU throttle for VM. (80%) 2024-09-29 00:10:00 (94246): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 900 seconds)) 2024-09-29 00:10:01 (94246): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 00:10:02 (94246): Guest Log: 04:18:01.976096 timesync vgsvcTimeSyncWorker: Radical host time change: 68 861 918 000 000ns (HostNow=1 727 593 762 378 000 000 ns HostLast=1 727 524 900 460 000 000 ns) 2024-09-29 00:10:02 (94246): Guest Log: 04:18:01.976441 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 873 253 571 000ns (GuestNow=1 727 524 910 467 009 000 ns GuestLast=1 727 523 037 213 438 000 ns fSetTimeLastLoop=true ) 2024-09-29 00:10:12 (94246): Guest Log: 04:18:11.989417 timesync vgsvcTimeSyncWorker: Radical guest time change: 68 861 932 969 000ns (GuestNow=1 727 593 772 399 978 000 ns GuestLast=1 727 524 910 467 009 000 ns fSetTimeLastLoop=true ) 2024-09-29 01:06:59 (94246): Guest Log: 04:22:24.088489 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 967 586 758 000ns (GuestNow=1 727 597 218 210 322 000 ns GuestLast=1 727 595 250 623 564 000 ns fSetTimeLastLoop=true ) 2024-09-29 02:09:40 (94246): Guest Log: 04:26:45.503147 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 939 428 824 000ns (GuestNow=1 727 600 936 199 931 000 ns GuestLast=1 727 598 996 771 107 000 ns fSetTimeLastLoop=true ) 2024-09-29 02:41:57 (94246): Guest Log: 04:28:35.832039 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 861 111 392 000ns (GuestNow=1 727 602 897 614 604 000 ns GuestLast=1 727 601 036 503 212 000 ns fSetTimeLastLoop=true ) 2024-09-29 03:15:26 (94246): Guest Log: 04:31:27.182283 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 831 077 611 000ns (GuestNow=1 727 604 890 025 243 000 ns GuestLast=1 727 603 058 947 632 000 ns fSetTimeLastLoop=true ) 2024-09-29 03:49:50 (94246): Guest Log: 04:33:57.973757 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 917 695 164 000ns (GuestNow=1 727 606 948 509 925 000 ns GuestLast=1 727 605 030 814 761 000 ns fSetTimeLastLoop=true ) 2024-09-29 04:25:38 (94246): Guest Log: 04:36:38.483045 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 982 774 249 000ns (GuestNow=1 727 609 081 750 400 000 ns GuestLast=1 727 607 098 976 151 000 ns fSetTimeLastLoop=true ) 2024-09-29 05:02:16 (94246): Stopping VM. 2024-09-29 05:02:52 (94246): Successfully stopped VM. 2024-09-29 21:47:07 (98543): Detected: vboxwrapper 26206 2024-09-29 21:47:07 (98543): Detected: BOINC client v7.24.2 2024-09-29 21:47:09 (98543): Detected: VirtualBox VboxManage Interface (Version: 7.0.12) 2024-09-29 21:47:09 (98543): Detected: Sandbox Configuration Enabled 2024-09-29 21:47:10 (98543): Guest Log: 04:18:01.976096 timesync vgsvcTimeSyncWorker: Radical host time change: 68 861 918 000 000ns (HostNow=1 727 593 762 378 000 000 ns HostLast=1 727 524 900 460 000 000 ns) 2024-09-29 21:47:10 (98543): Guest Log: 04:18:01.976441 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 873 253 571 000ns (GuestNow=1 727 524 910 467 009 000 ns GuestLast=1 727 523 037 213 438 000 ns fSetTimeLastLoop=true ) 2024-09-29 21:47:10 (98543): Guest Log: 04:18:11.989417 timesync vgsvcTimeSyncWorker: Radical guest time change: 68 861 932 969 000ns (GuestNow=1 727 593 772 399 978 000 ns GuestLast=1 727 524 910 467 009 000 ns fSetTimeLastLoop=true ) 2024-09-29 21:47:10 (98543): Guest Log: 04:22:24.088489 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 967 586 758 000ns (GuestNow=1 727 597 218 210 322 000 ns GuestLast=1 727 595 250 623 564 000 ns fSetTimeLastLoop=true ) 2024-09-29 21:47:10 (98543): Guest Log: 04:26:45.503147 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 939 428 824 000ns (GuestNow=1 727 600 936 199 931 000 ns GuestLast=1 727 598 996 771 107 000 ns fSetTimeLastLoop=true ) 2024-09-29 21:47:10 (98543): Guest Log: 04:28:35.832039 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 861 111 392 000ns (GuestNow=1 727 602 897 614 604 000 ns GuestLast=1 727 601 036 503 212 000 ns fSetTimeLastLoop=true ) 2024-09-29 21:47:10 (98543): Guest Log: 04:31:27.182283 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 831 077 611 000ns (GuestNow=1 727 604 890 025 243 000 ns GuestLast=1 727 603 058 947 632 000 ns fSetTimeLastLoop=true ) 2024-09-29 21:47:10 (98543): Guest Log: 04:33:57.973757 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 917 695 164 000ns (GuestNow=1 727 606 948 509 925 000 ns GuestLast=1 727 605 030 814 761 000 ns fSetTimeLastLoop=true ) 2024-09-29 21:47:10 (98543): Guest Log: 04:36:38.483045 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 982 774 249 000ns (GuestNow=1 727 609 081 750 400 000 ns GuestLast=1 727 607 098 976 151 000 ns fSetTimeLastLoop=true ) 2024-09-29 21:47:10 (98543): Starting VM using VBoxManage interface. (boinc_71ea3e0dec4452e7, slot#6) 2024-09-29 21:47:27 (98543): Successfully started VM. (PID = '98561') 2024-09-29 21:47:27 (98543): Reporting VM Process ID to BOINC. 2024-09-29 21:47:27 (98543): VM state change detected. (old = 'poweredoff', new = 'running') 2024-09-29 21:47:27 (98543): Detected: Web Application Enabled (http://localhost:50754) 2024-09-29 21:47:28 (98543): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 22:33:10 (98543): Status Report: Elapsed Time: '18911.489719' 2024-09-29 22:33:10 (98543): Status Report: CPU Time: '35800.930000' 2024-09-29 22:33:10 (98543): Preference change detected 2024-09-29 22:33:10 (98543): Setting CPU throttle for VM. (80%) 2024-09-29 22:33:10 (98543): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 900 seconds)) 2024-09-29 22:33:10 (98543): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 22:33:11 (98543): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 00:34:03 (98543): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 00:34:09 (98543): Guest Log: 04:39:19.236684 timesync vgsvcTimeSyncWorker: Radical host time change: 70 303 203 000 000ns (HostNow=1 727 681 627 798 000 000 ns HostLast=1 727 611 324 595 000 000 ns) 2024-09-30 00:34:09 (98543): Guest Log: 04:39:19.236925 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 102 264 887 000ns (GuestNow=1 727 611 334 643 908 000 ns GuestLast=1 727 609 232 379 021 000 ns fSetTimeLastLoop=true ) 2024-09-30 00:34:19 (98543): Guest Log: 04:39:29.246357 timesync vgsvcTimeSyncWorker: Radical guest time change: 70 303 239 707 000ns (GuestNow=1 727 681 637 883 615 000 ns GuestLast=1 727 611 334 643 908 000 ns fSetTimeLastLoop=true ) 2024-09-30 02:09:19 (98543): Guest Log: 04:46:00.620877 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 975 639 891 000ns (GuestNow=1 727 687 348 949 677 000 ns GuestLast=1 727 685 373 309 786 000 ns fSetTimeLastLoop=true ) 2024-09-30 02:45:03 (98543): Guest Log: 04:48:11.014440 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 025 817 868 000ns (GuestNow=1 727 689 495 148 053 000 ns GuestLast=1 727 687 469 330 185 000 ns fSetTimeLastLoop=true ) 2024-09-30 03:19:51 (98543): Guest Log: 04:50:21.431579 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 970 512 649 000ns (GuestNow=1 727 691 586 061 919 000 ns GuestLast=1 727 689 615 549 270 000 ns fSetTimeLastLoop=true ) 2024-09-30 03:56:45 (98543): Guest Log: 04:53:52.827811 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 004 724 912 000ns (GuestNow=1 727 693 792 161 704 000 ns GuestLast=1 727 691 787 436 792 000 ns fSetTimeLastLoop=true ) 2024-09-30 04:32:46 (98543): Guest Log: 04:56:33.180389 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 986 724 864 000ns (GuestNow=1 727 695 929 175 554 000 ns GuestLast=1 727 693 942 450 690 000 ns fSetTimeLastLoop=true ) 2024-09-30 05:07:35 (98543): Stopping VM. 2024-09-30 05:08:12 (98543): Successfully stopped VM. 2024-09-30 17:03:27 (5568): Detected: vboxwrapper 26206 2024-09-30 17:03:27 (5568): Detected: BOINC client v7.24.2 2024-09-30 17:03:32 (5568): Detected: VirtualBox VboxManage Interface (Version: 7.0.12) 2024-09-30 17:03:32 (5568): Detected: Sandbox Configuration Enabled 2024-09-30 17:03:33 (5568): Guest Log: 04:39:19.236684 timesync vgsvcTimeSyncWorker: Radical host time change: 70 303 203 000 000ns (HostNow=1 727 681 627 798 000 000 ns HostLast=1 727 611 324 595 000 000 ns) 2024-09-30 17:03:33 (5568): Guest Log: 04:39:19.236925 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 102 264 887 000ns (GuestNow=1 727 611 334 643 908 000 ns GuestLast=1 727 609 232 379 021 000 ns fSetTimeLastLoop=true ) 2024-09-30 17:03:33 (5568): Guest Log: 04:39:29.246357 timesync vgsvcTimeSyncWorker: Radical guest time change: 70 303 239 707 000ns (GuestNow=1 727 681 637 883 615 000 ns GuestLast=1 727 611 334 643 908 000 ns fSetTimeLastLoop=true ) 2024-09-30 17:03:33 (5568): Guest Log: 04:46:00.620877 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 975 639 891 000ns (GuestNow=1 727 687 348 949 677 000 ns GuestLast=1 727 685 373 309 786 000 ns fSetTimeLastLoop=true ) 2024-09-30 17:03:33 (5568): Guest Log: 04:48:11.014440 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 025 817 868 000ns (GuestNow=1 727 689 495 148 053 000 ns GuestLast=1 727 687 469 330 185 000 ns fSetTimeLastLoop=true ) 2024-09-30 17:03:33 (5568): Guest Log: 04:50:21.431579 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 970 512 649 000ns (GuestNow=1 727 691 586 061 919 000 ns GuestLast=1 727 689 615 549 270 000 ns fSetTimeLastLoop=true ) 2024-09-30 17:03:33 (5568): Guest Log: 04:53:52.827811 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 004 724 912 000ns (GuestNow=1 727 693 792 161 704 000 ns GuestLast=1 727 691 787 436 792 000 ns fSetTimeLastLoop=true ) 2024-09-30 17:03:33 (5568): Guest Log: 04:56:33.180389 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 986 724 864 000ns (GuestNow=1 727 695 929 175 554 000 ns GuestLast=1 727 693 942 450 690 000 ns fSetTimeLastLoop=true ) 2024-09-30 17:03:34 (5568): Starting VM using VBoxManage interface. (boinc_71ea3e0dec4452e7, slot#6) 2024-09-30 17:23:51 (5568): Successfully started VM. (PID = '5587') 2024-09-30 17:23:51 (5568): Reporting VM Process ID to BOINC. 2024-09-30 17:23:51 (5568): VM state change detected. (old = 'poweredoff', new = 'running') 2024-09-30 17:23:51 (5568): Detected: Web Application Enabled (http://localhost:50754) 2024-09-30 17:23:52 (5568): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 17:23:57 (5568): Status Report: Elapsed Time: '20084.247487' 2024-09-30 17:23:57 (5568): Status Report: CPU Time: '39544.210000' 2024-09-30 17:23:57 (5568): Preference change detected 2024-09-30 17:23:57 (5568): Setting CPU throttle for VM. (80%) 2024-09-30 17:23:57 (5568): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 900 seconds)) 2024-09-30 17:23:57 (5568): Guest Log: 04:59:05.012055 timesync vgsvcTimeSyncWorker: Radical host time change: 44 239 613 000 000ns (HostNow=1 727 742 235 600 000 000 ns HostLast=1 727 697 995 987 000 000 ns) 2024-09-30 17:23:57 (5568): Guest Log: 04:59:05.012480 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 935 539 542 000ns (GuestNow=1 727 698 006 196 148 000 ns GuestLast=1 727 696 070 656 606 000 ns fSetTimeLastLoop=true ) 2024-09-30 17:23:57 (5568): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 17:24:08 (5568): Guest Log: 04:59:15.069710 timesync vgsvcTimeSyncWorker: Radical guest time change: 44 239 603 010 000ns (GuestNow=1 727 742 245 799 158 000 ns GuestLast=1 727 698 006 196 148 000 ns fSetTimeLastLoop=true ) 2024-09-30 17:42:43 (5568): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 20:05:31 (5568): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 20:05:32 (5568): Guest Log: 05:00:25.753660 timesync vgsvcTimeSyncWorker: Radical host time change: 8 578 428 000 000ns (HostNow=1 727 751 904 091 000 000 ns HostLast=1 727 743 325 663 000 000 ns) 2024-09-30 20:05:33 (5568): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 20:23:21 (5568): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 20:23:22 (5568): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 21:06:33 (5568): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 21:06:41 (5568): Guest Log: 05:00:35.762741 timesync vgsvcTimeSyncWorker: Radical host time change: 3 669 847 000 000ns (HostNow=1 727 755 573 938 000 000 ns HostLast=1 727 751 904 091 000 000 ns) 2024-09-30 21:06:41 (5568): Guest Log: 05:00:35.762849 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 597 812 788 000ns (GuestNow=1 727 751 914 197 998 000 ns GuestLast=1 727 742 316 385 210 000 ns fSetTimeLastLoop=true ) 2024-09-30 21:06:51 (5568): Guest Log: 05:00:45.800172 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 669 777 698 000ns (GuestNow=1 727 755 583 975 696 000 ns GuestLast=1 727 751 914 197 998 000 ns fSetTimeLastLoop=true ) 2024-09-30 21:22:23 (5568): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 21:23:25 (5568): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 21:39:13 (5568): Guest Log: 05:01:56.036053 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 886 692 490 000ns (GuestNow=1 727 757 530 902 521 000 ns GuestLast=1 727 755 644 210 031 000 ns fSetTimeLastLoop=true ) 2024-09-30 21:39:41 (5568): Guest Log: *** Job finished *** 2024-09-30 21:39:41 (5568): Guest Log: *** The last 20 lines of the pilot log: *** 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:03,772 | INFO | generated guid for lfn=HITS.41078631._019714.pool.root.1: CAAC0FF8-EAE5-4F34-B5C6-7A1A1090C542 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:03,773 | WARNING | aborting payload error diagnosis since an error has already been set: [1315, 1187] 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:04,632 | INFO | [job] job monitor thread has finished 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:04,886 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140328445114176)>', '<ExcThread(execute_payloads, started 140328166213376)>'] 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:04,886 | INFO | [payload] execute_payloads thread has finished 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:05,525 | INFO | caller=run is remaining thread - safe to abort (names=['<_MainThread(MainThread, started 140328445114176)>']) 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:10,530 | INFO | all workflow threads have been joined 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:10,530 | INFO | end of generic workflow (traces error code: 0) 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:10,531 | INFO | traces error code: 0 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:10,531 | INFO | pilot has finished (exit code=0, shell exit code=0) 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:11,411 [wrapper] ==== pilot stdout END ==== 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:11,428 [wrapper] ==== wrapper stdout RESUME ==== 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:11,568 [wrapper] pilotpid: 5937 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:11,596 [wrapper] Pilot exit status: 0 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:12,481 [wrapper] pandaids: 6341577803 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:12,607 [wrapper] cleanup supervisor_pilot 28926 5938 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:12,658 [wrapper] Test setup, not cleaning 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:12,681 [wrapper] ==== wrapper stdout END ==== 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:12,712 [wrapper] ==== wrapper stderr END ==== 2024-09-30 21:39:41 (5568): Guest Log: 2024-10-01 04:39:12,774 [wrapper] apfmon messages muted 2024-09-30 21:39:41 (5568): Guest Log: *** Error codes and diagnostics *** 2024-09-30 21:39:41 (5568): Guest Log: *** Listing of results directory *** 2024-09-30 21:39:41 (5568): Guest Log: total 5796 2024-09-30 21:39:41 (5568): Guest Log: -rw-r--r--. 1 atlas atlas 482533 Sep 22 22:23 pilot3.tar.gz 2024-09-30 21:39:41 (5568): Guest Log: -rw-r--r--. 1 atlas atlas 5655 Sep 22 22:28 queuedata.json 2024-09-30 21:39:41 (5568): Guest Log: -rwx------. 1 atlas atlas 33679 Sep 22 22:29 runpilot2-wrapper.sh 2024-09-30 21:39:41 (5568): Guest Log: -rw-r-----. 1 atlas atlas 17539 Sep 23 02:15 start_atlas.sh 2024-09-30 21:39:41 (5568): Guest Log: -rw-r-----. 1 atlas atlas 495957 Sep 23 02:15 input.tar.gz 2024-09-30 21:39:41 (5568): Guest Log: -rw-r--r--. 1 atlas atlas 8508 Sep 23 02:15 init_data.xml 2024-09-30 21:39:41 (5568): Guest Log: lrwxrwxrwx. 1 atlas atlas 20 Sep 23 02:15 EVNT.41078629._000745.pool.root.1 -> /data/./ATLAS.root_0 2024-09-30 21:39:41 (5568): Guest Log: -rw-r--r--. 1 atlas atlas 2555 Sep 23 02:15 pandaJob.out 2024-09-30 21:39:41 (5568): Guest Log: -rw-------. 1 atlas atlas 424 Sep 23 02:15 setup.sh.local 2024-09-30 21:39:41 (5568): Guest Log: -rw-------. 1 atlas atlas 1009807 Sep 23 02:16 agis_schedconf.cvmfs.json 2024-09-30 21:39:41 (5568): Guest Log: -rw-------. 1 atlas atlas 1618603 Sep 23 02:16 agis_ddmendpoints.agis.ALL.json 2024-09-30 21:39:41 (5568): Guest Log: drwx------. 4 atlas atlas 4096 Sep 23 02:16 pilot3 2024-09-30 21:39:41 (5568): Guest Log: -rw-------. 1 atlas atlas 98 Oct 1 00:24 pilot_heartbeat.json 2024-09-30 21:39:41 (5568): Guest Log: drwxrwx---. 2 atlas atlas 4096 Oct 1 04:06 PanDA_Pilot-6341577803 2024-09-30 21:39:41 (5568): Guest Log: -rw-------. 1 atlas atlas 1047 Oct 1 04:06 memory_monitor_summary.json 2024-09-30 21:39:41 (5568): Guest Log: -rw-------. 1 atlas atlas 1198 Oct 1 04:39 heartbeat.json 2024-09-30 21:39:41 (5568): Guest Log: -rw-------. 1 atlas atlas 718021 Oct 1 04:39 pilotlog.txt 2024-09-30 21:39:41 (5568): Guest Log: -rw-------. 1 atlas atlas 730718 Oct 1 04:39 log.41078631._019714.job.log.1 2024-09-30 21:39:41 (5568): Guest Log: -rw-r--r--. 1 atlas atlas 571 Oct 1 04:39 runtime_log 2024-09-30 21:39:41 (5568): Guest Log: -rw-------. 1 atlas atlas 737280 Oct 1 04:39 result.tar.gz 2024-09-30 21:39:41 (5568): Guest Log: -rw-r--r--. 1 atlas atlas 10272 Oct 1 04:39 runtime_log.err 2024-09-30 21:39:41 (5568): Guest Log: -rw-------. 1 atlas atlas 645 Oct 1 04:39 RFkKDmoFkC6n9Rq4apOajLDm4fhM0noT9bVorHsSDmisVKDmlsn7Yn.diag 2024-09-30 21:39:41 (5568): Guest Log: Looking for outputfile HITS.41078631._019714.pool.root.1 2024-09-30 21:39:41 (5568): Guest Log: No HITS file was produced 2024-09-30 21:39:41 (5568): Guest Log: Successfully finished the ATLAS job! 2024-09-30 21:39:41 (5568): Guest Log: Copying the results back to the shared directory! 2024-09-30 21:39:42 (5568): Guest Log: *** Contents of shared directory: *** 2024-09-30 21:39:42 (5568): Guest Log: total 434164 2024-09-30 21:39:42 (5568): Guest Log: -rw-rw----. 1 root root 443311981 Sep 23 00:08 ATLAS.root_0 2024-09-30 21:39:42 (5568): Guest Log: -rw-rw-r--. 1 root root 8508 Sep 23 00:08 init_data.xml 2024-09-30 21:39:42 (5568): Guest Log: -rw-rw----. 1 root root 495957 Sep 23 00:08 input.tar.gz 2024-09-30 21:39:42 (5568): Guest Log: -rw-r--r--. 1 root root 737280 Oct 1 2024 result.tar.gz 2024-09-30 21:39:42 (5568): Guest Log: -rw-rw----. 1 root root 17539 Sep 23 00:08 start_atlas.sh 2024-09-30 21:39:42 (5568): Guest Log: *** Success! Shutting down the machine. *** 2024-09-30 21:39:42 (5568): VM Completion File Detected. 2024-09-30 21:39:42 (5568): Powering off VM. 2024-09-30 21:39:43 (5568): Successfully stopped VM. 2024-09-30 21:39:43 (5568): Deregistering VM. (boinc_71ea3e0dec4452e7, slot#6) 2024-09-30 21:39:43 (5568): Removing network bandwidth throttle group from VM. 2024-09-30 21:39:43 (5568): Removing VM from VirtualBox. 21:39:49 (5568): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN