Stderr output

<core_client_version>7.24.1</core_client_version>
<![CDATA[
<stderr_txt>
2024-11-26 02:22:08 (10372): vboxwrapper version 26207
2024-11-26 02:22:08 (10372): BOINC client version: 7.24.1
2024-11-26 02:22:08 (10372): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2024-11-26 02:22:08 (10372): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-11-26 02:22:08 (10372): Successfully copied 'init_data.xml' to the shared directory.
2024-11-26 02:22:08 (10372): Successfully copied 'input' to the shared directory.
2024-11-26 02:22:09 (10372): Create VM. (boinc_4716c3fd1deed5c2, slot#2)
2024-11-26 02:22:09 (10372): Setting Memory Size for VM. (630MB)
2024-11-26 02:22:09 (10372): Setting CPU Count for VM. (1)
2024-11-26 02:22:10 (10372): Setting Chipset Options for VM.
2024-11-26 02:22:10 (10372): Setting Graphics Controller Options for VM.
2024-11-26 02:22:10 (10372): Setting Boot Options for VM.
2024-11-26 02:22:10 (10372): Setting Network Configuration for NAT.
2024-11-26 02:22:11 (10372): Enabling VM Network Access.
2024-11-26 02:22:11 (10372): Disabling USB Support for VM.
2024-11-26 02:22:11 (10372): Disabling COM Port Support for VM.
2024-11-26 02:22:11 (10372): Disabling LPT Port Support for VM.
2024-11-26 02:22:12 (10372): Disabling Audio Support for VM.
2024-11-26 02:22:12 (10372): Disabling Clipboard Support for VM.
2024-11-26 02:22:12 (10372): Disabling Drag and Drop Support for VM.
2024-11-26 02:22:12 (10372): Adding storage controller(s) to VM.
2024-11-26 02:22:13 (10372): Adding virtual disk drive to VM. (Theory_2024_04_30_prod.vdi)
2024-11-26 02:22:13 (10372): Adding VirtualBox Guest Additions to VM.
2024-11-26 02:22:13 (10372): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2024-11-26 02:22:14 (10372): forwarding host port 64276 to guest port 80
2024-11-26 02:22:14 (10372): Enabling remote desktop for VM.
2024-11-26 02:22:14 (10372): Required extension pack not installed, remote desktop not enabled.
2024-11-26 02:22:14 (10372): Enabling shared directory for VM.
2024-11-26 02:22:15 (10372): Starting VM using VBoxManage interface. (boinc_4716c3fd1deed5c2, slot#2)
2024-11-26 02:22:21 (10372): Successfully started VM. (PID = '10120')
2024-11-26 02:22:21 (10372): Reporting VM Process ID to BOINC.
2024-11-26 02:22:21 (10372): Guest Log: BIOS: VirtualBox 7.0.6
2024-11-26 02:22:21 (10372): Guest Log: CPUID EDX: 0x178bfbff
2024-11-26 02:22:21 (10372): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-11-26 02:22:21 (10372): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-11-26 02:22:21 (10372): VM state change detected. (old = 'poweredoff', new = 'running')
2024-11-26 02:22:21 (10372): Detected: Web Application Enabled (http://localhost:64276)
2024-11-26 02:22:21 (10372): Preference change detected
2024-11-26 02:22:21 (10372): Setting CPU throttle for VM. (100%)
2024-11-26 02:22:22 (10372): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-11-26 02:22:23 (10372): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-11-26 02:22:23 (10372): Guest Log: BIOS: Booting from Hard Disk...
2024-11-26 02:22:26 (10372): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-11-26 02:22:26 (10372): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-11-26 02:22:50 (10372): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-11-26 02:22:50 (10372): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-11-26 02:22:54 (10372): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-11-26 02:22:54 (10372): Guest Log: 00:00:00.000144 main     Log opened 2024-11-26T08:22:53.105426000Z
2024-11-26 02:22:54 (10372): Guest Log: 00:00:00.000290 main     OS Product: Linux
2024-11-26 02:22:54 (10372): Guest Log: 00:00:00.000356 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-11-26 02:22:54 (10372): Guest Log: 00:00:00.000420 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-11-26 02:22:54 (10372): Guest Log: 00:00:00.000482 main     Executable: /usr/sbin/VBoxService
2024-11-26 02:22:54 (10372): Guest Log: 00:00:00.000484 main     Process ID: 3224
2024-11-26 02:22:54 (10372): Guest Log: 00:00:00.000485 main     Package type: LINUX_64BITS_GENERIC
2024-11-26 02:22:54 (10372): Guest Log: 00:00:00.004389 main     5.2.6 r120293 started. Verbose level = 0
2024-11-26 02:23:23 (10372): Guest Log: 09:23:22 CET +01:00 2024-11-26: cranky: [INFO] Detected Theory App
2024-11-26 02:23:23 (10372): Guest Log: 09:23:22 CET +01:00 2024-11-26: cranky: [INFO] Checking CVMFS.
2024-11-26 02:23:24 (10372): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-11-26 02:23:25 (10372): 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-11-26 02:23:25 (10372): Guest Log: 2.5.2.0 4063 0 28484 31073 3 1 278320 4096000 0 65024 0 0 n/a 5 2 http://s1fnal-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-11-26 02:23:26 (10372): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-11-26 02:23:27 (10372): 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-11-26 02:23:27 (10372): Guest Log: 2.5.2.0 4112 0 27504 25827 3 2 278320 4096000 0 65024 0 2 -100 10080 7700 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-11-26 02:23:28 (10372): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-11-26 02:23:29 (10372): 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-11-26 02:23:29 (10372): Guest Log: 2.5.2.0 4089 0 25180 272 3 1 278320 4096000 0 65024 0 0 n/a 4 5 http://s1fnal-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-11-26 02:23:30 (10372): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-11-26 02:23:31 (10372): 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-11-26 02:23:31 (10372): Guest Log: 2.5.2.0 4133 0 40148 20445 3 1 278320 4096000 0 65024 0 0 n/a 6335 1969 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-11-26 02:23:31 (10372): Guest Log: 09:23:29 CET +01:00 2024-11-26: cranky: [INFO] Checking runc.
2024-11-26 02:23:31 (10372): Guest Log: 09:23:30 CET +01:00 2024-11-26: cranky: [INFO] Creating the filesystem.
2024-11-26 02:23:31 (10372): Guest Log: 09:23:30 CET +01:00 2024-11-26: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-11-26 02:23:31 (10372): Guest Log: 09:23:30 CET +01:00 2024-11-26: cranky: [INFO] Updating config.json.
2024-11-26 02:23:31 (10372): Guest Log: 09:23:30 CET +01:00 2024-11-26: cranky: [INFO] Running Container 'runc'.
2024-11-26 02:23:36 (10372): Guest Log: job: htmld=/shared/html/job
2024-11-26 02:23:37 (10372): Guest Log: job: unpack exitcode=0
2024-11-26 02:23:37 (10372): Guest Log: INFO: activated the work-around for ld:
2024-11-26 02:23:37 (10372): Guest Log: lrwxrwxrwx 1 0 0 15 Nov 26 08:23 /tmp/tmp.7FueDuHB7B/ld -> /usr/bin/ld.bfd
2024-11-26 02:23:41 (10372): Guest Log: 09:23:39 CET +01:00 2024-11-26: cranky: [INFO] ===> [runRivet] Tue Nov 26 08:23:36 UTC 2024 [boinc PbPb heavyion-mb 2760 - - pythia8 8.305 default 100000 1144]
2024-11-26 03:22:32 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-11-26 03:43:21 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-11-26 03:43:25 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-11-26 03:43:45 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-11-26 04:23:39 (10372): Status Report: Job Duration: '864000.000000'
2024-11-26 04:23:39 (10372): Status Report: Elapsed Time: '6000.000000'
2024-11-26 04:23:39 (10372): Status Report: CPU Time: '4768.406250'
2024-11-26 06:03:44 (10372): Status Report: Job Duration: '864000.000000'
2024-11-26 06:03:44 (10372): Status Report: Elapsed Time: '12000.000000'
2024-11-26 06:03:44 (10372): Status Report: CPU Time: '10774.000000'
2024-11-26 07:43:48 (10372): Status Report: Job Duration: '864000.000000'
2024-11-26 07:43:48 (10372): Status Report: Elapsed Time: '18000.000000'
2024-11-26 07:43:48 (10372): Status Report: CPU Time: '16779.015625'
2024-11-26 08:00:04 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-11-26 22:10:13 (10372): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_4716c3fd1deed5c2" 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-11-26 22:10:14 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-11-26 22:10:22 (10372): Guest Log: 05:16:21.690745 timesync vgsvcTimeSyncWorker: Radical host time change: 51 015 676 000 000ns (HostNow=1 732 680 618 096 000 000 ns HostLast=1 732 629 602 420 000 000 ns)
2024-11-26 22:10:32 (10372): Guest Log: 05:16:32.875558 timesync vgsvcTimeSyncWorker: Radical guest time change: 51 032 359 994 000ns (GuestNow=1 732 680 632 470 426 000 ns GuestLast=1 732 629 600 110 432 000 ns fSetTimeLastLoop=true )
2024-11-26 23:34:03 (10372): Status Report: Job Duration: '864000.000000'
2024-11-26 23:34:03 (10372): Status Report: Elapsed Time: '24000.000000'
2024-11-26 23:34:03 (10372): Status Report: CPU Time: '22785.609375'
2024-11-27 00:41:43 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-11-27 00:42:03 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-11-27 01:14:28 (10372): Status Report: Job Duration: '864000.000000'
2024-11-27 01:14:28 (10372): Status Report: Elapsed Time: '30000.423586'
2024-11-27 01:14:28 (10372): Status Report: CPU Time: '28791.390625'
2024-11-27 02:54:33 (10372): Status Report: Job Duration: '864000.000000'
2024-11-27 02:54:33 (10372): Status Report: Elapsed Time: '36001.008570'
2024-11-27 02:54:33 (10372): Status Report: CPU Time: '34798.875000'
2024-11-27 04:34:39 (10372): Status Report: Job Duration: '864000.000000'
2024-11-27 04:34:39 (10372): Status Report: Elapsed Time: '42001.008570'
2024-11-27 04:34:39 (10372): Status Report: CPU Time: '40805.640625'
2024-11-27 06:14:43 (10372): Status Report: Job Duration: '864000.000000'
2024-11-27 06:14:43 (10372): Status Report: Elapsed Time: '48001.008570'
2024-11-27 06:14:43 (10372): Status Report: CPU Time: '46810.046875'
2024-11-27 07:54:48 (10372): Status Report: Job Duration: '864000.000000'
2024-11-27 07:54:48 (10372): Status Report: Elapsed Time: '54001.008570'
2024-11-27 07:54:48 (10372): Status Report: CPU Time: '52815.968750'
2024-11-27 08:00:04 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-11-27 12:02:59 (10372): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_4716c3fd1deed5c2" 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-11-27 12:03:00 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-11-27 12:03:05 (10372): Guest Log: 15:06:10.314252 timesync vgsvcTimeSyncWorker: Radical host time change: 14 580 085 000 000ns (HostNow=1 732 730 583 547 000 000 ns HostLast=1 732 716 003 462 000 000 ns)
2024-11-27 12:03:15 (10372): Guest Log: 15:06:20.323522 timesync vgsvcTimeSyncWorker: Radical guest time change: 14 586 954 942 000ns (GuestNow=1 732 730 595 210 172 000 ns GuestLast=1 732 716 008 255 230 000 ns fSetTimeLastLoop=true )
2024-11-27 13:37:48 (10372): Status Report: Job Duration: '864000.000000'
2024-11-27 13:37:48 (10372): Status Report: Elapsed Time: '60001.044201'
2024-11-27 13:37:48 (10372): Status Report: CPU Time: '58826.562500'
2024-11-27 15:17:53 (10372): Status Report: Job Duration: '864000.000000'
2024-11-27 15:17:53 (10372): Status Report: Elapsed Time: '66001.044201'
2024-11-27 15:17:53 (10372): Status Report: CPU Time: '64832.515625'
2024-11-27 16:57:58 (10372): Status Report: Job Duration: '864000.000000'
2024-11-27 16:57:58 (10372): Status Report: Elapsed Time: '72001.044201'
2024-11-27 16:57:58 (10372): Status Report: CPU Time: '70838.765625'
2024-11-27 18:38:03 (10372): Status Report: Job Duration: '864000.000000'
2024-11-27 18:38:03 (10372): Status Report: Elapsed Time: '78001.044201'
2024-11-27 18:38:03 (10372): Status Report: CPU Time: '76842.828125'
2024-11-27 20:18:07 (10372): Status Report: Job Duration: '864000.000000'
2024-11-27 20:18:07 (10372): Status Report: Elapsed Time: '84001.044201'
2024-11-27 20:18:07 (10372): Status Report: CPU Time: '82848.218750'
2024-11-27 21:58:12 (10372): Status Report: Job Duration: '864000.000000'
2024-11-27 21:58:12 (10372): Status Report: Elapsed Time: '90001.044201'
2024-11-27 21:58:12 (10372): Status Report: CPU Time: '88854.343750'
2024-11-27 23:38:17 (10372): Status Report: Job Duration: '864000.000000'
2024-11-27 23:38:17 (10372): Status Report: Elapsed Time: '96001.044201'
2024-11-27 23:38:17 (10372): Status Report: CPU Time: '94860.312500'
2024-11-28 01:18:22 (10372): Status Report: Job Duration: '864000.000000'
2024-11-28 01:18:22 (10372): Status Report: Elapsed Time: '102001.044201'
2024-11-28 01:18:22 (10372): Status Report: CPU Time: '100866.421875'
2024-11-28 02:58:27 (10372): Status Report: Job Duration: '864000.000000'
2024-11-28 02:58:27 (10372): Status Report: Elapsed Time: '108001.044201'
2024-11-28 02:58:27 (10372): Status Report: CPU Time: '106872.437500'
2024-11-28 04:38:32 (10372): Status Report: Job Duration: '864000.000000'
2024-11-28 04:38:32 (10372): Status Report: Elapsed Time: '114001.044201'
2024-11-28 04:38:32 (10372): Status Report: CPU Time: '112879.015625'
2024-11-28 06:18:37 (10372): Status Report: Job Duration: '864000.000000'
2024-11-28 06:18:37 (10372): Status Report: Elapsed Time: '120001.044201'
2024-11-28 06:18:37 (10372): Status Report: CPU Time: '118883.046875'
2024-11-28 07:58:42 (10372): Status Report: Job Duration: '864000.000000'
2024-11-28 07:58:42 (10372): Status Report: Elapsed Time: '126001.044201'
2024-11-28 07:58:42 (10372): Status Report: CPU Time: '124889.078125'
2024-11-28 08:00:04 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-11-29 20:32:06 (10372): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_4716c3fd1deed5c2" 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-11-29 20:32:07 (10372): Guest Log: 35:03:13.805592 timesync vgsvcTimeSyncWorker: Radical host time change: 131 487 181 000 000ns (HostNow=1 732 933 883 670 000 000 ns HostLast=1 732 802 396 489 000 000 ns)
2024-11-29 20:32:07 (10372): Guest Log: 35:03:24.675743 timesync vgsvcTimeSyncWorker: Radical guest time change: 131 490 236 369 000ns (GuestNow=1 732 933 896 734 367 000 ns GuestLast=1 732 802 406 497 998 000 ns fSetTimeLastLoop=true )
2024-11-29 20:32:07 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-11-29 20:32:18 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-11-29 20:32:36 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-11-29 22:11:12 (10372): Status Report: Job Duration: '864000.000000'
2024-11-29 22:11:12 (10372): Status Report: Elapsed Time: '132001.044201'
2024-11-29 22:11:12 (10372): Status Report: CPU Time: '130916.671875'
2024-11-29 23:51:16 (10372): Status Report: Job Duration: '864000.000000'
2024-11-29 23:51:16 (10372): Status Report: Elapsed Time: '138001.044201'
2024-11-29 23:51:16 (10372): Status Report: CPU Time: '136922.921875'
2024-11-30 01:31:21 (10372): Status Report: Job Duration: '864000.000000'
2024-11-30 01:31:21 (10372): Status Report: Elapsed Time: '144001.044201'
2024-11-30 01:31:21 (10372): Status Report: CPU Time: '142929.062500'
2024-11-30 03:11:26 (10372): Status Report: Job Duration: '864000.000000'
2024-11-30 03:11:26 (10372): Status Report: Elapsed Time: '150001.044201'
2024-11-30 03:11:26 (10372): Status Report: CPU Time: '148935.156250'
2024-11-30 04:51:31 (10372): Status Report: Job Duration: '864000.000000'
2024-11-30 04:51:31 (10372): Status Report: Elapsed Time: '156001.044201'
2024-11-30 04:51:31 (10372): Status Report: CPU Time: '154939.953125'
2024-11-30 06:31:36 (10372): Status Report: Job Duration: '864000.000000'
2024-11-30 06:31:36 (10372): Status Report: Elapsed Time: '162001.044201'
2024-11-30 06:31:36 (10372): Status Report: CPU Time: '160944.984375'
2024-11-30 08:00:04 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-12-01 12:27:43 (10372): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_4716c3fd1deed5c2" 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-12-01 12:27:45 (10372): Guest Log: 46:32:10.377215 timesync vgsvcTimeSyncWorker: Radical host time change: 102 454 902 000 000ns (HostNow=1 733 077 651 212 000 000 ns HostLast=1 732 975 196 310 000 000 ns)
2024-12-01 12:27:45 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-12-01 12:27:46 (10372): Guest Log: 46:32:20.387979 timesync vgsvcTimeSyncWorker: Radical guest time change: 102 448 874 580 000ns (GuestNow=1 733 077 666 266 593 000 ns GuestLast=1 732 975 217 392 013 000 ns fSetTimeLastLoop=true )
2024-12-01 12:32:46 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-12-01 12:33:06 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-12-01 12:36:18 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-12-01 12:36:37 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-12-01 12:40:06 (10372): Status Report: Job Duration: '864000.000000'
2024-12-01 12:40:06 (10372): Status Report: Elapsed Time: '168001.044201'
2024-12-01 12:40:06 (10372): Status Report: CPU Time: '166947.687500'
2024-12-01 14:20:11 (10372): Status Report: Job Duration: '864000.000000'
2024-12-01 14:20:11 (10372): Status Report: Elapsed Time: '174001.044201'
2024-12-01 14:20:11 (10372): Status Report: CPU Time: '172951.531250'
2024-12-01 16:00:16 (10372): Status Report: Job Duration: '864000.000000'
2024-12-01 16:00:16 (10372): Status Report: Elapsed Time: '180001.044201'
2024-12-01 16:00:16 (10372): Status Report: CPU Time: '178957.750000'
2024-12-01 17:40:21 (10372): Status Report: Job Duration: '864000.000000'
2024-12-01 17:40:21 (10372): Status Report: Elapsed Time: '186001.044201'
2024-12-01 17:40:21 (10372): Status Report: CPU Time: '184963.640625'
2024-12-01 19:20:25 (10372): Status Report: Job Duration: '864000.000000'
2024-12-01 19:20:25 (10372): Status Report: Elapsed Time: '192001.044201'
2024-12-01 19:20:25 (10372): Status Report: CPU Time: '190966.625000'
2024-12-01 21:00:30 (10372): Status Report: Job Duration: '864000.000000'
2024-12-01 21:00:30 (10372): Status Report: Elapsed Time: '198001.044201'
2024-12-01 21:00:30 (10372): Status Report: CPU Time: '196972.484375'
2024-12-01 22:40:35 (10372): Status Report: Job Duration: '864000.000000'
2024-12-01 22:40:35 (10372): Status Report: Elapsed Time: '204001.044201'
2024-12-01 22:40:35 (10372): Status Report: CPU Time: '202978.296875'
2024-12-02 00:20:40 (10372): Status Report: Job Duration: '864000.000000'
2024-12-02 00:20:40 (10372): Status Report: Elapsed Time: '210001.044201'
2024-12-02 00:20:40 (10372): Status Report: CPU Time: '208984.406250'
2024-12-02 02:00:44 (10372): Status Report: Job Duration: '864000.000000'
2024-12-02 02:00:44 (10372): Status Report: Elapsed Time: '216001.044201'
2024-12-02 02:00:44 (10372): Status Report: CPU Time: '214990.421875'
2024-12-02 03:40:49 (10372): Status Report: Job Duration: '864000.000000'
2024-12-02 03:40:49 (10372): Status Report: Elapsed Time: '222001.044201'
2024-12-02 03:40:49 (10372): Status Report: CPU Time: '220996.468750'
2024-12-02 05:20:54 (10372): Status Report: Job Duration: '864000.000000'
2024-12-02 05:20:54 (10372): Status Report: Elapsed Time: '228001.044201'
2024-12-02 05:20:54 (10372): Status Report: CPU Time: '227001.390625'
2024-12-02 07:00:59 (10372): Status Report: Job Duration: '864000.000000'
2024-12-02 07:00:59 (10372): Status Report: Elapsed Time: '234001.044201'
2024-12-02 07:00:59 (10372): Status Report: CPU Time: '233006.593750'
2024-12-02 08:00:04 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-12-02 20:34:56 (10372): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_4716c3fd1deed5c2" 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-12-02 20:35:22 (10372): Guest Log: 66:04:43.247661 timesync vgsvcTimeSyncWorker: Radical host time change: 45 297 380 000 000ns (HostNow=1 733 193 297 176 000 000 ns HostLast=1 733 147 999 796 000 000 ns)
2024-12-02 20:35:22 (10372): Guest Log: 66:04:55.210452 timesync vgsvcTimeSyncWorker: Radical guest time change: 45 304 103 221 000ns (GuestNow=1 733 193 311 184 142 000 ns GuestLast=1 733 148 007 080 921 000 ns fSetTimeLastLoop=true )
2024-12-02 20:35:22 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-12-02 20:35:34 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-12-02 20:35:54 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-12-02 21:16:18 (10372): Status Report: Job Duration: '864000.000000'
2024-12-02 21:16:18 (10372): Status Report: Elapsed Time: '240001.678543'
2024-12-02 21:16:18 (10372): Status Report: CPU Time: '239019.343750'
2024-12-02 22:56:23 (10372): Status Report: Job Duration: '864000.000000'
2024-12-02 22:56:23 (10372): Status Report: Elapsed Time: '246001.678543'
2024-12-02 22:56:23 (10372): Status Report: CPU Time: '245025.281250'
2024-12-03 00:36:28 (10372): Status Report: Job Duration: '864000.000000'
2024-12-03 00:36:28 (10372): Status Report: Elapsed Time: '252001.678543'
2024-12-03 00:36:28 (10372): Status Report: CPU Time: '251031.015625'
2024-12-03 02:16:33 (10372): Status Report: Job Duration: '864000.000000'
2024-12-03 02:16:33 (10372): Status Report: Elapsed Time: '258001.678543'
2024-12-03 02:16:33 (10372): Status Report: CPU Time: '257037.078125'
2024-12-03 03:56:38 (10372): Status Report: Job Duration: '864000.000000'
2024-12-03 03:56:38 (10372): Status Report: Elapsed Time: '264001.678543'
2024-12-03 03:56:38 (10372): Status Report: CPU Time: '263043.234375'
2024-12-03 05:36:42 (10372): Status Report: Job Duration: '864000.000000'
2024-12-03 05:36:42 (10372): Status Report: Elapsed Time: '270001.678543'
2024-12-03 05:36:42 (10372): Status Report: CPU Time: '269048.234375'
2024-12-03 07:16:47 (10372): Status Report: Job Duration: '864000.000000'
2024-12-03 07:16:47 (10372): Status Report: Elapsed Time: '276001.678543'
2024-12-03 07:16:47 (10372): Status Report: CPU Time: '275053.765625'
2024-12-03 08:00:04 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-12-03 20:23:32 (10372): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_4716c3fd1deed5c2" 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-12-03 20:23:33 (10372): Guest Log: 77:29:56.659700 timesync vgsvcTimeSyncWorker: Radical host time change: 44 610 545 000 000ns (HostNow=1 733 279 009 682 000 000 ns HostLast=1 733 234 399 137 000 000 ns)
2024-12-03 20:23:33 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-12-03 20:23:41 (10372): Guest Log: 77:30:06.660254 timesync vgsvcTimeSyncWorker: Radical guest time change: 44 610 856 559 000ns (GuestNow=1 733 279 021 586 232 000 ns GuestLast=1 733 234 410 729 673 000 ns fSetTimeLastLoop=true )
2024-12-03 21:20:21 (10372): Status Report: Job Duration: '864000.000000'
2024-12-03 21:20:21 (10372): Status Report: Elapsed Time: '282001.678543'
2024-12-03 21:20:21 (10372): Status Report: CPU Time: '281025.968750'
2024-12-03 23:00:26 (10372): Status Report: Job Duration: '864000.000000'
2024-12-03 23:00:26 (10372): Status Report: Elapsed Time: '288001.678543'
2024-12-03 23:00:26 (10372): Status Report: CPU Time: '287032.046875'
2024-12-04 00:40:30 (10372): Status Report: Job Duration: '864000.000000'
2024-12-04 00:40:30 (10372): Status Report: Elapsed Time: '294001.678543'
2024-12-04 00:40:30 (10372): Status Report: CPU Time: '293037.937500'
2024-12-04 02:20:35 (10372): Status Report: Job Duration: '864000.000000'
2024-12-04 02:20:35 (10372): Status Report: Elapsed Time: '300001.678543'
2024-12-04 02:20:35 (10372): Status Report: CPU Time: '299044.375000'
2024-12-04 04:00:41 (10372): Status Report: Job Duration: '864000.000000'
2024-12-04 04:00:41 (10372): Status Report: Elapsed Time: '306001.678543'
2024-12-04 04:00:41 (10372): Status Report: CPU Time: '305051.437500'
2024-12-04 05:40:46 (10372): Status Report: Job Duration: '864000.000000'
2024-12-04 05:40:46 (10372): Status Report: Elapsed Time: '312001.678543'
2024-12-04 05:40:46 (10372): Status Report: CPU Time: '311054.812500'
2024-12-04 07:20:50 (10372): Status Report: Job Duration: '864000.000000'
2024-12-04 07:20:50 (10372): Status Report: Elapsed Time: '318001.678543'
2024-12-04 07:20:50 (10372): Status Report: CPU Time: '317059.078125'
2024-12-04 08:00:04 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-12-04 20:03:10 (10372): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_4716c3fd1deed5c2" 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-12-04 20:03:11 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-12-04 20:03:12 (10372): Guest Log: 89:06:39.039486 timesync vgsvcTimeSyncWorker: Radical host time change: 43 387 796 000 000ns (HostNow=1 733 364 187 326 000 000 ns HostLast=1 733 320 799 530 000 000 ns)
2024-12-04 20:03:22 (10372): Guest Log: 89:06:49.048934 timesync vgsvcTimeSyncWorker: Radical guest time change: 43 392 223 414 000ns (GuestNow=1 733 364 201 762 153 000 ns GuestLast=1 733 320 809 538 739 000 ns fSetTimeLastLoop=true )
2024-12-04 21:04:02 (10372): Status Report: Job Duration: '864000.000000'
2024-12-04 21:04:02 (10372): Status Report: Elapsed Time: '324001.678543'
2024-12-04 21:04:02 (10372): Status Report: CPU Time: '323041.187500'
2024-12-04 22:44:07 (10372): Status Report: Job Duration: '864000.000000'
2024-12-04 22:44:07 (10372): Status Report: Elapsed Time: '330001.678543'
2024-12-04 22:44:07 (10372): Status Report: CPU Time: '329047.359375'
2024-12-05 00:24:11 (10372): Status Report: Job Duration: '864000.000000'
2024-12-05 00:24:11 (10372): Status Report: Elapsed Time: '336001.678543'
2024-12-05 00:24:11 (10372): Status Report: CPU Time: '335053.656250'
2024-12-05 02:04:16 (10372): Status Report: Job Duration: '864000.000000'
2024-12-05 02:04:16 (10372): Status Report: Elapsed Time: '342001.678543'
2024-12-05 02:04:16 (10372): Status Report: CPU Time: '341059.593750'
2024-12-05 03:44:21 (10372): Status Report: Job Duration: '864000.000000'
2024-12-05 03:44:21 (10372): Status Report: Elapsed Time: '348001.678543'
2024-12-05 03:44:21 (10372): Status Report: CPU Time: '347065.718750'
2024-12-05 05:24:26 (10372): Status Report: Job Duration: '864000.000000'
2024-12-05 05:24:26 (10372): Status Report: Elapsed Time: '354001.678543'
2024-12-05 05:24:26 (10372): Status Report: CPU Time: '353070.718750'
2024-12-05 07:04:30 (10372): Status Report: Job Duration: '864000.000000'
2024-12-05 07:04:30 (10372): Status Report: Elapsed Time: '360001.678543'
2024-12-05 07:04:30 (10372): Status Report: CPU Time: '359074.625000'
2024-12-05 08:00:04 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-12-05 20:25:21 (10372): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_4716c3fd1deed5c2" 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-12-05 20:25:22 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-12-05 20:25:28 (10372): Guest Log: 101:03:43.698972 timesync vgsvcTimeSyncWorker: Radical host time change: 44 721 616 000 000ns (HostNow=1 733 451 922 033 000 000 ns HostLast=1 733 407 200 417 000 000 ns)
2024-12-05 20:25:38 (10372): Guest Log: 101:03:54.324134 timesync vgsvcTimeSyncWorker: Radical guest time change: 44 728 227 862 000ns (GuestNow=1 733 451 938 649 097 000 ns GuestLast=1 733 407 210 421 235 000 ns fSetTimeLastLoop=true )
2024-12-05 21:09:53 (10372): Status Report: Job Duration: '864000.000000'
2024-12-05 21:09:53 (10372): Status Report: Elapsed Time: '366001.678543'
2024-12-05 21:09:53 (10372): Status Report: CPU Time: '365053.562500'
2024-12-05 22:49:58 (10372): Status Report: Job Duration: '864000.000000'
2024-12-05 22:49:58 (10372): Status Report: Elapsed Time: '372001.678543'
2024-12-05 22:49:58 (10372): Status Report: CPU Time: '371059.578125'
2024-12-06 00:30:03 (10372): Status Report: Job Duration: '864000.000000'
2024-12-06 00:30:03 (10372): Status Report: Elapsed Time: '378001.678543'
2024-12-06 00:30:03 (10372): Status Report: CPU Time: '377065.578125'
2024-12-06 02:10:08 (10372): Status Report: Job Duration: '864000.000000'
2024-12-06 02:10:08 (10372): Status Report: Elapsed Time: '384001.678543'
2024-12-06 02:10:08 (10372): Status Report: CPU Time: '383071.625000'
2024-12-06 03:50:13 (10372): Status Report: Job Duration: '864000.000000'
2024-12-06 03:50:13 (10372): Status Report: Elapsed Time: '390001.678543'
2024-12-06 03:50:13 (10372): Status Report: CPU Time: '389077.421875'
2024-12-06 05:30:18 (10372): Status Report: Job Duration: '864000.000000'
2024-12-06 05:30:18 (10372): Status Report: Elapsed Time: '396001.678543'
2024-12-06 05:30:18 (10372): Status Report: CPU Time: '395080.171875'
2024-12-06 07:10:22 (10372): Status Report: Job Duration: '864000.000000'
2024-12-06 07:10:22 (10372): Status Report: Elapsed Time: '402001.678543'
2024-12-06 07:10:22 (10372): Status Report: CPU Time: '401084.781250'
2024-12-06 08:00:04 (10372): VM state change detected. (old = 'running', new = 'paused')
2024-12-06 23:08:12 (10372): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_4716c3fd1deed5c2" 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-12-06 23:08:13 (10372): Guest Log: 112:38:24.877906 timesync vgsvcTimeSyncWorker: Radical host time change: 54 489 546 000 000ns (HostNow=1 733 548 086 659 000 000 ns HostLast=1 733 493 597 113 000 000 ns)
2024-12-06 23:08:13 (10372): VM state change detected. (old = 'paused', new = 'running')
2024-12-06 23:08:18 (10372): Guest Log: 112:38:34.879053 timesync vgsvcTimeSyncWorker: Radical guest time change: 54 491 608 715 000ns (GuestNow=1 733 548 098 735 846 000 ns GuestLast=1 733 493 607 127 131 000 ns fSetTimeLastLoop=true )
2024-12-06 23:58:37 (10372): Status Report: Job Duration: '864000.000000'
2024-12-06 23:58:37 (10372): Status Report: Elapsed Time: '408001.678543'
2024-12-06 23:58:37 (10372): Status Report: CPU Time: '407091.937500'
2024-12-07 01:38:41 (10372): Status Report: Job Duration: '864000.000000'
2024-12-07 01:38:41 (10372): Status Report: Elapsed Time: '414001.678543'
2024-12-07 01:38:41 (10372): Status Report: CPU Time: '413096.218750'
2024-12-07 03:18:46 (10372): Status Report: Job Duration: '864000.000000'
2024-12-07 03:18:46 (10372): Status Report: Elapsed Time: '420001.678543'
2024-12-07 03:18:46 (10372): Status Report: CPU Time: '419097.296875'
2024-12-07 04:30:27 (10372): Guest Log: job: run exitcode=0
2024-12-07 04:30:27 (10372): Guest Log: job: diskusage=10100
2024-12-07 04:30:27 (10372): Guest Log: job: logsize=120 k
2024-12-07 04:30:27 (10372): Guest Log: job: times=
2024-12-07 04:30:27 (10372): Guest Log: 0m0.016s 0m0.016s
2024-12-07 04:30:27 (10372): Guest Log: 6945m17.270s 43m56.157s
2024-12-07 04:30:27 (10372): Guest Log: job: cpuusage=419353
2024-12-07 04:30:28 (10372): Guest Log: 11:30:28 CET +01:00 2024-12-07: cranky: [INFO] Container 'runc' finished with status code 0.
2024-12-07 04:30:28 (10372): Guest Log: 11:30:28 CET +01:00 2024-12-07: cranky: [INFO] Preparing output.
2024-12-07 04:30:31 (10372): Guest Log: [INFO] Job Finished
2024-12-07 04:30:31 (10372): Guest Log: [INFO] Shutting Down.
2024-12-07 04:30:31 (10372): VM Completion File Detected.
2024-12-07 04:30:31 (10372): VM Completion Message: Job Finished
.
2024-12-07 04:30:31 (10372): Powering off VM.
2024-12-07 04:30:32 (10372): Successfully stopped VM.
2024-12-07 04:30:32 (10372): Deregistering VM. (boinc_4716c3fd1deed5c2, slot#2)
2024-12-07 04:30:32 (10372): Removing network bandwidth throttle group from VM.
2024-12-07 04:30:32 (10372): Removing VM from VirtualBox.
2024-12-07 04:30:37 (10372): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN