| Name | Theory_2743-2783910-392_0 |
| Workunit | 225047290 |
| Created | 25 Aug 2024, 18:24:11 UTC |
| Sent | 25 Aug 2024, 22:56:30 UTC |
| Report deadline | 5 Sep 2024, 22:56:30 UTC |
| Received | 19 Sep 2024, 5:40:43 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10857730 |
| Run time | 6 hours 33 min 36 sec |
| CPU time | 5 hours 31 min 7 sec |
| Validate state | Valid |
| Credit | 54.67 |
| Device peak FLOPS | 1.00 GFLOPS |
| Application version | Theory Simulation v300.30 (vbox64_theory) windows_x86_64 |
| Peak working set size | 81.67 MB |
| Peak swap size | 75.34 MB |
| Peak disk usage | 1.37 GB |
Stderr output
<core_client_version>8.0.4</core_client_version> <![CDATA[ <stderr_txt> 2024-08-30 07:07:02 (7616): vboxwrapper version 26207 2024-08-30 07:07:02 (7616): BOINC client version: 8.0.4 2024-08-30 07:07:02 (7616): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2024-08-30 07:07:03 (7616): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-08-30 07:07:03 (7616): Successfully copied 'init_data.xml' to the shared directory. 2024-08-30 07:07:03 (7616): Successfully copied 'input' to the shared directory. 2024-08-30 07:07:03 (7616): Create VM. (boinc_fb5d9a5b3f64f498, slot#6) 2024-08-30 07:07:03 (7616): Setting Memory Size for VM. (630MB) 2024-08-30 07:07:04 (7616): Setting CPU Count for VM. (1) 2024-08-30 07:07:04 (7616): Setting Chipset Options for VM. 2024-08-30 07:07:04 (7616): Setting Graphics Controller Options for VM. 2024-08-30 07:07:04 (7616): Setting Boot Options for VM. 2024-08-30 07:07:05 (7616): Setting Network Configuration for NAT. 2024-08-30 07:07:05 (7616): Enabling VM Network Access. 2024-08-30 07:07:05 (7616): Disabling USB Support for VM. 2024-08-30 07:07:05 (7616): Disabling COM Port Support for VM. 2024-08-30 07:07:06 (7616): Disabling LPT Port Support for VM. 2024-08-30 07:07:06 (7616): Disabling Audio Support for VM. 2024-08-30 07:07:06 (7616): Disabling Clipboard Support for VM. 2024-08-30 07:07:06 (7616): Disabling Drag and Drop Support for VM. 2024-08-30 07:07:07 (7616): Adding storage controller(s) to VM. 2024-08-30 07:07:07 (7616): Adding virtual disk drive to VM. (Theory_2024_04_30_prod.vdi) 2024-08-30 07:07:07 (7616): Adding VirtualBox Guest Additions to VM. 2024-08-30 07:07:08 (7616): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2024-08-30 07:07:08 (7616): forwarding host port 63298 to guest port 80 2024-08-30 07:07:08 (7616): Enabling remote desktop for VM. 2024-08-30 07:07:08 (7616): Required extension pack not installed, remote desktop not enabled. 2024-08-30 07:07:08 (7616): Enabling shared directory for VM. 2024-08-30 07:07:09 (7616): Starting VM using VBoxManage interface. (boinc_fb5d9a5b3f64f498, slot#6) 2024-08-30 07:07:14 (7616): Successfully started VM. (PID = '30768') 2024-08-30 07:07:14 (7616): Reporting VM Process ID to BOINC. 2024-08-30 07:07:14 (7616): Guest Log: BIOS: VirtualBox 7.0.6 2024-08-30 07:07:14 (7616): Guest Log: CPUID EDX: 0x178bfbff 2024-08-30 07:07:14 (7616): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-08-30 07:07:14 (7616): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-08-30 07:07:14 (7616): VM state change detected. (old = 'poweredoff', new = 'running') 2024-08-30 07:07:14 (7616): Detected: Web Application Enabled (http://localhost:63298) 2024-08-30 07:07:14 (7616): Preference change detected 2024-08-30 07:07:14 (7616): Setting CPU throttle for VM. (100%) 2024-08-30 07:07:15 (7616): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds)) 2024-08-30 07:07:16 (7616): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-08-30 07:07:16 (7616): Guest Log: BIOS: Booting from Hard Disk... 2024-08-30 07:07:18 (7616): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-08-30 07:07:18 (7616): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-08-30 07:07:35 (7616): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-08-30 07:07:35 (7616): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-08-30 07:07:37 (7616): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-08-30 07:07:37 (7616): Guest Log: 00:00:00.000114 main Log opened 2024-08-30T14:07:38.025775000Z 2024-08-30 07:07:37 (7616): Guest Log: 00:00:00.000281 main OS Product: Linux 2024-08-30 07:07:37 (7616): Guest Log: 00:00:00.000321 main OS Release: 4.14.76-13.cernvm.x86_64 2024-08-30 07:07:37 (7616): Guest Log: 00:00:00.000354 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-08-30 07:07:37 (7616): Guest Log: 00:00:00.000379 main Executable: /usr/sbin/VBoxService 2024-08-30 07:07:37 (7616): Guest Log: 00:00:00.000380 main Process ID: 3218 2024-08-30 07:07:37 (7616): Guest Log: 00:00:00.000380 main Package type: LINUX_64BITS_GENERIC 2024-08-30 07:07:37 (7616): Guest Log: 00:00:00.002447 main 5.2.6 r120293 started. Verbose level = 0 2024-08-30 07:07:58 (7616): Guest Log: 08:07:58 MDT -06:00 2024-08-30: cranky: [INFO] Detected Theory App 2024-08-30 07:07:58 (7616): Guest Log: 08:07:58 MDT -06:00 2024-08-30: cranky: [INFO] Checking CVMFS. 2024-08-30 07:07:58 (7616): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-08-30 07:07:59 (7616): 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-08-30 07:07:59 (7616): Guest Log: 2.5.2.0 4120 0 25132 30344 3 1 273004 4096000 0 65024 0 0 n/a 5 7 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-08-30 07:07:59 (7616): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-08-30 07:07:59 (7616): 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-08-30 07:07:59 (7616): Guest Log: 2.5.2.0 4051 0 27532 25101 3 2 273004 4096000 0 65024 0 2 -100 7969 2288 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-08-30 07:07:59 (7616): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-08-30 07:08:00 (7616): 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-08-30 07:08:00 (7616): Guest Log: 2.5.2.0 4072 0 27232 272 3 1 273004 4096000 0 65024 0 0 n/a 4 2 http://s1bnl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-08-30 07:08:00 (7616): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-08-30 07:08:01 (7616): 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-08-30 07:08:01 (7616): Guest Log: 2.5.2.0 4092 0 39652 19532 3 1 273004 4096000 0 65024 0 0 n/a 5791 1611 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-08-30 07:08:01 (7616): Guest Log: 08:08:02 MDT -06:00 2024-08-30: cranky: [INFO] Checking runc. 2024-08-30 07:08:01 (7616): Guest Log: 08:08:02 MDT -06:00 2024-08-30: cranky: [INFO] Creating the filesystem. 2024-08-30 07:08:01 (7616): Guest Log: 08:08:02 MDT -06:00 2024-08-30: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-08-30 07:08:01 (7616): Guest Log: 08:08:02 MDT -06:00 2024-08-30: cranky: [INFO] Updating config.json. 2024-08-30 07:08:01 (7616): Guest Log: 08:08:02 MDT -06:00 2024-08-30: cranky: [INFO] Running Container 'runc'. 2024-08-30 07:08:16 (7616): Guest Log: job: htmld=/shared/html/job 2024-08-30 07:08:17 (7616): Guest Log: job: unpack exitcode=0 2024-08-30 07:08:18 (7616): Guest Log: INFO: activated the work-around for ld: 2024-08-30 07:08:18 (7616): Guest Log: lrwxrwxrwx 1 0 0 15 Aug 30 14:08 /tmp/tmp.9IhSX6O903/ld -> /usr/bin/ld.bfd 2024-08-30 07:08:19 (7616): Guest Log: 08:08:20 MDT -06:00 2024-08-30: cranky: [INFO] ===> [runRivet] Fri Aug 30 14:08:19 UTC 2024 [boinc pp jets 7000 800 - pythia8 8.230 early 100000 392] 2024-08-30 07:27:05 (7616): VM state change detected. (old = 'running', new = 'paused') 2024-08-30 16:46:14 (7616): VM state change detected. (old = 'paused', new = 'running') 2024-08-30 16:46:20 (7616): Guest Log: 00:19:30.522491 timesync vgsvcTimeSyncWorker: Radical host time change: 33 559 900 000 000ns (HostNow=1 725 061 577 935 000 000 ns HostLast=1 725 028 018 035 000 000 ns) 2024-08-30 16:46:20 (7616): VM state change detected. (old = 'running', new = 'paused') 2024-08-30 17:17:32 (7616): VM state change detected. (old = 'paused', new = 'running') 2024-08-30 17:17:40 (7616): Guest Log: 00:19:40.562037 timesync vgsvcTimeSyncWorker: Radical host time change: 1 881 459 000 000ns (HostNow=1 725 063 459 394 000 000 ns HostLast=1 725 061 577 935 000 000 ns) 2024-08-30 17:17:40 (7616): Guest Log: 00:19:40.562085 timesync vgsvcTimeSyncWorker: Radical guest time change: 33 559 555 326 000ns (GuestNow=1 725 061 588 010 801 000 ns GuestLast=1 725 028 028 455 475 000 ns fSetTimeLastLoop=true ) 2024-08-30 17:17:50 (7616): Guest Log: 00:19:50.564428 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 881 385 597 000ns (GuestNow=1 725 063 469 396 398 000 ns GuestLast=1 725 061 588 010 801 000 ns fSetTimeLastLoop=true ) 2024-08-30 17:26:33 (7616): VM state change detected. (old = 'running', new = 'paused') 2024-08-30 21:49:48 (7616): VM state change detected. (old = 'paused', new = 'running') 2024-08-30 21:49:54 (7616): Guest Log: 00:28:40.734208 timesync vgsvcTimeSyncWorker: Radical host time change: 15 804 455 000 000ns (HostNow=1 725 079 794 017 000 000 ns HostLast=1 725 063 989 562 000 000 ns) 2024-08-30 21:50:04 (7616): Guest Log: 00:28:50.734475 timesync vgsvcTimeSyncWorker: Radical guest time change: 15 804 451 113 000ns (GuestNow=1 725 079 804 017 274 000 ns GuestLast=1 725 063 999 566 161 000 ns fSetTimeLastLoop=true ) 2024-08-30 22:10:09 (7616): VM state change detected. (old = 'running', new = 'paused') 2024-08-31 06:54:08 (7616): VM state change detected. (old = 'paused', new = 'running') 2024-08-31 06:54:13 (7616): Guest Log: 00:49:01.213928 timesync vgsvcTimeSyncWorker: Radical host time change: 31 448 369 000 000ns (HostNow=1 725 112 452 857 000 000 ns HostLast=1 725 081 004 488 000 000 ns) 2024-08-31 06:54:23 (7616): VM state change detected. (old = 'running', new = 'paused') 2024-08-31 11:07:20 (7616): Guest Log: 00:49:11.214868 timesync vgsvcTimeSyncWorker: Radical host time change: 15 186 619 000 000ns (HostNow=1 725 127 639 476 000 000 ns HostLast=1 725 112 452 857 000 000 ns) 2024-08-31 11:07:20 (7616): Guest Log: 00:49:11.214917 timesync vgsvcTimeSyncWorker: Radical guest time change: 31 448 361 238 000ns (GuestNow=1 725 112 462 857 940 000 ns GuestLast=1 725 081 014 496 702 000 ns fSetTimeLastLoop=true ) 2024-08-31 11:07:20 (7616): VM state change detected. (old = 'paused', new = 'running') 2024-08-31 11:07:30 (7616): Guest Log: 00:49:21.225149 timesync vgsvcTimeSyncWorker: Radical guest time change: 15 186 628 345 000ns (GuestNow=1 725 127 649 486 285 000 ns GuestLast=1 725 112 462 857 940 000 ns fSetTimeLastLoop=true ) 2024-08-31 11:27:33 (7616): VM state change detected. (old = 'running', new = 'paused') 2024-08-31 15:23:50 (7616): Error in resume VM for VM: -182 Command: VBoxManage -q controlvm "boinc_fb5d9a5b3f64f498" 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 395 of file VBoxManageControlVM.cpp 2024-08-31 15:23:50 (7616): VM state change detected. (old = 'paused', new = 'running') 2024-08-31 15:23:51 (7616): VM state change detected. (old = 'running', new = 'paused') 2024-08-31 15:34:01 (7616): Guest Log: 01:09:31.780944 timesync vgsvcTimeSyncWorker: Radical host time change: 14 791 112 000 000ns (HostNow=1 725 143 641 145 000 000 ns HostLast=1 725 128 850 033 000 000 ns) 2024-08-31 15:34:01 (7616): VM state change detected. (old = 'paused', new = 'running') 2024-08-31 15:34:11 (7616): Guest Log: 01:09:41.781415 timesync vgsvcTimeSyncWorker: Radical guest time change: 14 791 103 428 000ns (GuestNow=1 725 143 651 145 483 000 ns GuestLast=1 725 128 860 042 055 000 ns fSetTimeLastLoop=true ) 2024-08-31 15:54:09 (7616): VM state change detected. (old = 'running', new = 'paused') 2024-08-31 20:42:56 (7616): Error in resume VM for VM: -182 Command: VBoxManage -q controlvm "boinc_fb5d9a5b3f64f498" 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 395 of file VBoxManageControlVM.cpp 2024-08-31 20:42:57 (7616): Guest Log: 01:29:42.233358 timesync vgsvcTimeSyncWorker: Radical host time change: 17 330 473 000 000ns (HostNow=1 725 162 172 067 000 000 ns HostLast=1 725 144 841 594 000 000 ns) 2024-08-31 20:42:57 (7616): VM state change detected. (old = 'paused', new = 'running') 2024-08-31 20:42:58 (7616): VM state change detected. (old = 'running', new = 'paused') 2024-09-01 00:04:47 (7616): VM state change detected. (old = 'paused', new = 'running') 2024-09-01 00:04:51 (7616): Guest Log: 01:29:52.234169 timesync vgsvcTimeSyncWorker: Radical host time change: 12 118 918 000 000ns (HostNow=1 725 174 290 985 000 000 ns HostLast=1 725 162 172 067 000 000 ns) 2024-09-01 00:04:51 (7616): Guest Log: 01:29:52.234228 timesync vgsvcTimeSyncWorker: Radical guest time change: 17 330 470 429 000ns (GuestNow=1 725 162 182 067 822 000 ns GuestLast=1 725 144 851 597 393 000 ns fSetTimeLastLoop=true ) 2024-09-01 00:05:01 (7616): Guest Log: 01:30:02.235515 timesync vgsvcTimeSyncWorker: Radical guest time change: 12 118 918 529 000ns (GuestNow=1 725 174 300 986 351 000 ns GuestLast=1 725 162 182 067 822 000 ns fSetTimeLastLoop=true ) 2024-09-01 00:15:02 (7616): Status Report: Job Duration: '864000.000000' 2024-09-01 00:15:02 (7616): Status Report: Elapsed Time: '6000.000000' 2024-09-01 00:15:02 (7616): Status Report: CPU Time: '5799.906250' 2024-09-01 00:23:49 (7616): VM state change detected. (old = 'running', new = 'paused') 2024-09-01 09:12:19 (7616): Stopping VM. 2024-09-01 09:12:19 (7616): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_fb5d9a5b3f64f498" savestate Output: 2024-09-01 09:12:19 (7616): VM did not stop when requested. 2024-09-01 09:12:19 (7616): VM was NOT successfully terminated. 2024-09-01 11:42:51 (13172): vboxwrapper version 26207 2024-09-01 11:42:51 (13172): BOINC client version: 8.0.4 2024-09-01 11:42:52 (13172): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2024-09-01 11:42:52 (13172): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-09-01 11:42:53 (13172): Guest Log: BIOS: VirtualBox 7.0.6 2024-09-01 11:42:53 (13172): Guest Log: CPUID EDX: 0x178bfbff 2024-09-01 11:42:53 (13172): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-09-01 11:42:53 (13172): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-09-01 11:42:53 (13172): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-09-01 11:42:53 (13172): Guest Log: BIOS: Booting from Hard Disk... 2024-09-01 11:42:53 (13172): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-09-01 11:42:53 (13172): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-09-01 11:42:53 (13172): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-09-01 11:42:53 (13172): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-09-01 11:42:53 (13172): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-09-01 11:42:53 (13172): Guest Log: 00:00:00.000114 main Log opened 2024-08-30T14:07:38.025775000Z 2024-09-01 11:42:53 (13172): Guest Log: 00:00:00.000281 main OS Product: Linux 2024-09-01 11:42:53 (13172): Guest Log: 00:00:00.000321 main OS Release: 4.14.76-13.cernvm.x86_64 2024-09-01 11:42:53 (13172): Guest Log: 00:00:00.000354 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-09-01 11:42:53 (13172): Guest Log: 00:00:00.000379 main Executable: /usr/sbin/VBoxService 2024-09-01 11:42:53 (13172): Guest Log: 00:00:00.000380 main Process ID: 3218 2024-09-01 11:42:53 (13172): Guest Log: 00:00:00.000380 main Package type: LINUX_64BITS_GENERIC 2024-09-01 11:42:53 (13172): Guest Log: 00:00:00.002447 main 5.2.6 r120293 started. Verbose level = 0 2024-09-01 11:42:53 (13172): Guest Log: 08:07:58 MDT -06:00 2024-08-30: cranky: [INFO] Detected Theory App 2024-09-01 11:42:53 (13172): Guest Log: 08:07:58 MDT -06:00 2024-08-30: cranky: [INFO] Checking CVMFS. 2024-09-01 11:42:53 (13172): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-09-01 11:42:53 (13172): 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-01 11:42:53 (13172): Guest Log: 2.5.2.0 4120 0 25132 30344 3 1 273004 4096000 0 65024 0 0 n/a 5 7 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-09-01 11:42:53 (13172): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-09-01 11:42:53 (13172): 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-01 11:42:53 (13172): Guest Log: 2.5.2.0 4051 0 27532 25101 3 2 273004 4096000 0 65024 0 2 -100 7969 2288 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-09-01 11:42:53 (13172): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-09-01 11:42:53 (13172): 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-01 11:42:53 (13172): Guest Log: 2.5.2.0 4072 0 27232 272 3 1 273004 4096000 0 65024 0 0 n/a 4 2 http://s1bnl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-09-01 11:42:53 (13172): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-09-01 11:42:53 (13172): 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-01 11:42:53 (13172): Guest Log: 2.5.2.0 4092 0 39652 19532 3 1 273004 4096000 0 65024 0 0 n/a 5791 1611 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-09-01 11:42:53 (13172): Guest Log: 08:08:02 MDT -06:00 2024-08-30: cranky: [INFO] Checking runc. 2024-09-01 11:42:53 (13172): Guest Log: 08:08:02 MDT -06:00 2024-08-30: cranky: [INFO] Creating the filesystem. 2024-09-01 11:42:53 (13172): Guest Log: 08:08:02 MDT -06:00 2024-08-30: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-09-01 11:42:53 (13172): Guest Log: 08:08:02 MDT -06:00 2024-08-30: cranky: [INFO] Updating config.json. 2024-09-01 11:42:53 (13172): Guest Log: 08:08:02 MDT -06:00 2024-08-30: cranky: [INFO] Running Container 'runc'. 2024-09-01 11:42:53 (13172): Guest Log: job: htmld=/shared/html/job 2024-09-01 11:42:53 (13172): Guest Log: job: unpack exitcode=0 2024-09-01 11:42:53 (13172): Guest Log: INFO: activated the work-around for ld: 2024-09-01 11:42:53 (13172): Guest Log: lrwxrwxrwx 1 0 0 15 Aug 30 14:08 /tmp/tmp.9IhSX6O903/ld -> /usr/bin/ld.bfd 2024-09-01 11:42:53 (13172): Guest Log: 08:08:20 MDT -06:00 2024-08-30: cranky: [INFO] ===> [runRivet] Fri Aug 30 14:08:19 UTC 2024 [boinc pp jets 7000 800 - pythia8 8.230 early 100000 392] 2024-09-01 11:42:53 (13172): Guest Log: 00:19:30.522491 timesync vgsvcTimeSyncWorker: Radical host time change: 33 559 900 000 000ns (HostNow=1 725 061 577 935 000 000 ns HostLast=1 725 028 018 035 000 000 ns) 2024-09-01 11:42:53 (13172): Guest Log: 00:19:40.562037 timesync vgsvcTimeSyncWorker: Radical host time change: 1 881 459 000 000ns (HostNow=1 725 063 459 394 000 000 ns HostLast=1 725 061 577 935 000 000 ns) 2024-09-01 11:42:53 (13172): Guest Log: 00:19:40.562085 timesync vgsvcTimeSyncWorker: Radical guest time change: 33 559 555 326 000ns (GuestNow=1 725 061 588 010 801 000 ns GuestLast=1 725 028 028 455 475 000 ns fSetTimeLastLoop=true ) 2024-09-01 11:42:53 (13172): Guest Log: 00:19:50.564428 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 881 385 597 000ns (GuestNow=1 725 063 469 396 398 000 ns GuestLast=1 725 061 588 010 801 000 ns fSetTimeLastLoop=true ) 2024-09-01 11:42:53 (13172): Guest Log: 00:28:40.734208 timesync vgsvcTimeSyncWorker: Radical host time change: 15 804 455 000 000ns (HostNow=1 725 079 794 017 000 000 ns HostLast=1 725 063 989 562 000 000 ns) 2024-09-01 11:42:53 (13172): Guest Log: 00:28:50.734475 timesync vgsvcTimeSyncWorker: Radical guest time change: 15 804 451 113 000ns (GuestNow=1 725 079 804 017 274 000 ns GuestLast=1 725 063 999 566 161 000 ns fSetTimeLastLoop=true ) 2024-09-01 11:42:53 (13172): Guest Log: 00:49:01.213928 timesync vgsvcTimeSyncWorker: Radical host time change: 31 448 369 000 000ns (HostNow=1 725 112 452 857 000 000 ns HostLast=1 725 081 004 488 000 000 ns) 2024-09-01 11:42:53 (13172): Guest Log: 00:49:11.214868 timesync vgsvcTimeSyncWorker: Radical host time change: 15 186 619 000 000ns (HostNow=1 725 127 639 476 000 000 ns HostLast=1 725 112 452 857 000 000 ns) 2024-09-01 11:42:53 (13172): Guest Log: 00:49:11.214917 timesync vgsvcTimeSyncWorker: Radical guest time change: 31 448 361 238 000ns (GuestNow=1 725 112 462 857 940 000 ns GuestLast=1 725 081 014 496 702 000 ns fSetTimeLastLoop=true ) 2024-09-01 11:42:53 (13172): Guest Log: 00:49:21.225149 timesync vgsvcTimeSyncWorker: Radical guest time change: 15 186 628 345 000ns (GuestNow=1 725 127 649 486 285 000 ns GuestLast=1 725 112 462 857 940 000 ns fSetTimeLastLoop=true ) 2024-09-01 11:42:53 (13172): Guest Log: 01:09:31.780944 timesync vgsvcTimeSyncWorker: Radical host time change: 14 791 112 000 000ns (HostNow=1 725 143 641 145 000 000 ns HostLast=1 725 128 850 033 000 000 ns) 2024-09-01 11:42:53 (13172): Guest Log: 01:09:41.781415 timesync vgsvcTimeSyncWorker: Radical guest time change: 14 791 103 428 000ns (GuestNow=1 725 143 651 145 483 000 ns GuestLast=1 725 128 860 042 055 000 ns fSetTimeLastLoop=true ) 2024-09-01 11:42:53 (13172): Guest Log: 01:29:42.233358 timesync vgsvcTimeSyncWorker: Radical host time change: 17 330 473 000 000ns (HostNow=1 725 162 172 067 000 000 ns HostLast=1 725 144 841 594 000 000 ns) 2024-09-01 11:42:53 (13172): Guest Log: 01:29:52.234169 timesync vgsvcTimeSyncWorker: Radical host time change: 12 118 918 000 000ns (HostNow=1 725 174 290 985 000 000 ns HostLast=1 725 162 172 067 000 000 ns) 2024-09-01 11:42:53 (13172): Guest Log: 01:29:52.234228 timesync vgsvcTimeSyncWorker: Radical guest time change: 17 330 470 429 000ns (GuestNow=1 725 162 182 067 822 000 ns GuestLast=1 725 144 851 597 393 000 ns fSetTimeLastLoop=true ) 2024-09-01 11:42:53 (13172): Guest Log: 01:30:02.235515 timesync vgsvcTimeSyncWorker: Radical guest time change: 12 118 918 529 000ns (GuestNow=1 725 174 300 986 351 000 ns GuestLast=1 725 162 182 067 822 000 ns fSetTimeLastLoop=true ) 2024-09-01 11:42:53 (13172): Starting VM using VBoxManage interface. (boinc_fb5d9a5b3f64f498, slot#6) 2024-09-01 11:42:59 (13172): Successfully started VM. (PID = '18608') 2024-09-01 11:42:59 (13172): Reporting VM Process ID to BOINC. 2024-09-01 11:42:59 (13172): Guest Log: BIOS: VirtualBox 7.0.6 2024-09-01 11:42:59 (13172): Guest Log: CPUID EDX: 0x178bfbff 2024-09-01 11:42:59 (13172): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-09-01 11:42:59 (13172): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-09-01 11:42:59 (13172): VM state change detected. (old = 'poweredoff', new = 'running') 2024-09-01 11:42:59 (13172): Detected: Web Application Enabled (http://localhost:63298) 2024-09-01 11:42:59 (13172): Status Report: Job Duration: '864000.000000' 2024-09-01 11:42:59 (13172): Status Report: Elapsed Time: '6524.000000' 2024-09-01 11:42:59 (13172): Status Report: CPU Time: '6319.781250' 2024-09-01 11:42:59 (13172): Preference change detected 2024-09-01 11:42:59 (13172): Setting CPU throttle for VM. (100%) 2024-09-01 11:42:59 (13172): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds)) 2024-09-01 11:43:01 (13172): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-09-01 11:43:01 (13172): Guest Log: BIOS: Booting from Hard Disk... 2024-09-01 11:43:03 (13172): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-09-01 11:43:03 (13172): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-09-01 11:43:23 (13172): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-09-01 11:43:23 (13172): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-09-01 11:43:27 (13172): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-09-01 11:43:27 (13172): Guest Log: 00:00:00.000132 main Log opened 2024-09-01T18:43:27.637097000Z 2024-09-01 11:43:27 (13172): Guest Log: 00:00:00.000248 main OS Product: Linux 2024-09-01 11:43:27 (13172): Guest Log: 00:00:00.000267 main OS Release: 4.14.76-13.cernvm.x86_64 2024-09-01 11:43:27 (13172): Guest Log: 00:00:00.000281 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-09-01 11:43:27 (13172): Guest Log: 00:00:00.000296 main Executable: /usr/sbin/VBoxService 2024-09-01 11:43:27 (13172): Guest Log: 00:00:00.000297 main Process ID: 3220 2024-09-01 11:43:27 (13172): Guest Log: 00:00:00.000297 main Package type: LINUX_64BITS_GENERIC 2024-09-01 11:43:27 (13172): Guest Log: 00:00:00.001825 main 5.2.6 r120293 started. Verbose level = 0 2024-09-01 11:44:21 (13172): Guest Log: 12:44:21 MDT -06:00 2024-09-01: cranky: [INFO] Detected Theory App 2024-09-01 11:44:21 (13172): Guest Log: 12:44:21 MDT -06:00 2024-09-01: cranky: [INFO] Checking CVMFS. 2024-09-01 11:44:21 (13172): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-09-01 11:44:22 (13172): 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-01 11:44:22 (13172): Guest Log: 2.5.2.0 4093 0 27204 30361 3 1 1109148 4096000 0 65024 0 0 n/a 5 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-09-01 11:44:22 (13172): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-09-01 11:44:22 (13172): 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-01 11:44:22 (13172): Guest Log: 2.5.2.0 4129 0 27520 25119 3 2 1109148 4096000 0 65024 0 2 0 5830 187 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-09-01 11:44:23 (13172): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-09-01 11:44:25 (13172): 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-01 11:44:25 (13172): Guest Log: 2.5.2.0 4061 0 27224 272 3 1 1109148 4096000 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-09-01 11:44:26 (13172): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-09-01 11:44:29 (13172): 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-01 11:44:29 (13172): Guest Log: 2.5.2.0 4152 0 38188 19553 3 1 1109148 4096000 0 65024 0 0 n/a 5795 172 http://s1ihep-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-09-01 11:44:29 (13172): Guest Log: 12:44:29 MDT -06:00 2024-09-01: cranky: [INFO] Checking runc. 2024-09-01 11:44:29 (13172): Guest Log: 12:44:29 MDT -06:00 2024-09-01: cranky: [INFO] Creating the filesystem. 2024-09-01 11:44:29 (13172): Guest Log: 12:44:29 MDT -06:00 2024-09-01: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-09-01 11:44:29 (13172): Guest Log: 12:44:29 MDT -06:00 2024-09-01: cranky: [INFO] Updating config.json. 2024-09-01 11:44:29 (13172): Guest Log: 12:44:29 MDT -06:00 2024-09-01: cranky: [INFO] Running Container 'runc'. 2024-09-01 11:44:29 (13172): Guest Log: job: htmld=/shared/html/job 2024-09-01 11:44:30 (13172): Guest Log: job: unpack exitcode=0 2024-09-01 11:44:30 (13172): Guest Log: INFO: activated the work-around for ld: 2024-09-01 11:44:30 (13172): Guest Log: lrwxrwxrwx 1 0 0 15 Sep 1 18:44 /tmp/tmp.jYBjkVSgDp/ld -> /usr/bin/ld.bfd 2024-09-01 11:44:31 (13172): Guest Log: 12:44:31 MDT -06:00 2024-09-01: cranky: [INFO] ===> [runRivet] Sun Sep 1 18:44:29 UTC 2024 [boinc pp jets 7000 800 - pythia8 8.230 early 100000 392] 2024-09-01 11:48:58 (13172): VM state change detected. (old = 'running', new = 'paused') 2024-09-01 20:36:43 (13172): Guest Log: 00:05:30.215860 timesync vgsvcTimeSyncWorker: Radical host time change: 31 675 147 000 000ns (HostNow=1 725 248 202 642 000 000 ns HostLast=1 725 216 527 495 000 000 ns) 2024-09-01 20:36:43 (13172): VM state change detected. (old = 'paused', new = 'running') 2024-09-01 20:36:53 (13172): Guest Log: 00:05:40.251129 timesync vgsvcTimeSyncWorker: Radical guest time change: 31 674 907 631 000ns (GuestNow=1 725 248 212 690 754 000 ns GuestLast=1 725 216 537 783 123 000 ns fSetTimeLastLoop=true ) 2024-09-01 20:56:46 (13172): VM state change detected. (old = 'running', new = 'paused') 2024-09-02 05:33:39 (13172): Error in resume VM for VM: -182 Command: VBoxManage -q controlvm "boinc_fb5d9a5b3f64f498" 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 395 of file VBoxManageControlVM.cpp 2024-09-02 05:33:40 (13172): VM state change detected. (old = 'paused', new = 'running') 2024-09-02 05:33:41 (13172): VM state change detected. (old = 'running', new = 'paused') 2024-09-02 06:33:26 (13172): Guest Log: 00:25:40.744129 timesync vgsvcTimeSyncWorker: Radical host time change: 34 603 167 000 000ns (HostNow=1 725 284 006 403 000 000 ns HostLast=1 725 249 403 236 000 000 ns) 2024-09-02 06:33:26 (13172): VM state change detected. (old = 'paused', new = 'running') 2024-09-02 06:33:29 (13172): VM state change detected. (old = 'running', new = 'paused') 2024-09-02 06:44:30 (13172): VM state change detected. (old = 'paused', new = 'running') 2024-09-02 06:44:37 (13172): Guest Log: 00:25:50.745992 timesync vgsvcTimeSyncWorker: Radical guest time change: 34 603 221 113 000ns (GuestNow=1 725 284 016 404 856 000 ns GuestLast=1 725 249 413 183 743 000 ns fSetTimeLastLoop=true ) 2024-09-02 07:04:29 (13172): VM state change detected. (old = 'running', new = 'paused') 2024-09-02 16:00:53 (13172): Error in resume VM for VM: -182 Command: VBoxManage -q controlvm "boinc_fb5d9a5b3f64f498" 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 395 of file VBoxManageControlVM.cpp 2024-09-02 16:00:54 (13172): Guest Log: 00:45:51.191529 timesync vgsvcTimeSyncWorker: Radical host time change: 32 186 146 000 000ns (HostNow=1 725 318 052 714 000 000 ns HostLast=1 725 285 866 568 000 000 ns) 2024-09-02 16:00:54 (13172): VM state change detected. (old = 'paused', new = 'running') 2024-09-02 16:00:55 (13172): VM state change detected. (old = 'running', new = 'paused') 2024-09-02 20:06:48 (13172): VM state change detected. (old = 'paused', new = 'running') 2024-09-02 20:06:57 (13172): Guest Log: 00:46:01.192647 timesync vgsvcTimeSyncWorker: Radical host time change: 14 763 397 000 000ns (HostNow=1 725 332 816 111 000 000 ns HostLast=1 725 318 052 714 000 000 ns) 2024-09-02 20:06:57 (13172): Guest Log: 00:46:01.192718 timesync vgsvcTimeSyncWorker: Radical guest time change: 32 845 864 722 000ns (GuestNow=1 725 318 062 715 114 000 ns GuestLast=1 725 285 216 850 392 000 ns fSetTimeLastLoop=true ) 2024-09-02 20:07:07 (13172): Guest Log: 00:46:11.193902 timesync vgsvcTimeSyncWorker: Radical guest time change: 14 763 397 148 000ns (GuestNow=1 725 332 826 112 262 000 ns GuestLast=1 725 318 062 715 114 000 ns fSetTimeLastLoop=true ) 2024-09-02 20:27:13 (13172): VM state change detected. (old = 'running', new = 'paused') 2024-09-03 05:33:10 (13172): Error in resume VM for VM: -182 Command: VBoxManage -q controlvm "boinc_fb5d9a5b3f64f498" 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 395 of file VBoxManageControlVM.cpp 2024-09-03 05:33:11 (13172): Guest Log: 01:06:21.683917 timesync vgsvcTimeSyncWorker: Radical host time change: 32 735 251 000 000ns (HostNow=1 725 366 761 847 000 000 ns HostLast=1 725 334 026 596 000 000 ns) 2024-09-03 05:33:11 (13172): Guest Log: 01:06:31.687153 timesync vgsvcTimeSyncWorker: Radical guest time change: 32 735 247 995 000ns (GuestNow=1 725 366 771 850 249 000 ns GuestLast=1 725 334 036 602 254 000 ns fSetTimeLastLoop=true ) 2024-09-03 05:33:11 (13172): VM state change detected. (old = 'paused', new = 'running') 2024-09-03 05:33:12 (13172): VM state change detected. (old = 'running', new = 'paused') 2024-09-03 06:44:34 (13172): Guest Log: 01:06:51.691164 timesync vgsvcTimeSyncWorker: Radical host time change: 4 291 604 000 000ns (HostNow=1 725 371 073 460 000 000 ns HostLast=1 725 366 781 856 000 000 ns) 2024-09-03 06:44:34 (13172): VM state change detected. (old = 'paused', new = 'running') 2024-09-03 06:44:43 (13172): Guest Log: 01:07:01.699167 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 291 613 746 000ns (GuestNow=1 725 371 083 467 996 000 ns GuestLast=1 725 366 791 854 250 000 ns fSetTimeLastLoop=true ) 2024-09-03 06:47:54 (13172): VM state change detected. (old = 'running', new = 'paused') 2024-09-03 06:58:41 (13172): VM state change detected. (old = 'paused', new = 'running') 2024-09-03 07:19:32 (13172): VM state change detected. (old = 'running', new = 'paused') 2024-09-03 15:09:37 (13172): Error in resume VM for VM: -182 Command: VBoxManage -q controlvm "boinc_fb5d9a5b3f64f498" 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 395 of file VBoxManageControlVM.cpp 2024-09-03 15:09:38 (13172): Guest Log: 01:31:12.346783 timesync vgsvcTimeSyncWorker: Radical host time change: 28 204 891 000 000ns (HostNow=1 725 401 374 918 000 000 ns HostLast=1 725 373 170 027 000 000 ns) 2024-09-03 15:09:38 (13172): VM state change detected. (old = 'paused', new = 'running') 2024-09-03 15:09:39 (13172): VM state change detected. (old = 'running', new = 'paused') 2024-09-03 20:25:24 (13172): VM state change detected. (old = 'paused', new = 'running') 2024-09-03 20:25:30 (13172): Guest Log: 01:31:22.347479 timesync vgsvcTimeSyncWorker: Radical host time change: 18 955 663 000 000ns (HostNow=1 725 420 330 581 000 000 ns HostLast=1 725 401 374 918 000 000 ns) 2024-09-03 20:25:30 (13172): Guest Log: 01:31:22.347560 timesync vgsvcTimeSyncWorker: Radical guest time change: 28 850 803 091 000ns (GuestNow=1 725 401 384 918 677 000 ns GuestLast=1 725 372 534 115 586 000 ns fSetTimeLastLoop=true ) 2024-09-03 20:25:40 (13172): Guest Log: 01:31:32.355247 timesync vgsvcTimeSyncWorker: Radical guest time change: 18 955 670 130 000ns (GuestNow=1 725 420 340 588 807 000 ns GuestLast=1 725 401 384 918 677 000 ns fSetTimeLastLoop=true ) 2024-09-03 20:25:41 (13172): VM state change detected. (old = 'running', new = 'paused') 2024-09-06 07:52:58 (19488): vboxwrapper version 26207 2024-09-06 07:52:58 (19488): BOINC client version: 8.0.4 2024-09-06 07:52:59 (19488): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2024-09-06 07:52:59 (19488): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-09-06 07:53:00 (19488): Guest Log: BIOS: VirtualBox 7.0.6 2024-09-06 07:53:00 (19488): Guest Log: CPUID EDX: 0x178bfbff 2024-09-06 07:53:00 (19488): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-09-06 07:53:00 (19488): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-09-06 07:53:00 (19488): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-09-06 07:53:00 (19488): Guest Log: BIOS: Booting from Hard Disk... 2024-09-06 07:53:00 (19488): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-09-06 07:53:00 (19488): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-09-06 07:53:00 (19488): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-09-06 07:53:00 (19488): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-09-06 07:53:00 (19488): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-09-06 07:53:00 (19488): Guest Log: 00:00:00.000132 main Log opened 2024-09-01T18:43:27.637097000Z 2024-09-06 07:53:00 (19488): Guest Log: 00:00:00.000248 main OS Product: Linux 2024-09-06 07:53:00 (19488): Guest Log: 00:00:00.000267 main OS Release: 4.14.76-13.cernvm.x86_64 2024-09-06 07:53:00 (19488): Guest Log: 00:00:00.000281 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-09-06 07:53:00 (19488): Guest Log: 00:00:00.000296 main Executable: /usr/sbin/VBoxService 2024-09-06 07:53:00 (19488): Guest Log: 00:00:00.000297 main Process ID: 3220 2024-09-06 07:53:00 (19488): Guest Log: 00:00:00.000297 main Package type: LINUX_64BITS_GENERIC 2024-09-06 07:53:00 (19488): Guest Log: 00:00:00.001825 main 5.2.6 r120293 started. Verbose level = 0 2024-09-06 07:53:00 (19488): Guest Log: 12:44:21 MDT -06:00 2024-09-01: cranky: [INFO] Detected Theory App 2024-09-06 07:53:00 (19488): Guest Log: 12:44:21 MDT -06:00 2024-09-01: cranky: [INFO] Checking CVMFS. 2024-09-06 07:53:00 (19488): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-09-06 07:53:00 (19488): 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-06 07:53:00 (19488): Guest Log: 2.5.2.0 4093 0 27204 30361 3 1 1109148 4096000 0 65024 0 0 n/a 5 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-09-06 07:53:00 (19488): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-09-06 07:53:00 (19488): 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-06 07:53:00 (19488): Guest Log: 2.5.2.0 4129 0 27520 25119 3 2 1109148 4096000 0 65024 0 2 0 5830 187 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-09-06 07:53:00 (19488): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-09-06 07:53:00 (19488): 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-06 07:53:00 (19488): Guest Log: 2.5.2.0 4061 0 27224 272 3 1 1109148 4096000 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-09-06 07:53:00 (19488): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-09-06 07:53:00 (19488): 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-06 07:53:00 (19488): Guest Log: 2.5.2.0 4152 0 38188 19553 3 1 1109148 4096000 0 65024 0 0 n/a 5795 172 http://s1ihep-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-09-06 07:53:00 (19488): Guest Log: 12:44:29 MDT -06:00 2024-09-01: cranky: [INFO] Checking runc. 2024-09-06 07:53:00 (19488): Guest Log: 12:44:29 MDT -06:00 2024-09-01: cranky: [INFO] Creating the filesystem. 2024-09-06 07:53:00 (19488): Guest Log: 12:44:29 MDT -06:00 2024-09-01: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-09-06 07:53:00 (19488): Guest Log: 12:44:29 MDT -06:00 2024-09-01: cranky: [INFO] Updating config.json. 2024-09-06 07:53:00 (19488): Guest Log: 12:44:29 MDT -06:00 2024-09-01: cranky: [INFO] Running Container 'runc'. 2024-09-06 07:53:00 (19488): Guest Log: job: htmld=/shared/html/job 2024-09-06 07:53:00 (19488): Guest Log: job: unpack exitcode=0 2024-09-06 07:53:00 (19488): Guest Log: INFO: activated the work-around for ld: 2024-09-06 07:53:00 (19488): Guest Log: lrwxrwxrwx 1 0 0 15 Sep 1 18:44 /tmp/tmp.jYBjkVSgDp/ld -> /usr/bin/ld.bfd 2024-09-06 07:53:00 (19488): Guest Log: 12:44:31 MDT -06:00 2024-09-01: cranky: [INFO] ===> [runRivet] Sun Sep 1 18:44:29 UTC 2024 [boinc pp jets 7000 800 - pythia8 8.230 early 100000 392] 2024-09-06 07:53:00 (19488): Guest Log: 00:05:30.215860 timesync vgsvcTimeSyncWorker: Radical host time change: 31 675 147 000 000ns (HostNow=1 725 248 202 642 000 000 ns HostLast=1 725 216 527 495 000 000 ns) 2024-09-06 07:53:00 (19488): Guest Log: 00:05:40.251129 timesync vgsvcTimeSyncWorker: Radical guest time change: 31 674 907 631 000ns (GuestNow=1 725 248 212 690 754 000 ns GuestLast=1 725 216 537 783 123 000 ns fSetTimeLastLoop=true ) 2024-09-06 07:53:00 (19488): Guest Log: 00:25:40.744129 timesync vgsvcTimeSyncWorker: Radical host time change: 34 603 167 000 000ns (HostNow=1 725 284 006 403 000 000 ns HostLast=1 725 249 403 236 000 000 ns) 2024-09-06 07:53:00 (19488): Guest Log: 00:25:50.745992 timesync vgsvcTimeSyncWorker: Radical guest time change: 34 603 221 113 000ns (GuestNow=1 725 284 016 404 856 000 ns GuestLast=1 725 249 413 183 743 000 ns fSetTimeLastLoop=true ) 2024-09-06 07:53:00 (19488): Guest Log: 00:45:51.191529 timesync vgsvcTimeSyncWorker: Radical host time change: 32 186 146 000 000ns (HostNow=1 725 318 052 714 000 000 ns HostLast=1 725 285 866 568 000 000 ns) 2024-09-06 07:53:00 (19488): Guest Log: 00:46:01.192647 timesync vgsvcTimeSyncWorker: Radical host time change: 14 763 397 000 000ns (HostNow=1 725 332 816 111 000 000 ns HostLast=1 725 318 052 714 000 000 ns) 2024-09-06 07:53:00 (19488): Guest Log: 00:46:01.192718 timesync vgsvcTimeSyncWorker: Radical guest time change: 32 845 864 722 000ns (GuestNow=1 725 318 062 715 114 000 ns GuestLast=1 725 285 216 850 392 000 ns fSetTimeLastLoop=true ) 2024-09-06 07:53:00 (19488): Guest Log: 00:46:11.193902 timesync vgsvcTimeSyncWorker: Radical guest time change: 14 763 397 148 000ns (GuestNow=1 725 332 826 112 262 000 ns GuestLast=1 725 318 062 715 114 000 ns fSetTimeLastLoop=true ) 2024-09-06 07:53:00 (19488): Guest Log: 01:06:21.683917 timesync vgsvcTimeSyncWorker: Radical host time change: 32 735 251 000 000ns (HostNow=1 725 366 761 847 000 000 ns HostLast=1 725 334 026 596 000 000 ns) 2024-09-06 07:53:00 (19488): Guest Log: 01:06:31.687153 timesync vgsvcTimeSyncWorker: Radical guest time change: 32 735 247 995 000ns (GuestNow=1 725 366 771 850 249 000 ns GuestLast=1 725 334 036 602 254 000 ns fSetTimeLastLoop=true ) 2024-09-06 07:53:00 (19488): Guest Log: 01:06:51.691164 timesync vgsvcTimeSyncWorker: Radical host time change: 4 291 604 000 000ns (HostNow=1 725 371 073 460 000 000 ns HostLast=1 725 366 781 856 000 000 ns) 2024-09-06 07:53:00 (19488): Guest Log: 01:07:01.699167 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 291 613 746 000ns (GuestNow=1 725 371 083 467 996 000 ns GuestLast=1 725 366 791 854 250 000 ns fSetTimeLastLoop=true ) 2024-09-06 07:53:00 (19488): Guest Log: 01:31:12.346783 timesync vgsvcTimeSyncWorker: Radical host time change: 28 204 891 000 000ns (HostNow=1 725 401 374 918 000 000 ns HostLast=1 725 373 170 027 000 000 ns) 2024-09-06 07:53:00 (19488): Guest Log: 01:31:22.347479 timesync vgsvcTimeSyncWorker: Radical host time change: 18 955 663 000 000ns (HostNow=1 725 420 330 581 000 000 ns HostLast=1 725 401 374 918 000 000 ns) 2024-09-06 07:53:00 (19488): Guest Log: 01:31:22.347560 timesync vgsvcTimeSyncWorker: Radical guest time change: 28 850 803 091 000ns (GuestNow=1 725 401 384 918 677 000 ns GuestLast=1 725 372 534 115 586 000 ns fSetTimeLastLoop=true ) 2024-09-06 07:53:00 (19488): Guest Log: 01:31:32.355247 timesync vgsvcTimeSyncWorker: Radical guest time change: 18 955 670 130 000ns (GuestNow=1 725 420 340 588 807 000 ns GuestLast=1 725 401 384 918 677 000 ns fSetTimeLastLoop=true ) 2024-09-06 07:53:00 (19488): Starting VM using VBoxManage interface. (boinc_fb5d9a5b3f64f498, slot#6) 2024-09-06 07:53:11 (19488): Successfully started VM. (PID = '11340') 2024-09-06 07:53:11 (19488): Reporting VM Process ID to BOINC. 2024-09-06 07:53:11 (19488): Guest Log: BIOS: VirtualBox 7.0.6 2024-09-06 07:53:11 (19488): Guest Log: CPUID EDX: 0x178bfbff 2024-09-06 07:53:11 (19488): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-09-06 07:53:11 (19488): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-09-06 07:53:11 (19488): VM state change detected. (old = 'poweredoff', new = 'running') 2024-09-06 07:53:11 (19488): Detected: Web Application Enabled (http://localhost:63298) 2024-09-06 07:53:11 (19488): Status Report: Job Duration: '864000.000000' 2024-09-06 07:53:11 (19488): Status Report: Elapsed Time: '11607.500509' 2024-09-06 07:53:11 (19488): Status Report: CPU Time: '11583.328125' 2024-09-06 07:53:11 (19488): Preference change detected 2024-09-06 07:53:11 (19488): Setting CPU throttle for VM. (100%) 2024-09-06 07:53:12 (19488): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds)) 2024-09-06 07:53:13 (19488): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-09-06 07:53:13 (19488): Guest Log: BIOS: Booting from Hard Disk... 2024-09-06 07:53:15 (19488): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-09-06 07:53:15 (19488): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-09-06 07:53:42 (19488): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-09-06 07:53:42 (19488): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-09-06 07:53:47 (19488): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-09-06 07:53:47 (19488): Guest Log: 00:00:00.000080 main Log opened 2024-09-06T14:53:47.049772000Z 2024-09-06 07:53:47 (19488): Guest Log: 00:00:00.000183 main OS Product: Linux 2024-09-06 07:53:47 (19488): Guest Log: 00:00:00.000211 main OS Release: 4.14.76-13.cernvm.x86_64 2024-09-06 07:53:47 (19488): Guest Log: 00:00:00.000232 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-09-06 07:53:47 (19488): Guest Log: 00:00:00.000246 main Executable: /usr/sbin/VBoxService 2024-09-06 07:53:47 (19488): Guest Log: 00:00:00.000247 main Process ID: 3216 2024-09-06 07:53:47 (19488): Guest Log: 00:00:00.000247 main Package type: LINUX_64BITS_GENERIC 2024-09-06 07:53:47 (19488): Guest Log: 00:00:00.007836 main 5.2.6 r120293 started. Verbose level = 0 2024-09-06 07:54:56 (19488): Guest Log: 07:54:55 PDT -07:00 2024-09-06: cranky: [INFO] Detected Theory App 2024-09-06 07:54:56 (19488): Guest Log: 07:54:55 PDT -07:00 2024-09-06: cranky: [INFO] Checking CVMFS. 2024-09-06 07:54:56 (19488): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-09-06 07:54:57 (19488): 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-06 07:54:57 (19488): Guest Log: 2.5.2.0 4059 1 28312 30395 3 1 1141275 4096000 0 65024 0 0 n/a 5 1 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-09-06 07:54:58 (19488): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-09-06 07:54:58 (19488): 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-06 07:54:58 (19488): Guest Log: 2.5.2.0 4163 1 27632 25161 3 2 1141275 4096000 0 65024 0 2 0 7921 150 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-09-06 07:54:59 (19488): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-09-06 07:55:00 (19488): 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-06 07:55:00 (19488): Guest Log: 2.5.2.0 4085 1 27196 272 3 1 1141275 4096000 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-09-06 07:55:01 (19488): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-09-06 07:55:02 (19488): 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-06 07:55:02 (19488): Guest Log: 2.5.2.0 4143 1 39940 19619 3 1 1141275 4096000 0 65024 0 0 n/a 5841 121 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-09-06 07:55:02 (19488): Guest Log: 07:55:02 PDT -07:00 2024-09-06: cranky: [INFO] Checking runc. 2024-09-06 07:55:02 (19488): Guest Log: 07:55:02 PDT -07:00 2024-09-06: cranky: [INFO] Creating the filesystem. 2024-09-06 07:55:03 (19488): Guest Log: 07:55:02 PDT -07:00 2024-09-06: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-09-06 07:55:03 (19488): Guest Log: 07:55:02 PDT -07:00 2024-09-06: cranky: [INFO] Updating config.json. 2024-09-06 07:55:03 (19488): Guest Log: 07:55:03 PDT -07:00 2024-09-06: cranky: [INFO] Running Container 'runc'. 2024-09-06 07:55:04 (19488): Guest Log: job: htmld=/shared/html/job 2024-09-06 07:55:04 (19488): Guest Log: job: unpack exitcode=0 2024-09-06 07:55:04 (19488): Guest Log: INFO: activated the work-around for ld: 2024-09-06 07:55:04 (19488): Guest Log: lrwxrwxrwx 1 0 0 15 Sep 6 14:55 /tmp/tmp.SG96rrfaOG/ld -> /usr/bin/ld.bfd 2024-09-06 07:55:05 (19488): Guest Log: 07:55:05 PDT -07:00 2024-09-06: cranky: [INFO] ===> [runRivet] Fri Sep 6 14:55:04 UTC 2024 [boinc pp jets 7000 800 - pythia8 8.230 early 100000 392] 2024-09-06 09:34:23 (19488): Status Report: Job Duration: '864000.000000' 2024-09-06 09:34:23 (19488): Status Report: Elapsed Time: '17608.500509' 2024-09-06 09:34:23 (19488): Status Report: CPU Time: '14824.937500' 2024-09-06 11:07:17 (19488): Guest Log: job: run exitcode=0 2024-09-06 11:07:17 (19488): Guest Log: job: diskusage=3584 2024-09-06 11:07:17 (19488): Guest Log: job: logsize=76 k 2024-09-06 11:07:17 (19488): Guest Log: job: times= 2024-09-06 11:07:17 (19488): Guest Log: 0m0.011s 0m0.011s 2024-09-06 11:07:17 (19488): Guest Log: 188m55.617s 1m17.159s 2024-09-06 11:07:17 (19488): Guest Log: job: cpuusage=11413 2024-09-06 11:07:18 (19488): Guest Log: 11:07:17 PDT -07:00 2024-09-06: cranky: [INFO] Container 'runc' finished with status code 0. 2024-09-06 11:07:18 (19488): Guest Log: 11:07:17 PDT -07:00 2024-09-06: cranky: [INFO] Preparing output. 2024-09-06 11:07:18 (19488): Guest Log: [INFO] Job Finished 2024-09-06 11:07:18 (19488): Guest Log: [INFO] Shutting Down. 2024-09-06 11:07:18 (19488): VM Completion File Detected. 2024-09-06 11:07:18 (19488): VM Completion Message: Job Finished . 2024-09-06 11:07:18 (19488): Powering off VM. 2024-09-06 11:07:18 (19488): Successfully stopped VM. 2024-09-06 11:07:18 (19488): Deregistering VM. (boinc_fb5d9a5b3f64f498, slot#6) 2024-09-06 11:07:18 (19488): Removing network bandwidth throttle group from VM. 2024-09-06 11:07:18 (19488): Removing VM from VirtualBox. 2024-09-06 11:07:24 (19488): called boinc_finish(0) </stderr_txt> ]]>
©2025 CERN