Stderr output

<core_client_version>7.16.11</core_client_version>
<![CDATA[
<stderr_txt>
2022-03-29 12:19:14 (19572): Detected: vboxwrapper 26197
2022-03-29 12:19:14 (19572): Detected: BOINC client v7.7
2022-03-29 12:19:14 (19572): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-03-29 12:19:15 (19572): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-03-29 12:19:15 (19572): Successfully copied 'init_data.xml' to the shared directory.
2022-03-29 12:19:15 (19572): Successfully copied 'input' to the shared directory.
2022-03-29 12:19:16 (19572): Create VM. (boinc_a3b1ca1532650277, slot#19)
2022-03-29 12:19:16 (19572): Setting Memory Size for VM. (630MB)
2022-03-29 12:19:17 (19572): Setting CPU Count for VM. (1)
2022-03-29 12:19:17 (19572): Setting Chipset Options for VM.
2022-03-29 12:19:17 (19572): Setting Boot Options for VM.
2022-03-29 12:19:17 (19572): Setting Network Configuration for NAT.
2022-03-29 12:19:18 (19572): Enabling VM Network Access.
2022-03-29 12:19:18 (19572): Disabling USB Support for VM.
2022-03-29 12:19:18 (19572): Disabling COM Port Support for VM.
2022-03-29 12:19:19 (19572): Disabling LPT Port Support for VM.
2022-03-29 12:19:19 (19572): Disabling Audio Support for VM.
2022-03-29 12:19:20 (19572): Disabling Clipboard Support for VM.
2022-03-29 12:19:20 (19572): Disabling Drag and Drop Support for VM.
2022-03-29 12:19:20 (19572): Adding storage controller(s) to VM.
2022-03-29 12:19:21 (19572): Adding virtual disk drive to VM. (vm_image.vdi)
2022-03-29 12:19:21 (19572): Adding VirtualBox Guest Additions to VM.
2022-03-29 12:19:21 (19572): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2022-03-29 12:19:21 (19572): forwarding host port 50755 to guest port 80
2022-03-29 12:19:22 (19572): Enabling remote desktop for VM.
2022-03-29 12:19:22 (19572): Enabling shared directory for VM.
2022-03-29 12:19:22 (19572): Starting VM using VBoxManage interface. (boinc_a3b1ca1532650277, slot#19)
2022-03-29 12:19:28 (19572): Successfully started VM. (PID = '27948')
2022-03-29 12:19:28 (19572): Reporting VM Process ID to BOINC.
2022-03-29 12:19:28 (19572): Guest Log: BIOS: VirtualBox 6.1.26

2022-03-29 12:19:28 (19572): Guest Log: CPUID EDX: 0x178bfbff

2022-03-29 12:19:28 (19572): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2022-03-29 12:19:28 (19572): VM state change detected. (old = 'PoweredOff', new = 'Running')
2022-03-29 12:19:28 (19572): Detected: Web Application Enabled (http://localhost:50755)
2022-03-29 12:19:28 (19572): Detected: Remote Desktop Enabled (localhost:50756)
2022-03-29 12:19:28 (19572): Preference change detected
2022-03-29 12:19:28 (19572): Setting CPU throttle for VM. (100%)
2022-03-29 12:19:28 (19572): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 5 seconds) or (Vbox_job.xml: 600 seconds))
2022-03-29 12:19:30 (19572): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2022-03-29 12:19:30 (19572): Guest Log: BIOS: Booting from Hard Disk...

2022-03-29 12:19:32 (19572): Guest Log: BIOS: KBD: unsupported int 16h function 03

2022-03-29 12:19:32 (19572): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2022-03-29 12:19:57 (19572): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2022-03-29 12:19:57 (19572): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2022-03-29 12:20:01 (19572): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2022-03-29 12:20:01 (19572): Guest Log: 00:00:00.000242 main     Log opened 2022-03-29T11:20:03.200228000Z

2022-03-29 12:20:01 (19572): Guest Log: 00:00:00.000387 main     OS Product: Linux

2022-03-29 12:20:01 (19572): Guest Log: 00:00:00.000436 main     OS Release: 4.14.76-13.cernvm.x86_64

2022-03-29 12:20:01 (19572): Guest Log: 00:00:00.000483 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2022-03-29 12:20:01 (19572): Guest Log: 00:00:00.000528 main     Executable: /usr/sbin/VBoxService

2022-03-29 12:20:01 (19572): Guest Log: 00:00:00.000529 main     Process ID: 3199

2022-03-29 12:20:01 (19572): Guest Log: 00:00:00.000531 main     Package type: LINUX_64BITS_GENERIC

2022-03-29 12:20:01 (19572): Guest Log: 00:00:00.004280 main     5.2.6 r120293 started. Verbose level = 0

2022-03-29 12:20:46 (19572): Guest Log: 12:20:47 BST +01:00 2022-03-29: cranky: [INFO] Detected Theory App

2022-03-29 12:20:46 (19572): Guest Log: 12:20:47 BST +01:00 2022-03-29: cranky: [INFO] Checking CVMFS.

2022-03-29 12:20:47 (19572): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2022-03-29 12:20:49 (19572): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2022-03-29 12:20:49 (19572): Guest Log: 2.5.2.0 4038 0 27208 22528 3 1 285611 4096000 0 65024 0 0 n/a 5 2 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2022-03-29 12:20:50 (19572): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2022-03-29 12:20:51 (19572): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2022-03-29 12:20:51 (19572): Guest Log: 2.5.2.0 4067 0 25512 17845 3 2 285611 4096000 0 65024 0 2 -100 7751 806 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2022-03-29 12:20:53 (19572): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2022-03-29 12:20:54 (19572): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2022-03-29 12:20:54 (19572): Guest Log: 2.5.2.0 4114 0 25992 268 3 1 285611 4096000 0 65024 0 0 n/a 4 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2022-03-29 12:20:56 (19572): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2022-03-29 12:20:57 (19572): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2022-03-29 12:20:57 (19572): Guest Log: 2.5.2.0 4090 0 32520 11968 3 1 285611 4096000 0 65024 0 0 n/a 13072 2101 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2022-03-29 12:20:57 (19572): Guest Log: 12:20:59 BST +01:00 2022-03-29: cranky: [INFO] Checking runc.

2022-03-29 12:20:57 (19572): Guest Log: 12:20:59 BST +01:00 2022-03-29: cranky: [INFO] Creating the filesystem.

2022-03-29 12:20:57 (19572): Guest Log: 12:20:59 BST +01:00 2022-03-29: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2022-03-29 12:20:58 (19572): Guest Log: 12:20:59 BST +01:00 2022-03-29: cranky: [INFO] Updating config.json.

2022-03-29 12:20:58 (19572): Guest Log: 12:20:59 BST +01:00 2022-03-29: cranky: [INFO] Running Container 'runc'.

2022-03-29 12:21:11 (19572): Guest Log: job: htmld=/shared/html/job

2022-03-29 12:21:11 (19572): Guest Log: job: unpack exitcode=0

2022-03-29 12:21:12 (19572): Guest Log: 12:21:13 BST +01:00 2022-03-29: cranky: [INFO] ===> [runRivet] Tue Mar 29 11:21:12 UTC 2022 [boinc ppbar jets 1960 90 - pythia8 8.243 tune-AU2loxx 100000 242]

2022-03-29 13:19:17 (19572): VM state change detected. (old = 'Running', new = 'Paused')
2022-03-29 13:31:39 (19572): VM state change detected. (old = 'Paused', new = 'Running')
2022-03-29 14:11:56 (19572): Status Report: Job Duration: '864000.000000'
2022-03-29 14:11:56 (19572): Status Report: Elapsed Time: '6000.000000'
2022-03-29 14:11:56 (19572): Status Report: CPU Time: '5513.046875'
2022-03-29 15:52:00 (19572): Status Report: Job Duration: '864000.000000'
2022-03-29 15:52:00 (19572): Status Report: Elapsed Time: '12000.000000'
2022-03-29 15:52:00 (19572): Status Report: CPU Time: '11517.687500'
2022-03-29 17:02:34 (19572): VM state change detected. (old = 'Running', new = 'Paused')
2022-03-29 22:07:00 (19572): Stopping VM.
2022-03-29 22:07:01 (19572): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_a3b1ca1532650277" savestate
Output:

2022-03-29 22:07:01 (19572): VM did not stop when requested.
2022-03-29 22:07:01 (19572): VM was successfully terminated.
2022-03-30 06:01:06 (10196): Detected: vboxwrapper 26197
2022-03-30 06:01:06 (10196): Detected: BOINC client v7.7
2022-03-30 06:01:07 (10196): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-03-30 06:01:08 (10196): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-03-30 06:01:08 (10196): Starting VM using VBoxManage interface. (boinc_a3b1ca1532650277, slot#19)
2022-03-30 06:01:16 (10196): Successfully started VM. (PID = '22236')
2022-03-30 06:01:16 (10196): Reporting VM Process ID to BOINC.
2022-03-30 06:01:16 (10196): Guest Log: BIOS: VirtualBox 6.1.26

2022-03-30 06:01:16 (10196): Guest Log: CPUID EDX: 0x178bfbff

2022-03-30 06:01:16 (10196): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2022-03-30 06:01:16 (10196): VM state change detected. (old = 'PoweredOff', new = 'Running')
2022-03-30 06:01:16 (10196): Detected: Web Application Enabled (http://localhost:50755)
2022-03-30 06:01:16 (10196): Detected: Remote Desktop Enabled (localhost:50756)
2022-03-30 06:01:16 (10196): Status Report: Job Duration: '864000.000000'
2022-03-30 06:01:16 (10196): Status Report: Elapsed Time: '16229.000000'
2022-03-30 06:01:16 (10196): Status Report: CPU Time: '15751.203125'
2022-03-30 06:01:16 (10196): Preference change detected
2022-03-30 06:01:16 (10196): Setting CPU throttle for VM. (100%)
2022-03-30 06:01:16 (10196): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 5 seconds) or (Vbox_job.xml: 600 seconds))
2022-03-30 06:01:18 (10196): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2022-03-30 06:01:18 (10196): Guest Log: BIOS: Booting from Hard Disk...

2022-03-30 06:01:21 (10196): Guest Log: BIOS: KBD: unsupported int 16h function 03

2022-03-30 06:01:21 (10196): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2022-03-30 06:01:52 (10196): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2022-03-30 06:01:52 (10196): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2022-03-30 06:01:59 (10196): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2022-03-30 06:01:59 (10196): Guest Log: 00:00:00.000435 main     Log opened 2022-03-30T05:02:00.589554000Z

2022-03-30 06:01:59 (10196): Guest Log: 00:00:00.001692 main     OS Product: Linux

2022-03-30 06:01:59 (10196): Guest Log: 00:00:00.001753 main     OS Release: 4.14.76-13.cernvm.x86_64

2022-03-30 06:01:59 (10196): Guest Log: 00:00:00.001802 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2022-03-30 06:01:59 (10196): Guest Log: 00:00:00.001847 main     Executable: /usr/sbin/VBoxService

2022-03-30 06:01:59 (10196): Guest Log: 00:00:00.001848 main     Process ID: 3200

2022-03-30 06:01:59 (10196): Guest Log: 00:00:00.001850 main     Package type: LINUX_64BITS_GENERIC

2022-03-30 06:01:59 (10196): Guest Log: 00:00:00.004404 main     5.2.6 r120293 started. Verbose level = 0

2022-03-30 06:03:07 (10196): Guest Log: 06:03:07 BST +01:00 2022-03-30: cranky: [INFO] Detected Theory App

2022-03-30 06:03:07 (10196): Guest Log: 06:03:07 BST +01:00 2022-03-30: cranky: [INFO] Checking CVMFS.

2022-03-30 06:03:08 (10196): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2022-03-30 06:03:09 (10196): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2022-03-30 06:03:09 (10196): Guest Log: 2.5.2.0 4067 0 26312 22530 3 1 1547421 4096001 0 65024 0 0 n/a 5 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2022-03-30 06:03:10 (10196): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2022-03-30 06:03:11 (10196): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2022-03-30 06:03:11 (10196): Guest Log: 2.5.2.0 4135 0 25460 17851 3 2 1547421 4096001 0 65024 0 2 0 5617 161 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2022-03-30 06:03:12 (10196): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2022-03-30 06:03:13 (10196): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2022-03-30 06:03:13 (10196): Guest Log: 2.5.2.0 4044 0 27216 268 3 1 1547421 4096001 0 65024 0 0 n/a 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2022-03-30 06:03:15 (10196): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2022-03-30 06:03:16 (10196): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2022-03-30 06:03:16 (10196): Guest Log: 2.5.2.0 4109 1 35556 11976 3 1 1547421 4096001 0 65024 0 0 n/a 13071 383 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2022-03-30 06:03:16 (10196): Guest Log: 06:03:17 BST +01:00 2022-03-30: cranky: [INFO] Checking runc.

2022-03-30 06:03:16 (10196): Guest Log: 06:03:17 BST +01:00 2022-03-30: cranky: [INFO] Creating the filesystem.

2022-03-30 06:03:16 (10196): Guest Log: 06:03:17 BST +01:00 2022-03-30: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2022-03-30 06:03:16 (10196): Guest Log: 06:03:17 BST +01:00 2022-03-30: cranky: [INFO] Updating config.json.

2022-03-30 06:03:17 (10196): Guest Log: 06:03:17 BST +01:00 2022-03-30: cranky: [INFO] Running Container 'runc'.

2022-03-30 06:03:17 (10196): Guest Log: job: htmld=/shared/html/job

2022-03-30 06:03:17 (10196): Guest Log: job: unpack exitcode=0

2022-03-30 06:03:19 (10196): Guest Log: 06:03:20 BST +01:00 2022-03-30: cranky: [INFO] ===> [runRivet] Wed Mar 30 05:03:18 UTC 2022 [boinc ppbar jets 1960 90 - pythia8 8.243 tune-AU2loxx 100000 242]

2022-03-30 06:46:44 (10196): VM state change detected. (old = 'Running', new = 'Paused')
2022-03-30 11:33:46 (10196): VM state change detected. (old = 'Paused', new = 'Running')
2022-03-30 11:33:54 (10196): Guest Log: 00:44:51.355772 timesync vgsvcTimeSyncWorker: Radical host time change: 17 232 294 000 000ns (HostNow=1 648 636 434 135 000 000 ns HostLast=1 648 619 201 841 000 000 ns)

2022-03-30 11:34:04 (10196): Guest Log: 00:45:01.382202 timesync vgsvcTimeSyncWorker: Radical guest time change: 17 232 343 068 000ns (GuestNow=1 648 636 444 196 468 000 ns GuestLast=1 648 619 211 853 400 000 ns fSetTimeLastLoop=true )

2022-03-30 12:18:55 (10196): VM state change detected. (old = 'Running', new = 'Paused')
2022-03-30 17:12:26 (10196): Stopping VM.
2022-03-30 20:02:38 (36100): Detected: vboxwrapper 26197
2022-03-30 20:02:38 (36100): Detected: BOINC client v7.7
2022-03-30 20:02:39 (36100): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-03-30 20:02:39 (36100): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-03-30 20:02:40 (36100): Starting VM using VBoxManage interface. (boinc_a3b1ca1532650277, slot#19)
2022-03-30 20:02:55 (36100): Successfully started VM. (PID = '37844')
2022-03-30 20:02:55 (36100): Reporting VM Process ID to BOINC.
2022-03-30 20:02:55 (36100): Guest Log: 01:29:52.565700 timesync vgsvcTimeSyncWorker: Radical host time change: 27 849 489 000 000ns (HostNow=1 648 666 974 916 000 000 ns HostLast=1 648 639 125 427 000 000 ns)

2022-03-30 20:02:55 (36100): VM state change detected. (old = 'PoweredOff', new = 'Running')
2022-03-30 20:02:55 (36100): Detected: Web Application Enabled (http://localhost:50755)
2022-03-30 20:02:55 (36100): Detected: Remote Desktop Enabled (localhost:50756)
2022-03-30 20:02:55 (36100): Status Report: Job Duration: '864000.000000'
2022-03-30 20:02:55 (36100): Status Report: Elapsed Time: '21659.000000'
2022-03-30 20:02:55 (36100): Status Report: CPU Time: '21126.437500'
2022-03-30 20:02:55 (36100): Preference change detected
2022-03-30 20:02:55 (36100): Setting CPU throttle for VM. (100%)
2022-03-30 20:02:55 (36100): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 5 seconds) or (Vbox_job.xml: 600 seconds))
2022-03-30 20:03:05 (36100): Guest Log: 01:30:02.566522 timesync vgsvcTimeSyncWorker: Radical guest time change: 27 849 537 044 000ns (GuestNow=1 648 666 984 916 897 000 ns GuestLast=1 648 639 135 379 853 000 ns fSetTimeLastLoop=true )

2022-03-30 20:19:48 (36100): VM state change detected. (old = 'Running', new = 'Paused')
2022-03-30 23:24:47 (36100): Preference change detected
2022-03-30 23:24:47 (36100): Setting CPU throttle for VM. (100%)
2022-03-30 23:24:48 (36100): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 5 seconds) or (Vbox_job.xml: 600 seconds))
2022-03-30 23:24:48 (36100): VM state change detected. (old = 'Paused', new = 'Running')
2022-03-30 23:24:56 (36100): Guest Log: 01:46:52.957084 timesync vgsvcTimeSyncWorker: Radical host time change: 11 110 139 000 000ns (HostNow=1 648 679 095 441 000 000 ns HostLast=1 648 667 985 302 000 000 ns)

2022-03-30 23:25:06 (36100): Guest Log: 01:47:02.959068 timesync vgsvcTimeSyncWorker: Radical guest time change: 11 110 135 580 000ns (GuestNow=1 648 679 105 443 001 000 ns GuestLast=1 648 667 995 307 421 000 ns fSetTimeLastLoop=true )

2022-03-31 00:36:27 (36100): VM state change detected. (old = 'Running', new = 'Paused')
2022-03-31 09:41:52 (36100): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_a3b1ca1532650277" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2022-03-31 09:41:53 (36100): Guest Log: 02:58:41.734522 timesync vgsvcTimeSyncWorker: Radical host time change: 32 735 164 000 000ns (HostNow=1 648 716 112 072 000 000 ns HostLast=1 648 683 376 908 000 000 ns)

2022-03-31 09:41:53 (36100): VM state change detected. (old = 'Paused', new = 'Running')
2022-03-31 09:42:02 (36100): Guest Log: 02:58:51.735422 timesync vgsvcTimeSyncWorker: Radical guest time change: 32 717 854 498 000ns (GuestNow=1 648 716 122 072 919 000 ns GuestLast=1 648 683 404 218 421 000 ns fSetTimeLastLoop=true )

2022-03-31 09:53:26 (36100): Status Report: Job Duration: '864000.000000'
2022-03-31 09:53:26 (36100): Status Report: Elapsed Time: '27659.000000'
2022-03-31 09:53:26 (36100): Status Report: CPU Time: '27151.953125'
2022-03-31 10:07:25 (36100): VM state change detected. (old = 'Running', new = 'Paused')
2022-03-31 10:48:13 (36100): VM state change detected. (old = 'Paused', new = 'Running')
2022-03-31 10:48:21 (36100): Guest Log: 03:24:22.271918 timesync vgsvcTimeSyncWorker: Radical host time change: 2 458 427 000 000ns (HostNow=1 648 720 101 031 000 000 ns HostLast=1 648 717 642 604 000 000 ns)

2022-03-31 10:48:31 (36100): Guest Log: 03:24:32.276898 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 458 426 621 000ns (GuestNow=1 648 720 111 035 999 000 ns GuestLast=1 648 717 652 609 378 000 ns fSetTimeLastLoop=true )

2022-03-31 10:56:58 (36100): Preference change detected
2022-03-31 10:56:58 (36100): Setting CPU throttle for VM. (100%)
2022-03-31 10:56:58 (36100): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 5 seconds) or (Vbox_job.xml: 600 seconds))
2022-03-31 10:57:48 (36100): Preference change detected
2022-03-31 10:57:48 (36100): Setting CPU throttle for VM. (100%)
2022-03-31 10:57:48 (36100): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 5 seconds) or (Vbox_job.xml: 600 seconds))
2022-03-31 11:25:28 (36100): Preference change detected
2022-03-31 11:25:28 (36100): Setting CPU throttle for VM. (100%)
2022-03-31 11:25:28 (36100): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 5 seconds) or (Vbox_job.xml: 600 seconds))
2022-03-31 11:43:29 (36100): Preference change detected
2022-03-31 11:43:29 (36100): Setting CPU throttle for VM. (100%)
2022-03-31 11:43:29 (36100): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 5 seconds) or (Vbox_job.xml: 600 seconds))
2022-03-31 12:14:16 (36100): Preference change detected
2022-03-31 12:14:16 (36100): Setting CPU throttle for VM. (100%)
2022-03-31 12:14:16 (36100): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 5 seconds) or (Vbox_job.xml: 600 seconds))
2022-03-31 12:14:21 (36100): Status Report: Job Duration: '864000.000000'
2022-03-31 12:14:21 (36100): Status Report: Elapsed Time: '33659.000000'
2022-03-31 12:14:21 (36100): Status Report: CPU Time: '33146.265625'
2022-03-31 12:16:25 (36100): VM state change detected. (old = 'Running', new = 'Paused')
2022-03-31 12:17:13 (36100): Stopping VM.
2022-03-31 13:58:45 (18012): Detected: vboxwrapper 26197
2022-03-31 13:58:45 (18012): Detected: BOINC client v7.7
2022-03-31 13:58:46 (18012): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-03-31 13:58:47 (18012): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-03-31 13:58:47 (18012): Starting VM using VBoxManage interface. (boinc_a3b1ca1532650277, slot#19)
2022-03-31 13:59:05 (18012): Successfully started VM. (PID = '6184')
2022-03-31 13:59:05 (18012): Reporting VM Process ID to BOINC.
2022-03-31 13:59:05 (18012): VM state change detected. (old = 'PoweredOff', new = 'Running')
2022-03-31 13:59:05 (18012): Detected: Web Application Enabled (http://localhost:50755)
2022-03-31 13:59:05 (18012): Detected: Remote Desktop Enabled (localhost:50756)
2022-03-31 13:59:05 (18012): Status Report: Job Duration: '864000.000000'
2022-03-31 13:59:05 (18012): Status Report: Elapsed Time: '33781.000000'
2022-03-31 13:59:05 (18012): Status Report: CPU Time: '33266.390625'
2022-03-31 13:59:05 (18012): Preference change detected
2022-03-31 13:59:05 (18012): Setting CPU throttle for VM. (100%)
2022-03-31 13:59:05 (18012): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 5 seconds) or (Vbox_job.xml: 600 seconds))
2022-03-31 13:59:13 (18012): Guest Log: 04:52:34.347679 timesync vgsvcTimeSyncWorker: Radical host time change: 6 169 843 000 000ns (HostNow=1 648 731 552 948 000 000 ns HostLast=1 648 725 383 105 000 000 ns)

2022-03-31 13:59:13 (18012): Preference change detected
2022-03-31 13:59:13 (18012): Setting CPU throttle for VM. (100%)
2022-03-31 13:59:13 (18012): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 5 seconds) or (Vbox_job.xml: 600 seconds))
2022-03-31 13:59:23 (18012): Guest Log: 04:52:44.356436 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 169 850 112 000ns (GuestNow=1 648 731 562 956 814 000 ns GuestLast=1 648 725 393 106 702 000 ns fSetTimeLastLoop=true )

2022-03-31 14:16:18 (18012): VM state change detected. (old = 'Running', new = 'Paused')
2022-03-31 15:13:48 (18012): VM state change detected. (old = 'Paused', new = 'Running')
2022-03-31 15:13:54 (18012): Guest Log: 05:09:44.730878 timesync vgsvcTimeSyncWorker: Radical host time change: 3 460 585 000 000ns (HostNow=1 648 736 033 916 000 000 ns HostLast=1 648 732 573 331 000 000 ns)

2022-03-31 15:14:04 (18012): Guest Log: 05:09:54.733812 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 460 587 756 000ns (GuestNow=1 648 736 043 918 965 000 ns GuestLast=1 648 732 583 331 209 000 ns fSetTimeLastLoop=true )

2022-03-31 15:59:27 (18012): VM state change detected. (old = 'Running', new = 'Paused')
2022-03-31 17:07:08 (18012): VM state change detected. (old = 'Paused', new = 'Running')
2022-03-31 17:07:15 (18012): Guest Log: 05:55:25.708156 timesync vgsvcTimeSyncWorker: Radical host time change: 4 070 173 000 000ns (HostNow=1 648 742 835 065 000 000 ns HostLast=1 648 738 764 892 000 000 ns)

2022-03-31 17:07:25 (18012): Guest Log: 05:55:35.710029 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 070 173 644 000ns (GuestNow=1 648 742 845 066 901 000 ns GuestLast=1 648 738 774 893 257 000 ns fSetTimeLastLoop=true )

2022-03-31 17:44:22 (18012): Status Report: Job Duration: '864000.000000'
2022-03-31 17:44:22 (18012): Status Report: Elapsed Time: '39781.000000'
2022-03-31 17:44:22 (18012): Status Report: CPU Time: '39282.734375'
2022-03-31 17:54:02 (18012): VM state change detected. (old = 'Running', new = 'Paused')
2022-03-31 20:16:33 (18012): VM state change detected. (old = 'Paused', new = 'Running')
2022-03-31 20:16:37 (18012): Guest Log: 06:42:16.812511 timesync vgsvcTimeSyncWorker: Radical host time change: 8 561 335 000 000ns (HostNow=1 648 754 197 494 000 000 ns HostLast=1 648 745 636 159 000 000 ns)

2022-03-31 20:16:47 (18012): Guest Log: 06:42:26.813112 timesync vgsvcTimeSyncWorker: Radical guest time change: 8 561 325 356 000ns (GuestNow=1 648 754 207 494 659 000 ns GuestLast=1 648 745 646 169 303 000 ns fSetTimeLastLoop=true )

