Stderr output

<core_client_version>7.24.1</core_client_version>
<![CDATA[
<stderr_txt>
2025-01-18 09:59:21 (32730): vboxwrapper version 26208
2025-01-18 09:59:21 (32730): BOINC client version: 7.24.1
2025-01-18 09:59:21 (32730): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-01-18 09:59:22 (32730): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-01-18 09:59:22 (32730): Successfully copied 'init_data.xml' to the shared directory.
2025-01-18 09:59:22 (32730): Successfully copied 'input' to the shared directory.
2025-01-18 09:59:22 (32730): Create VM. (boinc_9c612dd981d5e06c, slot#3)
2025-01-18 09:59:22 (32730): Setting Memory Size for VM. (630MB)
2025-01-18 09:59:22 (32730): Setting CPU Count for VM. (1)
2025-01-18 09:59:22 (32730): Setting Chipset Options for VM.
2025-01-18 09:59:22 (32730): Setting Graphics Controller Options for VM.
2025-01-18 09:59:22 (32730): Setting Boot Options for VM.
2025-01-18 09:59:23 (32730): Setting Network Configuration for NAT.
2025-01-18 09:59:23 (32730): Enabling VM Network Access.
2025-01-18 09:59:23 (32730): Disabling USB Support for VM.
2025-01-18 09:59:23 (32730): Disabling COM Port Support for VM.
2025-01-18 09:59:23 (32730): Disabling LPT Port Support for VM.
2025-01-18 09:59:23 (32730): Disabling Audio Support for VM.
2025-01-18 09:59:23 (32730): Disabling Clipboard Support for VM.
2025-01-18 09:59:23 (32730): Disabling Drag and Drop Support for VM.
2025-01-18 09:59:24 (32730): Adding storage controller(s) to VM.
2025-01-18 09:59:24 (32730): Adding virtual disk drive to VM. (Theory_2025_01_16_prod.vdi)
2025-01-18 09:59:24 (32730): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2025-01-18 09:59:24 (32730): forwarding host port 47353 to guest port 80
2025-01-18 09:59:24 (32730): Enabling remote desktop for VM.
2025-01-18 09:59:24 (32730): Required extension pack not installed, remote desktop not enabled.
2025-01-18 09:59:24 (32730): Enabling shared directory for VM.
2025-01-18 09:59:24 (32730): Starting VM using VBoxManage interface. (boinc_9c612dd981d5e06c, slot#3)
2025-01-18 09:59:26 (32730): Successfully started VM. (PID = '686')
2025-01-18 09:59:26 (32730): Reporting VM Process ID to BOINC.
2025-01-18 09:59:26 (32730): Guest Log: BIOS: VirtualBox 7.1.4
2025-01-18 09:59:26 (32730): Guest Log: CPUID EDX: 0x178bfbff
2025-01-18 09:59:26 (32730): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-18 09:59:26 (32730): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-18 09:59:26 (32730): VM state change detected. (old = 'poweredoff', new = 'running')
2025-01-18 09:59:26 (32730): Detected: Web Application Enabled (http://localhost:47353)
2025-01-18 09:59:27 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 09:59:44 (32730): Preference change detected
2025-01-18 09:59:44 (32730): Setting CPU throttle for VM. (80%)
2025-01-18 09:59:44 (32730): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2025-01-18 09:59:44 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 09:59:45 (32730): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-18 09:59:45 (32730): Guest Log: BIOS: Booting from Hard Disk...
2025-01-18 09:59:48 (32730): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-18 09:59:48 (32730): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-01-18 10:00:34 (32730): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-18 10:00:34 (32730): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-18 10:00:41 (32730): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-18 10:00:41 (32730): Guest Log: 00:00:00.000377 main     Log opened 2025-01-18T02:00:48.165194000Z
2025-01-18 10:00:41 (32730): Guest Log: 00:00:00.000720 main     OS Product: Linux
2025-01-18 10:00:41 (32730): Guest Log: 00:00:00.000851 main     OS Release: 4.14.76-13.cernvm.x86_64
2025-01-18 10:00:41 (32730): Guest Log: 00:00:00.000975 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2025-01-18 10:00:41 (32730): Guest Log: 00:00:00.003636 main     Executable: /usr/sbin/VBoxService
2025-01-18 10:00:41 (32730): Guest Log: 00:00:00.003641 main     Process ID: 3156
2025-01-18 10:00:41 (32730): Guest Log: 00:00:00.003644 main     Package type: LINUX_64BITS_GENERIC
2025-01-18 10:00:41 (32730): Guest Log: 00:00:00.008587 main     5.2.6 r120293 started. Verbose level = 0
2025-01-18 10:01:40 (32730): Guest Log: 10:01:48 PST +08:00 2025-01-18: cranky: [INFO] Detected Theory App
2025-01-18 10:01:41 (32730): Guest Log: 10:01:48 PST +08:00 2025-01-18: cranky: [INFO] Checking CVMFS.
2025-01-18 10:01:45 (32730): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2025-01-18 10:01:47 (32730): 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
2025-01-18 10:01:47 (32730): Guest Log: 2.5.2.0 4079 0 28336 31500 3 1 278899 4096000 0 65024 0 0 n/a 5 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2025-01-18 10:01:52 (32730): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2025-01-18 10:01:54 (32730): 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
2025-01-18 10:01:54 (32730): Guest Log: 2.5.2.0 4099 0 25836 26253 3 2 278899 4096000 0 65024 0 2 -100 10085 1883 http://s1ihep-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2025-01-18 10:01:59 (32730): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2025-01-18 10:02:02 (32730): 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
2025-01-18 10:02:02 (32730): Guest Log: 2.5.2.0 4058 0 27232 272 3 1 278899 4096000 0 65024 0 0 n/a 4 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2025-01-18 10:02:07 (32730): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2025-01-18 10:02:09 (32730): 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
2025-01-18 10:02:09 (32730): Guest Log: 2.5.2.0 4038 0 41684 20887 3 1 278899 4096000 0 65024 0 0 n/a 6574 714 http://s1ihep-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2025-01-18 10:02:09 (32730): Guest Log: 10:02:16 PST +08:00 2025-01-18: cranky: [INFO] Checking runc.
2025-01-18 10:02:09 (32730): Guest Log: 10:02:16 PST +08:00 2025-01-18: cranky: [INFO] Creating the filesystem.
2025-01-18 10:02:09 (32730): Guest Log: 10:02:16 PST +08:00 2025-01-18: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2025-01-18 10:02:10 (32730): Guest Log: 10:02:17 PST +08:00 2025-01-18: cranky: [INFO] Updating config.json.
2025-01-18 10:02:10 (32730): Guest Log: 10:02:17 PST +08:00 2025-01-18: cranky: [INFO] Running Container 'runc'.
2025-01-18 10:02:24 (32730): Guest Log: job: htmld=/shared/html/job
2025-01-18 10:02:31 (32730): Guest Log: job: unpack exitcode=0
2025-01-18 10:02:32 (32730): Guest Log: INFO: activated the work-around for ld:
2025-01-18 10:02:33 (32730): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 18 02:02 /tmp/tmp.eOrYyUzKRd/ld -> /usr/bin/ld.bfd
2025-01-18 10:02:33 (32730): Guest Log: 10:02:39 PST +08:00 2025-01-18: cranky: [INFO] ===> [runRivet] Sat Jan 18 02:02:39 UTC 2025 [boinc pp jets 7000 20,-,360 - powheg-box r3744 ptdef2 100000 205]
2025-01-18 10:22:21 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 10:22:41 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 10:23:33 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 10:23:53 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 10:33:46 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 10:34:47 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 10:34:48 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 10:35:07 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 10:35:49 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 10:36:10 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 10:36:30 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 10:37:30 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 10:37:41 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 10:38:01 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 10:42:22 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 10:42:42 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 10:43:22 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 11:44:34 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 11:44:43 (32730): Guest Log: 00:38:52.461031 timesync vgsvcTimeSyncWorker: Radical host time change: 3 681 058 000 000ns (HostNow=1 737 171 882 725 000 000 ns HostLast=1 737 168 201 667 000 000 ns)
2025-01-18 11:44:53 (32730): Guest Log: 00:39:02.482921 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 912 291 843 000ns (GuestNow=1 737 171 892 794 557 000 ns GuestLast=1 737 167 980 502 714 000 ns fSetTimeLastLoop=true )
2025-01-18 12:44:06 (32730): Status Report: Job Duration: '864000.000000'
2025-01-18 12:44:06 (32730): Status Report: Elapsed Time: '6000.000000'
2025-01-18 12:44:06 (32730): Status Report: CPU Time: '3619.690000'
2025-01-18 13:09:22 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 15:03:45 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 15:03:48 (32730): Guest Log: 02:03:35.215878 timesync vgsvcTimeSyncWorker: Radical host time change: 6 872 965 000 000ns (HostNow=1 737 183 828 439 000 000 ns HostLast=1 737 176 955 474 000 000 ns)
2025-01-18 15:03:55 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 15:04:16 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 15:04:19 (32730): Guest Log: 02:03:45.217895 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 872 913 605 000ns (GuestNow=1 737 183 838 441 051 000 ns GuestLast=1 737 176 965 527 446 000 ns fSetTimeLastLoop=true )
2025-01-18 15:07:37 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 15:07:57 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 16:18:14 (32730): Status Report: Job Duration: '864000.000000'
2025-01-18 16:18:14 (32730): Status Report: Elapsed Time: '12000.000000'
2025-01-18 16:18:14 (32730): Status Report: CPU Time: '8269.200000'
2025-01-18 17:57:16 (32730): Status Report: Job Duration: '864000.000000'
2025-01-18 17:57:16 (32730): Status Report: Elapsed Time: '18000.000000'
2025-01-18 17:57:16 (32730): Status Report: CPU Time: '12911.930000'
2025-01-18 18:56:49 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 18:57:09 (32730): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 19:36:39 (32730): Status Report: Job Duration: '864000.000000'
2025-01-18 19:36:39 (32730): Status Report: Elapsed Time: '24000.000000'
2025-01-18 19:36:39 (32730): Status Report: CPU Time: '17554.790000'
2025-01-18 19:54:22 (32730): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:03:49 (32730): Stopping VM.
2025-01-18 21:03:57 (32730): Successfully stopped VM.
2025-01-20 16:35:07 (32217): vboxwrapper version 26208
2025-01-20 16:35:07 (32217): BOINC client version: 7.24.1
2025-01-20 16:35:07 (32217): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-01-20 16:35:07 (32217): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-01-20 16:35:07 (32217): Guest Log: BIOS: VirtualBox 7.1.4
2025-01-20 16:35:07 (32217): Guest Log: CPUID EDX: 0x178bfbff
2025-01-20 16:35:07 (32217): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-20 16:35:07 (32217): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-20 16:35:07 (32217): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-20 16:35:07 (32217): Guest Log: BIOS: Booting from Hard Disk...
2025-01-20 16:35:07 (32217): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-20 16:35:07 (32217): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-01-20 16:35:07 (32217): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-20 16:35:07 (32217): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-20 16:35:07 (32217): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-20 16:35:07 (32217): Guest Log: 00:00:00.000377 main     Log opened 2025-01-18T02:00:48.165194000Z
2025-01-20 16:35:07 (32217): Guest Log: 00:00:00.000720 main     OS Product: Linux
2025-01-20 16:35:07 (32217): Guest Log: 00:00:00.000851 main     OS Release: 4.14.76-13.cernvm.x86_64
2025-01-20 16:35:07 (32217): Guest Log: 00:00:00.000975 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2025-01-20 16:35:07 (32217): Guest Log: 00:00:00.003636 main     Executable: /usr/sbin/VBoxService
2025-01-20 16:35:07 (32217): Guest Log: 00:00:00.003641 main     Process ID: 3156
2025-01-20 16:35:07 (32217): Guest Log: 00:00:00.003644 main     Package type: LINUX_64BITS_GENERIC
2025-01-20 16:35:07 (32217): Guest Log: 00:00:00.008587 main     5.2.6 r120293 started. Verbose level = 0
2025-01-20 16:35:07 (32217): Guest Log: 10:01:48 PST +08:00 2025-01-18: cranky: [INFO] Detected Theory App
2025-01-20 16:35:07 (32217): Guest Log: 10:01:48 PST +08:00 2025-01-18: cranky: [INFO] Checking CVMFS.
2025-01-20 16:35:07 (32217): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2025-01-20 16:35:07 (32217): 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
2025-01-20 16:35:07 (32217): Guest Log: 2.5.2.0 4079 0 28336 31500 3 1 278899 4096000 0 65024 0 0 n/a 5 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2025-01-20 16:35:07 (32217): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2025-01-20 16:35:07 (32217): 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
2025-01-20 16:35:07 (32217): Guest Log: 2.5.2.0 4099 0 25836 26253 3 2 278899 4096000 0 65024 0 2 -100 10085 1883 http://s1ihep-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2025-01-20 16:35:07 (32217): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2025-01-20 16:35:07 (32217): 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
2025-01-20 16:35:07 (32217): Guest Log: 2.5.2.0 4058 0 27232 272 3 1 278899 4096000 0 65024 0 0 n/a 4 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2025-01-20 16:35:07 (32217): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2025-01-20 16:35:07 (32217): 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
2025-01-20 16:35:07 (32217): Guest Log: 2.5.2.0 4038 0 41684 20887 3 1 278899 4096000 0 65024 0 0 n/a 6574 714 http://s1ihep-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2025-01-20 16:35:07 (32217): Guest Log: 10:02:16 PST +08:00 2025-01-18: cranky: [INFO] Checking runc.
2025-01-20 16:35:07 (32217): Guest Log: 10:02:16 PST +08:00 2025-01-18: cranky: [INFO] Creating the filesystem.
2025-01-20 16:35:07 (32217): Guest Log: 10:02:16 PST +08:00 2025-01-18: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2025-01-20 16:35:07 (32217): Guest Log: 10:02:17 PST +08:00 2025-01-18: cranky: [INFO] Updating config.json.
2025-01-20 16:35:07 (32217): Guest Log: 10:02:17 PST +08:00 2025-01-18: cranky: [INFO] Running Container 'runc'.
2025-01-20 16:35:07 (32217): Guest Log: job: htmld=/shared/html/job
2025-01-20 16:35:07 (32217): Guest Log: job: unpack exitcode=0
2025-01-20 16:35:07 (32217): Guest Log: INFO: activated the work-around for ld:
2025-01-20 16:35:07 (32217): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 18 02:02 /tmp/tmp.eOrYyUzKRd/ld -> /usr/bin/ld.bfd
2025-01-20 16:35:07 (32217): Guest Log: 10:02:39 PST +08:00 2025-01-18: cranky: [INFO] ===> [runRivet] Sat Jan 18 02:02:39 UTC 2025 [boinc pp jets 7000 20,-,360 - powheg-box r3744 ptdef2 100000 205]
2025-01-20 16:35:07 (32217): Guest Log: 00:38:52.461031 timesync vgsvcTimeSyncWorker: Radical host time change: 3 681 058 000 000ns (HostNow=1 737 171 882 725 000 000 ns HostLast=1 737 168 201 667 000 000 ns)
2025-01-20 16:35:07 (32217): Guest Log: 00:39:02.482921 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 912 291 843 000ns (GuestNow=1 737 171 892 794 557 000 ns GuestLast=1 737 167 980 502 714 000 ns fSetTimeLastLoop=true )
2025-01-20 16:35:07 (32217): Guest Log: 02:03:35.215878 timesync vgsvcTimeSyncWorker: Radical host time change: 6 872 965 000 000ns (HostNow=1 737 183 828 439 000 000 ns HostLast=1 737 176 955 474 000 000 ns)
2025-01-20 16:35:07 (32217): Guest Log: 02:03:45.217895 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 872 913 605 000ns (GuestNow=1 737 183 838 441 051 000 ns GuestLast=1 737 176 965 527 446 000 ns fSetTimeLastLoop=true )
2025-01-20 16:35:07 (32217): Starting VM using VBoxManage interface. (boinc_9c612dd981d5e06c, slot#3)
2025-01-20 16:35:12 (32217): Successfully started VM. (PID = '32299')
2025-01-20 16:35:12 (32217): Reporting VM Process ID to BOINC.
2025-01-20 16:35:12 (32217): VM state change detected. (old = 'poweredoff', new = 'running')
2025-01-20 16:35:12 (32217): Detected: Web Application Enabled (http://localhost:47353)
2025-01-20 16:35:12 (32217): Status Report: Job Duration: '864000.000000'
2025-01-20 16:35:12 (32217): Status Report: Elapsed Time: '25071.000000'
2025-01-20 16:35:12 (32217): Status Report: CPU Time: '18382.730000'
2025-01-20 16:35:12 (32217): Preference change detected
2025-01-20 16:35:12 (32217): Setting CPU throttle for VM. (80%)
2025-01-20 16:35:12 (32217): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2025-01-20 16:35:20 (32217): Guest Log: 06:53:24.738200 timesync vgsvcTimeSyncWorker: Radical host time change: 160 860 346 000 000ns (HostNow=1 737 362 120 252 000 000 ns HostLast=1 737 201 259 906 000 000 ns)
2025-01-20 16:35:30 (32217): Guest Log: 06:53:34.800243 timesync vgsvcTimeSyncWorker: Radical guest time change: 160 912 352 820 000ns (GuestNow=1 737 362 130 314 112 000 ns GuestLast=1 737 201 217 961 292 000 ns fSetTimeLastLoop=true )
2025-01-20 18:14:15 (32217): Status Report: Job Duration: '864000.000000'
2025-01-20 18:14:15 (32217): Status Report: Elapsed Time: '31071.000000'
2025-01-20 18:14:15 (32217): Status Report: CPU Time: '23032.390000'
2025-01-20 19:53:17 (32217): Status Report: Job Duration: '864000.000000'
2025-01-20 19:53:17 (32217): Status Report: Elapsed Time: '37071.000000'
2025-01-20 19:53:17 (32217): Status Report: CPU Time: '27678.110000'
2025-01-20 20:48:48 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-20 22:53:07 (32217): Guest Log: 11:06:53.235808 timesync vgsvcTimeSyncWorker: Radical host time change: 7 468 772 000 000ns (HostNow=1 737 384 787 493 000 000 ns HostLast=1 737 377 318 721 000 000 ns)
2025-01-20 22:53:07 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-20 22:53:14 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-20 22:53:34 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-20 22:53:37 (32217): Guest Log: 11:07:03.239981 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 468 747 610 000ns (GuestNow=1 737 384 797 497 213 000 ns GuestLast=1 737 377 328 749 603 000 ns fSetTimeLastLoop=true )
2025-01-20 23:37:01 (32217): Status Report: Job Duration: '864000.000000'
2025-01-20 23:37:01 (32217): Status Report: Elapsed Time: '43071.000000'
2025-01-20 23:37:01 (32217): Status Report: CPU Time: '32323.190000'
2025-01-21 00:53:52 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-22 06:40:46 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-22 06:40:51 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-22 06:41:12 (32217): Guest Log: 13:07:27.382865 timesync vgsvcTimeSyncWorker: Radical host time change: 107 244 857 000 000ns (HostNow=1 737 499 272 358 000 000 ns HostLast=1 737 392 027 501 000 000 ns)
2025-01-22 06:41:12 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-22 06:41:22 (32217): Guest Log: 13:07:37.396849 timesync vgsvcTimeSyncWorker: Radical guest time change: 107 260 731 974 000ns (GuestNow=1 737 499 282 372 030 000 ns GuestLast=1 737 392 021 640 056 000 ns fSetTimeLastLoop=true )
2025-01-22 07:03:20 (32217): Status Report: Job Duration: '864000.000000'
2025-01-22 07:03:20 (32217): Status Report: Elapsed Time: '49071.000000'
2025-01-22 07:03:20 (32217): Status Report: CPU Time: '36974.060000'
2025-01-22 08:41:40 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-22 10:06:09 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-22 10:06:12 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-22 10:06:32 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-22 10:06:35 (32217): Guest Log: 15:08:01.319459 timesync vgsvcTimeSyncWorker: Radical host time change: 5 099 214 000 000ns (HostNow=1 737 511 595 476 000 000 ns HostLast=1 737 506 496 262 000 000 ns)
2025-01-22 10:06:45 (32217): Guest Log: 15:08:11.335535 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 099 197 522 000ns (GuestNow=1 737 511 605 492 108 000 ns GuestLast=1 737 506 506 294 586 000 ns fSetTimeLastLoop=true )
2025-01-22 10:07:14 (32217): Status Report: Job Duration: '864000.000000'
2025-01-22 10:07:14 (32217): Status Report: Elapsed Time: '55071.000000'
2025-01-22 10:07:14 (32217): Status Report: CPU Time: '41618.420000'
2025-01-22 10:41:30 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-22 10:41:50 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-22 11:46:37 (32217): Status Report: Job Duration: '864000.000000'
2025-01-22 11:46:37 (32217): Status Report: Elapsed Time: '61071.000000'
2025-01-22 11:46:37 (32217): Status Report: CPU Time: '46259.960000'
2025-01-22 12:58:23 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-22 12:58:43 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-22 13:26:01 (32217): Status Report: Job Duration: '864000.000000'
2025-01-22 13:26:01 (32217): Status Report: Elapsed Time: '67071.000000'
2025-01-22 13:26:01 (32217): Status Report: CPU Time: '50900.670000'
2025-01-22 13:47:37 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-23 02:44:14 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-23 02:44:15 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-23 02:44:35 (32217): Guest Log: 18:48:28.992686 timesync vgsvcTimeSyncWorker: Radical host time change: 46 626 973 000 000ns (HostNow=1 737 571 474 829 000 000 ns HostLast=1 737 524 847 856 000 000 ns)
2025-01-23 02:44:35 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-23 02:44:45 (32217): Guest Log: 18:48:38.993691 timesync vgsvcTimeSyncWorker: Radical guest time change: 46 661 680 896 000ns (GuestNow=1 737 571 484 830 073 000 ns GuestLast=1 737 524 823 149 177 000 ns fSetTimeLastLoop=true )
2025-01-23 04:02:03 (32217): Status Report: Job Duration: '864000.000000'
2025-01-23 04:02:03 (32217): Status Report: Elapsed Time: '73071.000000'
2025-01-23 04:02:03 (32217): Status Report: CPU Time: '55552.830000'
2025-01-23 04:45:02 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 19:30:55 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 19:31:02 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 19:31:22 (32217): Guest Log: 20:49:03.450768 timesync vgsvcTimeSyncWorker: Radical host time change: 139 582 138 000 000ns (HostNow=1 737 718 281 293 000 000 ns HostLast=1 737 578 699 155 000 000 ns)
2025-01-24 19:31:22 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 19:31:32 (32217): Guest Log: 20:49:13.477717 timesync vgsvcTimeSyncWorker: Radical guest time change: 139 582 032 851 000ns (GuestNow=1 737 718 291 319 970 000 ns GuestLast=1 737 578 709 287 119 000 ns fSetTimeLastLoop=true )
2025-01-24 20:27:20 (32217): Status Report: Job Duration: '864000.000000'
2025-01-24 20:27:20 (32217): Status Report: Elapsed Time: '79071.000000'
2025-01-24 20:27:20 (32217): Status Report: CPU Time: '60200.820000'
2025-01-24 22:06:23 (32217): Status Report: Job Duration: '864000.000000'
2025-01-24 22:06:23 (32217): Status Report: Elapsed Time: '85071.000000'
2025-01-24 22:06:23 (32217): Status Report: CPU Time: '64840.840000'
2025-01-24 22:23:48 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 22:24:08 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 23:26:42 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 23:27:03 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 23:46:08 (32217): Status Report: Job Duration: '864000.000000'
2025-01-24 23:46:08 (32217): Status Report: Elapsed Time: '91071.000000'
2025-01-24 23:46:08 (32217): Status Report: CPU Time: '69487.750000'
2025-01-25 01:25:10 (32217): Status Report: Job Duration: '864000.000000'
2025-01-25 01:25:10 (32217): Status Report: Elapsed Time: '97071.000000'
2025-01-25 01:25:10 (32217): Status Report: CPU Time: '74129.310000'
2025-01-25 02:27:57 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-25 02:28:17 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-25 03:04:34 (32217): Status Report: Job Duration: '864000.000000'
2025-01-25 03:04:34 (32217): Status Report: Elapsed Time: '103071.000000'
2025-01-25 03:04:34 (32217): Status Report: CPU Time: '78771.610000'
2025-01-25 03:09:15 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-25 03:09:36 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-25 04:43:58 (32217): Status Report: Job Duration: '864000.000000'
2025-01-25 04:43:58 (32217): Status Report: Elapsed Time: '109071.000000'
2025-01-25 04:43:58 (32217): Status Report: CPU Time: '83379.430000'
2025-01-25 06:15:52 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-25 06:16:12 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-25 06:23:21 (32217): Status Report: Job Duration: '864000.000000'
2025-01-25 06:23:21 (32217): Status Report: Elapsed Time: '115071.000000'
2025-01-25 06:23:21 (32217): Status Report: CPU Time: '88022.560000'
2025-01-25 06:58:11 (32217): VM state change detected. (old = 'running', new = 'paused')
2025-01-25 06:58:31 (32217): VM state change detected. (old = 'paused', new = 'running')
2025-01-25 07:47:45 (32217): Guest Log: job: run exitcode=0
2025-01-25 07:47:45 (32217): Guest Log: job: diskusage=99752
2025-01-25 07:47:45 (32217): Guest Log: job: logsize=76 k
2025-01-25 07:47:45 (32217): Guest Log: job: times=
2025-01-25 07:47:45 (32217): Guest Log: 0m0.027s 0m0.037s
2025-01-25 07:47:45 (32217): Guest Log: 1886m55.265s 26m8.298s
2025-01-25 07:47:45 (32217): Guest Log: job: cpuusage=114784
2025-01-25 07:47:49 (32217): Guest Log: 07:46:06 PST +08:00 2025-01-25: cranky: [INFO] Container 'runc' finished with status code 0.
2025-01-25 07:47:49 (32217): Guest Log: 07:46:06 PST +08:00 2025-01-25: cranky: [INFO] Preparing output.
2025-01-25 07:47:51 (32217): Guest Log: [INFO] Job Finished
2025-01-25 07:47:51 (32217): Guest Log: [INFO] Shutting Down.
2025-01-25 07:47:51 (32217): VM Completion File Detected.
2025-01-25 07:47:51 (32217): VM Completion Message: Job Finished
.
2025-01-25 07:47:51 (32217): Powering off VM.
2025-01-25 07:47:52 (32217): Successfully stopped VM.
2025-01-25 07:47:52 (32217): Deregistering VM. (boinc_9c612dd981d5e06c, slot#3)
2025-01-25 07:47:52 (32217): Removing network bandwidth throttle group from VM.
2025-01-25 07:47:52 (32217): Removing VM from VirtualBox.
2025-01-25 07:47:57 (32217): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN