Task 414640685

Name	Theory_2743-2826798-469_0
Workunit	225744343
Created	30 Sep 2024, 15:22:30 UTC
Sent	30 Sep 2024, 21:08:32 UTC
Report deadline	11 Oct 2024, 21:08:32 UTC
Received	1 Oct 2024, 17:04:29 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	10838559
Run time	2 hours 11 min 35 sec
CPU time	1 hours 17 min 39 sec
Validate state	Valid
Credit	43.44
Device peak FLOPS	2.38 GFLOPS
Application version	Theory Simulation v300.30 (vbox64_theory) windows_x86_64
Peak working set size	80.28 MB
Peak swap size	79.52 MB
Peak disk usage	1.94 GB
Stderr output

<core_client_version>8.0.2</core_client_version>
<![CDATA[
<stderr_txt>
Guest Log: 23:37:57 +00 +00:00 2024-09-30: cranky: [INFO] Updating config.json.
2024-10-01 00:39:15 (6672): Guest Log: 23:37:57 +00 +00:00 2024-09-30: cranky: [INFO] Running Container 'runc'.
2024-10-01 00:39:20 (6672): Guest Log: job: htmld=/shared/html/job
2024-10-01 00:39:20 (6672): Guest Log: job: unpack exitcode=0
2024-10-01 00:39:21 (6672): Guest Log: INFO: activated the work-around for ld:
2024-10-01 00:39:21 (6672): Guest Log: lrwxrwxrwx 1 0 0 15 Sep 30 23:38 /tmp/tmp.3QspUpmxBK/ld -> /usr/bin/ld.bfd
2024-10-01 00:39:21 (6672): Guest Log: 23:38:03 +00 +00:00 2024-09-30: cranky: [INFO] ===> [runRivet] Mon Sep 30 23:38:03 UTC 2024 [boinc pp ue 200 4 - pythia8 8.308 tune-AU2lox 71000 469]
2024-10-01 00:42:48 (6672): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 00:43:01 (6672): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 00:47:41 (6672): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 00:47:52 (6672): Stopping VM.
2024-10-01 00:48:39 (6672): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_eaf939d778381088" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...
2024-10-01 00:48:39 (6672): VM did not stop when requested.
2024-10-01 00:48:39 (6672): VM was NOT successfully terminated.
2024-10-01 00:51:46 (7832): vboxwrapper version 26207
2024-10-01 00:51:46 (7832): BOINC client version: 8.0.2
2024-10-01 00:51:47 (7832): Detected: VirtualBox VboxManage Interface (Version: 7.0.10)
2024-10-01 00:51:48 (7832): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-10-01 00:51:49 (7832): Guest Log: 23:37:29 +00 +00:00 2024-09-30: cranky: [INFO] Detected Theory App
2024-10-01 00:51:49 (7832): Guest Log: 23:37:29 +00 +00:00 2024-09-30: cranky: [INFO] Checking CVMFS.
2024-10-01 00:51:49 (7832): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-10-01 00:51:49 (7832): 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-10-01 00:51:49 (7832): Guest Log: 2.5.2.0 4144 0 27172 30597 3 1 1279737 4096000 0 65024 0 0 n/a 5 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-10-01 00:51:49 (7832): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-10-01 00:51:49 (7832): 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-10-01 00:51:49 (7832): Guest Log: 2.5.2.0 4169 0 27588 25363 3 2 1279737 4096000 0 65024 0 2 0 7926 1188 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-10-01 00:51:49 (7832): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-10-01 00:51:49 (7832): 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-10-01 00:51:49 (7832): Guest Log: 2.5.2.0 4121 1 25184 272 3 1 1279737 4096000 0 65024 0 0 n/a 0 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-10-01 00:51:49 (7832): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-10-01 00:51:49 (7832): 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-10-01 00:51:49 (7832): Guest Log: 2.5.2.0 4192 1 39800 19921 3 1 1279737 4096000 0 65024 0 0 n/a 6125 1181 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-10-01 00:51:49 (7832): Guest Log: 23:37:55 +00 +00:00 2024-09-30: cranky: [INFO] Checking runc.
2024-10-01 00:51:49 (7832): Guest Log: 23:37:56 +00 +00:00 2024-09-30: cranky: [INFO] Creating the filesystem.
2024-10-01 00:51:49 (7832): Guest Log: 23:37:56 +00 +00:00 2024-09-30: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-10-01 00:51:49 (7832): Guest Log: 23:37:57 +00 +00:00 2024-09-30: cranky: [INFO] Updating config.json.
2024-10-01 00:51:49 (7832): Guest Log: 23:37:57 +00 +00:00 2024-09-30: cranky: [INFO] Running Container 'runc'.
2024-10-01 00:51:49 (7832): Guest Log: job: htmld=/shared/html/job
2024-10-01 00:51:49 (7832): Guest Log: job: unpack exitcode=0
2024-10-01 00:51:49 (7832): Guest Log: INFO: activated the work-around for ld:
2024-10-01 00:51:49 (7832): Guest Log: lrwxrwxrwx 1 0 0 15 Sep 30 23:38 /tmp/tmp.3QspUpmxBK/ld -> /usr/bin/ld.bfd
2024-10-01 00:51:49 (7832): Guest Log: 23:38:03 +00 +00:00 2024-09-30: cranky: [INFO] ===> [runRivet] Mon Sep 30 23:38:03 UTC 2024 [boinc pp ue 200 4 - pythia8 8.308 tune-AU2lox 71000 469]
2024-10-01 00:51:49 (7832): Starting VM using VBoxManage interface. (boinc_eaf939d778381088, slot#1)
2024-10-01 00:52:20 (7832): Successfully started VM. (PID = '6732')
2024-10-01 00:52:20 (7832): Reporting VM Process ID to BOINC.
2024-10-01 00:52:20 (7832): Guest Log: BIOS: VirtualBox 7.0.10
2024-10-01 00:52:20 (7832): Guest Log: CPUID EDX: 0x178bfbff
2024-10-01 00:52:20 (7832): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-10-01 00:52:20 (7832): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-10-01 00:52:20 (7832): VM state change detected. (old = 'poweredoff', new = 'running')
2024-10-01 00:52:20 (7832): Detected: Web Application Enabled (http://localhost:49262)
2024-10-01 00:52:21 (7832): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 00:52:41 (7832): Preference change detected
2024-10-01 00:52:41 (7832): Setting CPU throttle for VM. (100%)
2024-10-01 00:52:41 (7832): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-10-01 00:52:41 (7832): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 00:52:41 (7832): Stopping VM.
2024-10-01 00:52:43 (7832): Error in stop VM for VM: -2147024891
Command:
VBoxManage -q controlvm "boinc_eaf939d778381088" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...E_ACCESSDENIED
VBoxManage.exe: error: Failed to save machine state.
VBoxManage.exe: error: Assertion failed: [SUCCEEDED(hrc)] at 'D:\tinderbox\win-7.0\src\VBox\Main\src-server\MachineImpl.cpp' (10978) in long __cdecl Machine::i_saveStorageControllers(struct settings::Storage &).
VBoxManage.exe: error: COM RC=E_ACCESSDENIED (0x80070005).
VBoxManage.exe: error: Please contact the product vendor!
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component SessionMachine, interface IMachine
VBoxManage.exe: error: Context: "enum RTEXITCODE __cdecl handleControlVM(struct HandlerArg *)" at line 650 of file VBoxManageControlVM.cpp

2024-10-01 00:52:43 (7832): VM did not stop when requested.
2024-10-01 00:52:43 (7832): VM was NOT successfully terminated.
2024-10-01 01:01:08 (3792): vboxwrapper version 26207
2024-10-01 01:01:08 (3792): BOINC client version: 8.0.2
2024-10-01 01:01:09 (3792): Detected: VirtualBox VboxManage Interface (Version: 7.0.10)
2024-10-01 01:01:10 (3792): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-10-01 01:01:11 (3792): Guest Log: BIOS: VirtualBox 7.0.10
2024-10-01 01:01:11 (3792): Guest Log: CPUID EDX: 0x178bfbff
2024-10-01 01:01:11 (3792): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-10-01 01:01:11 (3792): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-10-01 01:01:11 (3792): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-10-01 01:01:11 (3792): Guest Log: BIOS: Booting from Hard Disk...
2024-10-01 01:01:11 (3792): Starting VM using VBoxManage interface. (boinc_eaf939d778381088, slot#1)
2024-10-01 01:01:34 (3792): Successfully started VM. (PID = '1660')
2024-10-01 01:01:34 (3792): Reporting VM Process ID to BOINC.
2024-10-01 01:01:34 (3792): VM state change detected. (old = 'poweredoff', new = 'running')
2024-10-01 01:01:34 (3792): Detected: Web Application Enabled (http://localhost:49262)
2024-10-01 01:01:35 (3792): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 01:02:00 (3792): Preference change detected
2024-10-01 01:02:00 (3792): Setting CPU throttle for VM. (100%)
2024-10-01 01:02:01 (3792): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-10-01 01:02:01 (3792): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 01:02:01 (3792): Stopping VM.
2024-10-01 01:02:03 (3792): Successfully stopped VM.
2024-10-01 01:16:47 (10012): vboxwrapper version 26207
2024-10-01 01:16:47 (10012): BOINC client version: 8.0.2
2024-10-01 01:16:48 (10012): Detected: VirtualBox VboxManage Interface (Version: 7.0.10)
2024-10-01 01:16:49 (10012): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-10-01 01:16:50 (10012): Starting VM using VBoxManage interface. (boinc_eaf939d778381088, slot#1)
2024-10-01 01:17:23 (10012): Successfully started VM. (PID = '8492')
2024-10-01 01:17:23 (10012): Reporting VM Process ID to BOINC.
2024-10-01 01:17:23 (10012): Guest Log: BIOS: int13_harddisk_ext: function 42, error 04 !
2024-10-01 01:17:23 (10012): VM state change detected. (old = 'poweredoff', new = 'running')
2024-10-01 01:17:23 (10012): Detected: Web Application Enabled (http://localhost:49262)
2024-10-01 01:17:24 (10012): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 01:18:18 (10012): Stopping VM.
2024-10-01 01:18:24 (10012): Successfully stopped VM.
2024-10-01 01:22:05 (10272): vboxwrapper version 26207
2024-10-01 01:22:05 (10272): BOINC client version: 8.0.2
2024-10-01 01:22:10 (10272): Detected: VirtualBox VboxManage Interface (Version: 7.0.10)
2024-10-01 01:22:12 (10272): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-10-01 01:22:23 (10272): Guest Log: BIOS: int13_harddisk_ext: function 42, error 04 !
2024-10-01 01:22:23 (10272): Starting VM using VBoxManage interface. (boinc_eaf939d778381088, slot#1)
2024-10-01 01:23:13 (10272): Successfully started VM. (PID = '8116')
2024-10-01 01:23:13 (10272): Reporting VM Process ID to BOINC.
2024-10-01 01:23:13 (10272): Guest Log: BIOS: int13_harddisk_ext: function 42, error 04 !
2024-10-01 01:23:13 (10272): VM state change detected. (old = 'poweredoff', new = 'running')
2024-10-01 01:23:13 (10272): Detected: Web Application Enabled (http://localhost:49262)
2024-10-01 01:23:13 (10272): Stopping VM.
2024-10-01 01:23:17 (10272): Successfully stopped VM.
2024-10-01 01:34:13 (6512): vboxwrapper version 26207
2024-10-01 01:34:13 (6512): BOINC client version: 8.0.2
2024-10-01 01:34:14 (6512): Detected: VirtualBox VboxManage Interface (Version: 7.0.10)
2024-10-01 01:34:14 (6512): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-10-01 01:34:15 (6512): Guest Log: BIOS: int13_harddisk_ext: function 42, error 04 !
2024-10-01 01:34:15 (6512): Starting VM using VBoxManage interface. (boinc_eaf939d778381088, slot#1)
2024-10-01 01:34:37 (6512): Successfully started VM. (PID = '4940')
2024-10-01 01:34:37 (6512): Reporting VM Process ID to BOINC.
2024-10-01 01:34:37 (6512): Guest Log: BIOS: int13_harddisk_ext: function 42, error 04 !
2024-10-01 01:34:37 (6512): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-10-01 01:34:37 (6512): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-10-01 01:34:37 (6512): VM state change detected. (old = 'poweredoff', new = 'running')
2024-10-01 01:34:37 (6512): Detected: Web Application Enabled (http://localhost:49262)
2024-10-01 01:34:38 (6512): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 01:34:54 (6512): Preference change detected
2024-10-01 01:34:54 (6512): Setting CPU throttle for VM. (100%)
2024-10-01 01:34:55 (6512): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-10-01 01:34:55 (6512): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 01:35:56 (6512): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-10-01 01:35:57 (6512): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-10-01 01:36:04 (6512): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-10-01 01:36:04 (6512): Guest Log: 00:00:00.000298 main     Log opened 2024-10-01T00:36:04.647587000Z
2024-10-01 01:36:04 (6512): Guest Log: 00:00:00.000535 main     OS Product: Linux
2024-10-01 01:36:04 (6512): Guest Log: 00:00:00.000630 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-10-01 01:36:04 (6512): Guest Log: 00:00:00.000750 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-10-01 01:36:04 (6512): Guest Log: 00:00:00.000838 main     Executable: /usr/sbin/VBoxService
2024-10-01 01:36:04 (6512): Guest Log: 00:00:00.000840 main     Process ID: 3188
2024-10-01 01:36:04 (6512): Guest Log: 00:00:00.000841 main     Package type: LINUX_64BITS_GENERIC
2024-10-01 01:36:04 (6512): Guest Log: 00:00:00.005918 main     5.2.6 r120293 started. Verbose level = 0
2024-10-01 01:36:06 (6512): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 01:36:25 (6512): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 01:36:35 (6512): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 01:37:01 (6512): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 01:37:15 (6512): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 01:37:35 (6512): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 01:37:37 (6512): Stopping VM.
2024-10-01 01:37:50 (6512): Successfully stopped VM.
2024-10-01 01:40:37 (1852): vboxwrapper version 26207
2024-10-01 01:40:37 (1852): BOINC client version: 8.0.2
2024-10-01 01:40:38 (1852): Detected: VirtualBox VboxManage Interface (Version: 7.0.10)
2024-10-01 01:40:38 (1852): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-10-01 01:40:39 (1852): Guest Log: BIOS: int13_harddisk_ext: function 42, error 04 !
2024-10-01 01:40:39 (1852): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-10-01 01:40:39 (1852): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-10-01 01:40:39 (1852): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-10-01 01:40:39 (1852): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-10-01 01:40:39 (1852): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-10-01 01:40:39 (1852): Guest Log: 00:00:00.000298 main     Log opened 2024-10-01T00:36:04.647587000Z
2024-10-01 01:40:39 (1852): Guest Log: 00:00:00.000535 main     OS Product: Linux
2024-10-01 01:40:39 (1852): Guest Log: 00:00:00.000630 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-10-01 01:40:39 (1852): Guest Log: 00:00:00.000750 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-10-01 01:40:39 (1852): Guest Log: 00:00:00.000838 main     Executable: /usr/sbin/VBoxService
2024-10-01 01:40:39 (1852): Guest Log: 00:00:00.000840 main     Process ID: 3188
2024-10-01 01:40:39 (1852): Guest Log: 00:00:00.000841 main     Package type: LINUX_64BITS_GENERIC
2024-10-01 01:40:39 (1852): Guest Log: 00:00:00.005918 main     5.2.6 r120293 started. Verbose level = 0
2024-10-01 01:40:39 (1852): Starting VM using VBoxManage interface. (boinc_eaf939d778381088, slot#1)
2024-10-01 01:41:06 (1852): Successfully started VM. (PID = '9720')
2024-10-01 01:41:06 (1852): Reporting VM Process ID to BOINC.
2024-10-01 01:41:06 (1852): VM state change detected. (old = 'poweredoff', new = 'running')
2024-10-01 01:41:06 (1852): Detected: Web Application Enabled (http://localhost:49262)
2024-10-01 01:41:07 (1852): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 01:41:26 (1852): Preference change detected
2024-10-01 01:41:26 (1852): Setting CPU throttle for VM. (100%)
2024-10-01 01:41:27 (1852): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-10-01 01:41:27 (1852): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 01:41:37 (1852): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 01:41:57 (1852): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 01:42:38 (1852): Guest Log: 00:42:17 +00 +00:00 2024-10-01: cranky: [INFO] Detected Theory App
2024-10-01 01:42:38 (1852): Guest Log: 00:42:17 +00 +00:00 2024-10-01: cranky: [INFO] Checking CVMFS.
2024-10-01 01:42:42 (1852): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-10-01 01:42:45 (1852): 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-10-01 01:42:45 (1852): Guest Log: 2.5.2.0 4140 5 27176 30600 3 1 1280148 4096000 0 65024 0 0 n/a 5 3 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-10-01 01:42:49 (1852): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-10-01 01:42:49 (1852): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 01:43:08 (1852): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 01:43:10 (1852): 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-10-01 01:43:10 (1852): Guest Log: 2.5.2.0 4119 5 27508 25363 3 2 1280148 4096000 0 65024 0 2 100 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-10-01 01:43:13 (1852): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-10-01 01:43:15 (1852): 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-10-01 01:43:15 (1852): Guest Log: 2.5.2.0 4094 5 27200 272 3 1 1280148 4096000 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-10-01 01:43:18 (1852): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 01:43:37 (1852): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 01:43:39 (1852): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-10-01 01:43:41 (1852): 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-10-01 01:43:41 (1852): Guest Log: 2.5.2.0 4166 6 40072 19921 3 1 1280148 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-10-01 01:43:41 (1852): Guest Log: 00:42:43 +00 +00:00 2024-10-01: cranky: [INFO] Checking runc.
2024-10-01 01:43:43 (1852): Guest Log: 00:42:46 +00 +00:00 2024-10-01: cranky: [INFO] Creating the filesystem.
2024-10-01 01:43:44 (1852): Guest Log: 00:42:47 +00 +00:00 2024-10-01: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-10-01 01:43:45 (1852): Guest Log: 00:42:47 +00 +00:00 2024-10-01: cranky: [INFO] Updating config.json.
2024-10-01 01:43:45 (1852): Guest Log: 00:42:48 +00 +00:00 2024-10-01: cranky: [INFO] Running Container 'runc'.
2024-10-01 01:43:51 (1852): Guest Log: job: htmld=/shared/html/job
2024-10-01 01:43:51 (1852): Guest Log: job: unpack exitcode=0
2024-10-01 01:43:52 (1852): Guest Log: INFO: activated the work-around for ld:
2024-10-01 01:43:52 (1852): Guest Log: lrwxrwxrwx 1 0 0 15 Oct  1 00:42 /tmp/tmp.BfMmeu12oO/ld -> /usr/bin/ld.bfd
2024-10-01 01:43:53 (1852): Guest Log: 00:42:56 +00 +00:00 2024-10-01: cranky: [INFO] ===> [runRivet] Tue Oct  1 00:42:55 UTC 2024 [boinc pp ue 200 4 - pythia8 8.308 tune-AU2lox 71000 469]
2024-10-01 01:45:18 (1852): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 01:46:02 (1852): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 01:46:02 (1852): Stopping VM.
2024-10-01 14:30:12 (9632): vboxwrapper version 26207
2024-10-01 14:30:13 (9632): BOINC client version: 8.0.2
2024-10-01 14:30:13 (9632): Detected: VirtualBox VboxManage Interface (Version: 7.0.10)
2024-10-01 14:30:13 (9632): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-10-01 14:30:14 (9632): Guest Log: 00:42:17 +00 +00:00 2024-10-01: cranky: [INFO] Detected Theory App
2024-10-01 14:30:14 (9632): Guest Log: 00:42:17 +00 +00:00 2024-10-01: cranky: [INFO] Checking CVMFS.
2024-10-01 14:30:14 (9632): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-10-01 14:30:14 (9632): 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-10-01 14:30:14 (9632): Guest Log: 2.5.2.0 4140 5 27176 30600 3 1 1280148 4096000 0 65024 0 0 n/a 5 3 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-10-01 14:30:14 (9632): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-10-01 14:30:14 (9632): 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-10-01 14:30:14 (9632): Guest Log: 2.5.2.0 4119 5 27508 25363 3 2 1280148 4096000 0 65024 0 2 100 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-10-01 14:30:14 (9632): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-10-01 14:30:14 (9632): 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-10-01 14:30:14 (9632): Guest Log: 2.5.2.0 4094 5 27200 272 3 1 1280148 4096000 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-10-01 14:30:14 (9632): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-10-01 14:30:14 (9632): 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-10-01 14:30:14 (9632): Guest Log: 2.5.2.0 4166 6 40072 19921 3 1 1280148 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-10-01 14:30:14 (9632): Guest Log: 00:42:43 +00 +00:00 2024-10-01: cranky: [INFO] Checking runc.
2024-10-01 14:30:14 (9632): Guest Log: 00:42:46 +00 +00:00 2024-10-01: cranky: [INFO] Creating the filesystem.
2024-10-01 14:30:14 (9632): Guest Log: 00:42:47 +00 +00:00 2024-10-01: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-10-01 14:30:14 (9632): Guest Log: 00:42:47 +00 +00:00 2024-10-01: cranky: [INFO] Updating config.json.
2024-10-01 14:30:14 (9632): Guest Log: 00:42:48 +00 +00:00 2024-10-01: cranky: [INFO] Running Container 'runc'.
2024-10-01 14:30:14 (9632): Guest Log: job: htmld=/shared/html/job
2024-10-01 14:30:14 (9632): Guest Log: job: unpack exitcode=0
2024-10-01 14:30:14 (9632): Guest Log: INFO: activated the work-around for ld:
2024-10-01 14:30:14 (9632): Guest Log: lrwxrwxrwx 1 0 0 15 Oct  1 00:42 /tmp/tmp.BfMmeu12oO/ld -> /usr/bin/ld.bfd
2024-10-01 14:30:14 (9632): Guest Log: 00:42:56 +00 +00:00 2024-10-01: cranky: [INFO] ===> [runRivet] Tue Oct  1 00:42:55 UTC 2024 [boinc pp ue 200 4 - pythia8 8.308 tune-AU2lox 71000 469]
2024-10-01 14:30:14 (9632): Starting VM using VBoxManage interface. (boinc_eaf939d778381088, slot#1)
2024-10-01 14:30:32 (9632): Successfully started VM. (PID = '4960')
2024-10-01 14:30:32 (9632): Reporting VM Process ID to BOINC.
2024-10-01 14:30:32 (9632): Guest Log: BIOS: VirtualBox 7.0.10
2024-10-01 14:30:32 (9632): Guest Log: CPUID EDX: 0x178bfbff
2024-10-01 14:30:32 (9632): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-10-01 14:30:32 (9632): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-10-01 14:30:32 (9632): VM state change detected. (old = 'poweredoff', new = 'running')
2024-10-01 14:30:32 (9632): Detected: Web Application Enabled (http://localhost:49262)
2024-10-01 14:30:33 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:30:43 (9632): Preference change detected
2024-10-01 14:30:43 (9632): Setting CPU throttle for VM. (100%)
2024-10-01 14:30:43 (9632): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-10-01 14:30:43 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:30:44 (9632): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-10-01 14:30:44 (9632): Guest Log: BIOS: Booting from Hard Disk...
2024-10-01 14:30:47 (9632): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-10-01 14:30:47 (9632): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-10-01 14:31:40 (9632): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-10-01 14:31:40 (9632): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-10-01 14:31:44 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:32:04 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:32:07 (9632): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-10-01 14:32:07 (9632): Guest Log: 00:00:00.005934 main     Log opened 2024-10-01T13:31:49.088000000Z
2024-10-01 14:32:07 (9632): Guest Log: 00:00:00.023366 main     OS Product: Linux
2024-10-01 14:32:07 (9632): Guest Log: 00:00:00.023643 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-10-01 14:32:07 (9632): Guest Log: 00:00:00.023736 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-10-01 14:32:07 (9632): Guest Log: 00:00:00.023827 main     Executable: /usr/sbin/VBoxService
2024-10-01 14:32:07 (9632): Guest Log: 00:00:00.023830 main     Process ID: 3184
2024-10-01 14:32:07 (9632): Guest Log: 00:00:00.023831 main     Package type: LINUX_64BITS_GENERIC
2024-10-01 14:32:07 (9632): Guest Log: 00:00:00.065129 main     5.2.6 r120293 started. Verbose level = 0
2024-10-01 14:32:21 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:33:00 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:33:15 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:33:35 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:34:04 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:34:45 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:34:50 (9632): Guest Log: 13:32:52 +00 +00:00 2024-10-01: cranky: [INFO] Detected Theory App
2024-10-01 14:34:50 (9632): Guest Log: 13:32:52 +00 +00:00 2024-10-01: cranky: [INFO] Checking CVMFS.
2024-10-01 14:34:53 (9632): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-10-01 14:34:55 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:35:15 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:35:16 (9632): 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-10-01 14:35:16 (9632): Guest Log: 2.5.2.0 4140 0 26588 30602 3 1 1312049 4096000 0 65024 0 0 n/a 5 4 http://s1ihep-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-10-01 14:35:19 (9632): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-10-01 14:35:21 (9632): 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-10-01 14:35:21 (9632): Guest Log: 2.5.2.0 4114 0 25648 25367 3 2 1312049 4096000 0 65024 0 2 0 7921 3696 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-10-01 14:35:25 (9632): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-10-01 14:35:26 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:35:46 (9632): 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-10-01 14:35:46 (9632): Guest Log: 2.5.2.0 4088 0 25184 272 3 1 1312049 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-10-01 14:35:46 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:35:49 (9632): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-10-01 14:35:50 (9632): 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-10-01 14:35:50 (9632): Guest Log: 2.5.2.0 4163 0 40556 19925 3 1 1312049 4096000 0 65024 0 0 n/a 6128 2426 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-10-01 14:35:50 (9632): Guest Log: 13:33:14 +00 +00:00 2024-10-01: cranky: [INFO] Checking runc.
2024-10-01 14:35:52 (9632): Guest Log: 13:33:15 +00 +00:00 2024-10-01: cranky: [INFO] Creating the filesystem.
2024-10-01 14:35:52 (9632): Guest Log: 13:33:16 +00 +00:00 2024-10-01: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-10-01 14:35:53 (9632): Guest Log: 13:33:16 +00 +00:00 2024-10-01: cranky: [INFO] Updating config.json.
2024-10-01 14:35:53 (9632): Guest Log: 13:33:16 +00 +00:00 2024-10-01: cranky: [INFO] Running Container 'runc'.
2024-10-01 14:35:58 (9632): Guest Log: job: htmld=/shared/html/job
2024-10-01 14:35:58 (9632): Guest Log: job: unpack exitcode=0
2024-10-01 14:35:58 (9632): Guest Log: INFO: activated the work-around for ld:
2024-10-01 14:35:58 (9632): Guest Log: lrwxrwxrwx 1 0 0 15 Oct  1 13:33 /tmp/tmp.1rkvHUUsVs/ld -> /usr/bin/ld.bfd
2024-10-01 14:35:59 (9632): Guest Log: 13:33:23 +00 +00:00 2024-10-01: cranky: [INFO] ===> [runRivet] Tue Oct  1 13:33:21 UTC 2024 [boinc pp ue 200 4 - pythia8 8.308 tune-AU2lox 71000 469]
2024-10-01 14:37:26 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:37:46 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:37:56 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:38:16 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:42:28 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:43:08 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:49:13 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:49:30 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:50:20 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:50:40 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:51:00 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:51:20 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:52:10 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:52:31 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:52:42 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:53:01 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:53:41 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:54:01 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:54:11 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:54:31 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 14:54:51 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 14:55:10 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:07:08 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:07:25 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:08:17 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:08:35 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:08:46 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:09:05 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:09:15 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:09:35 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:09:45 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:10:05 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:10:46 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:11:06 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:11:36 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:11:56 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:15:55 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:16:08 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:21:35 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:21:49 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:23:00 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:23:20 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:23:46 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:23:59 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:24:20 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:24:39 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:25:22 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:25:41 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:26:02 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:26:23 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:27:00 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:27:20 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:27:35 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:28:02 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:28:05 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:28:21 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:28:30 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:28:50 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:29:02 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:29:22 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:29:34 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:29:51 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:30:01 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:30:21 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:30:32 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:30:51 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:31:01 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:31:22 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:31:31 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:31:52 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:32:02 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:32:21 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:32:31 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:32:51 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:33:01 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:33:21 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:33:31 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:33:52 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:34:02 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:34:22 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:34:32 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:34:52 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:35:02 (9632): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:35:23 (9632): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:35:26 (9632): Stopping VM.
2024-10-01 15:36:16 (9632): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_eaf939d778381088" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...70%...
2024-10-01 15:36:16 (9632): VM did not stop when requested.
2024-10-01 15:36:16 (9632): VM was NOT successfully terminated.
2024-10-01 15:40:26 (10428): vboxwrapper version 26207
2024-10-01 15:40:26 (10428): BOINC client version: 8.0.2
2024-10-01 15:40:28 (10428): Detected: VirtualBox VboxManage Interface (Version: 7.0.10)
2024-10-01 15:40:29 (10428): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-10-01 15:40:33 (10428): Guest Log: BIOS: VirtualBox 7.0.10
2024-10-01 15:40:33 (10428): Guest Log: CPUID EDX: 0x178bfbff
2024-10-01 15:40:33 (10428): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-10-01 15:40:33 (10428): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-10-01 15:40:33 (10428): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-10-01 15:40:33 (10428): Guest Log: BIOS: Booting from Hard Disk...
2024-10-01 15:40:33 (10428): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-10-01 15:40:33 (10428): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-10-01 15:40:33 (10428): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-10-01 15:40:33 (10428): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-10-01 15:40:33 (10428): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-10-01 15:40:33 (10428): Guest Log: 00:00:00.005934 main     Log opened 2024-10-01T13:31:49.088000000Z
2024-10-01 15:40:33 (10428): Guest Log: 00:00:00.023366 main     OS Product: Linux
2024-10-01 15:40:33 (10428): Guest Log: 00:00:00.023643 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-10-01 15:40:33 (10428): Guest Log: 00:00:00.023736 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-10-01 15:40:33 (10428): Guest Log: 00:00:00.023827 main     Executable: /usr/sbin/VBoxService
2024-10-01 15:40:33 (10428): Guest Log: 00:00:00.023830 main     Process ID: 3184
2024-10-01 15:40:33 (10428): Guest Log: 00:00:00.023831 main     Package type: LINUX_64BITS_GENERIC
2024-10-01 15:40:33 (10428): Guest Log: 00:00:00.065129 main     5.2.6 r120293 started. Verbose level = 0
2024-10-01 15:40:33 (10428): Guest Log: 13:32:52 +00 +00:00 2024-10-01: cranky: [INFO] Detected Theory App
2024-10-01 15:40:33 (10428): Guest Log: 13:32:52 +00 +00:00 2024-10-01: cranky: [INFO] Checking CVMFS.
2024-10-01 15:40:33 (10428): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-10-01 15:40:33 (10428): 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-10-01 15:40:33 (10428): Guest Log: 2.5.2.0 4140 0 26588 30602 3 1 1312049 4096000 0 65024 0 0 n/a 5 4 http://s1ihep-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-10-01 15:40:33 (10428): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-10-01 15:40:33 (10428): 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-10-01 15:40:33 (10428): Guest Log: 2.5.2.0 4114 0 25648 25367 3 2 1312049 4096000 0 65024 0 2 0 7921 3696 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-10-01 15:40:33 (10428): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-10-01 15:40:33 (10428): 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-10-01 15:40:33 (10428): Guest Log: 2.5.2.0 4088 0 25184 272 3 1 1312049 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-10-01 15:40:33 (10428): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-10-01 15:40:33 (10428): 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-10-01 15:40:33 (10428): Guest Log: 2.5.2.0 4163 0 40556 19925 3 1 1312049 4096000 0 65024 0 0 n/a 6128 2426 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-10-01 15:40:33 (10428): Guest Log: 13:33:14 +00 +00:00 2024-10-01: cranky: [INFO] Checking runc.
2024-10-01 15:40:33 (10428): Guest Log: 13:33:15 +00 +00:00 2024-10-01: cranky: [INFO] Creating the filesystem.
2024-10-01 15:40:33 (10428): Guest Log: 13:33:16 +00 +00:00 2024-10-01: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-10-01 15:40:33 (10428): Guest Log: 13:33:16 +00 +00:00 2024-10-01: cranky: [INFO] Updating config.json.
2024-10-01 15:40:33 (10428): Guest Log: 13:33:16 +00 +00:00 2024-10-01: cranky: [INFO] Running Container 'runc'.
2024-10-01 15:40:33 (10428): Guest Log: job: htmld=/shared/html/job
2024-10-01 15:40:33 (10428): Guest Log: job: unpack exitcode=0
2024-10-01 15:40:33 (10428): Guest Log: INFO: activated the work-around for ld:
2024-10-01 15:40:33 (10428): Guest Log: lrwxrwxrwx 1 0 0 15 Oct  1 13:33 /tmp/tmp.1rkvHUUsVs/ld -> /usr/bin/ld.bfd
2024-10-01 15:40:33 (10428): Guest Log: 13:33:23 +00 +00:00 2024-10-01: cranky: [INFO] ===> [runRivet] Tue Oct  1 13:33:21 UTC 2024 [boinc pp ue 200 4 - pythia8 8.308 tune-AU2lox 71000 469]
2024-10-01 15:40:33 (10428): Starting VM using VBoxManage interface. (boinc_eaf939d778381088, slot#1)
2024-10-01 15:41:04 (10428): Successfully started VM. (PID = '1948')
2024-10-01 15:41:04 (10428): Reporting VM Process ID to BOINC.
2024-10-01 15:41:04 (10428): Guest Log: BIOS: VirtualBox 7.0.10
2024-10-01 15:41:04 (10428): Guest Log: CPUID EDX: 0x178bfbff
2024-10-01 15:41:05 (10428): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-10-01 15:41:05 (10428): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-10-01 15:41:05 (10428): VM state change detected. (old = 'poweredoff', new = 'running')
2024-10-01 15:41:05 (10428): Detected: Web Application Enabled (http://localhost:49262)
2024-10-01 15:41:06 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:43:25 (10428): Preference change detected
2024-10-01 15:43:25 (10428): Setting CPU throttle for VM. (100%)
2024-10-01 15:43:56 (10428): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-10-01 15:43:57 (10428): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-10-01 15:43:57 (10428): Guest Log: BIOS: Booting from Hard Disk...
2024-10-01 15:43:57 (10428): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-10-01 15:43:57 (10428): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-10-01 15:43:57 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:43:59 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:56:40 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:57:01 (10428): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-10-01 15:57:02 (10428): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-10-01 15:57:08 (10428): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-10-01 15:57:08 (10428): Guest Log: 00:00:00.000359 main     Log opened 2024-10-01T14:57:08.992509000Z
2024-10-01 15:57:08 (10428): Guest Log: 00:00:00.000683 main     OS Product: Linux
2024-10-01 15:57:08 (10428): Guest Log: 00:00:00.000868 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-10-01 15:57:08 (10428): Guest Log: 00:00:00.001207 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-10-01 15:57:08 (10428): Guest Log: 00:00:00.004157 main     Executable: /usr/sbin/VBoxService
2024-10-01 15:57:08 (10428): Guest Log: 00:00:00.004161 main     Process ID: 3223
2024-10-01 15:57:08 (10428): Guest Log: 00:00:00.004163 main     Package type: LINUX_64BITS_GENERIC
2024-10-01 15:57:08 (10428): Guest Log: 00:00:00.016818 main     5.2.6 r120293 started. Verbose level = 0
2024-10-01 15:57:10 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:57:31 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:57:41 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 15:58:01 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 15:58:11 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 16:46:58 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 16:47:06 (10428): Guest Log: 00:00:30.044724 timesync vgsvcTimeSyncWorker: Radical host time change: 2 937 138 000 000ns (HostNow=1 727 797 626 038 000 000 ns HostLast=1 727 794 688 900 000 000 ns)
2024-10-01 16:47:08 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 16:47:28 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 16:47:36 (10428): Guest Log: 00:00:40.047838 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 977 003 086 000ns (GuestNow=1 727 797 636 039 710 000 ns GuestLast=1 727 794 659 036 624 000 ns fSetTimeLastLoop=true )
2024-10-01 16:47:48 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 16:48:08 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 16:48:12 (10428): Guest Log: 15:47:32 +00 +00:00 2024-10-01: cranky: [INFO] Detected Theory App
2024-10-01 16:48:12 (10428): Guest Log: 15:47:32 +00 +00:00 2024-10-01: cranky: [INFO] Checking CVMFS.
2024-10-01 16:48:16 (10428): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-10-01 16:48:18 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 16:48:38 (10428): 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-10-01 16:48:38 (10428): Guest Log: 2.5.2.0 4121 50 27196 30602 3 1 1312067 4096000 0 65024 0 0 n/a 0 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-10-01 16:48:38 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 16:48:41 (10428): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-10-01 16:48:43 (10428): 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-10-01 16:48:43 (10428): Guest Log: 2.5.2.0 4100 50 27376 25367 3 2 1312067 4096000 0 65024 0 2 100 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-10-01 16:48:46 (10428): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-10-01 16:48:48 (10428): 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-10-01 16:48:48 (10428): Guest Log: 2.5.2.0 4079 50 27216 272 3 1 1312067 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-10-01 16:48:48 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 16:49:08 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 16:49:11 (10428): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-10-01 16:49:13 (10428): 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-10-01 16:49:13 (10428): Guest Log: 2.5.2.0 4151 50 39608 19925 3 1 1312067 4096000 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-10-01 16:49:13 (10428): Guest Log: 15:47:53 +00 +00:00 2024-10-01: cranky: [INFO] Checking runc.
2024-10-01 16:49:13 (10428): Guest Log: 15:47:53 +00 +00:00 2024-10-01: cranky: [INFO] Creating the filesystem.
2024-10-01 16:49:13 (10428): Guest Log: 15:47:53 +00 +00:00 2024-10-01: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-10-01 16:49:13 (10428): Guest Log: 15:47:54 +00 +00:00 2024-10-01: cranky: [INFO] Updating config.json.
2024-10-01 16:49:14 (10428): Guest Log: 15:47:54 +00 +00:00 2024-10-01: cranky: [INFO] Running Container 'runc'.
2024-10-01 16:49:17 (10428): Guest Log: job: htmld=/shared/html/job
2024-10-01 16:49:17 (10428): Guest Log: job: unpack exitcode=0
2024-10-01 16:49:17 (10428): Guest Log: INFO: activated the work-around for ld:
2024-10-01 16:49:17 (10428): Guest Log: lrwxrwxrwx 1 0 0 15 Oct  1 15:47 /tmp/tmp.EZ00pKCynQ/ld -> /usr/bin/ld.bfd
2024-10-01 16:49:18 (10428): Guest Log: 15:47:58 +00 +00:00 2024-10-01: cranky: [INFO] ===> [runRivet] Tue Oct  1 15:47:58 UTC 2024 [boinc pp ue 200 4 - pythia8 8.308 tune-AU2lox 71000 469]
2024-10-01 16:58:52 (10428): Status Report: Job Duration: '864000.000000'
2024-10-01 16:58:52 (10428): Status Report: Elapsed Time: '6000.323554'
2024-10-01 16:58:52 (10428): Status Report: CPU Time: '3396.859375'
2024-10-01 17:07:21 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:07:23 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:13:04 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:13:24 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:13:44 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:14:04 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:14:14 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:14:35 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:14:45 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:15:04 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:15:14 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:15:35 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:15:46 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:16:06 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:16:15 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:16:35 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:16:45 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:17:05 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:17:16 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:17:35 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:17:45 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:18:05 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:18:15 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:18:36 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:18:47 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:19:06 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:19:16 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:19:36 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:19:46 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:20:08 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:20:16 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:20:36 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:20:46 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:21:06 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:21:16 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:21:37 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:21:47 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:22:07 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:22:17 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:22:36 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:22:48 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:23:07 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:23:17 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:23:38 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:23:57 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:24:17 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:24:27 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:24:48 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:24:58 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:25:18 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:25:28 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:25:48 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:25:58 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:26:18 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:26:28 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:26:48 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:26:58 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:27:18 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:27:29 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:27:48 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:27:59 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:28:20 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:28:29 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:28:49 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:28:59 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:29:19 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:29:29 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:29:49 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:30:01 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:30:19 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:30:30 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:30:49 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:31:00 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:31:20 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:31:30 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:31:50 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:32:01 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:32:20 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:32:30 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:32:50 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:33:00 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:33:20 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:33:33 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:33:51 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:34:01 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:34:21 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:34:31 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:34:51 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:35:02 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:35:21 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:35:32 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:35:50 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:36:01 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:36:21 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:36:31 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:36:51 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:37:01 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:37:21 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:37:31 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:37:51 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:38:03 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:38:22 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:38:32 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:38:51 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:39:01 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:39:21 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:39:31 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:39:51 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:40:01 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:40:22 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:40:32 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:40:52 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:41:02 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:41:23 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:41:34 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:41:52 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:42:03 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:42:23 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:42:33 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:42:52 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:43:02 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:43:22 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:43:33 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:43:53 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:44:03 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:44:23 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:44:33 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:44:53 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:45:03 (10428): VM state change detected. (old = 'running', new = 'paused')
2024-10-01 17:45:23 (10428): VM state change detected. (old = 'paused', new = 'running')
2024-10-01 17:45:40 (10428): Guest Log: job: run exitcode=0
2024-10-01 17:45:40 (10428): Guest Log: job: diskusage=3520
2024-10-01 17:45:40 (10428): Guest Log: job: logsize=64 k
2024-10-01 17:45:40 (10428): Guest Log: job: times=
2024-10-01 17:45:40 (10428): Guest Log: 0m0.024s 0m0.040s
2024-10-01 17:45:40 (10428): Guest Log: 20m38.810s 0m37.362s
2024-10-01 17:45:40 (10428): Guest Log: job: cpuusage=1276
2024-10-01 17:45:42 (10428): Guest Log: 16:43:45 +00 +00:00 2024-10-01: cranky: [INFO] Container 'runc' finished with status code 0.
2024-10-01 17:45:42 (10428): Guest Log: 16:43:45 +00 +00:00 2024-10-01: cranky: [INFO] Preparing output.
2024-10-01 17:45:46 (10428): Guest Log: [INFO] Job Finished
2024-10-01 17:45:46 (10428): Guest Log: [INFO] Shutting Down.
2024-10-01 17:45:46 (10428): VM Completion File Detected.
2024-10-01 17:45:46 (10428): VM Completion Message: Job Finished
.
2024-10-01 17:45:46 (10428): Powering off VM.
2024-10-01 17:45:51 (10428): Successfully stopped VM.
2024-10-01 17:45:51 (10428): Deregister
LHC@home

Stderr output