2022-03-31 21:47:00 (18012): Status Report: Job Duration: '864000.000000'
2022-03-31 21:47:00 (18012): Status Report: Elapsed Time: '45781.000000'
2022-03-31 21:47:00 (18012): Status Report: CPU Time: '45282.937500'
2022-03-31 21:58:35 (18012): VM state change detected. (old = 'Running', new = 'Paused')
2022-03-31 22:14:33 (18012): VM state change detected. (old = 'Paused', new = 'Running')
2022-03-31 22:42:41 (18012): VM state change detected. (old = 'Running', new = 'Paused')
2022-03-31 22:42:44 (18012): Stopping VM.
2022-04-02 09:37:44 (17696): Detected: vboxwrapper 26197
2022-04-02 09:37:44 (17696): Detected: BOINC client v7.7
2022-04-02 09:37:46 (17696): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-04-02 09:37:47 (17696): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-04-02 09:37:47 (17696): Starting VM using VBoxManage interface. (boinc_a3b1ca1532650277, slot#19)
2022-04-02 09:38:06 (17696): Successfully started VM. (PID = '12664')
2022-04-02 09:38:06 (17696): Reporting VM Process ID to BOINC.
2022-04-02 09:38:06 (17696): VM state change detected. (old = 'PoweredOff', new = 'Running')
2022-04-02 09:38:06 (17696): Detected: Web Application Enabled (http://localhost:50755)
2022-04-02 09:38:06 (17696): Detected: Remote Desktop Enabled (localhost:50756)
2022-04-02 09:38:06 (17696): Status Report: Job Duration: '864000.000000'
2022-04-02 09:38:06 (17696): Status Report: Elapsed Time: '48158.000000'
2022-04-02 09:38:06 (17696): Status Report: CPU Time: '47663.718750'
2022-04-02 09:38:06 (17696): Preference change detected
2022-04-02 09:38:06 (17696): Setting CPU throttle for VM. (100%)
2022-04-02 09:38:06 (17696): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 5 seconds) or (Vbox_job.xml: 600 seconds))
2022-04-02 09:38:13 (17696): Guest Log: 08:52:29.809422 timesync vgsvcTimeSyncWorker: Radical host time change: 125 735 278 000 000ns (HostNow=1 648 888 693 428 000 000 ns HostLast=1 648 762 958 150 000 000 ns)

