| Name | Theory_2390-1122175-316_0 |
| Workunit | 200134806 |
| Created | 18 Dec 2022, 4:22:51 UTC |
| Sent | 18 Dec 2022, 17:20:57 UTC |
| Report deadline | 29 Dec 2022, 17:20:57 UTC |
| Received | 31 Dec 2022, 3:24:07 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10592369 |
| Run time | 20 hours 59 min 55 sec |
| CPU time | 9 hours 25 min 52 sec |
| Validate state | Valid |
| Credit | 806.79 |
| Device peak FLOPS | 4.61 GFLOPS |
| Application version | Theory Simulation v300.07 (vbox64_theory) windows_x86_64 |
| Peak working set size | 84.09 MB |
| Peak swap size | 99.82 MB |
| Peak disk usage | 2.05 GB |
Stderr output
<core_client_version>7.16.11</core_client_version> <![CDATA[ <stderr_txt> (8240): Guest Log: 23:01:53 GMT +00:00 2022-12-23: cranky: [INFO] Updating config.json. 2022-12-28 01:57:31 (8240): Guest Log: 23:01:53 GMT +00:00 2022-12-23: cranky: [INFO] Running Container 'runc'. 2022-12-28 01:57:31 (8240): Guest Log: job: htmld=/shared/html/job 2022-12-28 01:57:31 (8240): Guest Log: job: unpack exitcode=0 2022-12-28 01:57:31 (8240): Guest Log: 23:01:56 GMT +00:00 2022-12-23: cranky: [INFO] ===> [runRivet] Fri Dec 23 23:01:54 UTC 2022 [boinc pp jets 7000 40,-,610 - pythia8 8.244 tune-1 100000 316] 2022-12-28 01:57:31 (8240): Guest Log: 00:32:02.955432 timesync vgsvcTimeSyncWorker: Radical host time change: 4 376 485 000 000ns (HostNow=1 671 842 706 243 000 000 ns HostLast=1 671 838 329 758 000 000 ns) 2022-12-28 01:57:31 (8240): Guest Log: 00:32:13.020095 timesync vgsvcTimeSyncWorker: Radical host time change: 2 816 711 000 000ns (HostNow=1 671 845 522 954 000 000 ns HostLast=1 671 842 706 243 000 000 ns) 2022-12-28 01:57:31 (8240): Guest Log: 00:32:13.020280 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 376 551 194 000ns (GuestNow=1 671 842 716 402 961 000 ns GuestLast=1 671 838 339 851 767 000 ns fSetTimeLastLoop=true ) 2022-12-28 01:57:31 (8240): Guest Log: 00:32:23.021620 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 816 552 599 000ns (GuestNow=1 671 845 532 955 560 000 ns GuestLast=1 671 842 716 402 961 000 ns fSetTimeLastLoop=true ) 2022-12-28 01:57:31 (8240): Starting VM using VBoxManage interface. (boinc_826653789bab990c, slot#1) 2022-12-28 01:57:35 (8240): Successfully started VM. (PID = '12920') 2022-12-28 01:57:35 (8240): Reporting VM Process ID to BOINC. 2022-12-28 01:57:35 (8240): Guest Log: BIOS: VirtualBox 6.1.12 2022-12-28 01:57:35 (8240): Guest Log: CPUID EDX: 0x178bfbff 2022-12-28 01:57:35 (8240): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2022-12-28 01:57:35 (8240): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2022-12-28 01:57:35 (8240): VM state change detected. (old = 'poweredoff', new = 'running') 2022-12-28 01:57:35 (8240): Detected: Web Application Enabled (http://localhost:50500) 2022-12-28 01:57:35 (8240): Detected: Remote Desktop Enabled (localhost:50501) 2022-12-28 01:57:35 (8240): Status Report: Job Duration: '864000.000000' 2022-12-28 01:57:35 (8240): Status Report: Elapsed Time: '9758.000000' 2022-12-28 01:57:35 (8240): Status Report: CPU Time: '4191.640625' 2022-12-28 01:57:35 (8240): Preference change detected 2022-12-28 01:57:35 (8240): Setting CPU throttle for VM. (42%) 2022-12-28 01:57:36 (8240): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 77 seconds) or (Vbox_job.xml: 600 seconds)) 2022-12-28 01:57:38 (8240): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2022-12-28 01:57:38 (8240): Guest Log: BIOS: Booting from Hard Disk... 2022-12-28 01:57:41 (8240): Guest Log: BIOS: KBD: unsupported int 16h function 03 2022-12-28 01:57:41 (8240): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2022-12-28 01:58:22 (8240): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2022-12-28 01:58:22 (8240): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2022-12-28 01:58:33 (8240): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2022-12-28 01:58:33 (8240): Guest Log: 00:00:00.000288 main Log opened 2022-12-28T01:58:33.395807000Z 2022-12-28 01:58:33 (8240): Guest Log: 00:00:00.002373 main OS Product: Linux 2022-12-28 01:58:33 (8240): Guest Log: 00:00:00.002537 main OS Release: 4.14.76-13.cernvm.x86_64 2022-12-28 01:58:33 (8240): Guest Log: 00:00:00.002720 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2022-12-28 01:58:33 (8240): Guest Log: 00:00:00.002834 main Executable: /usr/sbin/VBoxService 2022-12-28 01:58:33 (8240): Guest Log: 00:00:00.002837 main Process ID: 3230 2022-12-28 01:58:33 (8240): Guest Log: 00:00:00.002840 main Package type: LINUX_64BITS_GENERIC 2022-12-28 01:58:33 (8240): Guest Log: 00:00:00.007980 main 5.2.6 r120293 started. Verbose level = 0 2022-12-28 01:59:38 (8240): Guest Log: 01:59:38 GMT +00:00 2022-12-28: cranky: [INFO] Detected Theory App 2022-12-28 01:59:38 (8240): Guest Log: 01:59:38 GMT +00:00 2022-12-28: cranky: [INFO] Checking CVMFS. 2022-12-28 01:59:43 (8240): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2022-12-28 01:59:45 (8240): 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 2022-12-28 01:59:45 (8240): Guest Log: 2.5.2.0 4095 0 27176 24655 3 1 1137071 4096001 0 65024 0 0 n/a 5 13 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2022-12-28 01:59:49 (8240): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2022-12-28 01:59:52 (8240): 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 2022-12-28 01:59:52 (8240): Guest Log: 2.5.2.0 4145 0 27576 20117 3 2 1137071 4096001 0 65024 0 2 0 5672 1657 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2022-12-28 01:59:56 (8240): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2022-12-28 01:59:58 (8240): 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 2022-12-28 01:59:58 (8240): Guest Log: 2.5.2.0 4119 0 27208 268 3 1 1137071 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2022-12-28 02:00:02 (8240): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2022-12-28 02:00:03 (8240): 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 2022-12-28 02:00:03 (8240): Guest Log: 2.5.2.0 4172 0 32056 14206 3 1 1137071 4096001 0 65024 0 0 n/a 15367 1861 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2022-12-28 02:00:03 (8240): Guest Log: 02:00:02 GMT +00:00 2022-12-28: cranky: [INFO] Checking runc. 2022-12-28 02:00:03 (8240): Guest Log: 02:00:03 GMT +00:00 2022-12-28: cranky: [INFO] Creating the filesystem. 2022-12-28 02:00:03 (8240): Guest Log: 02:00:03 GMT +00:00 2022-12-28: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2022-12-28 02:00:03 (8240): Guest Log: 02:00:03 GMT +00:00 2022-12-28: cranky: [INFO] Updating config.json. 2022-12-28 02:00:03 (8240): Guest Log: 02:00:03 GMT +00:00 2022-12-28: cranky: [INFO] Running Container 'runc'. 2022-12-28 02:00:03 (8240): Guest Log: job: htmld=/shared/html/job 2022-12-28 02:00:04 (8240): Guest Log: job: unpack exitcode=0 2022-12-28 02:00:05 (8240): Guest Log: 02:00:05 GMT +00:00 2022-12-28: cranky: [INFO] ===> [runRivet] Wed Dec 28 02:00:03 UTC 2022 [boinc pp jets 7000 40,-,610 - pythia8 8.244 tune-1 100000 316] 2022-12-28 02:04:10 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 02:11:13 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 02:19:15 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 02:22:56 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 03:48:26 (8240): Status Report: Job Duration: '864000.000000' 2022-12-28 03:48:26 (8240): Status Report: Elapsed Time: '15758.000000' 2022-12-28 03:48:26 (8240): Status Report: CPU Time: '6909.781250' 2022-12-28 04:20:06 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 10:15:42 (8240): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_826653789bab990c" resume Output: VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running) VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2022-12-28 10:15:42 (8240): Guest Log: 02:10:55.042220 timesync vgsvcTimeSyncWorker: Radical host time change: 21 337 217 000 000ns (HostNow=1 672 222 535 304 000 000 ns HostLast=1 672 201 198 087 000 000 ns) 2022-12-28 10:15:42 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 10:15:43 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 10:24:02 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 10:24:05 (8240): Guest Log: 02:11:05.069987 timesync vgsvcTimeSyncWorker: Radical guest time change: 21 977 103 850 000ns (GuestNow=1 672 222 545 436 668 000 ns GuestLast=1 672 200 568 332 818 000 ns fSetTimeLastLoop=true ) 2022-12-28 10:30:35 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 11:04:09 (8240): Guest Log: 02:17:35.664406 timesync vgsvcTimeSyncWorker: Radical host time change: 2 023 877 000 000ns (HostNow=1 672 225 448 406 000 000 ns HostLast=1 672 223 424 529 000 000 ns) 2022-12-28 11:04:09 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 11:04:19 (8240): Guest Log: 02:17:45.667128 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 522 377 646 000ns (GuestNow=1 672 225 458 408 729 000 ns GuestLast=1 672 222 936 031 083 000 ns fSetTimeLastLoop=true ) 2022-12-28 11:06:55 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 11:11:08 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 11:11:14 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 11:14:19 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 11:24:47 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 11:28:29 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 11:32:57 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 11:36:16 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 11:40:28 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 11:43:58 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 11:45:32 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 11:48:37 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 12:02:37 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 12:06:39 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 12:11:24 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 12:17:07 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 12:21:07 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 12:26:56 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 12:32:47 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 13:22:03 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 13:22:07 (8240): Guest Log: 03:09:51.859546 timesync vgsvcTimeSyncWorker: Radical host time change: 2 965 288 000 000ns (HostNow=1 672 233 726 831 000 000 ns HostLast=1 672 230 761 543 000 000 ns) 2022-12-28 13:22:17 (8240): Guest Log: 03:10:01.860665 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 898 408 341 000ns (GuestNow=1 672 233 736 832 144 000 ns GuestLast=1 672 229 838 423 803 000 ns fSetTimeLastLoop=true ) 2022-12-28 13:25:40 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 14:41:50 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 14:41:57 (8240): Guest Log: 03:13:32.298544 timesync vgsvcTimeSyncWorker: Radical host time change: 4 579 610 000 000ns (HostNow=1 672 238 516 874 000 000 ns HostLast=1 672 233 937 264 000 000 ns) 2022-12-28 14:42:07 (8240): Guest Log: 03:13:42.343692 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 579 649 169 000ns (GuestNow=1 672 238 526 919 166 000 ns GuestLast=1 672 233 947 269 997 000 ns fSetTimeLastLoop=true ) 2022-12-28 14:42:21 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 14:55:49 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 15:01:34 (8240): Status Report: Job Duration: '864000.000000' 2022-12-28 15:01:34 (8240): Status Report: Elapsed Time: '21758.000000' 2022-12-28 15:01:34 (8240): Status Report: CPU Time: '9638.875000' 2022-12-28 15:05:47 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 15:16:01 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 15:56:54 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 17:30:52 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 17:30:54 (8240): Guest Log: 04:04:48.096974 timesync vgsvcTimeSyncWorker: Radical host time change: 5 648 099 000 000ns (HostNow=1 672 248 653 340 000 000 ns HostLast=1 672 243 005 241 000 000 ns) 2022-12-28 17:31:04 (8240): Guest Log: 04:04:58.126463 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 648 122 705 000ns (GuestNow=1 672 248 663 369 467 000 ns GuestLast=1 672 243 015 246 762 000 ns fSetTimeLastLoop=true ) 2022-12-28 17:40:35 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 19:28:44 (8240): Guest Log: 04:14:29.678011 timesync vgsvcTimeSyncWorker: Radical host time change: 6 498 427 000 000ns (HostNow=1 672 255 723 245 000 000 ns HostLast=1 672 249 224 818 000 000 ns) 2022-12-28 19:28:44 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 19:28:54 (8240): Guest Log: 04:14:39.732504 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 498 378 445 000ns (GuestNow=1 672 255 733 299 506 000 ns GuestLast=1 672 249 234 921 061 000 ns fSetTimeLastLoop=true ) 2022-12-28 19:31:32 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 19:34:36 (8240): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 19:43:55 (8240): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 21:04:11 (11076): Detected: vboxwrapper 26206 2022-12-28 21:04:11 (11076): Detected: BOINC client v7.16.11 2022-12-28 21:04:13 (11076): Detected: VirtualBox VboxManage Interface (Version: 6.1.12) 2022-12-28 21:04:13 (11076): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2022-12-28 21:04:14 (11076): Guest Log: BIOS: VirtualBox 6.1.12 2022-12-28 21:04:14 (11076): Guest Log: CPUID EDX: 0x178bfbff 2022-12-28 21:04:14 (11076): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2022-12-28 21:04:14 (11076): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2022-12-28 21:04:14 (11076): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2022-12-28 21:04:14 (11076): Guest Log: BIOS: Booting from Hard Disk... 2022-12-28 21:04:14 (11076): Guest Log: BIOS: KBD: unsupported int 16h function 03 2022-12-28 21:04:14 (11076): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2022-12-28 21:04:14 (11076): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2022-12-28 21:04:14 (11076): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2022-12-28 21:04:14 (11076): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2022-12-28 21:04:14 (11076): Guest Log: 00:00:00.000288 main Log opened 2022-12-28T01:58:33.395807000Z 2022-12-28 21:04:14 (11076): Guest Log: 00:00:00.002373 main OS Product: Linux 2022-12-28 21:04:14 (11076): Guest Log: 00:00:00.002537 main OS Release: 4.14.76-13.cernvm.x86_64 2022-12-28 21:04:14 (11076): Guest Log: 00:00:00.002720 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2022-12-28 21:04:14 (11076): Guest Log: 00:00:00.002834 main Executable: /usr/sbin/VBoxService 2022-12-28 21:04:14 (11076): Guest Log: 00:00:00.002837 main Process ID: 3230 2022-12-28 21:04:14 (11076): Guest Log: 00:00:00.002840 main Package type: LINUX_64BITS_GENERIC 2022-12-28 21:04:14 (11076): Guest Log: 00:00:00.007980 main 5.2.6 r120293 started. Verbose level = 0 2022-12-28 21:04:14 (11076): Guest Log: 01:59:38 GMT +00:00 2022-12-28: cranky: [INFO] Detected Theory App 2022-12-28 21:04:14 (11076): Guest Log: 01:59:38 GMT +00:00 2022-12-28: cranky: [INFO] Checking CVMFS. 2022-12-28 21:04:14 (11076): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2022-12-28 21:04:14 (11076): 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 2022-12-28 21:04:14 (11076): Guest Log: 2.5.2.0 4095 0 27176 24655 3 1 1137071 4096001 0 65024 0 0 n/a 5 13 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2022-12-28 21:04:14 (11076): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2022-12-28 21:04:14 (11076): 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 2022-12-28 21:04:14 (11076): Guest Log: 2.5.2.0 4145 0 27576 20117 3 2 1137071 4096001 0 65024 0 2 0 5672 1657 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2022-12-28 21:04:14 (11076): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2022-12-28 21:04:14 (11076): 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 2022-12-28 21:04:14 (11076): Guest Log: 2.5.2.0 4119 0 27208 268 3 1 1137071 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2022-12-28 21:04:14 (11076): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2022-12-28 21:04:14 (11076): 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 2022-12-28 21:04:14 (11076): Guest Log: 2.5.2.0 4172 0 32056 14206 3 1 1137071 4096001 0 65024 0 0 n/a 15367 1861 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2022-12-28 21:04:14 (11076): Guest Log: 02:00:02 GMT +00:00 2022-12-28: cranky: [INFO] Checking runc. 2022-12-28 21:04:14 (11076): Guest Log: 02:00:03 GMT +00:00 2022-12-28: cranky: [INFO] Creating the filesystem. 2022-12-28 21:04:14 (11076): Guest Log: 02:00:03 GMT +00:00 2022-12-28: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2022-12-28 21:04:14 (11076): Guest Log: 02:00:03 GMT +00:00 2022-12-28: cranky: [INFO] Updating config.json. 2022-12-28 21:04:14 (11076): Guest Log: 02:00:03 GMT +00:00 2022-12-28: cranky: [INFO] Running Container 'runc'. 2022-12-28 21:04:14 (11076): Guest Log: job: htmld=/shared/html/job 2022-12-28 21:04:14 (11076): Guest Log: job: unpack exitcode=0 2022-12-28 21:04:14 (11076): Guest Log: 02:00:05 GMT +00:00 2022-12-28: cranky: [INFO] ===> [runRivet] Wed Dec 28 02:00:03 UTC 2022 [boinc pp jets 7000 40,-,610 - pythia8 8.244 tune-1 100000 316] 2022-12-28 21:04:14 (11076): Guest Log: 02:10:55.042220 timesync vgsvcTimeSyncWorker: Radical host time change: 21 337 217 000 000ns (HostNow=1 672 222 535 304 000 000 ns HostLast=1 672 201 198 087 000 000 ns) 2022-12-28 21:04:14 (11076): Guest Log: 02:11:05.069987 timesync vgsvcTimeSyncWorker: Radical guest time change: 21 977 103 850 000ns (GuestNow=1 672 222 545 436 668 000 ns GuestLast=1 672 200 568 332 818 000 ns fSetTimeLastLoop=true ) 2022-12-28 21:04:14 (11076): Guest Log: 02:17:35.664406 timesync vgsvcTimeSyncWorker: Radical host time change: 2 023 877 000 000ns (HostNow=1 672 225 448 406 000 000 ns HostLast=1 672 223 424 529 000 000 ns) 2022-12-28 21:04:14 (11076): Guest Log: 02:17:45.667128 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 522 377 646 000ns (GuestNow=1 672 225 458 408 729 000 ns GuestLast=1 672 222 936 031 083 000 ns fSetTimeLastLoop=true ) 2022-12-28 21:04:14 (11076): Guest Log: 03:09:51.859546 timesync vgsvcTimeSyncWorker: Radical host time change: 2 965 288 000 000ns (HostNow=1 672 233 726 831 000 000 ns HostLast=1 672 230 761 543 000 000 ns) 2022-12-28 21:04:14 (11076): Guest Log: 03:10:01.860665 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 898 408 341 000ns (GuestNow=1 672 233 736 832 144 000 ns GuestLast=1 672 229 838 423 803 000 ns fSetTimeLastLoop=true ) 2022-12-28 21:04:14 (11076): Guest Log: 03:13:32.298544 timesync vgsvcTimeSyncWorker: Radical host time change: 4 579 610 000 000ns (HostNow=1 672 238 516 874 000 000 ns HostLast=1 672 233 937 264 000 000 ns) 2022-12-28 21:04:14 (11076): Guest Log: 03:13:42.343692 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 579 649 169 000ns (GuestNow=1 672 238 526 919 166 000 ns GuestLast=1 672 233 947 269 997 000 ns fSetTimeLastLoop=true ) 2022-12-28 21:04:14 (11076): Guest Log: 04:04:48.096974 timesync vgsvcTimeSyncWorker: Radical host time change: 5 648 099 000 000ns (HostNow=1 672 248 653 340 000 000 ns HostLast=1 672 243 005 241 000 000 ns) 2022-12-28 21:04:14 (11076): Guest Log: 04:04:58.126463 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 648 122 705 000ns (GuestNow=1 672 248 663 369 467 000 ns GuestLast=1 672 243 015 246 762 000 ns fSetTimeLastLoop=true ) 2022-12-28 21:04:14 (11076): Guest Log: 04:14:29.678011 timesync vgsvcTimeSyncWorker: Radical host time change: 6 498 427 000 000ns (HostNow=1 672 255 723 245 000 000 ns HostLast=1 672 249 224 818 000 000 ns) 2022-12-28 21:04:14 (11076): Guest Log: 04:14:39.732504 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 498 378 445 000ns (GuestNow=1 672 255 733 299 506 000 ns GuestLast=1 672 249 234 921 061 000 ns fSetTimeLastLoop=true ) 2022-12-28 21:04:14 (11076): Starting VM using VBoxManage interface. (boinc_826653789bab990c, slot#1) 2022-12-28 21:04:19 (11076): Successfully started VM. (PID = '8544') 2022-12-28 21:04:19 (11076): Reporting VM Process ID to BOINC. 2022-12-28 21:04:19 (11076): Guest Log: BIOS: VirtualBox 6.1.12 2022-12-28 21:04:19 (11076): Guest Log: CPUID EDX: 0x178bfbff 2022-12-28 21:04:19 (11076): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2022-12-28 21:04:19 (11076): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2022-12-28 21:04:19 (11076): VM state change detected. (old = 'poweredoff', new = 'running') 2022-12-28 21:04:19 (11076): Detected: Web Application Enabled (http://localhost:50500) 2022-12-28 21:04:19 (11076): Detected: Remote Desktop Enabled (localhost:50501) 2022-12-28 21:04:19 (11076): Status Report: Job Duration: '864000.000000' 2022-12-28 21:04:19 (11076): Status Report: Elapsed Time: '25749.000000' 2022-12-28 21:04:19 (11076): Status Report: CPU Time: '11450.359375' 2022-12-28 21:04:19 (11076): Preference change detected 2022-12-28 21:04:19 (11076): Setting CPU throttle for VM. (42%) 2022-12-28 21:04:20 (11076): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 77 seconds) or (Vbox_job.xml: 600 seconds)) 2022-12-28 21:04:21 (11076): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2022-12-28 21:04:21 (11076): Guest Log: BIOS: Booting from Hard Disk... 2022-12-28 21:04:24 (11076): Guest Log: BIOS: KBD: unsupported int 16h function 03 2022-12-28 21:04:24 (11076): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2022-12-28 21:04:49 (11076): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2022-12-28 21:04:49 (11076): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2022-12-28 21:04:55 (11076): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2022-12-28 21:04:55 (11076): Guest Log: 00:00:00.000220 main Log opened 2022-12-28T21:04:55.107796000Z 2022-12-28 21:04:55 (11076): Guest Log: 00:00:00.000798 main OS Product: Linux 2022-12-28 21:04:55 (11076): Guest Log: 00:00:00.000843 main OS Release: 4.14.76-13.cernvm.x86_64 2022-12-28 21:04:55 (11076): Guest Log: 00:00:00.000875 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2022-12-28 21:04:55 (11076): Guest Log: 00:00:00.000912 main Executable: /usr/sbin/VBoxService 2022-12-28 21:04:55 (11076): Guest Log: 00:00:00.000913 main Process ID: 3218 2022-12-28 21:04:55 (11076): Guest Log: 00:00:00.000913 main Package type: LINUX_64BITS_GENERIC 2022-12-28 21:04:55 (11076): Guest Log: 00:00:00.002346 main 5.2.6 r120293 started. Verbose level = 0 2022-12-28 21:05:39 (11076): Guest Log: 21:05:38 GMT +00:00 2022-12-28: cranky: [INFO] Detected Theory App 2022-12-28 21:05:39 (11076): Guest Log: 21:05:38 GMT +00:00 2022-12-28: cranky: [INFO] Checking CVMFS. 2022-12-28 21:05:40 (11076): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2022-12-28 21:05:41 (11076): 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 2022-12-28 21:05:41 (11076): Guest Log: 2.5.2.0 4067 0 27176 24663 3 1 1191854 4096001 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2022-12-28 21:05:42 (11076): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2022-12-28 21:05:43 (11076): 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 2022-12-28 21:05:43 (11076): Guest Log: 2.5.2.0 4118 0 27528 20125 3 2 1191854 4096001 0 65024 0 2 0 5673 546 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2022-12-28 21:05:45 (11076): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2022-12-28 21:05:46 (11076): 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 2022-12-28 21:05:46 (11076): Guest Log: 2.5.2.0 4090 0 27212 268 3 1 1191854 4096001 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2022-12-28 21:05:47 (11076): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2022-12-28 21:05:48 (11076): 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 2022-12-28 21:05:48 (11076): Guest Log: 2.5.2.0 4140 0 34088 14212 3 1 1191854 4096001 0 65024 0 0 n/a 15368 863 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2022-12-28 21:05:48 (11076): Guest Log: 21:05:48 GMT +00:00 2022-12-28: cranky: [INFO] Checking runc. 2022-12-28 21:05:48 (11076): Guest Log: 21:05:48 GMT +00:00 2022-12-28: cranky: [INFO] Creating the filesystem. 2022-12-28 21:05:48 (11076): Guest Log: 21:05:48 GMT +00:00 2022-12-28: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2022-12-28 21:05:48 (11076): Guest Log: 21:05:48 GMT +00:00 2022-12-28: cranky: [INFO] Updating config.json. 2022-12-28 21:05:48 (11076): Guest Log: 21:05:48 GMT +00:00 2022-12-28: cranky: [INFO] Running Container 'runc'. 2022-12-28 21:05:49 (11076): Guest Log: job: htmld=/shared/html/job 2022-12-28 21:05:49 (11076): Guest Log: job: unpack exitcode=0 2022-12-28 21:05:50 (11076): Guest Log: 21:05:50 GMT +00:00 2022-12-28: cranky: [INFO] ===> [runRivet] Wed Dec 28 21:05:49 UTC 2022 [boinc pp jets 7000 40,-,610 - pythia8 8.244 tune-1 100000 316] 2022-12-28 21:06:06 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 21:59:27 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 21:59:36 (11076): Guest Log: 00:01:20.290410 timesync vgsvcTimeSyncWorker: Radical host time change: 3 210 327 000 000ns (HostNow=1 672 264 775 621 000 000 ns HostLast=1 672 261 565 294 000 000 ns) 2022-12-28 21:59:46 (11076): Guest Log: 00:01:30.472925 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 210 489 285 000ns (GuestNow=1 672 264 785 750 233 000 ns GuestLast=1 672 261 575 260 948 000 ns fSetTimeLastLoop=true ) 2022-12-28 22:01:40 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 22:05:08 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 22:07:34 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 22:22:33 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 22:24:34 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 22:27:39 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 22:31:13 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 22:34:33 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 22:34:41 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 22:42:57 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 22:54:06 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 23:06:22 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 23:08:27 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 23:11:32 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 23:21:53 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 23:24:58 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 23:27:45 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 23:30:55 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 23:43:52 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-28 23:48:22 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-28 23:52:47 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 00:01:13 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 00:14:12 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 00:18:16 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 00:20:47 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 00:25:33 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 00:54:28 (11076): Status Report: Job Duration: '864000.000000' 2022-12-29 00:54:28 (11076): Status Report: Elapsed Time: '31749.000000' 2022-12-29 00:54:28 (11076): Status Report: CPU Time: '14168.406250' 2022-12-29 00:58:44 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 01:07:15 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 01:13:07 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 01:20:54 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 01:22:11 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 01:22:21 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 01:28:09 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 01:32:47 (11076): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 02:04:39 (11076): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 10:47:42 (5420): Detected: vboxwrapper 26206 2022-12-29 10:47:42 (5420): Detected: BOINC client v7.16.11 2022-12-29 10:47:43 (5420): Detected: VirtualBox VboxManage Interface (Version: 6.1.12) 2022-12-29 10:47:43 (5420): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2022-12-29 10:47:44 (5420): Guest Log: BIOS: VirtualBox 6.1.12 2022-12-29 10:47:44 (5420): Guest Log: CPUID EDX: 0x178bfbff 2022-12-29 10:47:44 (5420): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2022-12-29 10:47:44 (5420): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2022-12-29 10:47:44 (5420): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2022-12-29 10:47:44 (5420): Guest Log: BIOS: Booting from Hard Disk... 2022-12-29 10:47:44 (5420): Guest Log: BIOS: KBD: unsupported int 16h function 03 2022-12-29 10:47:44 (5420): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2022-12-29 10:47:44 (5420): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2022-12-29 10:47:44 (5420): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2022-12-29 10:47:44 (5420): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2022-12-29 10:47:44 (5420): Guest Log: 00:00:00.000220 main Log opened 2022-12-28T21:04:55.107796000Z 2022-12-29 10:47:44 (5420): Guest Log: 00:00:00.000798 main OS Product: Linux 2022-12-29 10:47:44 (5420): Guest Log: 00:00:00.000843 main OS Release: 4.14.76-13.cernvm.x86_64 2022-12-29 10:47:44 (5420): Guest Log: 00:00:00.000875 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2022-12-29 10:47:44 (5420): Guest Log: 00:00:00.000912 main Executable: /usr/sbin/VBoxService 2022-12-29 10:47:44 (5420): Guest Log: 00:00:00.000913 main Process ID: 3218 2022-12-29 10:47:44 (5420): Guest Log: 00:00:00.000913 main Package type: LINUX_64BITS_GENERIC 2022-12-29 10:47:44 (5420): Guest Log: 00:00:00.002346 main 5.2.6 r120293 started. Verbose level = 0 2022-12-29 10:47:44 (5420): Guest Log: 21:05:38 GMT +00:00 2022-12-28: cranky: [INFO] Detected Theory App 2022-12-29 10:47:44 (5420): Guest Log: 21:05:38 GMT +00:00 2022-12-28: cranky: [INFO] Checking CVMFS. 2022-12-29 10:47:44 (5420): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2022-12-29 10:47:44 (5420): 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 2022-12-29 10:47:44 (5420): Guest Log: 2.5.2.0 4067 0 27176 24663 3 1 1191854 4096001 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2022-12-29 10:47:44 (5420): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2022-12-29 10:47:44 (5420): 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 2022-12-29 10:47:44 (5420): Guest Log: 2.5.2.0 4118 0 27528 20125 3 2 1191854 4096001 0 65024 0 2 0 5673 546 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2022-12-29 10:47:44 (5420): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2022-12-29 10:47:44 (5420): 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 2022-12-29 10:47:44 (5420): Guest Log: 2.5.2.0 4090 0 27212 268 3 1 1191854 4096001 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2022-12-29 10:47:44 (5420): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2022-12-29 10:47:44 (5420): 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 2022-12-29 10:47:44 (5420): Guest Log: 2.5.2.0 4140 0 34088 14212 3 1 1191854 4096001 0 65024 0 0 n/a 15368 863 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2022-12-29 10:47:44 (5420): Guest Log: 21:05:48 GMT +00:00 2022-12-28: cranky: [INFO] Checking runc. 2022-12-29 10:47:44 (5420): Guest Log: 21:05:48 GMT +00:00 2022-12-28: cranky: [INFO] Creating the filesystem. 2022-12-29 10:47:44 (5420): Guest Log: 21:05:48 GMT +00:00 2022-12-28: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2022-12-29 10:47:44 (5420): Guest Log: 21:05:48 GMT +00:00 2022-12-28: cranky: [INFO] Updating config.json. 2022-12-29 10:47:44 (5420): Guest Log: 21:05:48 GMT +00:00 2022-12-28: cranky: [INFO] Running Container 'runc'. 2022-12-29 10:47:44 (5420): Guest Log: job: htmld=/shared/html/job 2022-12-29 10:47:44 (5420): Guest Log: job: unpack exitcode=0 2022-12-29 10:47:44 (5420): Guest Log: 21:05:50 GMT +00:00 2022-12-28: cranky: [INFO] ===> [runRivet] Wed Dec 28 21:05:49 UTC 2022 [boinc pp jets 7000 40,-,610 - pythia8 8.244 tune-1 100000 316] 2022-12-29 10:47:44 (5420): Guest Log: 00:01:20.290410 timesync vgsvcTimeSyncWorker: Radical host time change: 3 210 327 000 000ns (HostNow=1 672 264 775 621 000 000 ns HostLast=1 672 261 565 294 000 000 ns) 2022-12-29 10:47:44 (5420): Guest Log: 00:01:30.472925 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 210 489 285 000ns (GuestNow=1 672 264 785 750 233 000 ns GuestLast=1 672 261 575 260 948 000 ns fSetTimeLastLoop=true ) 2022-12-29 10:47:44 (5420): Starting VM using VBoxManage interface. (boinc_826653789bab990c, slot#1) 2022-12-29 10:47:49 (5420): Successfully started VM. (PID = '2420') 2022-12-29 10:47:49 (5420): Reporting VM Process ID to BOINC. 2022-12-29 10:47:49 (5420): Guest Log: BIOS: VirtualBox 6.1.12 2022-12-29 10:47:49 (5420): Guest Log: CPUID EDX: 0x178bfbff 2022-12-29 10:47:49 (5420): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2022-12-29 10:47:49 (5420): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2022-12-29 10:47:49 (5420): VM state change detected. (old = 'poweredoff', new = 'running') 2022-12-29 10:47:49 (5420): Detected: Web Application Enabled (http://localhost:50500) 2022-12-29 10:47:49 (5420): Detected: Remote Desktop Enabled (localhost:50501) 2022-12-29 10:47:49 (5420): Status Report: Job Duration: '864000.000000' 2022-12-29 10:47:49 (5420): Status Report: Elapsed Time: '34632.000000' 2022-12-29 10:47:49 (5420): Status Report: CPU Time: '15481.812500' 2022-12-29 10:47:49 (5420): Preference change detected 2022-12-29 10:47:49 (5420): Setting CPU throttle for VM. (42%) 2022-12-29 10:47:49 (5420): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 77 seconds) or (Vbox_job.xml: 600 seconds)) 2022-12-29 10:47:51 (5420): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2022-12-29 10:47:51 (5420): Guest Log: BIOS: Booting from Hard Disk... 2022-12-29 10:47:54 (5420): Guest Log: BIOS: KBD: unsupported int 16h function 03 2022-12-29 10:47:54 (5420): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2022-12-29 10:48:17 (5420): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2022-12-29 10:48:17 (5420): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2022-12-29 10:48:22 (5420): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2022-12-29 10:48:22 (5420): Guest Log: 00:00:00.000070 main Log opened 2022-12-29T10:48:21.752399000Z 2022-12-29 10:48:22 (5420): Guest Log: 00:00:00.000140 main OS Product: Linux 2022-12-29 10:48:22 (5420): Guest Log: 00:00:00.000168 main OS Release: 4.14.76-13.cernvm.x86_64 2022-12-29 10:48:22 (5420): Guest Log: 00:00:00.000192 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2022-12-29 10:48:22 (5420): Guest Log: 00:00:00.000214 main Executable: /usr/sbin/VBoxService 2022-12-29 10:48:22 (5420): Guest Log: 00:00:00.000214 main Process ID: 3218 2022-12-29 10:48:22 (5420): Guest Log: 00:00:00.000215 main Package type: LINUX_64BITS_GENERIC 2022-12-29 10:48:22 (5420): Guest Log: 00:00:00.002148 main 5.2.6 r120293 started. Verbose level = 0 2022-12-29 10:48:54 (5420): Guest Log: 10:48:53 GMT +00:00 2022-12-29: cranky: [INFO] Detected Theory App 2022-12-29 10:48:54 (5420): Guest Log: 10:48:53 GMT +00:00 2022-12-29: cranky: [INFO] Checking CVMFS. 2022-12-29 10:48:55 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 11:03:18 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 11:03:19 (5420): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2022-12-29 11:03:21 (5420): 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 2022-12-29 11:03:21 (5420): Guest Log: 2.5.2.0 4086 0 27204 24668 3 1 1235735 4096001 0 65024 0 0 n/a 5 1 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2022-12-29 11:03:26 (5420): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2022-12-29 11:03:30 (5420): 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 2022-12-29 11:03:30 (5420): Guest Log: 2.5.2.0 4128 0 27472 20129 3 2 1235735 4096001 0 65024 0 2 0 5673 994 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1 2022-12-29 11:03:34 (5420): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2022-12-29 11:03:37 (5420): 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 2022-12-29 11:03:37 (5420): Guest Log: 2.5.2.0 4058 0 27216 268 3 1 1235735 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2022-12-29 11:03:42 (5420): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2022-12-29 11:03:44 (5420): 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 2022-12-29 11:03:44 (5420): Guest Log: 2.5.2.0 4107 0 33236 14217 3 1 1235735 4096001 0 65024 0 0 n/a 15370 1536 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/alice.cern.ch DIRECT 1 2022-12-29 11:03:44 (5420): Guest Log: 10:49:19 GMT +00:00 2022-12-29: cranky: [INFO] Checking runc. 2022-12-29 11:03:44 (5420): Guest Log: 10:49:20 GMT +00:00 2022-12-29: cranky: [INFO] Creating the filesystem. 2022-12-29 11:03:44 (5420): Guest Log: 10:49:20 GMT +00:00 2022-12-29: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2022-12-29 11:03:45 (5420): Guest Log: 10:49:20 GMT +00:00 2022-12-29: cranky: [INFO] Updating config.json. 2022-12-29 11:03:45 (5420): Guest Log: 10:49:21 GMT +00:00 2022-12-29: cranky: [INFO] Running Container 'runc'. 2022-12-29 11:03:46 (5420): Guest Log: job: htmld=/shared/html/job 2022-12-29 11:03:46 (5420): Guest Log: job: unpack exitcode=0 2022-12-29 11:03:48 (5420): Guest Log: 10:49:23 GMT +00:00 2022-12-29: cranky: [INFO] ===> [runRivet] Thu Dec 29 10:49:21 UTC 2022 [boinc pp jets 7000 40,-,610 - pythia8 8.244 tune-1 100000 316] 2022-12-29 11:09:42 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 12:30:58 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 12:31:02 (5420): Guest Log: 00:07:01.002868 timesync vgsvcTimeSyncWorker: Radical host time change: 4 886 148 000 000ns (HostNow=1 672 317 062 128 000 000 ns HostLast=1 672 312 175 980 000 000 ns) 2022-12-29 12:31:12 (5420): Guest Log: 00:07:11.045086 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 749 717 830 000ns (GuestNow=1 672 317 072 264 737 000 ns GuestLast=1 672 311 322 546 907 000 ns fSetTimeLastLoop=true ) 2022-12-29 12:38:05 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 13:20:34 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 13:20:42 (5420): Guest Log: 00:14:12.175581 timesync vgsvcTimeSyncWorker: Radical host time change: 2 558 159 000 000ns (HostNow=1 672 320 041 486 000 000 ns HostLast=1 672 317 483 327 000 000 ns) 2022-12-29 13:20:52 (5420): Guest Log: 00:14:22.200431 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 558 115 693 000ns (GuestNow=1 672 320 051 510 878 000 ns GuestLast=1 672 317 493 395 185 000 ns fSetTimeLastLoop=true ) 2022-12-29 13:21:08 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 13:32:38 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 14:23:09 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 16:01:04 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 16:01:12 (5420): Guest Log: 01:05:19.538200 timesync vgsvcTimeSyncWorker: Radical host time change: 5 884 515 000 000ns (HostNow=1 672 329 672 285 000 000 ns HostLast=1 672 323 787 770 000 000 ns) 2022-12-29 16:01:22 (5420): Guest Log: 01:05:29.540962 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 573 439 133 000ns (GuestNow=1 672 329 682 287 779 000 ns GuestLast=1 672 323 108 848 646 000 ns fSetTimeLastLoop=true ) 2022-12-29 16:35:31 (5420): Status Report: Job Duration: '864000.000000' 2022-12-29 16:35:31 (5420): Status Report: Elapsed Time: '40632.000000' 2022-12-29 16:35:31 (5420): Status Report: CPU Time: '18197.890625' 2022-12-29 17:05:08 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 17:08:13 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 17:34:30 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 17:37:50 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 17:38:17 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 17:56:54 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 17:58:29 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 19:51:56 (5420): Guest Log: 02:37:34.784051 timesync vgsvcTimeSyncWorker: Radical host time change: 6 816 981 000 000ns (HostNow=1 672 343 515 677 000 000 ns HostLast=1 672 336 698 696 000 000 ns) 2022-12-29 19:51:56 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 19:52:06 (5420): Guest Log: 02:37:44.789859 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 816 983 051 000ns (GuestNow=1 672 343 525 682 817 000 ns GuestLast=1 672 336 708 699 766 000 ns fSetTimeLastLoop=true ) 2022-12-29 19:57:44 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 20:01:22 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 20:01:33 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 20:25:15 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 20:27:09 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 21:18:57 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 21:19:04 (5420): Guest Log: 02:45:35.988194 timesync vgsvcTimeSyncWorker: Radical host time change: 3 117 919 000 000ns (HostNow=1 672 348 744 578 000 000 ns HostLast=1 672 345 626 659 000 000 ns) 2022-12-29 21:19:06 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 21:56:11 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 21:56:19 (5420): Guest Log: 02:45:45.994258 timesync vgsvcTimeSyncWorker: Radical host time change: 2 235 280 000 000ns (HostNow=1 672 350 979 858 000 000 ns HostLast=1 672 348 744 578 000 000 ns) 2022-12-29 21:56:19 (5420): Guest Log: 02:45:45.994312 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 117 895 029 000ns (GuestNow=1 672 348 754 584 065 000 ns GuestLast=1 672 345 636 689 036 000 ns fSetTimeLastLoop=true ) 2022-12-29 21:56:29 (5420): Guest Log: 02:45:55.998288 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 235 277 970 000ns (GuestNow=1 672 350 989 862 035 000 ns GuestLast=1 672 348 754 584 065 000 ns fSetTimeLastLoop=true ) 2022-12-29 21:57:02 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 22:11:32 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 22:16:13 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 22:19:18 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 22:25:31 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 22:28:36 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 22:33:01 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 22:58:24 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 23:01:21 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 23:19:23 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 23:20:27 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 23:25:32 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 23:26:15 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-29 23:30:04 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-29 23:43:32 (5420): Status Report: Job Duration: '864000.000000' 2022-12-29 23:43:32 (5420): Status Report: Elapsed Time: '46632.000000' 2022-12-29 23:43:32 (5420): Status Report: CPU Time: '20921.265625' 2022-12-30 00:03:53 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 00:07:20 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 00:09:03 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 00:14:48 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 00:22:47 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 00:28:23 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 00:34:46 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 00:37:56 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 00:50:21 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 00:56:17 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 00:57:03 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 01:00:08 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 01:07:10 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 01:10:15 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 01:11:36 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 01:14:59 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 01:20:20 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 01:23:26 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 01:23:51 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 01:26:56 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 01:34:34 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 01:42:29 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 01:46:05 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 01:49:10 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 01:55:37 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 01:58:41 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 02:17:33 (5420): Status Report: Job Duration: '864000.000000' 2022-12-30 02:17:33 (5420): Status Report: Elapsed Time: '52632.000000' 2022-12-30 02:17:33 (5420): Status Report: CPU Time: '23652.218750' 2022-12-30 03:56:05 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 10:34:31 (5420): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_826653789bab990c" resume Output: VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running) VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2022-12-30 10:34:32 (5420): Guest Log: 06:38:57.456399 timesync vgsvcTimeSyncWorker: Radical host time change: 23 911 505 000 000ns (HostNow=1 672 396 467 892 000 000 ns HostLast=1 672 372 556 387 000 000 ns) 2022-12-30 10:34:32 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 10:34:33 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 10:47:22 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 10:47:27 (5420): Guest Log: 06:39:07.531392 timesync vgsvcTimeSyncWorker: Radical guest time change: 24 752 226 965 000ns (GuestNow=1 672 396 477 967 048 000 ns GuestLast=1 672 371 725 740 083 000 ns fSetTimeLastLoop=true ) 2022-12-30 10:48:45 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 11:48:55 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 11:48:57 (5420): Guest Log: 06:40:27.682221 timesync vgsvcTimeSyncWorker: Radical host time change: 3 620 114 000 000ns (HostNow=1 672 400 937 310 000 000 ns HostLast=1 672 397 317 196 000 000 ns) 2022-12-30 11:49:06 (5420): Status Report: Job Duration: '864000.000000' 2022-12-30 11:49:06 (5420): Status Report: Elapsed Time: '58632.000000' 2022-12-30 11:49:06 (5420): Status Report: CPU Time: '26378.359375' 2022-12-30 11:49:07 (5420): Guest Log: 06:40:37.683194 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 389 193 107 000ns (GuestNow=1 672 400 947 310 978 000 ns GuestLast=1 672 396 558 117 871 000 ns fSetTimeLastLoop=true ) 2022-12-30 11:53:36 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 13:45:20 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 13:45:22 (5420): Guest Log: 06:45:08.488455 timesync vgsvcTimeSyncWorker: Radical host time change: 6 714 220 000 000ns (HostNow=1 672 407 922 283 000 000 ns HostLast=1 672 401 208 063 000 000 ns) 2022-12-30 13:45:32 (5420): Guest Log: 06:45:18.490844 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 714 169 173 000ns (GuestNow=1 672 407 932 285 397 000 ns GuestLast=1 672 401 218 116 224 000 ns fSetTimeLastLoop=true ) 2022-12-30 13:55:20 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 14:03:58 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 14:21:17 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 14:27:46 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 14:29:59 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 15:09:27 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 15:09:31 (5420): Guest Log: 07:14:42.740277 timesync vgsvcTimeSyncWorker: Radical host time change: 2 377 606 000 000ns (HostNow=1 672 412 971 027 000 000 ns HostLast=1 672 410 593 421 000 000 ns) 2022-12-30 15:09:41 (5420): Guest Log: 07:14:52.741859 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 284 493 769 000ns (GuestNow=1 672 412 981 028 590 000 ns GuestLast=1 672 409 696 534 821 000 ns fSetTimeLastLoop=true ) 2022-12-30 15:12:45 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 15:47:26 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 15:47:32 (5420): Guest Log: 07:18:03.109029 timesync vgsvcTimeSyncWorker: Radical host time change: 2 090 562 000 000ns (HostNow=1 672 415 251 945 000 000 ns HostLast=1 672 413 161 383 000 000 ns) 2022-12-30 15:47:42 (5420): Guest Log: 07:18:13.110133 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 090 550 378 000ns (GuestNow=1 672 415 261 946 116 000 ns GuestLast=1 672 413 171 395 738 000 ns fSetTimeLastLoop=true ) 2022-12-30 15:50:17 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 16:20:52 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 16:20:57 (5420): Guest Log: 07:20:53.558649 timesync vgsvcTimeSyncWorker: Radical host time change: 1 845 152 000 000ns (HostNow=1 672 417 257 492 000 000 ns HostLast=1 672 415 412 340 000 000 ns) 2022-12-30 16:21:07 (5420): Guest Log: 07:21:03.567683 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 845 106 430 000ns (GuestNow=1 672 417 267 501 044 000 ns GuestLast=1 672 415 422 394 614 000 ns fSetTimeLastLoop=true ) 2022-12-30 16:37:00 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 17:05:51 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 17:15:03 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 17:28:05 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 17:29:28 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 19:49:47 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 19:49:52 (5420): Guest Log: 07:47:36.421833 timesync vgsvcTimeSyncWorker: Radical host time change: 8 429 039 000 000ns (HostNow=1 672 429 791 792 000 000 ns HostLast=1 672 421 362 753 000 000 ns) 2022-12-30 19:50:02 (5420): Guest Log: 07:47:46.436980 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 210 429 754 000ns (GuestNow=1 672 429 801 807 173 000 ns GuestLast=1 672 420 591 377 419 000 ns fSetTimeLastLoop=true ) 2022-12-30 19:53:16 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 20:00:31 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 20:01:56 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 21:21:35 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 21:21:36 (5420): Guest Log: 07:52:27.076144 timesync vgsvcTimeSyncWorker: Radical host time change: 4 788 804 000 000ns (HostNow=1 672 435 296 440 000 000 ns HostLast=1 672 430 507 636 000 000 ns) 2022-12-30 21:21:46 (5420): Guest Log: 07:52:37.079995 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 223 997 606 000ns (GuestNow=1 672 435 306 443 894 000 ns GuestLast=1 672 430 082 446 288 000 ns fSetTimeLastLoop=true ) 2022-12-30 21:22:24 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 21:27:16 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 21:27:47 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 21:34:13 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 21:34:53 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 21:57:58 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 21:58:09 (5420): Guest Log: 07:54:37.326559 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 073 112 301 000ns (GuestNow=1 672 437 489 801 378 000 ns GuestLast=1 672 435 416 689 077 000 ns fSetTimeLastLoop=true ) 2022-12-30 22:24:29 (5420): Status Report: Job Duration: '864000.000000' 2022-12-30 22:24:29 (5420): Status Report: Elapsed Time: '64632.000000' 2022-12-30 22:24:29 (5420): Status Report: CPU Time: '29095.921875' 2022-12-30 22:37:44 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 22:40:50 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 22:48:50 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 22:54:38 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 23:03:12 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 23:06:40 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 23:07:03 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 23:14:57 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 23:17:15 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 23:29:16 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 23:36:59 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 23:42:21 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 23:43:31 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-30 23:46:36 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-30 23:57:47 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-31 00:00:52 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-31 00:07:49 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-31 00:10:54 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-31 00:39:36 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-31 00:46:49 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-31 00:58:51 (5420): Status Report: Job Duration: '864000.000000' 2022-12-31 00:58:51 (5420): Status Report: Elapsed Time: '70632.000000' 2022-12-31 00:58:51 (5420): Status Report: CPU Time: '31823.250000' 2022-12-31 01:00:16 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-31 01:05:19 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-31 01:07:46 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-31 01:36:13 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-31 01:36:26 (5420): Guest Log: 10:05:19.344752 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 821 230 181 000ns (GuestNow=1 672 450 585 839 159 000 ns GuestLast=1 672 447 764 608 978 000 ns fSetTimeLastLoop=true ) 2022-12-31 01:37:24 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-31 01:37:34 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-31 01:38:37 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-31 01:47:27 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-31 01:48:19 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-31 02:07:09 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-31 02:17:20 (5420): VM state change detected. (old = 'running', new = 'paused') 2022-12-31 02:22:30 (5420): VM state change detected. (old = 'paused', new = 'running') 2022-12-31 03:23:43 (5420): Guest Log: job: run exitcode=0 2022-12-31 03:23:43 (5420): Guest Log: job: diskusage=2136 2022-12-31 03:23:43 (5420): Guest Log: job: logsize=100 k 2022-12-31 03:23:43 (5420): Guest Log: job: times= 2022-12-31 03:23:43 (5420): Guest Log: 0m0.005s 0m0.008s 2022-12-31 03:23:43 (5420): Guest Log: 663m2.263s 4m55.041s 2022-12-31 03:23:43 (5420): Guest Log: job: cpuusage=40077 2022-12-31 03:23:44 (5420): Guest Log: 03:18:35 GMT +00:00 2022-12-31: cranky: [INFO] Container 'runc' finished with status code 0. 2022-12-31 03:23:44 (5420): Guest Log: 03:18:35 GMT +00:00 2022-12-31: cranky: [INFO] Preparing output. 2022-12-31 03:23:48 (5420): Guest Log: [INFO] Job Finished 2022-12-31 03:23:48 (5420): Guest Log: [INFO] Shutting Down. 2022-12-31 03:23:48 (5420): VM Completion File Detected. 2022-12-31 03:23:48 (5420): VM Completion Message: Job Finished . 2022-12-31 03:23:48 (5420): Powering off VM. 2022-12-31 03:23:49 (5420): Successfully stopped VM. 2022-12-31 03:23:49 (5420): Deregistering VM. (boinc_826653789bab990c, slot#1) 2022-12-31 03:23:49 (5420): Removing network bandwidth throttle group from VM. 2022-12-31 03:23:49 (5420): Removing VM from VirtualBox. 03:23:54 (5420): called boinc_f
©2025 CERN