| Name | Theory_2743-2707589-233_2 |
| Workunit | 223714633 |
| Created | 24 Jun 2024, 11:00:22 UTC |
| Sent | 24 Jun 2024, 16:59:17 UTC |
| Report deadline | 5 Jul 2024, 16:59:17 UTC |
| Received | 29 Jun 2024, 7:42:16 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10773647 |
| Run time | 10 hours 25 min 0 sec |
| CPU time | 5 hours 27 min 6 sec |
| Validate state | Valid |
| Credit | 229.58 |
| Device peak FLOPS | 2.64 GFLOPS |
| Application version | Theory Simulation v300.30 (vbox64_theory) windows_x86_64 |
| Peak working set size | 90.77 MB |
| Peak swap size | 104.52 MB |
| Peak disk usage | 1.72 GB |
Stderr output
<core_client_version>8.0.2</core_client_version> <![CDATA[ <stderr_txt> 4096000 0 65024 0 0 n/a 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-06-26 10:51:07 (5196): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-06-26 10:51:07 (5196): 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-06-26 10:51:07 (5196): Guest Log: 2.5.2.0 4101 0 39668 19001 3 1 1262445 4096000 0 65024 0 0 n/a 5537 2244 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-06-26 10:51:07 (5196): Guest Log: 07:59:28 AEST +10:00 2024-06-26: cranky: [INFO] Checking runc. 2024-06-26 10:51:07 (5196): Guest Log: 07:59:28 AEST +10:00 2024-06-26: cranky: [INFO] Creating the filesystem. 2024-06-26 10:51:07 (5196): Guest Log: 07:59:28 AEST +10:00 2024-06-26: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-06-26 10:51:07 (5196): Guest Log: 07:59:28 AEST +10:00 2024-06-26: cranky: [INFO] Updating config.json. 2024-06-26 10:51:07 (5196): Guest Log: 07:59:28 AEST +10:00 2024-06-26: cranky: [INFO] Running Container 'runc'. 2024-06-26 10:51:07 (5196): Guest Log: job: htmld=/shared/html/job 2024-06-26 10:51:07 (5196): Guest Log: job: unpack exitcode=0 2024-06-26 10:51:07 (5196): Guest Log: INFO: activated the work-around for ld: 2024-06-26 10:51:07 (5196): Guest Log: lrwxrwxrwx 1 0 0 15 Jun 25 21:59 /tmp/tmp.ffAyga7jC7/ld -> /usr/bin/ld.bfd 2024-06-26 10:51:07 (5196): Guest Log: 07:59:30 AEST +10:00 2024-06-26: cranky: [INFO] ===> [runRivet] Tue Jun 25 21:59:29 UTC 2024 [boinc pp bbbar 7000 - - pythia8 8.308 CP1-CR2 100000 233] 2024-06-26 10:51:07 (5196): Guest Log: 00:24:42.055644 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 210 317 406 000ns (GuestNow=1 719 356 404 715 887 000 ns GuestLast=1 719 354 194 398 481 000 ns fSetTimeLastLoop=true ) 2024-06-26 10:51:07 (5196): Starting VM using VBoxManage interface. (boinc_93c00f1e5af4b7c6, slot#2) 2024-06-26 10:51:18 (5196): Successfully started VM. (PID = '20268') 2024-06-26 10:51:18 (5196): Reporting VM Process ID to BOINC. 2024-06-26 10:51:18 (5196): Guest Log: BIOS: VirtualBox 7.0.14 2024-06-26 10:51:18 (5196): Guest Log: CPUID EDX: 0x178bfbff 2024-06-26 10:51:18 (5196): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-26 10:51:18 (5196): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-26 10:51:18 (5196): VM state change detected. (old = 'poweredoff', new = 'running') 2024-06-26 10:51:18 (5196): Detected: Web Application Enabled (http://localhost:51347) 2024-06-26 10:51:18 (5196): Detected: Remote Desktop Enabled (localhost:51348) 2024-06-26 10:51:18 (5196): Status Report: Job Duration: '864000.000000' 2024-06-26 10:51:18 (5196): Status Report: Elapsed Time: '10147.596727' 2024-06-26 10:51:18 (5196): Status Report: CPU Time: '4961.718750' 2024-06-26 10:51:18 (5196): Preference change detected 2024-06-26 10:51:18 (5196): Setting CPU throttle for VM. (100%) 2024-06-26 10:51:18 (5196): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-26 10:51:20 (5196): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-26 10:51:20 (5196): Guest Log: BIOS: Booting from Hard Disk... 2024-06-26 10:51:23 (5196): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-26 10:51:23 (5196): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-26 10:51:46 (5196): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-26 10:51:46 (5196): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-26 10:51:50 (5196): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-26 10:51:50 (5196): Guest Log: 00:00:00.000116 main Log opened 2024-06-26T00:51:50.591567000Z 2024-06-26 10:51:50 (5196): Guest Log: 00:00:00.000291 main OS Product: Linux 2024-06-26 10:51:50 (5196): Guest Log: 00:00:00.000343 main OS Release: 4.14.76-13.cernvm.x86_64 2024-06-26 10:51:50 (5196): Guest Log: 00:00:00.000385 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-06-26 10:51:50 (5196): Guest Log: 00:00:00.000426 main Executable: /usr/sbin/VBoxService 2024-06-26 10:51:50 (5196): Guest Log: 00:00:00.000427 main Process ID: 3228 2024-06-26 10:51:50 (5196): Guest Log: 00:00:00.000428 main Package type: LINUX_64BITS_GENERIC 2024-06-26 10:51:50 (5196): Guest Log: 00:00:00.004569 main 5.2.6 r120293 started. Verbose level = 0 2024-06-26 10:52:44 (5196): Guest Log: 10:52:44 AEST +10:00 2024-06-26: cranky: [INFO] Detected Theory App 2024-06-26 10:52:44 (5196): Guest Log: 10:52:44 AEST +10:00 2024-06-26: cranky: [INFO] Checking CVMFS. 2024-06-26 10:52:45 (5196): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-06-26 10:52:45 (5196): 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-06-26 10:52:45 (5196): Guest Log: 2.5.2.0 4121 0 27200 29746 3 1 1291189 4096001 0 65024 0 0 n/a 5 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-06-26 10:52:46 (5196): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-06-26 10:52:47 (5196): 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-06-26 10:52:47 (5196): Guest Log: 2.5.2.0 4088 0 27644 24563 3 2 1291189 4096001 0 65024 0 2 100 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-06-26 10:52:48 (5196): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-06-26 10:52:49 (5196): 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-06-26 10:52:49 (5196): Guest Log: 2.5.2.0 4152 0 28392 272 3 1 1291189 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-06-26 10:52:50 (5196): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-06-26 10:52:51 (5196): 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-06-26 10:52:51 (5196): Guest Log: 2.5.2.0 4062 0 39660 19002 3 1 1291189 4096001 0 65024 0 0 n/a 5537 3581 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-06-26 10:52:51 (5196): Guest Log: 10:52:51 AEST +10:00 2024-06-26: cranky: [INFO] Checking runc. 2024-06-26 10:52:51 (5196): Guest Log: 10:52:52 AEST +10:00 2024-06-26: cranky: [INFO] Creating the filesystem. 2024-06-26 10:52:51 (5196): Guest Log: 10:52:52 AEST +10:00 2024-06-26: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-06-26 10:52:52 (5196): Guest Log: 10:52:52 AEST +10:00 2024-06-26: cranky: [INFO] Updating config.json. 2024-06-26 10:52:52 (5196): Guest Log: 10:52:52 AEST +10:00 2024-06-26: cranky: [INFO] Running Container 'runc'. 2024-06-26 10:52:52 (5196): Guest Log: job: htmld=/shared/html/job 2024-06-26 10:52:53 (5196): Guest Log: job: unpack exitcode=0 2024-06-26 10:52:53 (5196): Guest Log: INFO: activated the work-around for ld: 2024-06-26 10:52:53 (5196): Guest Log: lrwxrwxrwx 1 0 0 15 Jun 26 00:52 /tmp/tmp.B9zLCxt2QG/ld -> /usr/bin/ld.bfd 2024-06-26 10:52:54 (5196): Guest Log: 10:52:54 AEST +10:00 2024-06-26: cranky: [INFO] ===> [runRivet] Wed Jun 26 00:52:53 UTC 2024 [boinc pp bbbar 7000 - - pythia8 8.308 CP1-CR2 100000 233] 2024-06-26 10:53:43 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 11:03:33 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 11:09:45 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 11:26:50 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 11:31:34 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 11:54:45 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 11:54:54 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 12:24:56 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 12:24:59 (5196): Guest Log: 00:13:01.138865 timesync vgsvcTimeSyncWorker: Radical host time change: 1 812 526 000 000ns (HostNow=1 719 368 699 227 000 000 ns HostLast=1 719 366 886 701 000 000 ns) 2024-06-26 12:25:10 (5196): Guest Log: 00:13:11.192382 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 812 621 015 000ns (GuestNow=1 719 368 709 331 777 000 ns GuestLast=1 719 366 896 710 762 000 ns fSetTimeLastLoop=true ) 2024-06-26 12:26:34 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 12:40:21 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 12:42:42 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 12:59:07 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 12:59:21 (5196): Guest Log: 00:17:11.494561 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 821 158 249 000ns (GuestNow=1 719 370 760 790 602 000 ns GuestLast=1 719 368 939 632 353 000 ns fSetTimeLastLoop=true ) 2024-06-26 13:00:57 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 13:07:24 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 13:19:29 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 13:24:07 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 13:35:21 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 13:46:51 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 13:49:39 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 14:02:16 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 14:03:14 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 14:20:00 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 14:20:15 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 14:23:20 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 14:30:22 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 14:35:41 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 14:42:03 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 14:55:45 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 14:58:05 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-26 15:09:05 (5196): VM state change detected. (old = 'paused', new = 'running') 2024-06-26 15:11:42 (5196): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 08:33:15 (16964): vboxwrapper version 26207 2024-06-27 08:33:15 (16964): BOINC client version: 8.0.2 2024-06-27 08:33:17 (16964): Detected: VirtualBox VboxManage Interface (Version: 7.0.14) 2024-06-27 08:33:18 (16964): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-06-27 08:33:21 (16964): Guest Log: BIOS: VirtualBox 7.0.14 2024-06-27 08:33:21 (16964): Guest Log: CPUID EDX: 0x178bfbff 2024-06-27 08:33:21 (16964): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-27 08:33:21 (16964): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-27 08:33:21 (16964): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-27 08:33:21 (16964): Guest Log: BIOS: Booting from Hard Disk... 2024-06-27 08:33:21 (16964): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-27 08:33:21 (16964): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-27 08:33:21 (16964): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-27 08:33:21 (16964): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-27 08:33:21 (16964): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-27 08:33:21 (16964): Guest Log: 00:00:00.000116 main Log opened 2024-06-26T00:51:50.591567000Z 2024-06-27 08:33:21 (16964): Guest Log: 00:00:00.000291 main OS Product: Linux 2024-06-27 08:33:21 (16964): Guest Log: 00:00:00.000343 main OS Release: 4.14.76-13.cernvm.x86_64 2024-06-27 08:33:21 (16964): Guest Log: 00:00:00.000385 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-06-27 08:33:21 (16964): Guest Log: 00:00:00.000426 main Executable: /usr/sbin/VBoxService 2024-06-27 08:33:21 (16964): Guest Log: 00:00:00.000427 main Process ID: 3228 2024-06-27 08:33:21 (16964): Guest Log: 00:00:00.000428 main Package type: LINUX_64BITS_GENERIC 2024-06-27 08:33:21 (16964): Guest Log: 00:00:00.004569 main 5.2.6 r120293 started. Verbose level = 0 2024-06-27 08:33:21 (16964): Guest Log: 10:52:44 AEST +10:00 2024-06-26: cranky: [INFO] Detected Theory App 2024-06-27 08:33:21 (16964): Guest Log: 10:52:44 AEST +10:00 2024-06-26: cranky: [INFO] Checking CVMFS. 2024-06-27 08:33:21 (16964): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-06-27 08:33:21 (16964): 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-06-27 08:33:21 (16964): Guest Log: 2.5.2.0 4121 0 27200 29746 3 1 1291189 4096001 0 65024 0 0 n/a 5 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-06-27 08:33:21 (16964): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-06-27 08:33:21 (16964): 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-06-27 08:33:21 (16964): Guest Log: 2.5.2.0 4088 0 27644 24563 3 2 1291189 4096001 0 65024 0 2 100 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-06-27 08:33:21 (16964): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-06-27 08:33:21 (16964): 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-06-27 08:33:21 (16964): Guest Log: 2.5.2.0 4152 0 28392 272 3 1 1291189 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-06-27 08:33:21 (16964): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-06-27 08:33:21 (16964): 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-06-27 08:33:21 (16964): Guest Log: 2.5.2.0 4062 0 39660 19002 3 1 1291189 4096001 0 65024 0 0 n/a 5537 3581 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-06-27 08:33:21 (16964): Guest Log: 10:52:51 AEST +10:00 2024-06-26: cranky: [INFO] Checking runc. 2024-06-27 08:33:21 (16964): Guest Log: 10:52:52 AEST +10:00 2024-06-26: cranky: [INFO] Creating the filesystem. 2024-06-27 08:33:21 (16964): Guest Log: 10:52:52 AEST +10:00 2024-06-26: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-06-27 08:33:21 (16964): Guest Log: 10:52:52 AEST +10:00 2024-06-26: cranky: [INFO] Updating config.json. 2024-06-27 08:33:21 (16964): Guest Log: 10:52:52 AEST +10:00 2024-06-26: cranky: [INFO] Running Container 'runc'. 2024-06-27 08:33:21 (16964): Guest Log: job: htmld=/shared/html/job 2024-06-27 08:33:21 (16964): Guest Log: job: unpack exitcode=0 2024-06-27 08:33:21 (16964): Guest Log: INFO: activated the work-around for ld: 2024-06-27 08:33:21 (16964): Guest Log: lrwxrwxrwx 1 0 0 15 Jun 26 00:52 /tmp/tmp.B9zLCxt2QG/ld -> /usr/bin/ld.bfd 2024-06-27 08:33:21 (16964): Guest Log: 10:52:54 AEST +10:00 2024-06-26: cranky: [INFO] ===> [runRivet] Wed Jun 26 00:52:53 UTC 2024 [boinc pp bbbar 7000 - - pythia8 8.308 CP1-CR2 100000 233] 2024-06-27 08:33:21 (16964): Guest Log: 00:13:01.138865 timesync vgsvcTimeSyncWorker: Radical host time change: 1 812 526 000 000ns (HostNow=1 719 368 699 227 000 000 ns HostLast=1 719 366 886 701 000 000 ns) 2024-06-27 08:33:21 (16964): Guest Log: 00:13:11.192382 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 812 621 015 000ns (GuestNow=1 719 368 709 331 777 000 ns GuestLast=1 719 366 896 710 762 000 ns fSetTimeLastLoop=true ) 2024-06-27 08:33:21 (16964): Guest Log: 00:17:11.494561 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 821 158 249 000ns (GuestNow=1 719 370 760 790 602 000 ns GuestLast=1 719 368 939 632 353 000 ns fSetTimeLastLoop=true ) 2024-06-27 08:33:21 (16964): Starting VM using VBoxManage interface. (boinc_93c00f1e5af4b7c6, slot#2) 2024-06-27 08:33:38 (16964): Successfully started VM. (PID = '20404') 2024-06-27 08:33:38 (16964): Reporting VM Process ID to BOINC. 2024-06-27 08:33:38 (16964): Guest Log: BIOS: VirtualBox 7.0.14 2024-06-27 08:33:38 (16964): Guest Log: CPUID EDX: 0x178bfbff 2024-06-27 08:33:38 (16964): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-27 08:33:38 (16964): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-27 08:33:38 (16964): VM state change detected. (old = 'poweredoff', new = 'running') 2024-06-27 08:33:38 (16964): Detected: Web Application Enabled (http://localhost:51347) 2024-06-27 08:33:39 (16964): Detected: Remote Desktop Enabled (localhost:51348) 2024-06-27 08:33:40 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 08:41:34 (16964): Status Report: Job Duration: '864000.000000' 2024-06-27 08:41:34 (16964): Status Report: Elapsed Time: '13950.601560' 2024-06-27 08:41:34 (16964): Status Report: CPU Time: '7183.296875' 2024-06-27 08:41:34 (16964): Preference change detected 2024-06-27 08:41:34 (16964): Setting CPU throttle for VM. (100%) 2024-06-27 08:41:35 (16964): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-27 08:41:35 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 08:41:36 (16964): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-27 08:41:36 (16964): Guest Log: BIOS: Booting from Hard Disk... 2024-06-27 08:41:38 (16964): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-27 08:41:38 (16964): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-27 08:42:02 (16964): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-27 08:42:02 (16964): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-27 08:42:06 (16964): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-27 08:42:06 (16964): Guest Log: 00:00:00.000201 main Log opened 2024-06-26T22:42:06.260232000Z 2024-06-27 08:42:06 (16964): Guest Log: 00:00:00.000587 main OS Product: Linux 2024-06-27 08:42:06 (16964): Guest Log: 00:00:00.000659 main OS Release: 4.14.76-13.cernvm.x86_64 2024-06-27 08:42:06 (16964): Guest Log: 00:00:00.000713 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-06-27 08:42:06 (16964): Guest Log: 00:00:00.000765 main Executable: /usr/sbin/VBoxService 2024-06-27 08:42:06 (16964): Guest Log: 00:00:00.000766 main Process ID: 3230 2024-06-27 08:42:06 (16964): Guest Log: 00:00:00.000767 main Package type: LINUX_64BITS_GENERIC 2024-06-27 08:42:06 (16964): Guest Log: 00:00:00.009210 main 5.2.6 r120293 started. Verbose level = 0 2024-06-27 08:43:17 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 08:46:24 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 08:46:27 (16964): Guest Log: 08:43:20 AEST +10:00 2024-06-27: cranky: [INFO] Detected Theory App 2024-06-27 08:46:27 (16964): Guest Log: 08:43:20 AEST +10:00 2024-06-27: cranky: [INFO] Checking CVMFS. 2024-06-27 08:46:28 (16964): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-06-27 08:46:29 (16964): 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-06-27 08:46:29 (16964): Guest Log: 2.5.2.0 4171 1 28164 29767 3 1 1317749 4096001 0 65024 0 0 n/a 6 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-06-27 08:46:30 (16964): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-06-27 08:46:30 (16964): 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-06-27 08:46:30 (16964): Guest Log: 2.5.2.0 4083 1 28068 24571 2 2 1317749 4096001 0 65024 0 2 0 5822 1932 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-06-27 08:46:31 (16964): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-06-27 08:46:32 (16964): 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-06-27 08:46:32 (16964): Guest Log: 2.5.2.0 4058 1 25176 272 2 1 1317749 4096001 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-06-27 08:46:33 (16964): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-06-27 08:46:34 (16964): 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-06-27 08:46:34 (16964): Guest Log: 2.5.2.0 4105 1 39660 19009 2 1 1317749 4096001 0 65024 0 0 n/a 5540 2239 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-06-27 08:46:34 (16964): Guest Log: 08:43:28 AEST +10:00 2024-06-27: cranky: [INFO] Checking runc. 2024-06-27 08:46:34 (16964): Guest Log: 08:43:28 AEST +10:00 2024-06-27: cranky: [INFO] Creating the filesystem. 2024-06-27 08:46:35 (16964): Guest Log: 08:43:28 AEST +10:00 2024-06-27: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-06-27 08:46:35 (16964): Guest Log: 08:43:28 AEST +10:00 2024-06-27: cranky: [INFO] Updating config.json. 2024-06-27 08:46:35 (16964): Guest Log: 08:43:28 AEST +10:00 2024-06-27: cranky: [INFO] Running Container 'runc'. 2024-06-27 08:46:36 (16964): Guest Log: job: htmld=/shared/html/job 2024-06-27 08:46:36 (16964): Guest Log: job: unpack exitcode=0 2024-06-27 08:46:36 (16964): Guest Log: INFO: activated the work-around for ld: 2024-06-27 08:46:36 (16964): Guest Log: lrwxrwxrwx 1 0 0 15 Jun 26 22:43 /tmp/tmp.81orv4OpcF/ld -> /usr/bin/ld.bfd 2024-06-27 08:46:37 (16964): Guest Log: 08:43:30 AEST +10:00 2024-06-27: cranky: [INFO] ===> [runRivet] Wed Jun 26 22:43:29 UTC 2024 [boinc pp bbbar 7000 - - pythia8 8.308 CP1-CR2 100000 233] 2024-06-27 09:04:52 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 09:08:20 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 09:10:27 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 09:13:34 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 09:13:35 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 09:30:10 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 09:32:30 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 09:45:59 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 09:48:14 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 09:51:19 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 09:51:36 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 10:10:19 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 10:10:39 (16964): Guest Log: 00:27:02.152108 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 125 656 194 000ns (GuestNow=1 719 447 038 160 745 000 ns GuestLast=1 719 444 912 504 551 000 ns fSetTimeLastLoop=true ) 2024-06-27 10:12:49 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 10:29:09 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 10:58:50 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 11:12:33 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 11:12:51 (16964): Guest Log: 00:59:14.802863 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 810 409 329 000ns (GuestNow=1 719 450 771 281 838 000 ns GuestLast=1 719 448 960 872 509 000 ns fSetTimeLastLoop=true ) 2024-06-27 11:16:19 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 11:19:36 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 11:19:39 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 11:33:27 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 11:38:42 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 12:08:20 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 12:08:33 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 12:20:26 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 12:20:27 (16964): Guest Log: 01:08:15.421262 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 811 783 707 000ns (GuestNow=1 719 454 113 671 965 000 ns GuestLast=1 719 451 301 888 258 000 ns fSetTimeLastLoop=true ) 2024-06-27 12:24:48 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 12:30:35 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 12:33:02 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 12:36:02 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 12:38:07 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 12:47:53 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 12:48:26 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:21:01 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:21:07 (16964): Guest Log: 01:17:56.182975 timesync vgsvcTimeSyncWorker: Radical host time change: 1 963 776 000 000ns (HostNow=1 719 458 466 985 000 000 ns HostLast=1 719 456 503 209 000 000 ns) 2024-06-27 13:21:17 (16964): Guest Log: 01:18:06.196382 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 549 959 004 000ns (GuestNow=1 719 458 476 998 433 000 ns GuestLast=1 719 455 927 039 429 000 ns fSetTimeLastLoop=true ) 2024-06-27 13:22:05 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:28:53 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:31:55 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:42:15 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:46:36 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:57:21 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:58:38 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:05:48 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:06:32 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:16:33 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:22:39 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:37:46 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:38:08 (16964): Guest Log: 01:34:47.604725 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 946 284 056 000ns (GuestNow=1 719 463 084 758 766 000 ns GuestLast=1 719 461 138 474 710 000 ns fSetTimeLastLoop=true ) 2024-06-27 14:38:21 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:48:45 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:50:52 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:00:57 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:04:51 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:08:22 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:10:12 (16964): Status Report: Job Duration: '864000.000000' 2024-06-27 15:10:12 (16964): Status Report: Elapsed Time: '19951.436254' 2024-06-27 15:10:12 (16964): Status Report: CPU Time: '10876.000000' 2024-06-27 15:14:57 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:18:48 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:36:17 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 16:02:53 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 16:03:10 (16964): Guest Log: 02:05:30.008383 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 045 282 613 000ns (GuestNow=1 719 468 189 693 897 000 ns GuestLast=1 719 466 144 411 284 000 ns fSetTimeLastLoop=true ) 2024-06-27 16:04:04 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 16:26:21 (16964): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 16:27:46 (16964): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 16:34:19 (16964): VM state change detected. (old = 'paused', new = 'stopping') 2024-06-27 16:34:19 (16964): Stopping VM. 2024-06-27 16:34:20 (16964): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_93c00f1e5af4b7c6" savestate Output: 2024-06-27 16:34:20 (16964): VM did not stop when requested. 2024-06-27 16:34:20 (16964): VM was NOT successfully terminated. 2024-06-28 08:15:08 (9184): vboxwrapper version 26207 2024-06-28 08:15:08 (9184): BOINC client version: 8.0.2 2024-06-28 08:15:11 (9184): Detected: VirtualBox VboxManage Interface (Version: 7.0.14) 2024-06-28 08:15:11 (9184): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-06-28 08:15:15 (9184): Guest Log: BIOS: VirtualBox 7.0.14 2024-06-28 08:15:15 (9184): Guest Log: CPUID EDX: 0x178bfbff 2024-06-28 08:15:15 (9184): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-28 08:15:15 (9184): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-28 08:15:15 (9184): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-28 08:15:15 (9184): Guest Log: BIOS: Booting from Hard Disk... 2024-06-28 08:15:15 (9184): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-28 08:15:15 (9184): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-28 08:15:15 (9184): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-28 08:15:15 (9184): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-28 08:15:15 (9184): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-28 08:15:15 (9184): Guest Log: 00:00:00.000201 main Log opened 2024-06-26T22:42:06.260232000Z 2024-06-28 08:15:15 (9184): Guest Log: 00:00:00.000587 main OS Product: Linux 2024-06-28 08:15:15 (9184): Guest Log: 00:00:00.000659 main OS Release: 4.14.76-13.cernvm.x86_64 2024-06-28 08:15:15 (9184): Guest Log: 00:00:00.000713 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-06-28 08:15:15 (9184): Guest Log: 00:00:00.000765 main Executable: /usr/sbin/VBoxService 2024-06-28 08:15:15 (9184): Guest Log: 00:00:00.000766 main Process ID: 3230 2024-06-28 08:15:15 (9184): Guest Log: 00:00:00.000767 main Package type: LINUX_64BITS_GENERIC 2024-06-28 08:15:15 (9184): Guest Log: 00:00:00.009210 main 5.2.6 r120293 started. Verbose level = 0 2024-06-28 08:15:15 (9184): Guest Log: 08:43:20 AEST +10:00 2024-06-27: cranky: [INFO] Detected Theory App 2024-06-28 08:15:15 (9184): Guest Log: 08:43:20 AEST +10:00 2024-06-27: cranky: [INFO] Checking CVMFS. 2024-06-28 08:15:15 (9184): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-06-28 08:15:15 (9184): 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-06-28 08:15:15 (9184): Guest Log: 2.5.2.0 4171 1 28164 29767 3 1 1317749 4096001 0 65024 0 0 n/a 6 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-06-28 08:15:15 (9184): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-06-28 08:15:15 (9184): 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-06-28 08:15:15 (9184): Guest Log: 2.5.2.0 4083 1 28068 24571 2 2 1317749 4096001 0 65024 0 2 0 5822 1932 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-06-28 08:15:15 (9184): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-06-28 08:15:15 (9184): 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-06-28 08:15:15 (9184): Guest Log: 2.5.2.0 4058 1 25176 272 2 1 1317749 4096001 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-06-28 08:15:15 (9184): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-06-28 08:15:15 (9184): 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-06-28 08:15:15 (9184): Guest Log: 2.5.2.0 4105 1 39660 19009 2 1 1317749 4096001 0 65024 0 0 n/a 5540 2239 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-06-28 08:15:15 (9184): Guest Log: 08:43:28 AEST +10:00 2024-06-27: cranky: [INFO] Checking runc. 2024-06-28 08:15:15 (9184): Guest Log: 08:43:28 AEST +10:00 2024-06-27: cranky: [INFO] Creating the filesystem. 2024-06-28 08:15:15 (9184): Guest Log: 08:43:28 AEST +10:00 2024-06-27: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-06-28 08:15:15 (9184): Guest Log: 08:43:28 AEST +10:00 2024-06-27: cranky: [INFO] Updating config.json. 2024-06-28 08:15:15 (9184): Guest Log: 08:43:28 AEST +10:00 2024-06-27: cranky: [INFO] Running Container 'runc'. 2024-06-28 08:15:15 (9184): Guest Log: job: htmld=/shared/html/job 2024-06-28 08:15:15 (9184): Guest Log: job: unpack exitcode=0 2024-06-28 08:15:15 (9184): Guest Log: INFO: activated the work-around for ld: 2024-06-28 08:15:15 (9184): Guest Log: lrwxrwxrwx 1 0 0 15 Jun 26 22:43 /tmp/tmp.81orv4OpcF/ld -> /usr/bin/ld.bfd 2024-06-28 08:15:15 (9184): Guest Log: 08:43:30 AEST +10:00 2024-06-27: cranky: [INFO] ===> [runRivet] Wed Jun 26 22:43:29 UTC 2024 [boinc pp bbbar 7000 - - pythia8 8.308 CP1-CR2 100000 233] 2024-06-28 08:15:15 (9184): Guest Log: 00:27:02.152108 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 125 656 194 000ns (GuestNow=1 719 447 038 160 745 000 ns GuestLast=1 719 444 912 504 551 000 ns fSetTimeLastLoop=true ) 2024-06-28 08:15:15 (9184): Guest Log: 00:59:14.802863 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 810 409 329 000ns (GuestNow=1 719 450 771 281 838 000 ns GuestLast=1 719 448 960 872 509 000 ns fSetTimeLastLoop=true ) 2024-06-28 08:15:15 (9184): Guest Log: 01:08:15.421262 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 811 783 707 000ns (GuestNow=1 719 454 113 671 965 000 ns GuestLast=1 719 451 301 888 258 000 ns fSetTimeLastLoop=true ) 2024-06-28 08:15:15 (9184): Guest Log: 01:17:56.182975 timesync vgsvcTimeSyncWorker: Radical host time change: 1 963 776 000 000ns (HostNow=1 719 458 466 985 000 000 ns HostLast=1 719 456 503 209 000 000 ns) 2024-06-28 08:15:15 (9184): Guest Log: 01:18:06.196382 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 549 959 004 000ns (GuestNow=1 719 458 476 998 433 000 ns GuestLast=1 719 455 927 039 429 000 ns fSetTimeLastLoop=true ) 2024-06-28 08:15:15 (9184): Guest Log: 01:34:47.604725 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 946 284 056 000ns (GuestNow=1 719 463 084 758 766 000 ns GuestLast=1 719 461 138 474 710 000 ns fSetTimeLastLoop=true ) 2024-06-28 08:15:15 (9184): Guest Log: 02:05:30.008383 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 045 282 613 000ns (GuestNow=1 719 468 189 693 897 000 ns GuestLast=1 719 466 144 411 284 000 ns fSetTimeLastLoop=true ) 2024-06-28 08:15:15 (9184): Starting VM using VBoxManage interface. (boinc_93c00f1e5af4b7c6, slot#2) 2024-06-28 08:15:30 (9184): Successfully started VM. (PID = '22316') 2024-06-28 08:15:30 (9184): Reporting VM Process ID to BOINC. 2024-06-28 08:15:30 (9184): Guest Log: BIOS: VirtualBox 7.0.14 2024-06-28 08:15:30 (9184): Guest Log: CPUID EDX: 0x178bfbff 2024-06-28 08:15:30 (9184): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-28 08:15:30 (9184): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-28 08:15:30 (9184): VM state change detected. (old = 'poweredoff', new = 'running') 2024-06-28 08:15:30 (9184): Detected: Web Application Enabled (http://localhost:51347) 2024-06-28 08:15:30 (9184): Detected: Remote Desktop Enabled (localhost:51348) 2024-06-28 08:15:30 (9184): Status Report: Job Duration: '864000.000000' 2024-06-28 08:15:30 (9184): Status Report: Elapsed Time: '21400.729261' 2024-06-28 08:15:30 (9184): Status Report: CPU Time: '11804.890625' 2024-06-28 08:15:30 (9184): Preference change detected 2024-06-28 08:15:30 (9184): Setting CPU throttle for VM. (100%) 2024-06-28 08:15:30 (9184): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-28 08:15:32 (9184): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-28 08:15:32 (9184): Guest Log: BIOS: Booting from Hard Disk... 2024-06-28 08:15:35 (9184): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-28 08:15:35 (9184): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-28 08:16:00 (9184): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-28 08:16:00 (9184): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-28 08:16:07 (9184): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-28 08:16:07 (9184): Guest Log: 00:00:00.000149 main Log opened 2024-06-27T22:16:05.980174000Z 2024-06-28 08:16:07 (9184): Guest Log: 00:00:00.000691 main OS Product: Linux 2024-06-28 08:16:07 (9184): Guest Log: 00:00:00.000788 main OS Release: 4.14.76-13.cernvm.x86_64 2024-06-28 08:16:07 (9184): Guest Log: 00:00:00.000853 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-06-28 08:16:07 (9184): Guest Log: 00:00:00.000914 main Executable: /usr/sbin/VBoxService 2024-06-28 08:16:07 (9184): Guest Log: 00:00:00.000915 main Process ID: 3239 2024-06-28 08:16:07 (9184): Guest Log: 00:00:00.000916 main Package type: LINUX_64BITS_GENERIC 2024-06-28 08:16:07 (9184): Guest Log: 00:00:00.021698 main 5.2.6 r120293 started. Verbose level = 0 2024-06-28 08:16:31 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 08:19:32 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 08:19:42 (9184): Guest Log: 08:16:39 AEST +10:00 2024-06-28: cranky: [INFO] Detected Theory App 2024-06-28 08:19:42 (9184): Guest Log: 08:16:39 AEST +10:00 2024-06-28: cranky: [INFO] Checking CVMFS. 2024-06-28 08:19:43 (9184): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-06-28 08:19:44 (9184): 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-06-28 08:19:44 (9184): Guest Log: 2.5.2.0 4101 0 26476 29782 3 1 1471741 4096001 0 65024 0 0 n/a 5 2 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-06-28 08:19:45 (9184): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-06-28 08:19:46 (9184): 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-06-28 08:19:46 (9184): Guest Log: 2.5.2.0 4146 0 27508 24579 3 2 1471741 4096001 0 65024 0 2 0 5819 1186 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-06-28 08:19:48 (9184): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-06-28 08:19:48 (9184): 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-06-28 08:19:48 (9184): Guest Log: 2.5.2.0 4074 0 25160 272 3 1 1471741 4096001 0 65024 0 0 n/a 0 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-06-28 08:19:50 (9184): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-06-28 08:19:50 (9184): 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-06-28 08:19:50 (9184): Guest Log: 2.5.2.0 4121 0 39632 19015 3 1 1471741 4096001 0 65024 0 0 n/a 5541 1646 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-06-28 08:19:50 (9184): Guest Log: 08:16:48 AEST +10:00 2024-06-28: cranky: [INFO] Checking runc. 2024-06-28 08:19:50 (9184): Guest Log: 08:16:48 AEST +10:00 2024-06-28: cranky: [INFO] Creating the filesystem. 2024-06-28 08:19:51 (9184): Guest Log: 08:16:48 AEST +10:00 2024-06-28: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-06-28 08:19:51 (9184): Guest Log: 08:16:49 AEST +10:00 2024-06-28: cranky: [INFO] Updating config.json. 2024-06-28 08:19:51 (9184): Guest Log: 08:16:49 AEST +10:00 2024-06-28: cranky: [INFO] Running Container 'runc'. 2024-06-28 08:19:52 (9184): Guest Log: job: htmld=/shared/html/job 2024-06-28 08:19:52 (9184): Guest Log: job: unpack exitcode=0 2024-06-28 08:19:52 (9184): Guest Log: INFO: activated the work-around for ld: 2024-06-28 08:19:52 (9184): Guest Log: lrwxrwxrwx 1 0 0 15 Jun 27 22:16 /tmp/tmp.Syvg2zKr7b/ld -> /usr/bin/ld.bfd 2024-06-28 08:19:53 (9184): Guest Log: 08:16:51 AEST +10:00 2024-06-28: cranky: [INFO] ===> [runRivet] Thu Jun 27 22:16:50 UTC 2024 [boinc pp bbbar 7000 - - pythia8 8.308 CP1-CR2 100000 233] 2024-06-28 08:19:58 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 08:37:27 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 08:38:57 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 08:46:07 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 08:47:10 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 08:50:10 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 08:50:48 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 08:57:09 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 08:57:37 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 09:00:36 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 09:06:06 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 09:14:31 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 09:20:32 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 09:56:36 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 09:56:44 (9184): Guest Log: 00:16:13.350695 timesync vgsvcTimeSyncWorker: Radical host time change: 2 173 758 000 000ns (HostNow=1 719 532 604 714 000 000 ns HostLast=1 719 530 430 956 000 000 ns) 2024-06-28 09:56:54 (9184): Guest Log: 00:16:23.366508 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 173 824 781 000ns (GuestNow=1 719 532 614 810 767 000 ns GuestLast=1 719 530 440 985 986 000 ns fSetTimeLastLoop=true ) 2024-06-28 09:57:52 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 10:00:52 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 10:04:57 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 10:31:30 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 10:32:25 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 10:35:26 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 10:36:16 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 10:39:22 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 10:44:42 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 11:04:53 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 11:05:35 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 11:15:30 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 11:17:15 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 11:21:15 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 11:22:42 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 11:27:15 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 11:30:36 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 11:56:08 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 11:56:23 (9184): Guest Log: 00:36:04.916307 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 647 409 277 000ns (GuestNow=1 719 539 783 319 987 000 ns GuestLast=1 719 537 135 910 710 000 ns fSetTimeLastLoop=true ) 2024-06-28 12:00:43 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 12:35:53 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 12:35:54 (9184): Guest Log: 00:40:25.252104 timesync vgsvcTimeSyncWorker: Radical host time change: 2 120 098 000 000ns (HostNow=1 719 542 153 741 000 000 ns HostLast=1 719 540 033 643 000 000 ns) 2024-06-28 12:36:04 (9184): Guest Log: 00:40:35.261670 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 120 094 859 000ns (GuestNow=1 719 542 163 750 592 000 ns GuestLast=1 719 540 043 655 733 000 ns fSetTimeLastLoop=true ) 2024-06-28 12:36:41 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 12:39:40 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 12:42:47 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 12:47:05 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 12:48:02 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 13:16:57 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 13:17:14 (9184): Guest Log: 00:45:35.752689 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 179 677 708 000ns (GuestNow=1 719 544 633 902 661 000 ns GuestLast=1 719 542 454 224 953 000 ns fSetTimeLastLoop=true ) 2024-06-28 14:00:07 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 14:20:55 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 14:34:08 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 14:46:13 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 14:46:14 (9184): Status Report: Job Duration: '864000.000000' 2024-06-28 14:46:14 (9184): Status Report: Elapsed Time: '27401.442684' 2024-06-28 14:46:14 (9184): Status Report: CPU Time: '15191.453125' 2024-06-28 14:47:38 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-28 15:05:21 (9184): VM state change detected. (old = 'paused', new = 'running') 2024-06-28 15:07:10 (9184): VM state change detected. (old = 'running', new = 'paused') 2024-06-29 12:54:16 (24728): vboxwrapper version 26207 2024-06-29 12:54:16 (24728): BOINC client version: 8.0.2 2024-06-29 12:54:21 (24728): Detected: VirtualBox VboxManage Interface (Version: 7.0.14) 2024-06-29 12:54:22 (24728): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-06-29 12:54:27 (24728): Guest Log: BIOS: VirtualBox 7.0.14 2024-06-29 12:54:27 (24728): Guest Log: CPUID EDX: 0x178bfbff 2024-06-29 12:54:27 (24728): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-29 12:54:27 (24728): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-29 12:54:27 (24728): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-29 12:54:27 (24728): Guest Log: BIOS: Booting from Hard Disk... 2024-06-29 12:54:27 (24728): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-29 12:54:27 (24728): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-29 12:54:28 (24728): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-29 12:54:28 (24728): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-29 12:54:28 (24728): Guest Log: 00:00:00.000149 main Log opened 2024-06-27T22:16:05.980174000Z 2024-06-29 12:54:28 (24728): Guest Log: 00:00:00.000691 main OS Product: Linux 2024-06-29 12:54:28 (24728): Guest Log: 00:00:00.000788 main OS Release: 4.14.76-13.cernvm.x86_64 2024-06-29 12:54:28 (24728): Guest Log: 00:00:00.000853 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-06-29 12:54:28 (24728): Guest Log: 00:00:00.000914 main Executable: /usr/sbin/VBoxService 2024-06-29 12:54:28 (24728): Guest Log: 00:00:00.000915 main Process ID: 3239 2024-06-29 12:54:28 (24728): Guest Log: 00:00:00.000916 main Package type: LINUX_64BITS_GENERIC 2024-06-29 12:54:28 (24728): Guest Log: 00:00:00.021698 main 5.2.6 r120293 started. Verbose level = 0 2024-06-29 12:54:28 (24728): Guest Log: 08:16:39 AEST +10:00 2024-06-28: cranky: [INFO] Detected Theory App 2024-06-29 12:54:28 (24728): Guest Log: 08:16:39 AEST +10:00 2024-06-28: cranky: [INFO] Checking CVMFS. 2024-06-29 12:54:28 (24728): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-06-29 12:54:28 (24728): 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-06-29 12:54:28 (24728): Guest Log: 2.5.2.0 4101 0 26476 29782 3 1 1471741 4096001 0 65024 0 0 n/a 5 2 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-06-29 12:54:28 (24728): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-06-29 12:54:28 (24728): 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-06-29 12:54:28 (24728): Guest Log: 2.5.2.0 4146 0 27508 24579 3 2 1471741 4096001 0 65024 0 2 0 5819 1186 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-06-29 12:54:28 (24728): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-06-29 12:54:28 (24728): 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-06-29 12:54:28 (24728): Guest Log: 2.5.2.0 4074 0 25160 272 3 1 1471741 4096001 0 65024 0 0 n/a 0 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-06-29 12:54:28 (24728): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-06-29 12:54:28 (24728): 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-06-29 12:54:28 (24728): Guest Log: 2.5.2.0 4121 0 39632 19015 3 1 1471741 4096001 0 65024 0 0 n/a 5541 1646 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-06-29 12:54:28 (24728): Guest Log: 08:16:48 AEST +10:00 2024-06-28: cranky: [INFO] Checking runc. 2024-06-29 12:54:28 (24728): Guest Log: 08:16:48 AEST +10:00 2024-06-28: cranky: [INFO] Creating the filesystem. 2024-06-29 12:54:28 (24728): Guest Log: 08:16:48 AEST +10:00 2024-06-28: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-06-29 12:54:28 (24728): Guest Log: 08:16:49 AEST +10:00 2024-06-28: cranky: [INFO] Updating config.json. 2024-06-29 12:54:28 (24728): Guest Log: 08:16:49 AEST +10:00 2024-06-28: cranky: [INFO] Running Container 'runc'. 2024-06-29 12:54:28 (24728): Guest Log: job: htmld=/shared/html/job 2024-06-29 12:54:28 (24728): Guest Log: job: unpack exitcode=0 2024-06-29 12:54:28 (24728): Guest Log: INFO: activated the work-around for ld: 2024-06-29 12:54:28 (24728): Guest Log: lrwxrwxrwx 1 0 0 15 Jun 27 22:16 /tmp/tmp.Syvg2zKr7b/ld -> /usr/bin/ld.bfd 2024-06-29 12:54:28 (24728): Guest Log: 08:16:51 AEST +10:00 2024-06-28: cranky: [INFO] ===> [runRivet] Thu Jun 27 22:16:50 UTC 2024 [boinc pp bbbar 7000 - - pythia8 8.308 CP1-CR2 100000 233] 2024-06-29 12:54:28 (24728): Guest Log: 00:16:13.350695 timesync vgsvcTimeSyncWorker: Radical host time change: 2 173 758 000 000ns (HostNow=1 719 532 604 714 000 000 ns HostLast=1 719 530 430 956 000 000 ns) 2024-06-29 12:54:28 (24728): Guest Log: 00:16:23.366508 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 173 824 781 000ns (GuestNow=1 719 532 614 810 767 000 ns GuestLast=1 719 530 440 985 986 000 ns fSetTimeLastLoop=true ) 2024-06-29 12:54:28 (24728): Guest Log: 00:36:04.916307 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 647 409 277 000ns (GuestNow=1 719 539 783 319 987 000 ns GuestLast=1 719 537 135 910 710 000 ns fSetTimeLastLoop=true ) 2024-06-29 12:54:28 (24728): Guest Log: 00:40:25.252104 timesync vgsvcTimeSyncWorker: Radical host time change: 2 120 098 000 000ns (HostNow=1 719 542 153 741 000 000 ns HostLast=1 719 540 033 643 000 000 ns) 2024-06-29 12:54:28 (24728): Guest Log: 00:40:35.261670 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 120 094 859 000ns (GuestNow=1 719 542 163 750 592 000 ns GuestLast=1 719 540 043 655 733 000 ns fSetTimeLastLoop=true ) 2024-06-29 12:54:28 (24728): Guest Log: 00:45:35.752689 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 179 677 708 000ns (GuestNow=1 719 544 633 902 661 000 ns GuestLast=1 719 542 454 224 953 000 ns fSetTimeLastLoop=true ) 2024-06-29 12:54:28 (24728): Starting VM using VBoxManage interface. (boinc_93c00f1e5af4b7c6, slot#2) 2024-06-29 12:54:54 (24728): Successfully started VM. (PID = '7280') 2024-06-29 12:54:54 (24728): Reporting VM Process ID to BOINC. 2024-06-29 12:54:54 (24728): Guest Log: BIOS: VirtualBox 7.0.14 2024-06-29 12:54:54 (24728): Guest Log: CPUID EDX: 0x178bfbff 2024-06-29 12:54:54 (24728): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-29 12:54:54 (24728): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-29 12:54:54 (24728): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-29 12:54:54 (24728): Guest Log: BIOS: Booting from Hard Disk... 2024-06-29 12:54:54 (24728): VM state change detected. (old = 'poweredoff', new = 'running') 2024-06-29 12:54:54 (24728): Detected: Web Application Enabled (http://localhost:51347) 2024-06-29 12:54:54 (24728): Detected: Remote Desktop Enabled (localhost:51348) 2024-06-29 12:54:54 (24728): Status Report: Job Duration: '864000.000000' 2024-06-29 12:54:54 (24728): Status Report: Elapsed Time: '27547.442684' 2024-06-29 12:54:54 (24728): Status Report: CPU Time: '15275.437500' 2024-06-29 12:54:54 (24728): Preference change detected 2024-06-29 12:54:54 (24728): Setting CPU throttle for VM. (100%) 2024-06-29 12:55:04 (24728): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-29 12:55:04 (24728): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-29 12:55:04 (24728): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-29 12:55:31 (24728): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-29 12:55:31 (24728): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-29 12:55:39 (24728): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-29 12:55:39 (24728): Guest Log: 00:00:00.000654 main Log opened 2024-06-29T02:55:38.469073000Z 2024-06-29 12:55:39 (24728): Guest Log: 00:00:00.002380 main OS Product: Linux 2024-06-29 12:55:39 (24728): Guest Log: 00:00:00.002657 main OS Release: 4.14.76-13.cernvm.x86_64 2024-06-29 12:55:39 (24728): Guest Log: 00:00:00.002806 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-06-29 12:55:39 (24728): Guest Log: 00:00:00.002912 main Executable: /usr/sbin/VBoxService 2024-06-29 12:55:39 (24728): Guest Log: 00:00:00.002914 main Process ID: 3240 2024-06-29 12:55:39 (24728): Guest Log: 00:00:00.002917 main Package type: LINUX_64BITS_GENERIC 2024-06-29 12:55:39 (24728): Guest Log: 00:00:00.015785 main 5.2.6 r120293 started. Verbose level = 0 2024-06-29 12:56:17 (24728): Guest Log: 12:56:17 AEST +10:00 2024-06-29: cranky: [INFO] Detected Theory App 2024-06-29 12:56:17 (24728): Guest Log: 12:56:17 AEST +10:00 2024-06-29: cranky: [INFO] Checking CVMFS. 2024-06-29 12:56:19 (24728): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-06-29 12:56:20 (24728): 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-06-29 12:56:20 (24728): Guest Log: 2.5.2.0 4089 0 28288 29803 3 1 1502955 4096001 0 65024 0 0 n/a 5 5 http://s1ihep-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-06-29 12:56:21 (24728): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-06-29 12:56:22 (24728): 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-06-29 12:56:22 (24728): Guest Log: 2.5.2.0 4110 0 27716 24587 3 2 1502955 4096001 0 65024 0 2 0 5819 3178 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-06-29 12:56:23 (24728): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-06-29 12:56:24 (24728): 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-06-29 12:56:24 (24728): Guest Log: 2.5.2.0 4136 0 27192 272 3 1 1502955 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-06-29 12:56:26 (24728): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-06-29 12:56:27 (24728): 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-06-29 12:56:27 (24728): Guest Log: 2.5.2.0 4160 0 39632 19023 3 1 1502955 4096001 0 65024 0 0 n/a 5543 1451 http://s1ihep-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-06-29 12:56:27 (24728): Guest Log: 12:56:27 AEST +10:00 2024-06-29: cranky: [INFO] Checking runc. 2024-06-29 12:56:27 (24728): Guest Log: 12:56:27 AEST +10:00 2024-06-29: cranky: [INFO] Creating the filesystem. 2024-06-29 12:56:27 (24728): Guest Log: 12:56:27 AEST +10:00 2024-06-29: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-06-29 12:56:27 (24728): Guest Log: 12:56:27 AEST +10:00 2024-06-29: cranky: [INFO] Updating config.json. 2024-06-29 12:56:27 (24728): Guest Log: 12:56:27 AEST +10:00 2024-06-29: cranky: [INFO] Running Container 'runc'. 2024-06-29 12:56:28 (24728): Guest Log: job: htmld=/shared/html/job 2024-06-29 12:56:28 (24728): Guest Log: job: unpack exitcode=0 2024-06-29 12:56:28 (24728): Guest Log: INFO: activated the work-around for ld: 2024-06-29 12:56:28 (24728): Guest Log: lrwxrwxrwx 1 0 0 15 Jun 29 02:56 /tmp/tmp.ndxIKaY3DQ/ld -> /usr/bin/ld.bfd 2024-06-29 12:56:29 (24728): Guest Log: 12:56:29 AEST +10:00 2024-06-29: cranky: [INFO] ===> [runRivet] Sat Jun 29 02:56:28 UTC 2024 [boinc pp bbbar 7000 - - pythia8 8.308 CP1-CR2 100000 233] 2024-06-29 13:26:31 (24728): VM state change detected. (old = 'running', new = 'paused') 2024-06-29 13:35:34 (24728): VM state change detected. (old = 'paused', new = 'running') 2024-06-29 13:36:07 (24728): VM state change detected. (old = 'running', new = 'paused') 2024-06-29 14:12:23 (24728): VM state change detected. (old = 'paused', new = 'running') 2024-06-29 14:12:29 (24728): Guest Log: 00:31:32.739774 timesync vgsvcTimeSyncWorker: Radical host time change: 2 185 410 000 000ns (HostNow=1 719 634 348 791 000 000 ns HostLast=1 719 632 163 381 000 000 ns) 2024-06-29 14:12:39 (24728): Guest Log: 00:31:42.787651 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 727 719 519 000ns (GuestNow=1 719 634 358 883 228 000 ns GuestLast=1 719 631 631 163 709 000 ns fSetTimeLastLoop=true ) 2024-06-29 14:19:06 (24728): VM state change detected. (old = 'running', new = 'paused') 2024-06-29 14:27:20 (24728): VM state change detected. (old = 'paused', new = 'running') 2024-06-29 15:00:41 (24728): VM state change detected. (old = 'running', new = 'paused') 2024-06-29 15:18:39 (24728): VM state change detected. (old = 'paused', new = 'running') 2024-06-29 15:48:08 (24728): Status Report: Job Duration: '864000.000000' 2024-06-29 15:48:08 (24728): Status Report: Elapsed Time: '33548.132292' 2024-06-29 15:48:08 (24728): Status Report: CPU Time: '18015.343750' 2024-06-29 16:44:03 (24728): Guest Log: job: run exitcode=0 2024-06-29 16:44:03 (24728): Guest Log: job: diskusage=3788 2024-06-29 16:44:03 (24728): Guest Log: job: logsize=68 k 2024-06-29 16:44:03 (24728): Guest Log: job: times= 2024-06-29 16:44:03 (24728): Guest Log: 0m0.014s 0m0.016s 2024-06-29 16:44:03 (24728): Guest Log: 144m38.082s 2m14.059s 2024-06-29 16:44:03 (24728): Guest Log: job: cpuusage=8812 2024-06-29 16:44:03 (24728): Guest Log: 16:44:02 AEST +10:00 2024-06-29: cranky: [INFO] Container 'runc' finished with status code 0. 2024-06-29 16:44:03 (24728): Guest Log: 16:44:02 AEST +10:00 2024-06-29: cranky: [INFO] Preparing output. 2024-06-29 16:44:04 (24728): Guest Log: [INFO] Job Finished 2024-06-29 16:44:04 (24728): Guest Log: [INFO] Shutting Down. 2024-06-29 16:44:04 (24728): VM Completion File Detected. 2024-06-29 16:44:04 (24728): VM Completion Message: Job Finished . 2024-06-29 16:44:04 (24728): Powering off VM. 2024-06-29 16:44:05 (24728): Successfully stopped VM. 2024-06-29 16:44:05 (24728): Deregistering VM. (boinc_93c00f1e5af4b7c6, slot#2) 2024-06-29 16:44:05 (24728): Removing network bandwidth throttle group from VM. 2024-06-29 16:44:06 (24728): Removing VM from VirtualBox. 2024-06-29 16:44:11 (24728): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN