Stderr output

<core_client_version>8.0.2</core_client_version>
<![CDATA[
<stderr_txt>
2024-07-24 14:36:15 (19168): vboxwrapper version 26207
2024-07-24 14:36:15 (19168): BOINC client version: 8.0.2
2024-07-24 14:36:16 (19168): Detected: VirtualBox VboxManage Interface (Version: 7.0.10)
2024-07-24 14:36:16 (19168): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-07-24 14:36:16 (19168): Successfully copied 'init_data.xml' to the shared directory.
2024-07-24 14:36:16 (19168): Successfully copied 'input' to the shared directory.
2024-07-24 14:36:17 (19168): Create VM. (boinc_ecd58209052ebf88, slot#5)
2024-07-24 14:36:17 (19168): Setting Memory Size for VM. (630MB)
2024-07-24 14:36:17 (19168): Setting CPU Count for VM. (1)
2024-07-24 14:36:18 (19168): Setting Chipset Options for VM.
2024-07-24 14:36:18 (19168): Setting Graphics Controller Options for VM.
2024-07-24 14:36:18 (19168): Setting Boot Options for VM.
2024-07-24 14:36:18 (19168): Setting Network Configuration for NAT.
2024-07-24 14:36:19 (19168): Enabling VM Network Access.
2024-07-24 14:36:19 (19168): Disabling USB Support for VM.
2024-07-24 14:36:19 (19168): Disabling COM Port Support for VM.
2024-07-24 14:36:20 (19168): Disabling LPT Port Support for VM.
2024-07-24 14:36:20 (19168): Disabling Audio Support for VM.
2024-07-24 14:36:20 (19168): Disabling Clipboard Support for VM.
2024-07-24 14:36:20 (19168): Disabling Drag and Drop Support for VM.
2024-07-24 14:36:21 (19168): Adding storage controller(s) to VM.
2024-07-24 14:36:21 (19168): Adding virtual disk drive to VM. (Theory_2024_04_30_prod.vdi)
2024-07-24 14:36:23 (19168): Adding VirtualBox Guest Additions to VM.
2024-07-24 14:36:23 (19168): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2024-07-24 14:36:23 (19168): forwarding host port 57719 to guest port 80
2024-07-24 14:36:24 (19168): Enabling remote desktop for VM.
2024-07-24 14:36:24 (19168): Enabling shared directory for VM.
2024-07-24 14:36:25 (19168): Starting VM using VBoxManage interface. (boinc_ecd58209052ebf88, slot#5)
2024-07-24 14:36:35 (19168): Successfully started VM. (PID = '22360')
2024-07-24 14:36:35 (19168): Reporting VM Process ID to BOINC.
2024-07-24 14:36:35 (19168): Guest Log: BIOS: VirtualBox 7.0.10
2024-07-24 14:36:35 (19168): Guest Log: CPUID EDX: 0x178bfbff
2024-07-24 14:36:35 (19168): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-07-24 14:36:35 (19168): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-07-24 14:36:35 (19168): VM state change detected. (old = 'poweredoff', new = 'running')
2024-07-24 14:36:35 (19168): Detected: Web Application Enabled (http://localhost:57719)
2024-07-24 14:36:35 (19168): Detected: Remote Desktop Enabled (localhost:57721)
2024-07-24 14:36:35 (19168): Preference change detected
2024-07-24 14:36:35 (19168): Setting CPU throttle for VM. (67%)
2024-07-24 14:36:35 (19168): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 120 seconds) or (Vbox_job.xml: 600 seconds))
2024-07-24 14:36:37 (19168): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-07-24 14:36:37 (19168): Guest Log: BIOS: Booting from Hard Disk...
2024-07-24 14:36:40 (19168): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-07-24 14:36:40 (19168): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-07-24 14:37:03 (19168): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-07-24 14:37:03 (19168): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-07-24 14:37:08 (19168): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-07-24 14:37:08 (19168): Guest Log: 00:00:00.000158 main     Log opened 2024-07-24T12:37:07.287646000Z
2024-07-24 14:37:08 (19168): Guest Log: 00:00:00.000397 main     OS Product: Linux
2024-07-24 14:37:08 (19168): Guest Log: 00:00:00.000465 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-07-24 14:37:08 (19168): Guest Log: 00:00:00.000518 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-07-24 14:37:08 (19168): Guest Log: 00:00:00.000569 main     Executable: /usr/sbin/VBoxService
2024-07-24 14:37:08 (19168): Guest Log: 00:00:00.000570 main     Process ID: 3236
2024-07-24 14:37:08 (19168): Guest Log: 00:00:00.000572 main     Package type: LINUX_64BITS_GENERIC
2024-07-24 14:37:08 (19168): Guest Log: 00:00:00.004864 main     5.2.6 r120293 started. Verbose level = 0
2024-07-24 14:37:37 (19168): Guest Log: 14:37:36 CEST +02:00 2024-07-24: cranky: [INFO] Detected Theory App
2024-07-24 14:37:37 (19168): Guest Log: 14:37:36 CEST +02:00 2024-07-24: cranky: [INFO] Checking CVMFS.
2024-07-24 14:37:39 (19168): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-07-24 14:37:39 (19168): 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-07-24 14:37:39 (19168): Guest Log: 2.5.2.0 4070 0 27212 30014 3 1 272623 4096000 0 65024 0 0 n/a 5 14 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-07-24 14:37:41 (19168): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-07-24 14:37:42 (19168): 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-07-24 14:37:42 (19168): Guest Log: 2.5.2.0 4118 0 27520 24457 3 2 272623 4096000 0 65024 0 2 -100 7962 5032 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-07-24 14:37:43 (19168): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-07-24 14:37:44 (19168): 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-07-24 14:37:44 (19168): Guest Log: 2.5.2.0 4140 0 26236 272 3 1 272623 4096000 0 65024 0 0 n/a 4 9 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-07-24 14:37:45 (19168): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-07-24 14:37:46 (19168): 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-07-24 14:37:46 (19168): Guest Log: 2.5.2.0 4092 0 39628 19203 3 1 272623 4096000 0 65024 0 0 n/a 5626 8498 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-07-24 14:37:46 (19168): Guest Log: 14:37:45 CEST +02:00 2024-07-24: cranky: [INFO] Checking runc.
2024-07-24 14:37:46 (19168): Guest Log: 14:37:45 CEST +02:00 2024-07-24: cranky: [INFO] Creating the filesystem.
2024-07-24 14:37:46 (19168): Guest Log: 14:37:45 CEST +02:00 2024-07-24: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-07-24 14:37:46 (19168): Guest Log: 14:37:45 CEST +02:00 2024-07-24: cranky: [INFO] Updating config.json.
2024-07-24 14:37:46 (19168): Guest Log: 14:37:46 CEST +02:00 2024-07-24: cranky: [INFO] Running Container 'runc'.
2024-07-24 14:37:49 (19168): Guest Log: job: htmld=/shared/html/job
2024-07-24 14:37:49 (19168): Guest Log: job: unpack exitcode=0
2024-07-24 14:37:50 (19168): Guest Log: INFO: activated the work-around for ld:
2024-07-24 14:37:50 (19168): Guest Log: lrwxrwxrwx 1 0 0 15 Jul 24 12:37 /tmp/tmp.1iEzxJ5pba/ld -> /usr/bin/ld.bfd
2024-07-24 14:37:51 (19168): Guest Log: 14:37:50 CEST +02:00 2024-07-24: cranky: [INFO] ===> [runRivet] Wed Jul 24 12:37:49 UTC 2024 [boinc pp jets 8000 150 - pythia8 8.243 tune-CUETP8S1 100000 330]
2024-07-24 15:00:21 (19168): VM state change detected. (old = 'running', new = 'paused')
2024-07-25 07:41:47 (19168): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_ecd58209052ebf88" 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-07-25 07:41:47 (19168): Guest Log: 00:23:21.324647 timesync vgsvcTimeSyncWorker: Radical host time change: 60 083 482 000 000ns (HostNow=1 721 886 101 742 000 000 ns HostLast=1 721 826 018 260 000 000 ns)
2024-07-25 07:41:47 (19168): VM state change detected. (old = 'paused', new = 'running')
2024-07-25 07:41:52 (19168): Guest Log: 00:23:31.349223 timesync vgsvcTimeSyncWorker: Radical guest time change: 60 083 419 600 000ns (GuestNow=1 721 886 111 838 782 000 ns GuestLast=1 721 826 028 419 182 000 ns fSetTimeLastLoop=true )
2024-07-25 08:58:16 (19168): Status Report: Job Duration: '864000.000000'
2024-07-25 08:58:16 (19168): Status Report: Elapsed Time: '6000.000000'
2024-07-25 08:58:16 (19168): Status Report: CPU Time: '4509.250000'
2024-07-25 10:38:22 (19168): Status Report: Job Duration: '864000.000000'
2024-07-25 10:38:22 (19168): Status Report: Elapsed Time: '12000.000000'
2024-07-25 10:38:22 (19168): Status Report: CPU Time: '9024.203125'
2024-07-25 12:18:26 (19168): Status Report: Job Duration: '864000.000000'
2024-07-25 12:18:26 (19168): Status Report: Elapsed Time: '18000.000000'
2024-07-25 12:18:26 (19168): Status Report: CPU Time: '13534.750000'
2024-07-25 13:58:31 (19168): Status Report: Job Duration: '864000.000000'
2024-07-25 13:58:31 (19168): Status Report: Elapsed Time: '24000.000000'
2024-07-25 13:58:31 (19168): Status Report: CPU Time: '18046.468750'
2024-07-25 14:58:21 (19168): VM state change detected. (old = 'running', new = 'paused')
2024-07-26 07:37:27 (19168): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_ecd58209052ebf88" 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-07-26 07:37:27 (19168): Guest Log: 07:40:03.288675 timesync vgsvcTimeSyncWorker: Radical host time change: 59 943 800 000 000ns (HostNow=1 721 972 237 535 000 000 ns HostLast=1 721 912 293 735 000 000 ns)
2024-07-26 07:37:27 (19168): VM state change detected. (old = 'paused', new = 'running')
2024-07-26 07:37:28 (19168): Guest Log: 07:40:13.296397 timesync vgsvcTimeSyncWorker: Radical guest time change: 59 943 764 501 000ns (GuestNow=1 721 972 247 542 735 000 ns GuestLast=1 721 912 303 778 234 000 ns fSetTimeLastLoop=true )
2024-07-26 08:17:53 (19168): Status Report: Job Duration: '864000.000000'
2024-07-26 08:17:53 (19168): Status Report: Elapsed Time: '30000.000000'
2024-07-26 08:17:53 (19168): Status Report: CPU Time: '22574.671875'
2024-07-26 09:58:00 (19168): Status Report: Job Duration: '864000.000000'
2024-07-26 09:58:00 (19168): Status Report: Elapsed Time: '36000.000000'
2024-07-26 09:58:00 (19168): Status Report: CPU Time: '27089.031250'
2024-07-26 11:29:47 (19168): VM state change detected. (old = 'running', new = 'paused')
2024-07-30 07:47:52 (19168): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_ecd58209052ebf88" 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-07-30 07:47:52 (19168): Guest Log: 11:32:38.061502 timesync vgsvcTimeSyncWorker: Radical host time change: 332 278 637 000 000ns (HostNow=1 722 318 458 208 000 000 ns HostLast=1 721 986 179 571 000 000 ns)
2024-07-30 07:47:52 (19168): Guest Log: 11:32:48.071959 timesync vgsvcTimeSyncWorker: Radical guest time change: 332 275 910 617 000ns (GuestNow=1 722 318 468 218 443 000 ns GuestLast=1 721 986 192 307 826 000 ns fSetTimeLastLoop=true )
2024-07-30 07:47:52 (19168): VM state change detected. (old = 'paused', new = 'running')
2024-07-30 07:56:18 (19168): Status Report: Job Duration: '864000.000000'
2024-07-30 07:56:18 (19168): Status Report: Elapsed Time: '42000.479212'
2024-07-30 07:56:18 (19168): Status Report: CPU Time: '31625.546875'
2024-07-30 08:58:17 (16796): vboxwrapper version 26207
2024-07-30 08:58:17 (16796): BOINC client version: 8.0.2
2024-07-30 08:58:20 (16796): Detected: VirtualBox VboxManage Interface (Version: 7.0.10)
2024-07-30 08:58:20 (16796): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-07-30 08:58:24 (16796): Guest Log: BIOS: VirtualBox 7.0.10
2024-07-30 08:58:24 (16796): Guest Log: CPUID EDX: 0x178bfbff
2024-07-30 08:58:24 (16796): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-07-30 08:58:24 (16796): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-07-30 08:58:24 (16796): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-07-30 08:58:24 (16796): Guest Log: BIOS: Booting from Hard Disk...
2024-07-30 08:58:24 (16796): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-07-30 08:58:24 (16796): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-07-30 08:58:24 (16796): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-07-30 08:58:24 (16796): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-07-30 08:58:24 (16796): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-07-30 08:58:24 (16796): Guest Log: 00:00:00.000158 main     Log opened 2024-07-24T12:37:07.287646000Z
2024-07-30 08:58:24 (16796): Guest Log: 00:00:00.000397 main     OS Product: Linux
2024-07-30 08:58:24 (16796): Guest Log: 00:00:00.000465 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-07-30 08:58:24 (16796): Guest Log: 00:00:00.000518 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-07-30 08:58:24 (16796): Guest Log: 00:00:00.000569 main     Executable: /usr/sbin/VBoxService
2024-07-30 08:58:24 (16796): Guest Log: 00:00:00.000570 main     Process ID: 3236
2024-07-30 08:58:24 (16796): Guest Log: 00:00:00.000572 main     Package type: LINUX_64BITS_GENERIC
2024-07-30 08:58:24 (16796): Guest Log: 00:00:00.004864 main     5.2.6 r120293 started. Verbose level = 0
2024-07-30 08:58:24 (16796): Guest Log: 14:37:36 CEST +02:00 2024-07-24: cranky: [INFO] Detected Theory App
2024-07-30 08:58:24 (16796): Guest Log: 14:37:36 CEST +02:00 2024-07-24: cranky: [INFO] Checking CVMFS.
2024-07-30 08:58:24 (16796): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-07-30 08:58:24 (16796): 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-07-30 08:58:24 (16796): Guest Log: 2.5.2.0 4070 0 27212 30014 3 1 272623 4096000 0 65024 0 0 n/a 5 14 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-07-30 08:58:24 (16796): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-07-30 08:58:24 (16796): 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-07-30 08:58:24 (16796): Guest Log: 2.5.2.0 4118 0 27520 24457 3 2 272623 4096000 0 65024 0 2 -100 7962 5032 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-07-30 08:58:24 (16796): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-07-30 08:58:24 (16796): 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-07-30 08:58:24 (16796): Guest Log: 2.5.2.0 4140 0 26236 272 3 1 272623 4096000 0 65024 0 0 n/a 4 9 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-07-30 08:58:24 (16796): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-07-30 08:58:24 (16796): 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-07-30 08:58:24 (16796): Guest Log: 2.5.2.0 4092 0 39628 19203 3 1 272623 4096000 0 65024 0 0 n/a 5626 8498 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-07-30 08:58:24 (16796): Guest Log: 14:37:45 CEST +02:00 2024-07-24: cranky: [INFO] Checking runc.
2024-07-30 08:58:24 (16796): Guest Log: 14:37:45 CEST +02:00 2024-07-24: cranky: [INFO] Creating the filesystem.
2024-07-30 08:58:24 (16796): Guest Log: 14:37:45 CEST +02:00 2024-07-24: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-07-30 08:58:24 (16796): Guest Log: 14:37:45 CEST +02:00 2024-07-24: cranky: [INFO] Updating config.json.
2024-07-30 08:58:24 (16796): Guest Log: 14:37:46 CEST +02:00 2024-07-24: cranky: [INFO] Running Container 'runc'.
2024-07-30 08:58:24 (16796): Guest Log: job: htmld=/shared/html/job
2024-07-30 08:58:24 (16796): Guest Log: job: unpack exitcode=0
2024-07-30 08:58:24 (16796): Guest Log: INFO: activated the work-around for ld:
2024-07-30 08:58:24 (16796): Guest Log: lrwxrwxrwx 1 0 0 15 Jul 24 12:37 /tmp/tmp.1iEzxJ5pba/ld -> /usr/bin/ld.bfd
2024-07-30 08:58:24 (16796): Guest Log: 14:37:50 CEST +02:00 2024-07-24: cranky: [INFO] ===> [runRivet] Wed Jul 24 12:37:49 UTC 2024 [boinc pp jets 8000 150 - pythia8 8.243 tune-CUETP8S1 100000 330]
2024-07-30 08:58:24 (16796): Guest Log: 00:23:21.324647 timesync vgsvcTimeSyncWorker: Radical host time change: 60 083 482 000 000ns (HostNow=1 721 886 101 742 000 000 ns HostLast=1 721 826 018 260 000 000 ns)
2024-07-30 08:58:24 (16796): Guest Log: 00:23:31.349223 timesync vgsvcTimeSyncWorker: Radical guest time change: 60 083 419 600 000ns (GuestNow=1 721 886 111 838 782 000 ns GuestLast=1 721 826 028 419 182 000 ns fSetTimeLastLoop=true )
2024-07-30 08:58:24 (16796): Guest Log: 07:40:03.288675 timesync vgsvcTimeSyncWorker: Radical host time change: 59 943 800 000 000ns (HostNow=1 721 972 237 535 000 000 ns HostLast=1 721 912 293 735 000 000 ns)
2024-07-30 08:58:24 (16796): Guest Log: 07:40:13.296397 timesync vgsvcTimeSyncWorker: Radical guest time change: 59 943 764 501 000ns (GuestNow=1 721 972 247 542 735 000 ns GuestLast=1 721 912 303 778 234 000 ns fSetTimeLastLoop=true )
2024-07-30 08:58:24 (16796): Guest Log: 11:32:38.061502 timesync vgsvcTimeSyncWorker: Radical host time change: 332 278 637 000 000ns (HostNow=1 722 318 458 208 000 000 ns HostLast=1 721 986 179 571 000 000 ns)
2024-07-30 08:58:24 (16796): Guest Log: 11:32:48.071959 timesync vgsvcTimeSyncWorker: Radical guest time change: 332 275 910 617 000ns (GuestNow=1 722 318 468 218 443 000 ns GuestLast=1 721 986 192 307 826 000 ns fSetTimeLastLoop=true )
2024-07-30 08:58:24 (16796): Starting VM using VBoxManage interface. (boinc_ecd58209052ebf88, slot#5)
2024-07-30 08:58:36 (16796): Successfully started VM. (PID = '12632')
2024-07-30 08:58:36 (16796): Reporting VM Process ID to BOINC.
2024-07-30 08:58:36 (16796): Guest Log: BIOS: VirtualBox 7.0.10
2024-07-30 08:58:36 (16796): Guest Log: CPUID EDX: 0x178bfbff
2024-07-30 08:58:36 (16796): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-07-30 08:58:36 (16796): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-07-30 08:58:36 (16796): VM state change detected. (old = 'poweredoff', new = 'running')
2024-07-30 08:58:36 (16796): Detected: Web Application Enabled (http://localhost:57719)
2024-07-30 08:58:36 (16796): Detected: Remote Desktop Enabled (localhost:57721)
2024-07-30 08:58:36 (16796): Status Report: Job Duration: '864000.000000'
2024-07-30 08:58:36 (16796): Status Report: Elapsed Time: '45255.941259'
2024-07-30 08:58:36 (16796): Status Report: CPU Time: '34072.296875'
2024-07-30 08:58:36 (16796): Preference change detected
2024-07-30 08:58:36 (16796): Setting CPU throttle for VM. (67%)
2024-07-30 08:58:36 (16796): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 120 seconds) or (Vbox_job.xml: 600 seconds))
2024-07-30 08:58:38 (16796): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-07-30 08:58:38 (16796): Guest Log: BIOS: Booting from Hard Disk...
2024-07-30 08:58:41 (16796): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-07-30 08:58:41 (16796): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-07-30 08:59:04 (16796): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-07-30 08:59:04 (16796): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-07-30 08:59:10 (16796): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-07-30 08:59:10 (16796): Guest Log: 00:00:00.000121 main     Log opened 2024-07-30T06:59:09.445483000Z
2024-07-30 08:59:10 (16796): Guest Log: 00:00:00.000265 main     OS Product: Linux
2024-07-30 08:59:10 (16796): Guest Log: 00:00:00.000343 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-07-30 08:59:10 (16796): Guest Log: 00:00:00.000389 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-07-30 08:59:10 (16796): Guest Log: 00:00:00.000433 main     Executable: /usr/sbin/VBoxService
2024-07-30 08:59:10 (16796): Guest Log: 00:00:00.000434 main     Process ID: 3231
2024-07-30 08:59:10 (16796): Guest Log: 00:00:00.000435 main     Package type: LINUX_64BITS_GENERIC
2024-07-30 08:59:10 (16796): Guest Log: 00:00:00.009593 main     5.2.6 r120293 started. Verbose level = 0
2024-07-30 08:59:41 (16796): Guest Log: 08:59:41 CEST +02:00 2024-07-30: cranky: [INFO] Detected Theory App
2024-07-30 08:59:41 (16796): Guest Log: 08:59:41 CEST +02:00 2024-07-30: cranky: [INFO] Checking CVMFS.
2024-07-30 08:59:43 (16796): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-07-30 08:59:43 (16796): 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-07-30 08:59:43 (16796): Guest Log: 2.5.2.0 4069 0 28316 30060 3 1 1167357 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-07-30 08:59:45 (16796): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-07-30 08:59:46 (16796): 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-07-30 08:59:46 (16796): Guest Log: 2.5.2.0 4090 0 27712 24845 3 2 1167357 4096001 0 65024 0 2 0 5826 6339 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-07-30 08:59:47 (16796): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-07-30 08:59:48 (16796): 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-07-30 08:59:48 (16796): Guest Log: 2.5.2.0 4135 0 28268 272 3 1 1167357 4096001 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-07-30 08:59:49 (16796): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-07-30 08:59:50 (16796): 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-07-30 08:59:50 (16796): Guest Log: 2.5.2.0 4111 0 39632 19250 3 1 1167357 4096001 0 65024 0 0 n/a 5645 4800 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-07-30 08:59:50 (16796): Guest Log: 08:59:49 CEST +02:00 2024-07-30: cranky: [INFO] Checking runc.
2024-07-30 08:59:50 (16796): Guest Log: 08:59:49 CEST +02:00 2024-07-30: cranky: [INFO] Creating the filesystem.
2024-07-30 08:59:50 (16796): Guest Log: 08:59:49 CEST +02:00 2024-07-30: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-07-30 08:59:50 (16796): Guest Log: 08:59:50 CEST +02:00 2024-07-30: cranky: [INFO] Updating config.json.
2024-07-30 08:59:51 (16796): Guest Log: 08:59:50 CEST +02:00 2024-07-30: cranky: [INFO] Running Container 'runc'.
2024-07-30 08:59:51 (16796): Guest Log: job: htmld=/shared/html/job
2024-07-30 08:59:51 (16796): Guest Log: job: unpack exitcode=0
2024-07-30 08:59:51 (16796): Guest Log: INFO: activated the work-around for ld:
2024-07-30 08:59:51 (16796): Guest Log: lrwxrwxrwx 1 0 0 15 Jul 30 06:59 /tmp/tmp.BgfSUp4xWt/ld -> /usr/bin/ld.bfd
2024-07-30 08:59:53 (16796): Guest Log: 08:59:52 CEST +02:00 2024-07-30: cranky: [INFO] ===> [runRivet] Tue Jul 30 06:59:51 UTC 2024 [boinc pp jets 8000 150 - pythia8 8.243 tune-CUETP8S1 100000 330]
2024-07-30 10:38:44 (16796): Status Report: Job Duration: '864000.000000'
2024-07-30 10:38:44 (16796): Status Report: Elapsed Time: '51255.941259'
2024-07-30 10:38:44 (16796): Status Report: CPU Time: '38598.062500'
2024-07-30 12:18:50 (16796): Status Report: Job Duration: '864000.000000'
2024-07-30 12:18:50 (16796): Status Report: Elapsed Time: '57255.941259'
2024-07-30 12:18:50 (16796): Status Report: CPU Time: '43114.484375'
2024-07-30 13:58:56 (16796): Status Report: Job Duration: '864000.000000'
2024-07-30 13:58:56 (16796): Status Report: Elapsed Time: '63255.941259'
2024-07-30 13:58:56 (16796): Status Report: CPU Time: '47625.781250'
2024-07-30 15:03:37 (16796): VM state change detected. (old = 'running', new = 'paused')
2024-07-31 07:22:31 (16796): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_ecd58209052ebf88" 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-07-31 07:22:32 (16796): Guest Log: 06:04:28.456566 timesync vgsvcTimeSyncWorker: Radical host time change: 58 732 879 000 000ns (HostNow=1 722 403 340 603 000 000 ns HostLast=1 722 344 607 724 000 000 ns)
2024-07-31 07:22:32 (16796): Guest Log: 06:04:38.476510 timesync vgsvcTimeSyncWorker: Radical guest time change: 58 732 831 557 000ns (GuestNow=1 722 403 350 661 673 000 ns GuestLast=1 722 344 617 830 116 000 ns fSetTimeLastLoop=true )
2024-07-31 07:22:32 (16796): VM state change detected. (old = 'paused', new = 'running')
2024-07-31 07:58:11 (16796): Status Report: Job Duration: '864000.000000'
2024-07-31 07:58:11 (16796): Status Report: Elapsed Time: '69255.941259'
2024-07-31 07:58:11 (16796): Status Report: CPU Time: '52152.093750'
2024-07-31 09:38:18 (16796): Status Report: Job Duration: '864000.000000'
2024-07-31 09:38:18 (16796): Status Report: Elapsed Time: '75255.941259'
2024-07-31 09:38:18 (16796): Status Report: CPU Time: '56667.640625'
2024-07-31 11:18:24 (16796): Status Report: Job Duration: '864000.000000'
2024-07-31 11:18:24 (16796): Status Report: Elapsed Time: '81255.941259'
2024-07-31 11:18:24 (16796): Status Report: CPU Time: '61183.500000'
2024-07-31 12:58:29 (16796): Status Report: Job Duration: '864000.000000'
2024-07-31 12:58:29 (16796): Status Report: Elapsed Time: '87255.941259'
2024-07-31 12:58:29 (16796): Status Report: CPU Time: '65694.625000'
2024-07-31 14:38:35 (16796): Status Report: Job Duration: '864000.000000'
2024-07-31 14:38:35 (16796): Status Report: Elapsed Time: '93255.941259'
2024-07-31 14:38:35 (16796): Status Report: CPU Time: '70209.078125'
2024-07-31 14:57:20 (16796): VM state change detected. (old = 'running', new = 'paused')
2024-08-01 07:59:34 (16796): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_ecd58209052ebf88" 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-01 07:59:35 (16796): Guest Log: 13:39:30.129219 timesync vgsvcTimeSyncWorker: Radical host time change: 61 335 617 000 000ns (HostNow=1 722 491 967 961 000 000 ns HostLast=1 722 430 632 344 000 000 ns)
2024-08-01 07:59:35 (16796): VM state change detected. (old = 'paused', new = 'running')
2024-08-01 07:59:38 (16796): Guest Log: 13:39:40.137843 timesync vgsvcTimeSyncWorker: Radical guest time change: 61 335 655 316 000ns (GuestNow=1 722 491 977 969 657 000 ns GuestLast=1 722 430 642 314 341 000 ns fSetTimeLastLoop=true )
2024-08-01 09:21:07 (16796): Status Report: Job Duration: '864000.000000'
2024-08-01 09:21:07 (16796): Status Report: Elapsed Time: '99256.007215'
2024-08-01 09:21:07 (16796): Status Report: CPU Time: '74734.281250'
2024-08-01 11:01:14 (16796): Status Report: Job Duration: '864000.000000'
2024-08-01 11:01:14 (16796): Status Report: Elapsed Time: '105256.734407'
2024-08-01 11:01:14 (16796): Status Report: CPU Time: '79244.031250'
2024-08-01 12:41:20 (16796): Status Report: Job Duration: '864000.000000'
2024-08-01 12:41:20 (16796): Status Report: Elapsed Time: '111257.337972'
2024-08-01 12:41:20 (16796): Status Report: CPU Time: '83757.000000'
2024-08-01 14:21:26 (16796): Status Report: Job Duration: '864000.000000'
2024-08-01 14:21:26 (16796): Status Report: Elapsed Time: '117257.337972'
2024-08-01 14:21:26 (16796): Status Report: CPU Time: '88270.187500'
2024-08-01 14:56:48 (16796): VM state change detected. (old = 'running', new = 'paused')
2024-08-01 14:56:57 (16796): VM state change detected. (old = 'paused', new = 'running')
2024-08-01 15:05:50 (16796): VM state change detected. (old = 'running', new = 'paused')
2024-08-02 07:48:26 (16796): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_ecd58209052ebf88" 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-02 07:48:27 (16796): Guest Log: 20:45:51.524984 timesync vgsvcTimeSyncWorker: Radical host time change: 60 153 809 000 000ns (HostNow=1 722 577 701 875 000 000 ns HostLast=1 722 517 548 066 000 000 ns)
2024-08-02 07:48:27 (16796): VM state change detected. (old = 'paused', new = 'running')
2024-08-02 07:48:32 (16796): Guest Log: 20:46:01.525997 timesync vgsvcTimeSyncWorker: Radical guest time change: 60 162 519 247 000ns (GuestNow=1 722 577 711 876 029 000 ns GuestLast=1 722 517 549 356 782 000 ns fSetTimeLastLoop=true )
2024-08-02 08:44:38 (16796): Status Report: Job Duration: '864000.000000'
2024-08-02 08:44:38 (16796): Status Report: Elapsed Time: '123257.337972'
2024-08-02 08:44:38 (16796): Status Report: CPU Time: '92813.000000'
2024-08-02 10:24:44 (16796): Status Report: Job Duration: '864000.000000'
2024-08-02 10:24:44 (16796): Status Report: Elapsed Time: '129257.337972'
2024-08-02 10:24:44 (16796): Status Report: CPU Time: '97327.375000'
2024-08-02 12:04:51 (16796): Status Report: Job Duration: '864000.000000'
2024-08-02 12:04:51 (16796): Status Report: Elapsed Time: '135258.337972'
2024-08-02 12:04:51 (16796): Status Report: CPU Time: '101844.500000'
2024-08-02 13:44:56 (16796): Status Report: Job Duration: '864000.000000'
2024-08-02 13:44:56 (16796): Status Report: Elapsed Time: '141258.472704'
2024-08-02 13:44:56 (16796): Status Report: CPU Time: '106354.734375'
2024-08-02 15:01:16 (16796): VM state change detected. (old = 'running', new = 'paused')
2024-08-05 07:47:58 (16796): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_ecd58209052ebf88" 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-05 07:47:58 (16796): Guest Log: 27:58:54.829833 timesync vgsvcTimeSyncWorker: Radical host time change: 233 197 969 000 000ns (HostNow=1 722 836 873 115 000 000 ns HostLast=1 722 603 675 146 000 000 ns)
2024-08-05 07:47:58 (16796): VM state change detected. (old = 'paused', new = 'running')
2024-08-05 07:48:01 (16796): Guest Log: 27:59:04.840094 timesync vgsvcTimeSyncWorker: Radical guest time change: 233 197 945 432 000ns (GuestNow=1 722 836 883 125 261 000 ns GuestLast=1 722 603 685 179 829 000 ns fSetTimeLastLoop=true )
2024-08-05 07:53:39 (16796): Guest Log: job: run exitcode=0
2024-08-05 07:53:39 (16796): Guest Log: job: diskusage=3288
2024-08-05 07:53:39 (16796): Guest Log: job: logsize=72 k
2024-08-05 07:53:39 (16796): Guest Log: job: times=
2024-08-05 07:53:39 (16796): Guest Log: 0m0.014s 0m0.014s
2024-08-05 07:53:39 (16796): Guest Log: 1608m44.429s 21m15.949s
2024-08-05 07:53:39 (16796): Guest Log: job: cpuusage=97800
2024-08-05 07:53:39 (16796): Guest Log: 07:53:40 CEST +02:00 2024-08-05: cranky: [INFO] Container 'runc' finished with status code 0.
2024-08-05 07:53:39 (16796): Guest Log: 07:53:41 CEST +02:00 2024-08-05: cranky: [INFO] Preparing output.
2024-08-05 07:53:40 (16796): Guest Log: [INFO] Job Finished
2024-08-05 07:53:40 (16796): Guest Log: [INFO] Shutting Down.
2024-08-05 07:53:40 (16796): VM Completion File Detected.
2024-08-05 07:53:40 (16796): VM Completion Message: Job Finished
.
2024-08-05 07:53:40 (16796): Powering off VM.
2024-08-05 07:53:41 (16796): Successfully stopped VM.
2024-08-05 07:53:41 (16796): Deregistering VM. (boinc_ecd58209052ebf88, slot#5)
2024-08-05 07:53:41 (16796): Removing network bandwidth throttle group from VM.
2024-08-05 07:53:41 (16796): Removing VM from VirtualBox.
2024-08-05 07:53:47 (16796): called boinc_finish(0)

</stderr_txt>
]]>

©2026 CERN