2022-04-02 09:38:23 (17696): Guest Log: 08:52:39.815419 timesync vgsvcTimeSyncWorker: Radical guest time change: 126 692 943 055 000ns (GuestNow=1 648 888 703 434 014 000 ns GuestLast=1 648 762 010 490 959 000 ns fSetTimeLastLoop=true )

2022-04-02 09:55:58 (17696): Preference change detected
2022-04-02 09:55:58 (17696): Setting CPU throttle for VM. (100%)
2022-04-02 09:55:58 (17696): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 5 seconds) or (Vbox_job.xml: 600 seconds))
2022-04-02 10:38:13 (17696): Guest Log: job: run exitcode=0

2022-04-02 10:38:13 (17696): Guest Log: job: diskusage=2676

2022-04-02 10:38:13 (17696): Guest Log: job: logsize=76 k

2022-04-02 10:38:13 (17696): Guest Log: job: times=

2022-04-02 10:38:13 (17696): Guest Log: 0m0.011s 0m0.026s

2022-04-02 10:38:13 (17696): Guest Log: 580m49.453s 3m28.231s

2022-04-02 10:38:13 (17696): Guest Log: job: cpuusage=35058

2022-04-02 10:38:13 (17696): Guest Log: 10:38:13 BST +01:00 2022-04-02: cranky: [INFO] Container 'runc' finished with status code 0.

2022-04-02 10:38:13 (17696): Guest Log: 10:38:13 BST +01:00 2022-04-02: cranky: [INFO] Preparing output.

2022-04-02 10:38:14 (17696): Guest Log: [INFO] Job Finished

2022-04-02 10:38:14 (17696): Guest Log: [INFO] Shutting Down.

2022-04-02 10:38:14 (17696): VM Completion File Detected.
2022-04-02 10:38:14 (17696): VM Completion Message: Job Finished
.
2022-04-02 10:38:14 (17696): Powering off VM.
2022-04-02 10:43:15 (17696): VM did not power off when requested.
2022-04-02 10:43:15 (17696): VM was successfully terminated.
2022-04-02 10:43:15 (17696): Deregistering VM. (boinc_a3b1ca1532650277, slot#19)
2022-04-02 10:43:15 (17696): Removing network bandwidth throttle group from VM.
2022-04-02 10:43:15 (17696): Removing VM from VirtualBox.
10:43:20 (17696): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN