Stderr output

<core_client_version>7.20.2</core_client_version>
<![CDATA[
<stderr_txt>
2022-12-15 19:08:22 (4516): Detected: vboxwrapper 26206
2022-12-15 19:08:22 (4516): Detected: BOINC client v7.20.2
2022-12-15 19:08:23 (4516): Detected: VirtualBox VboxManage Interface (Version: 6.1.32)
2022-12-15 19:08:23 (4516): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-12-15 19:08:23 (4516): Successfully copied 'init_data.xml' to the shared directory.
2022-12-15 19:08:23 (4516): Successfully copied 'input' to the shared directory.
2022-12-15 19:08:24 (4516): Create VM. (boinc_aa300dd332225811, slot#2)
2022-12-15 19:08:25 (4516): Setting Memory Size for VM. (630MB)
2022-12-15 19:08:25 (4516): Setting CPU Count for VM. (1)
2022-12-15 19:08:25 (4516): Setting Chipset Options for VM.
2022-12-15 19:08:25 (4516): Setting Graphics Controller Options for VM.
2022-12-15 19:08:26 (4516): Setting Boot Options for VM.
2022-12-15 19:08:26 (4516): Setting Network Configuration for NAT.
2022-12-15 19:08:26 (4516): Enabling VM Network Access.
2022-12-15 19:08:27 (4516): Disabling USB Support for VM.
2022-12-15 19:08:27 (4516): Disabling COM Port Support for VM.
2022-12-15 19:08:27 (4516): Disabling LPT Port Support for VM.
2022-12-15 19:08:27 (4516): Disabling Audio Support for VM.
2022-12-15 19:08:28 (4516): Disabling Clipboard Support for VM.
2022-12-15 19:08:28 (4516): Disabling Drag and Drop Support for VM.
2022-12-15 19:08:28 (4516): Adding storage controller(s) to VM.
2022-12-15 19:08:28 (4516): Adding virtual disk drive to VM. (vm_image.vdi)
2022-12-15 19:08:29 (4516): Adding VirtualBox Guest Additions to VM.
2022-12-15 19:08:29 (4516): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2022-12-15 19:08:29 (4516): forwarding host port 57493 to guest port 80
2022-12-15 19:08:30 (4516): Enabling remote desktop for VM.
2022-12-15 19:08:30 (4516): Required extension pack not installed, remote desktop not enabled.
2022-12-15 19:08:30 (4516): Enabling shared directory for VM.
2022-12-15 19:08:31 (4516): Starting VM using VBoxManage interface. (boinc_aa300dd332225811, slot#2)
2022-12-15 19:08:39 (4516): Successfully started VM. (PID = '19844')
2022-12-15 19:08:39 (4516): Reporting VM Process ID to BOINC.
2022-12-15 19:08:39 (4516): Guest Log: BIOS: VirtualBox 6.1.32
2022-12-15 19:08:39 (4516): Guest Log: CPUID EDX: 0x178bfbff
2022-12-15 19:08:39 (4516): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-12-15 19:08:39 (4516): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-12-15 19:08:39 (4516): VM state change detected. (old = 'poweredoff', new = 'running')
2022-12-15 19:08:39 (4516): Detected: Web Application Enabled (http://localhost:57493)
2022-12-15 19:08:39 (4516): Preference change detected
2022-12-15 19:08:39 (4516): Setting CPU throttle for VM. (80%)
2022-12-15 19:08:39 (4516): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 120 seconds) or (Vbox_job.xml: 600 seconds))
2022-12-15 19:08:41 (4516): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-12-15 19:08:41 (4516): Guest Log: BIOS: Booting from Hard Disk...
2022-12-15 19:08:44 (4516): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-12-15 19:08:44 (4516): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-12-15 19:09:08 (4516): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-12-15 19:09:08 (4516): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-12-15 19:09:13 (4516): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-12-15 19:09:13 (4516): Guest Log: 00:00:00.000163 main     Log opened 2022-12-16T01:09:13.679483000Z
2022-12-15 19:09:13 (4516): Guest Log: 00:00:00.000378 main     OS Product: Linux
2022-12-15 19:09:13 (4516): Guest Log: 00:00:00.000450 main     OS Release: 4.14.76-13.cernvm.x86_64
2022-12-15 19:09:13 (4516): Guest Log: 00:00:00.000544 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2022-12-15 19:09:13 (4516): Guest Log: 00:00:00.000616 main     Executable: /usr/sbin/VBoxService
2022-12-15 19:09:13 (4516): Guest Log: 00:00:00.000619 main     Process ID: 3223
2022-12-15 19:09:13 (4516): Guest Log: 00:00:00.000620 main     Package type: LINUX_64BITS_GENERIC
2022-12-15 19:09:13 (4516): Guest Log: 00:00:00.005007 main     5.2.6 r120293 started. Verbose level = 0
2022-12-15 19:09:46 (4516): Guest Log: 20:09:46 EST -05:00 2022-12-15: cranky: [INFO] Detected Theory App
2022-12-15 19:09:46 (4516): Guest Log: 20:09:46 EST -05:00 2022-12-15: cranky: [INFO] Checking CVMFS.
2022-12-15 19:09:48 (4516): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2022-12-15 19:09:48 (4516): 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-12-15 19:09:48 (4516): Guest Log: 2.5.2.0 4128 0 25156 24578 3 1 289418 4096000 0 65024 0 0 n/a 5 3 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2022-12-15 19:09:50 (4516): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2022-12-15 19:09:51 (4516): 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-12-15 19:09:51 (4516): Guest Log: 2.5.2.0 4077 0 27424 20021 3 2 289418 4096000 0 65024 0 2 -100 7812 2237 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2022-12-15 19:09:53 (4516): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2022-12-15 19:09:53 (4516): 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-12-15 19:09:53 (4516): Guest Log: 2.5.2.0 4057 0 25176 268 3 1 289418 4096000 0 65024 0 0 n/a 4 2 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2022-12-15 19:09:55 (4516): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2022-12-15 19:09:56 (4516): 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-12-15 19:09:56 (4516): Guest Log: 2.5.2.0 4107 0 34320 14114 3 1 289418 4096000 0 65024 0 0 n/a 15344 5099 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2022-12-15 19:09:56 (4516): Guest Log: 20:09:56 EST -05:00 2022-12-15: cranky: [INFO] Checking runc.
2022-12-15 19:09:56 (4516): Guest Log: 20:09:56 EST -05:00 2022-12-15: cranky: [INFO] Creating the filesystem.
2022-12-15 19:09:56 (4516): Guest Log: 20:09:56 EST -05:00 2022-12-15: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2022-12-15 19:09:56 (4516): Guest Log: 20:09:56 EST -05:00 2022-12-15: cranky: [INFO] Updating config.json.
2022-12-15 19:09:57 (4516): Guest Log: 20:09:56 EST -05:00 2022-12-15: cranky: [INFO] Running Container 'runc'.
2022-12-15 19:10:00 (4516): Guest Log: job: htmld=/shared/html/job
2022-12-15 19:10:00 (4516): Guest Log: job: unpack exitcode=0
2022-12-15 19:10:01 (4516): Guest Log: 20:10:00 EST -05:00 2022-12-15: cranky: [INFO] ===> [runRivet] Fri Dec 16 01:09:59 UTC 2022 [boinc pp jets 7000 25,-,760 - pythia8 8.244 default 100000 313]
2022-12-15 19:39:09 (4516): Stopping VM.
2022-12-15 19:39:13 (4516): Successfully stopped VM.
2022-12-15 19:40:12 (16216): Detected: vboxwrapper 26206
2022-12-15 19:40:12 (16216): Detected: BOINC client v7.20.2
2022-12-15 19:40:12 (16216): Detected: VirtualBox VboxManage Interface (Version: 6.1.32)
2022-12-15 19:40:13 (16216): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-12-15 19:40:13 (16216): Guest Log: BIOS: VirtualBox 6.1.32
2022-12-15 19:40:13 (16216): Guest Log: CPUID EDX: 0x178bfbff
2022-12-15 19:40:13 (16216): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-12-15 19:40:13 (16216): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-12-15 19:40:13 (16216): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-12-15 19:40:13 (16216): Guest Log: BIOS: Booting from Hard Disk...
2022-12-15 19:40:13 (16216): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-12-15 19:40:13 (16216): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-12-15 19:40:13 (16216): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-12-15 19:40:13 (16216): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-12-15 19:40:13 (16216): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-12-15 19:40:13 (16216): Guest Log: 00:00:00.000163 main     Log opened 2022-12-16T01:09:13.679483000Z
2022-12-15 19:40:13 (16216): Guest Log: 00:00:00.000378 main     OS Product: Linux
2022-12-15 19:40:13 (16216): Guest Log: 00:00:00.000450 main     OS Release: 4.14.76-13.cernvm.x86_64
2022-12-15 19:40:13 (16216): Guest Log: 00:00:00.000544 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2022-12-15 19:40:13 (16216): Guest Log: 00:00:00.000616 main     Executable: /usr/sbin/VBoxService
2022-12-15 19:40:13 (16216): Guest Log: 00:00:00.000619 main     Process ID: 3223
2022-12-15 19:40:13 (16216): Guest Log: 00:00:00.000620 main     Package type: LINUX_64BITS_GENERIC
2022-12-15 19:40:13 (16216): Guest Log: 00:00:00.005007 main     5.2.6 r120293 started. Verbose level = 0
2022-12-15 19:40:13 (16216): Guest Log: 20:09:46 EST -05:00 2022-12-15: cranky: [INFO] Detected Theory App
2022-12-15 19:40:13 (16216): Guest Log: 20:09:46 EST -05:00 2022-12-15: cranky: [INFO] Checking CVMFS.
2022-12-15 19:40:13 (16216): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2022-12-15 19:40:13 (16216): 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-12-15 19:40:13 (16216): Guest Log: 2.5.2.0 4128 0 25156 24578 3 1 289418 4096000 0 65024 0 0 n/a 5 3 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2022-12-15 19:40:13 (16216): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2022-12-15 19:40:13 (16216): 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-12-15 19:40:13 (16216): Guest Log: 2.5.2.0 4077 0 27424 20021 3 2 289418 4096000 0 65024 0 2 -100 7812 2237 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2022-12-15 19:40:13 (16216): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2022-12-15 19:40:13 (16216): 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-12-15 19:40:13 (16216): Guest Log: 2.5.2.0 4057 0 25176 268 3 1 289418 4096000 0 65024 0 0 n/a 4 2 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2022-12-15 19:40:13 (16216): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2022-12-15 19:40:13 (16216): 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-12-15 19:40:13 (16216): Guest Log: 2.5.2.0 4107 0 34320 14114 3 1 289418 4096000 0 65024 0 0 n/a 15344 5099 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2022-12-15 19:40:13 (16216): Guest Log: 20:09:56 EST -05:00 2022-12-15: cranky: [INFO] Checking runc.
2022-12-15 19:40:13 (16216): Guest Log: 20:09:56 EST -05:00 2022-12-15: cranky: [INFO] Creating the filesystem.
2022-12-15 19:40:13 (16216): Guest Log: 20:09:56 EST -05:00 2022-12-15: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2022-12-15 19:40:13 (16216): Guest Log: 20:09:56 EST -05:00 2022-12-15: cranky: [INFO] Updating config.json.
2022-12-15 19:40:13 (16216): Guest Log: 20:09:56 EST -05:00 2022-12-15: cranky: [INFO] Running Container 'runc'.
2022-12-15 19:40:13 (16216): Guest Log: job: htmld=/shared/html/job
2022-12-15 19:40:13 (16216): Guest Log: job: unpack exitcode=0
2022-12-15 19:40:13 (16216): Guest Log: 20:10:00 EST -05:00 2022-12-15: cranky: [INFO] ===> [runRivet] Fri Dec 16 01:09:59 UTC 2022 [boinc pp jets 7000 25,-,760 - pythia8 8.244 default 100000 313]
2022-12-15 19:40:13 (16216): Starting VM using VBoxManage interface. (boinc_aa300dd332225811, slot#2)
2022-12-15 19:40:22 (16216): Successfully started VM. (PID = '6408')
2022-12-15 19:40:22 (16216): Reporting VM Process ID to BOINC.
2022-12-15 19:40:22 (16216): VM state change detected. (old = 'poweredoff', new = 'running')
2022-12-15 19:40:23 (16216): Detected: Web Application Enabled (http://localhost:57493)
2022-12-15 19:40:24 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 19:40:33 (16216): Preference change detected
2022-12-15 19:40:33 (16216): Setting CPU throttle for VM. (80%)
2022-12-15 19:40:33 (16216): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 120 seconds) or (Vbox_job.xml: 600 seconds))
2022-12-15 19:40:34 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 19:51:34 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 19:51:54 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 19:52:04 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 19:52:14 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:16:09 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:16:19 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:16:29 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:16:39 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:16:49 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:16:59 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:17:09 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:17:49 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:17:59 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:18:09 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:18:19 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:18:29 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:18:39 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:18:49 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:18:59 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:22:39 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:22:49 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:23:39 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:23:49 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:23:59 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:24:09 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:26:09 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:29:20 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:29:30 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:30:10 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:30:20 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:32:10 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:32:20 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:34:20 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:34:30 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:36:30 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:36:40 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:38:30 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:38:40 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:39:20 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:39:30 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:42:11 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:42:21 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:42:31 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:42:41 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:44:41 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:44:51 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:45:11 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:45:21 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:47:31 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:47:41 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:50:41 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:50:51 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:52:51 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:53:01 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:56:22 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:56:32 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:57:12 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:57:22 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:58:02 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:58:12 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 20:58:22 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 20:58:32 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 21:00:52 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 21:01:02 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 21:01:12 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 21:01:22 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 21:02:53 (16216): Status Report: Job Duration: '864000.000000'
2022-12-15 21:02:53 (16216): Status Report: Elapsed Time: '6000.000000'
2022-12-15 21:02:53 (16216): Status Report: CPU Time: '5195.093750'
2022-12-15 21:10:23 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 21:10:33 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 21:10:53 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 21:11:03 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 21:11:13 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 21:11:23 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 21:20:13 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 21:20:23 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 21:35:25 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 21:35:35 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 21:45:15 (16216): VM state change detected. (old = 'running', new = 'paused')
2022-12-15 21:45:25 (16216): VM state change detected. (old = 'paused', new = 'running')
2022-12-15 21:48:16 (16216): Stopping VM.
2022-12-15 21:48:21 (16216): Successfully stopped VM.
2022-12-16 06:37:10 (1596): Detected: vboxwrapper 26206
2022-12-16 06:37:10 (1596): Detected: BOINC client v7.20.2
2022-12-16 06:37:10 (1596): Detected: VirtualBox VboxManage Interface (Version: 6.1.32)
2022-12-16 06:37:11 (1596): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-12-16 06:37:11 (1596): Starting VM using VBoxManage interface. (boinc_aa300dd332225811, slot#2)
2022-12-16 06:37:20 (1596): Successfully started VM. (PID = '1212')
2022-12-16 06:37:20 (1596): Reporting VM Process ID to BOINC.
2022-12-16 06:37:20 (1596): VM state change detected. (old = 'poweredoff', new = 'running')
2022-12-16 06:37:20 (1596): Detected: Web Application Enabled (http://localhost:57493)
2022-12-16 06:37:20 (1596): Status Report: Job Duration: '864000.000000'
2022-12-16 06:37:20 (1596): Status Report: Elapsed Time: '8653.000000'
2022-12-16 06:37:20 (1596): Status Report: CPU Time: '7592.750000'
2022-12-16 06:37:20 (1596): Preference change detected
2022-12-16 06:37:20 (1596): Setting CPU throttle for VM. (80%)
2022-12-16 06:37:21 (1596): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 120 seconds) or (Vbox_job.xml: 600 seconds))
2022-12-16 06:37:28 (1596): Guest Log: 02:24:44.486206 timesync vgsvcTimeSyncWorker: Radical host time change: 31 753 511 000 000ns (HostNow=1 671 194 248 700 000 000 ns HostLast=1 671 162 495 189 000 000 ns)
2022-12-16 06:37:38 (1596): Guest Log: 02:24:54.523420 timesync vgsvcTimeSyncWorker: Radical guest time change: 32 538 547 678 000ns (GuestNow=1 671 194 258 785 019 000 ns GuestLast=1 671 161 720 237 341 000 ns fSetTimeLastLoop=true )
2022-12-16 07:01:23 (1596): VM state change detected. (old = 'running', new = 'paused')
2022-12-16 07:02:03 (1596): VM state change detected. (old = 'paused', new = 'running')
2022-12-16 07:02:13 (1596): VM state change detected. (old = 'running', new = 'paused')
2022-12-16 07:02:23 (1596): VM state change detected. (old = 'paused', new = 'running')
2022-12-16 07:04:06 (1596): VM state change detected. (old = 'running', new = 'paused')
2022-12-16 07:04:16 (1596): VM state change detected. (old = 'paused', new = 'running')
2022-12-16 07:04:26 (1596): VM state change detected. (old = 'running', new = 'paused')
2022-12-16 07:04:37 (1596): VM state change detected. (old = 'paused', new = 'running')
2022-12-16 07:04:46 (1596): VM state change detected. (old = 'running', new = 'paused')
2022-12-16 07:04:57 (1596): VM state change detected. (old = 'paused', new = 'running')
2022-12-16 07:05:17 (1596): VM state change detected. (old = 'running', new = 'paused')
2022-12-16 07:05:26 (1596): VM state change detected. (old = 'paused', new = 'running')
2022-12-16 07:05:41 (1596): VM state change detected. (old = 'running', new = 'paused')
2022-12-16 07:05:47 (1596): VM state change detected. (old = 'paused', new = 'running')
2022-12-16 07:06:17 (1596): VM state change detected. (old = 'running', new = 'paused')
2022-12-16 07:06:27 (1596): VM state change detected. (old = 'paused', new = 'running')
2022-12-16 07:06:57 (1596): VM state change detected. (old = 'running', new = 'paused')
2022-12-16 07:07:07 (1596): VM state change detected. (old = 'paused', new = 'running')
2022-12-16 07:41:00 (1596): VM state change detected. (old = 'running', new = 'paused')
2022-12-16 07:41:10 (1596): VM state change detected. (old = 'paused', new = 'running')
2022-12-16 08:19:46 (1596): Status Report: Job Duration: '864000.000000'
2022-12-16 08:19:46 (1596): Status Report: Elapsed Time: '14653.457664'
2022-12-16 08:19:46 (1596): Status Report: CPU Time: '13014.500000'
2022-12-16 08:41:23 (1596): VM state change detected. (old = 'running', new = 'paused')
2022-12-16 08:41:33 (1596): VM state change detected. (old = 'paused', new = 'running')
2022-12-16 09:50:46 (1596): Stopping VM.
2022-12-16 09:50:49 (1596): Successfully stopped VM.
2022-12-16 17:56:27 (11832): Detected: vboxwrapper 26206
2022-12-16 17:56:27 (11832): Detected: BOINC client v7.20.2
2022-12-16 17:56:28 (11832): Detected: VirtualBox VboxManage Interface (Version: 6.1.32)
2022-12-16 17:56:28 (11832): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-12-16 17:56:29 (11832): Guest Log: 02:24:44.486206 timesync vgsvcTimeSyncWorker: Radical host time change: 31 753 511 000 000ns (HostNow=1 671 194 248 700 000 000 ns HostLast=1 671 162 495 189 000 000 ns)
2022-12-16 17:56:29 (11832): Guest Log: 02:24:54.523420 timesync vgsvcTimeSyncWorker: Radical guest time change: 32 538 547 678 000ns (GuestNow=1 671 194 258 785 019 000 ns GuestLast=1 671 161 720 237 341 000 ns fSetTimeLastLoop=true )
2022-12-16 17:56:29 (11832): Starting VM using VBoxManage interface. (boinc_aa300dd332225811, slot#2)
2022-12-16 17:56:36 (11832): Successfully started VM. (PID = '11916')
2022-12-16 17:56:36 (11832): Reporting VM Process ID to BOINC.
2022-12-16 17:56:36 (11832): VM state change detected. (old = 'poweredoff', new = 'running')
2022-12-16 17:56:36 (11832): Detected: Web Application Enabled (http://localhost:57493)
2022-12-16 17:56:36 (11832): Status Report: Job Duration: '864000.000000'
2022-12-16 17:56:36 (11832): Status Report: Elapsed Time: '20096.457664'
2022-12-16 17:56:36 (11832): Status Report: CPU Time: '17914.812500'
2022-12-16 17:56:36 (11832): Preference change detected
2022-12-16 17:56:36 (11832): Setting CPU throttle for VM. (80%)
2022-12-16 17:56:37 (11832): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 120 seconds) or (Vbox_job.xml: 600 seconds))
2022-12-16 17:56:37 (11832): Guest Log: 05:35:50.268452 timesync vgsvcTimeSyncWorker: Radical host time change: 29 158 667 000 000ns (HostNow=1 671 234 997 547 000 000 ns HostLast=1 671 205 838 880 000 000 ns)
2022-12-16 17:56:47 (11832): Guest Log: 05:36:00.269171 timesync vgsvcTimeSyncWorker: Radical guest time change: 29 293 017 713 000ns (GuestNow=1 671 235 007 547 737 000 ns GuestLast=1 671 205 714 530 024 000 ns fSetTimeLastLoop=true )
2022-12-16 17:57:19 (11832): VM state change detected. (old = 'running', new = 'paused')
2022-12-16 17:57:29 (11832): VM state change detected. (old = 'paused', new = 'running')
2022-12-16 18:06:34 (11832): Stopping VM.
2022-12-16 18:06:36 (11832): Successfully stopped VM.
2022-12-18 17:19:14 (8900): Detected: vboxwrapper 26206
2022-12-18 17:19:14 (8900): Detected: BOINC client v7.20.2
2022-12-18 17:19:14 (8900): Detected: VirtualBox VboxManage Interface (Version: 6.1.32)
2022-12-18 17:19:15 (8900): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-12-18 17:19:15 (8900): Guest Log: 05:35:50.268452 timesync vgsvcTimeSyncWorker: Radical host time change: 29 158 667 000 000ns (HostNow=1 671 234 997 547 000 000 ns HostLast=1 671 205 838 880 000 000 ns)
2022-12-18 17:19:15 (8900): Guest Log: 05:36:00.269171 timesync vgsvcTimeSyncWorker: Radical guest time change: 29 293 017 713 000ns (GuestNow=1 671 235 007 547 737 000 ns GuestLast=1 671 205 714 530 024 000 ns fSetTimeLastLoop=true )
2022-12-18 17:19:15 (8900): Starting VM using VBoxManage interface. (boinc_aa300dd332225811, slot#2)
2022-12-18 17:19:24 (8900): Successfully started VM. (PID = '13664')
2022-12-18 17:19:24 (8900): Reporting VM Process ID to BOINC.
2022-12-18 17:19:24 (8900): VM state change detected. (old = 'poweredoff', new = 'running')
2022-12-18 17:19:24 (8900): Detected: Web Application Enabled (http://localhost:57493)
2022-12-18 17:19:24 (8900): Status Report: Job Duration: '864000.000000'
2022-12-18 17:19:24 (8900): Status Report: Elapsed Time: '20682.457664'
2022-12-18 17:19:24 (8900): Status Report: CPU Time: '18441.875000'
2022-12-18 17:19:24 (8900): Preference change detected
2022-12-18 17:19:24 (8900): Setting CPU throttle for VM. (80%)
2022-12-18 17:19:25 (8900): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 120 seconds) or (Vbox_job.xml: 600 seconds))
2022-12-18 17:19:27 (8900): Guest Log: 05:45:40.611008 timesync vgsvcTimeSyncWorker: Radical host time change: 169 979 549 000 000ns (HostNow=1 671 405 567 134 000 000 ns HostLast=1 671 235 587 585 000 000 ns)
2022-12-18 17:19:37 (8900): Guest Log: 05:45:50.611449 timesync vgsvcTimeSyncWorker: Radical guest time change: 169 989 244 924 000ns (GuestNow=1 671 405 577 134 460 000 ns GuestLast=1 671 235 587 889 536 000 ns fSetTimeLastLoop=true )
2022-12-18 17:42:50 (8900): VM state change detected. (old = 'running', new = 'paused')
2022-12-18 17:43:00 (8900): VM state change detected. (old = 'paused', new = 'running')
2022-12-18 18:59:43 (8900): Status Report: Job Duration: '864000.000000'
2022-12-18 18:59:43 (8900): Status Report: Elapsed Time: '26682.457664'
2022-12-18 18:59:43 (8900): Status Report: CPU Time: '23855.750000'
2022-12-18 19:42:48 (8900): VM state change detected. (old = 'running', new = 'paused')
2022-12-18 19:42:58 (8900): VM state change detected. (old = 'paused', new = 'running')
2022-12-18 20:17:00 (8900): VM state change detected. (old = 'running', new = 'paused')
2022-12-18 20:17:10 (8900): VM state change detected. (old = 'paused', new = 'running')
2022-12-18 20:40:12 (8900): Status Report: Job Duration: '864000.000000'
2022-12-18 20:40:12 (8900): Status Report: Elapsed Time: '32682.457664'
2022-12-18 20:40:12 (8900): Status Report: CPU Time: '29263.031250'
2022-12-18 22:20:19 (8900): Status Report: Job Duration: '864000.000000'
2022-12-18 22:20:19 (8900): Status Report: Elapsed Time: '38682.457664'
2022-12-18 22:20:19 (8900): Status Report: CPU Time: '34669.375000'
2022-12-18 22:52:59 (8900): Guest Log: job: run exitcode=0
2022-12-18 22:52:59 (8900): Guest Log: job: diskusage=2444
2022-12-18 22:52:59 (8900): Guest Log: job: logsize=68 k
2022-12-18 22:52:59 (8900): Guest Log: job: times=
2022-12-18 22:52:59 (8900): Guest Log: 0m0.013s 0m0.021s
2022-12-18 22:52:59 (8900): Guest Log: 661m47.450s 3m53.651s
2022-12-18 22:52:59 (8900): Guest Log: job: cpuusage=39941
2022-12-18 22:52:59 (8900): Guest Log: 23:52:38 EST -05:00 2022-12-18: cranky: [INFO] Container 'runc' finished with status code 0.
2022-12-18 22:52:59 (8900): Guest Log: 23:52:38 EST -05:00 2022-12-18: cranky: [INFO] Preparing output.
2022-12-18 22:53:01 (8900): Guest Log: [INFO] Job Finished
2022-12-18 22:53:01 (8900): Guest Log: [INFO] Shutting Down.
2022-12-18 22:53:01 (8900): VM Completion File Detected.
2022-12-18 22:53:01 (8900): VM Completion Message: Job Finished
.
2022-12-18 22:53:01 (8900): Powering off VM.
2022-12-18 22:53:01 (8900): Successfully stopped VM.
2022-12-18 22:53:01 (8900): Deregistering VM. (boinc_aa300dd332225811, slot#2)
2022-12-18 22:53:02 (8900): Removing network bandwidth throttle group from VM.
2022-12-18 22:53:02 (8900): Removing VM from VirtualBox.
22:53:07 (8900): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN