Name Theory_2390-1104576-322_1
Workunit 200697546
Created 8 Jan 2023, 12:01:54 UTC
Sent 9 Jan 2023, 4:55:32 UTC
Report deadline 20 Jan 2023, 4:55:32 UTC
Received 21 Jan 2023, 14:05:01 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 10675689
Run time 3 days 11 hours 12 min 1 sec
CPU time 3 days 5 hours 15 min 40 sec
Validate state Valid
Credit 2,214.79
Device peak FLOPS 3.19 GFLOPS
Application version Theory Simulation v300.07 (vbox64_theory)
windows_x86_64
Peak working set size 86.01 MB
Peak swap size 77.97 MB
Peak disk usage 2.41 GB

Stderr output

<core_client_version>7.20.2</core_client_version>
<![CDATA[
<stderr_txt>

2023-01-16 15:11:39 (23640): Status Report: CPU Time: '158581.843750'
2023-01-16 15:11:39 (23640): Preference change detected
2023-01-16 15:11:39 (23640): Setting CPU throttle for VM. (90%)
2023-01-16 15:11:39 (23640): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-16 15:11:39 (23640): VM state change detected. (old = 'paused', new = 'running')
2023-01-16 15:11:40 (23640): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-16 15:11:40 (23640): Guest Log: BIOS: Booting from Hard Disk...
2023-01-16 15:11:43 (23640): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-16 15:11:43 (23640): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-16 15:12:04 (23640): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-16 15:12:04 (23640): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-16 15:12:08 (23640): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-16 15:12:08 (23640): Guest Log: 00:00:00.000112 main     Log opened 2023-01-16T22:12:08.194060000Z
2023-01-16 15:12:08 (23640): Guest Log: 00:00:00.000321 main     OS Product: Linux
2023-01-16 15:12:08 (23640): Guest Log: 00:00:00.000364 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-16 15:12:08 (23640): Guest Log: 00:00:00.000397 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-16 15:12:08 (23640): Guest Log: 00:00:00.000428 main     Executable: /usr/sbin/VBoxService
2023-01-16 15:12:08 (23640): Guest Log: 00:00:00.000429 main     Process ID: 3218
2023-01-16 15:12:08 (23640): Guest Log: 00:00:00.000429 main     Package type: LINUX_64BITS_GENERIC
2023-01-16 15:12:08 (23640): Guest Log: 00:00:00.003238 main     5.2.6 r120293 started. Verbose level = 0
2023-01-16 15:12:34 (23640): Guest Log: 17:12:34 EST -05:00 2023-01-16: cranky: [INFO] Detected Theory App
2023-01-16 15:12:34 (23640): Guest Log: 17:12:34 EST -05:00 2023-01-16: cranky: [INFO] Checking CVMFS.
2023-01-16 15:12:35 (23640): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-16 15:12:35 (23640): 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
2023-01-16 15:12:35 (23640): Guest Log: 2.5.2.0 4089 0 27176 24830 3 1 1268557 4096000 0 65024 0 0 n/a 5 4 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/sft.cern.ch DIRECT 1
2023-01-16 15:12:36 (23640): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-16 15:12:37 (23640): 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
2023-01-16 15:12:37 (23640): Guest Log: 2.5.2.0 4058 0 27516 20277 3 2 1268557 4096000 0 65024 0 2 0 5671 6287 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-16 15:12:37 (23640): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-16 15:12:38 (23640): 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
2023-01-16 15:12:38 (23640): Guest Log: 2.5.2.0 4081 0 25180 268 3 1 1268557 4096000 0 65024 0 0 n/a 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-16 15:12:39 (23640): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-16 15:12:39 (23640): 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
2023-01-16 15:12:39 (23640): Guest Log: 2.5.2.0 4126 0 34240 14376 3 1 1268557 4096000 0 65024 0 0 n/a 3325 4352 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-16 15:12:39 (23640): Guest Log: 17:12:39 EST -05:00 2023-01-16: cranky: [INFO] Checking runc.
2023-01-16 15:12:40 (23640): Guest Log: 17:12:39 EST -05:00 2023-01-16: cranky: [INFO] Creating the filesystem.
2023-01-16 15:12:40 (23640): Guest Log: 17:12:39 EST -05:00 2023-01-16: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-16 15:12:40 (23640): Guest Log: 17:12:39 EST -05:00 2023-01-16: cranky: [INFO] Updating config.json.
2023-01-16 15:12:40 (23640): Guest Log: 17:12:39 EST -05:00 2023-01-16: cranky: [INFO] Running Container 'runc'.
2023-01-16 15:12:40 (23640): Guest Log: job: htmld=/shared/html/job
2023-01-16 15:12:40 (23640): Guest Log: job: unpack exitcode=0
2023-01-16 15:12:42 (23640): Guest Log: 17:12:42 EST -05:00 2023-01-16: cranky: [INFO] ===> [runRivet] Mon Jan 16 22:12:40 UTC 2023 [boinc pp jets 13000 170,-,2960 - pythia8 8.301 tune-4cx 100000 322]
2023-01-16 15:15:05 (23640): VM state change detected. (old = 'running', new = 'paused')
2023-01-16 18:04:25 (23640): VM state change detected. (old = 'paused', new = 'running')
2023-01-16 18:04:29 (23640): Guest Log: 00:03:00.173251 timesync vgsvcTimeSyncWorker: Radical host time change: 10 170 599 000 000ns (HostNow=1 673 917 468 980 000 000 ns HostLast=1 673 907 298 381 000 000 ns)
2023-01-16 18:04:39 (23640): Guest Log: 00:03:10.219080 timesync vgsvcTimeSyncWorker: Radical guest time change: 10 170 810 422 000ns (GuestNow=1 673 917 479 074 528 000 ns GuestLast=1 673 907 308 264 106 000 ns fSetTimeLastLoop=true )
2023-01-16 18:25:21 (23640): VM state change detected. (old = 'running', new = 'paused')
2023-01-16 18:34:08 (23640): VM state change detected. (old = 'paused', new = 'running')
2023-01-16 19:49:54 (23640): Status Report: Job Duration: '864000.000000'
2023-01-16 19:49:54 (23640): Status Report: Elapsed Time: '182317.988926'
2023-01-16 19:49:54 (23640): Status Report: CPU Time: '163952.250000'
2023-01-16 21:30:00 (23640): Status Report: Job Duration: '864000.000000'
2023-01-16 21:30:00 (23640): Status Report: Elapsed Time: '188317.988926'
2023-01-16 21:30:00 (23640): Status Report: CPU Time: '169365.671875'
2023-01-16 23:10:06 (23640): Status Report: Job Duration: '864000.000000'
2023-01-16 23:10:06 (23640): Status Report: Elapsed Time: '194317.988926'
2023-01-16 23:10:06 (23640): Status Report: CPU Time: '174777.984375'
2023-01-16 23:59:39 (23640): Stopping VM.
2023-01-16 23:59:39 (23640): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_9708164831f3b68c" savestate
Output:

2023-01-16 23:59:39 (23640): VM did not stop when requested.
2023-01-16 23:59:39 (23640): VM was successfully terminated.
2023-01-17 11:42:23 (14968): Detected: vboxwrapper 26206
2023-01-17 11:42:23 (14968): Detected: BOINC client v7.20.2
2023-01-17 11:42:26 (14968): Detected: VirtualBox VboxManage Interface (Version: 7.0.4)
2023-01-17 11:42:27 (14968): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-17 11:42:27 (14968): Error in registration for VM: 0
Arguments:
VBoxManage -q showvminfo "boinc_9708164831f3b68c" --machinereadable 
Output:

2023-01-17 11:42:29 (14968): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-17 11:42:29 (14968): Guest Log: CPUID EDX: 0x178bfbff
2023-01-17 11:42:29 (14968): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-17 11:42:29 (14968): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-17 11:42:29 (14968): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-17 11:42:29 (14968): Guest Log: BIOS: Booting from Hard Disk...
2023-01-17 11:42:29 (14968): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-17 11:42:29 (14968): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-17 11:42:29 (14968): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-17 11:42:29 (14968): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-17 11:42:29 (14968): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-17 11:42:29 (14968): Guest Log: 00:00:00.000112 main     Log opened 2023-01-16T22:12:08.194060000Z
2023-01-17 11:42:29 (14968): Guest Log: 00:00:00.000321 main     OS Product: Linux
2023-01-17 11:42:29 (14968): Guest Log: 00:00:00.000364 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-17 11:42:29 (14968): Guest Log: 00:00:00.000397 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-17 11:42:29 (14968): Guest Log: 00:00:00.000428 main     Executable: /usr/sbin/VBoxService
2023-01-17 11:42:29 (14968): Guest Log: 00:00:00.000429 main     Process ID: 3218
2023-01-17 11:42:29 (14968): Guest Log: 00:00:00.000429 main     Package type: LINUX_64BITS_GENERIC
2023-01-17 11:42:29 (14968): Guest Log: 00:00:00.003238 main     5.2.6 r120293 started. Verbose level = 0
2023-01-17 11:42:29 (14968): Guest Log: 17:12:34 EST -05:00 2023-01-16: cranky: [INFO] Detected Theory App
2023-01-17 11:42:29 (14968): Guest Log: 17:12:34 EST -05:00 2023-01-16: cranky: [INFO] Checking CVMFS.
2023-01-17 11:42:29 (14968): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-17 11:42:29 (14968): 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
2023-01-17 11:42:29 (14968): Guest Log: 2.5.2.0 4089 0 27176 24830 3 1 1268557 4096000 0 65024 0 0 n/a 5 4 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/sft.cern.ch DIRECT 1
2023-01-17 11:42:29 (14968): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-17 11:42:29 (14968): 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
2023-01-17 11:42:29 (14968): Guest Log: 2.5.2.0 4058 0 27516 20277 3 2 1268557 4096000 0 65024 0 2 0 5671 6287 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-17 11:42:29 (14968): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-17 11:42:29 (14968): 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
2023-01-17 11:42:29 (14968): Guest Log: 2.5.2.0 4081 0 25180 268 3 1 1268557 4096000 0 65024 0 0 n/a 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-17 11:42:29 (14968): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-17 11:42:29 (14968): 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
2023-01-17 11:42:29 (14968): Guest Log: 2.5.2.0 4126 0 34240 14376 3 1 1268557 4096000 0 65024 0 0 n/a 3325 4352 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-17 11:42:29 (14968): Guest Log: 17:12:39 EST -05:00 2023-01-16: cranky: [INFO] Checking runc.
2023-01-17 11:42:29 (14968): Guest Log: 17:12:39 EST -05:00 2023-01-16: cranky: [INFO] Creating the filesystem.
2023-01-17 11:42:29 (14968): Guest Log: 17:12:39 EST -05:00 2023-01-16: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-17 11:42:29 (14968): Guest Log: 17:12:39 EST -05:00 2023-01-16: cranky: [INFO] Updating config.json.
2023-01-17 11:42:29 (14968): Guest Log: 17:12:39 EST -05:00 2023-01-16: cranky: [INFO] Running Container 'runc'.
2023-01-17 11:42:29 (14968): Guest Log: job: htmld=/shared/html/job
2023-01-17 11:42:29 (14968): Guest Log: job: unpack exitcode=0
2023-01-17 11:42:29 (14968): Guest Log: 17:12:42 EST -05:00 2023-01-16: cranky: [INFO] ===> [runRivet] Mon Jan 16 22:12:40 UTC 2023 [boinc pp jets 13000 170,-,2960 - pythia8 8.301 tune-4cx 100000 322]
2023-01-17 11:42:29 (14968): Guest Log: 00:03:00.173251 timesync vgsvcTimeSyncWorker: Radical host time change: 10 170 599 000 000ns (HostNow=1 673 917 468 980 000 000 ns HostLast=1 673 907 298 381 000 000 ns)
2023-01-17 11:42:29 (14968): Guest Log: 00:03:10.219080 timesync vgsvcTimeSyncWorker: Radical guest time change: 10 170 810 422 000ns (GuestNow=1 673 917 479 074 528 000 ns GuestLast=1 673 907 308 264 106 000 ns fSetTimeLastLoop=true )
2023-01-17 11:42:29 (14968): Starting VM using VBoxManage interface. (boinc_9708164831f3b68c, slot#28)
2023-01-17 11:43:08 (14968): Successfully started VM. (PID = '30492')
2023-01-17 11:43:08 (14968): Reporting VM Process ID to BOINC.
2023-01-17 11:43:08 (14968): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-17 11:43:08 (14968): Guest Log: CPUID EDX: 0x178bfbff
2023-01-17 11:43:08 (14968): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-17 11:43:08 (14968): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-17 11:43:08 (14968): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-17 11:43:08 (14968): Detected: Web Application Enabled (http://localhost:58435)
2023-01-17 11:43:09 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 11:43:14 (14968): Status Report: Job Duration: '864000.000000'
2023-01-17 11:43:14 (14968): Status Report: Elapsed Time: '197286.988926'
2023-01-17 11:43:14 (14968): Status Report: CPU Time: '177456.265625'
2023-01-17 11:43:14 (14968): Preference change detected
2023-01-17 11:43:14 (14968): Setting CPU throttle for VM. (90%)
2023-01-17 11:43:14 (14968): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-17 11:43:14 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 11:43:15 (14968): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-17 11:43:15 (14968): Guest Log: BIOS: Booting from Hard Disk...
2023-01-17 11:43:18 (14968): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-17 11:43:18 (14968): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-17 11:43:45 (14968): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-17 11:43:45 (14968): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-17 11:43:49 (14968): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-17 11:43:49 (14968): Guest Log: 00:00:00.000199 main     Log opened 2023-01-17T18:43:49.791003000Z
2023-01-17 11:43:49 (14968): Guest Log: 00:00:00.000374 main     OS Product: Linux
2023-01-17 11:43:49 (14968): Guest Log: 00:00:00.000441 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-17 11:43:49 (14968): Guest Log: 00:00:00.000482 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-17 11:43:49 (14968): Guest Log: 00:00:00.000537 main     Executable: /usr/sbin/VBoxService
2023-01-17 11:43:49 (14968): Guest Log: 00:00:00.000538 main     Process ID: 3218
2023-01-17 11:43:49 (14968): Guest Log: 00:00:00.000539 main     Package type: LINUX_64BITS_GENERIC
2023-01-17 11:43:49 (14968): Guest Log: 00:00:00.003487 main     5.2.6 r120293 started. Verbose level = 0
2023-01-17 11:44:16 (14968): Guest Log: 13:44:16 EST -05:00 2023-01-17: cranky: [INFO] Detected Theory App
2023-01-17 11:44:16 (14968): Guest Log: 13:44:16 EST -05:00 2023-01-17: cranky: [INFO] Checking CVMFS.
2023-01-17 11:44:17 (14968): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-17 11:44:18 (14968): 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
2023-01-17 11:44:18 (14968): Guest Log: 2.5.2.0 4079 0 27180 24837 3 1 1292274 4096000 0 65024 0 0 n/a 5 3 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2023-01-17 11:44:18 (14968): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-17 11:44:19 (14968): 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
2023-01-17 11:44:19 (14968): Guest Log: 2.5.2.0 4104 0 27716 20283 3 2 1292274 4096000 0 65024 0 2 0 5671 2223 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-17 11:44:19 (14968): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-17 11:44:20 (14968): 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
2023-01-17 11:44:20 (14968): Guest Log: 2.5.2.0 4124 0 27224 268 3 1 1292274 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-17 11:44:21 (14968): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-17 11:44:21 (14968): 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
2023-01-17 11:44:21 (14968): Guest Log: 2.5.2.0 4058 0 34636 14387 3 1 1292274 4096000 0 65024 0 0 n/a 3331 1226 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-17 11:44:21 (14968): Guest Log: 13:44:21 EST -05:00 2023-01-17: cranky: [INFO] Checking runc.
2023-01-17 11:44:21 (14968): Guest Log: 13:44:21 EST -05:00 2023-01-17: cranky: [INFO] Creating the filesystem.
2023-01-17 11:44:22 (14968): Guest Log: 13:44:21 EST -05:00 2023-01-17: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-17 11:44:22 (14968): Guest Log: 13:44:21 EST -05:00 2023-01-17: cranky: [INFO] Updating config.json.
2023-01-17 11:44:22 (14968): Guest Log: 13:44:22 EST -05:00 2023-01-17: cranky: [INFO] Running Container 'runc'.
2023-01-17 11:44:24 (14968): Guest Log: job: htmld=/shared/html/job
2023-01-17 11:44:24 (14968): Guest Log: job: unpack exitcode=0
2023-01-17 11:44:24 (14968): Guest Log: 13:44:24 EST -05:00 2023-01-17: cranky: [INFO] ===> [runRivet] Tue Jan 17 18:44:23 UTC 2023 [boinc pp jets 13000 170,-,2960 - pythia8 8.301 tune-4cx 100000 322]
2023-01-17 12:03:27 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 12:32:29 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 13:52:25 (14968): Status Report: Job Duration: '864000.000000'
2023-01-17 13:52:25 (14968): Status Report: Elapsed Time: '203287.070217'
2023-01-17 13:52:25 (14968): Status Report: CPU Time: '182811.031250'
2023-01-17 14:28:47 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 14:34:07 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 14:48:36 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 15:02:05 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 15:08:11 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 15:18:20 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 15:20:32 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 15:34:52 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 15:38:15 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 15:45:04 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 15:46:36 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 15:52:18 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 16:05:45 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 16:45:06 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 16:45:15 (14968): Guest Log: 02:57:16.158789 timesync vgsvcTimeSyncWorker: Radical host time change: 2 370 581 000 000ns (HostNow=1 673 999 114 445 000 000 ns HostLast=1 673 996 743 864 000 000 ns)
2023-01-17 16:45:25 (14968): Guest Log: 02:57:26.203254 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 713 065 513 000ns (GuestNow=1 673 999 124 940 557 000 ns GuestLast=1 673 996 411 875 044 000 ns fSetTimeLastLoop=true )
2023-01-17 16:46:12 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 17:11:19 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 17:12:17 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 17:23:08 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 17:35:46 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 17:41:04 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 17:42:37 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 17:55:02 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 18:01:34 (14968): Status Report: Job Duration: '864000.000000'
2023-01-17 18:01:34 (14968): Status Report: Elapsed Time: '209287.070217'
2023-01-17 18:01:34 (14968): Status Report: CPU Time: '188232.859375'
2023-01-17 18:09:29 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 18:14:47 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 19:00:34 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 19:08:09 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 19:50:09 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 20:03:43 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 20:05:22 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 20:12:55 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 20:15:45 (14968): Status Report: Job Duration: '864000.000000'
2023-01-17 20:15:45 (14968): Status Report: Elapsed Time: '215287.070217'
2023-01-17 20:15:45 (14968): Status Report: CPU Time: '193653.750000'
2023-01-17 20:31:12 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 20:41:58 (14968): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 22:06:38 (14968): Status Report: Job Duration: '864000.000000'
2023-01-17 22:06:38 (14968): Status Report: Elapsed Time: '221287.070217'
2023-01-17 22:06:38 (14968): Status Report: CPU Time: '199059.687500'
2023-01-17 23:46:44 (14968): Status Report: Job Duration: '864000.000000'
2023-01-17 23:46:44 (14968): Status Report: Elapsed Time: '227287.070217'
2023-01-17 23:46:44 (14968): Status Report: CPU Time: '204470.484375'
2023-01-17 23:57:18 (14968): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 23:59:37 (14968): Stopping VM.
2023-01-17 23:59:38 (14968): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_9708164831f3b68c" savestate
Output:

2023-01-17 23:59:38 (14968): VM did not stop when requested.
2023-01-17 23:59:38 (14968): VM was successfully terminated.
2023-01-18 06:08:09 (8524): Detected: vboxwrapper 26206
2023-01-18 06:08:09 (8524): Detected: BOINC client v7.20.2
2023-01-18 06:08:12 (8524): Detected: VirtualBox VboxManage Interface (Version: 7.0.4)
2023-01-18 06:08:13 (8524): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-18 06:08:14 (8524): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-18 06:08:14 (8524): Guest Log: CPUID EDX: 0x178bfbff
2023-01-18 06:08:14 (8524): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-18 06:08:14 (8524): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-18 06:08:14 (8524): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-18 06:08:14 (8524): Guest Log: BIOS: Booting from Hard Disk...
2023-01-18 06:08:14 (8524): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-18 06:08:14 (8524): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-18 06:08:14 (8524): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-18 06:08:14 (8524): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-18 06:08:14 (8524): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-18 06:08:14 (8524): Guest Log: 00:00:00.000199 main     Log opened 2023-01-17T18:43:49.791003000Z
2023-01-18 06:08:14 (8524): Guest Log: 00:00:00.000374 main     OS Product: Linux
2023-01-18 06:08:14 (8524): Guest Log: 00:00:00.000441 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-18 06:08:14 (8524): Guest Log: 00:00:00.000482 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-18 06:08:14 (8524): Guest Log: 00:00:00.000537 main     Executable: /usr/sbin/VBoxService
2023-01-18 06:08:14 (8524): Guest Log: 00:00:00.000538 main     Process ID: 3218
2023-01-18 06:08:14 (8524): Guest Log: 00:00:00.000539 main     Package type: LINUX_64BITS_GENERIC
2023-01-18 06:08:14 (8524): Guest Log: 00:00:00.003487 main     5.2.6 r120293 started. Verbose level = 0
2023-01-18 06:08:14 (8524): Guest Log: 13:44:16 EST -05:00 2023-01-17: cranky: [INFO] Detected Theory App
2023-01-18 06:08:14 (8524): Guest Log: 13:44:16 EST -05:00 2023-01-17: cranky: [INFO] Checking CVMFS.
2023-01-18 06:08:14 (8524): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-18 06:08:14 (8524): 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
2023-01-18 06:08:14 (8524): Guest Log: 2.5.2.0 4079 0 27180 24837 3 1 1292274 4096000 0 65024 0 0 n/a 5 3 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2023-01-18 06:08:14 (8524): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-18 06:08:14 (8524): 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
2023-01-18 06:08:14 (8524): Guest Log: 2.5.2.0 4104 0 27716 20283 3 2 1292274 4096000 0 65024 0 2 0 5671 2223 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-18 06:08:14 (8524): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-18 06:08:14 (8524): 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
2023-01-18 06:08:14 (8524): Guest Log: 2.5.2.0 4124 0 27224 268 3 1 1292274 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-18 06:08:14 (8524): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-18 06:08:14 (8524): 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
2023-01-18 06:08:14 (8524): Guest Log: 2.5.2.0 4058 0 34636 14387 3 1 1292274 4096000 0 65024 0 0 n/a 3331 1226 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-18 06:08:14 (8524): Guest Log: 13:44:21 EST -05:00 2023-01-17: cranky: [INFO] Checking runc.
2023-01-18 06:08:14 (8524): Guest Log: 13:44:21 EST -05:00 2023-01-17: cranky: [INFO] Creating the filesystem.
2023-01-18 06:08:14 (8524): Guest Log: 13:44:21 EST -05:00 2023-01-17: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-18 06:08:14 (8524): Guest Log: 13:44:21 EST -05:00 2023-01-17: cranky: [INFO] Updating config.json.
2023-01-18 06:08:14 (8524): Guest Log: 13:44:22 EST -05:00 2023-01-17: cranky: [INFO] Running Container 'runc'.
2023-01-18 06:08:14 (8524): Guest Log: job: htmld=/shared/html/job
2023-01-18 06:08:14 (8524): Guest Log: job: unpack exitcode=0
2023-01-18 06:08:14 (8524): Guest Log: 13:44:24 EST -05:00 2023-01-17: cranky: [INFO] ===> [runRivet] Tue Jan 17 18:44:23 UTC 2023 [boinc pp jets 13000 170,-,2960 - pythia8 8.301 tune-4cx 100000 322]
2023-01-18 06:08:14 (8524): Guest Log: 02:57:16.158789 timesync vgsvcTimeSyncWorker: Radical host time change: 2 370 581 000 000ns (HostNow=1 673 999 114 445 000 000 ns HostLast=1 673 996 743 864 000 000 ns)
2023-01-18 06:08:14 (8524): Guest Log: 02:57:26.203254 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 713 065 513 000ns (GuestNow=1 673 999 124 940 557 000 ns GuestLast=1 673 996 411 875 044 000 ns fSetTimeLastLoop=true )
2023-01-18 06:08:14 (8524): Starting VM using VBoxManage interface. (boinc_9708164831f3b68c, slot#28)
2023-01-18 06:08:35 (8524): Successfully started VM. (PID = '31296')
2023-01-18 06:08:35 (8524): Reporting VM Process ID to BOINC.
2023-01-18 06:08:35 (8524): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-18 06:08:35 (8524): Guest Log: CPUID EDX: 0x178bfbff
2023-01-18 06:08:35 (8524): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-18 06:08:35 (8524): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-18 06:08:35 (8524): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-18 06:08:35 (8524): Detected: Web Application Enabled (http://localhost:58435)
2023-01-18 06:08:36 (8524): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 06:08:41 (8524): Status Report: Job Duration: '864000.000000'
2023-01-18 06:08:41 (8524): Status Report: Elapsed Time: '227918.070217'
2023-01-18 06:08:41 (8524): Status Report: CPU Time: '205038.734375'
2023-01-18 06:08:41 (8524): Preference change detected
2023-01-18 06:08:41 (8524): Setting CPU throttle for VM. (90%)
2023-01-18 06:08:41 (8524): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-18 06:08:42 (8524): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 06:08:43 (8524): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-18 06:08:43 (8524): Guest Log: BIOS: Booting from Hard Disk...
2023-01-18 06:08:46 (8524): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-18 06:08:46 (8524): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-18 06:09:08 (8524): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-18 06:09:08 (8524): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-18 06:09:12 (8524): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-18 06:09:12 (8524): Guest Log: 00:00:00.000297 main     Log opened 2023-01-18T13:09:12.100369000Z
2023-01-18 06:09:12 (8524): Guest Log: 00:00:00.000499 main     OS Product: Linux
2023-01-18 06:09:12 (8524): Guest Log: 00:00:00.000619 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-18 06:09:12 (8524): Guest Log: 00:00:00.000675 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-18 06:09:12 (8524): Guest Log: 00:00:00.000725 main     Executable: /usr/sbin/VBoxService
2023-01-18 06:09:12 (8524): Guest Log: 00:00:00.000726 main     Process ID: 3215
2023-01-18 06:09:12 (8524): Guest Log: 00:00:00.000727 main     Package type: LINUX_64BITS_GENERIC
2023-01-18 06:09:12 (8524): Guest Log: 00:00:00.004039 main     5.2.6 r120293 started. Verbose level = 0
2023-01-18 06:09:35 (8524): Guest Log: 08:09:35 EST -05:00 2023-01-18: cranky: [INFO] Detected Theory App
2023-01-18 06:09:35 (8524): Guest Log: 08:09:35 EST -05:00 2023-01-18: cranky: [INFO] Checking CVMFS.
2023-01-18 06:09:36 (8524): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-18 06:09:37 (8524): 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
2023-01-18 06:09:37 (8524): Guest Log: 2.5.2.0 4096 0 28236 24844 3 1 1313644 4096000 0 65024 0 0 n/a 5 8 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2023-01-18 06:09:37 (8524): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-18 06:09:38 (8524): 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
2023-01-18 06:09:38 (8524): Guest Log: 2.5.2.0 4070 0 27660 20289 3 2 1313644 4096000 0 65024 0 2 0 5673 2971 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-18 06:09:38 (8524): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-18 06:09:39 (8524): 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
2023-01-18 06:09:39 (8524): Guest Log: 2.5.2.0 4049 0 25168 268 3 1 1313644 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-18 06:09:40 (8524): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-18 06:09:40 (8524): 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
2023-01-18 06:09:40 (8524): Guest Log: 2.5.2.0 4119 0 33320 14393 3 1 1313644 4096000 0 65024 0 0 n/a 3335 4446 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-18 06:09:40 (8524): Guest Log: 08:09:40 EST -05:00 2023-01-18: cranky: [INFO] Checking runc.
2023-01-18 06:09:41 (8524): Guest Log: 08:09:40 EST -05:00 2023-01-18: cranky: [INFO] Creating the filesystem.
2023-01-18 06:09:41 (8524): Guest Log: 08:09:40 EST -05:00 2023-01-18: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-18 06:09:41 (8524): Guest Log: 08:09:40 EST -05:00 2023-01-18: cranky: [INFO] Updating config.json.
2023-01-18 06:09:41 (8524): Guest Log: 08:09:40 EST -05:00 2023-01-18: cranky: [INFO] Running Container 'runc'.
2023-01-18 06:09:41 (8524): Guest Log: job: htmld=/shared/html/job
2023-01-18 06:09:42 (8524): Guest Log: job: unpack exitcode=0
2023-01-18 06:09:43 (8524): Guest Log: 08:09:42 EST -05:00 2023-01-18: cranky: [INFO] ===> [runRivet] Wed Jan 18 13:09:41 UTC 2023 [boinc pp jets 13000 170,-,2960 - pythia8 8.301 tune-4cx 100000 322]
2023-01-18 07:48:47 (8524): Status Report: Job Duration: '864000.000000'
2023-01-18 07:48:47 (8524): Status Report: Elapsed Time: '233918.070217'
2023-01-18 07:48:47 (8524): Status Report: CPU Time: '210413.500000'
2023-01-18 07:54:29 (8524): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 07:59:54 (8524): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 09:15:26 (8524): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 09:24:35 (8524): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 09:43:30 (8524): Status Report: Job Duration: '864000.000000'
2023-01-18 09:43:30 (8524): Status Report: Elapsed Time: '239918.070217'
2023-01-18 09:43:30 (8524): Status Report: CPU Time: '215833.328125'
2023-01-18 10:35:36 (8524): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 10:40:49 (8524): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 11:28:50 (8524): Status Report: Job Duration: '864000.000000'
2023-01-18 11:28:50 (8524): Status Report: Elapsed Time: '245918.070217'
2023-01-18 11:28:50 (8524): Status Report: CPU Time: '221244.640625'
2023-01-18 11:38:25 (8524): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 11:45:37 (8524): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 12:31:56 (8524): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 12:37:36 (8524): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 12:44:57 (8524): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 12:49:57 (8524): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 13:26:52 (8524): Status Report: Job Duration: '864000.000000'
2023-01-18 13:26:52 (8524): Status Report: Elapsed Time: '251918.070217'
2023-01-18 13:26:52 (8524): Status Report: CPU Time: '226665.796875'
2023-01-18 14:48:16 (8524): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 21:16:55 (8524): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 21:17:04 (8524): Guest Log: 08:01:43.094963 timesync vgsvcTimeSyncWorker: Radical host time change: 23 328 805 000 000ns (HostNow=1 674 101 824 350 000 000 ns HostLast=1 674 078 495 545 000 000 ns)
2023-01-18 21:17:14 (8524): Guest Log: 08:01:53.117484 timesync vgsvcTimeSyncWorker: Radical guest time change: 23 965 531 639 000ns (GuestNow=1 674 101 834 397 697 000 ns GuestLast=1 674 077 868 866 058 000 ns fSetTimeLastLoop=true )
2023-01-18 21:35:38 (8524): Status Report: Job Duration: '864000.000000'
2023-01-18 21:35:38 (8524): Status Report: Elapsed Time: '257918.070217'
2023-01-18 21:35:38 (8524): Status Report: CPU Time: '232073.968750'
2023-01-18 23:15:02 (8524): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 23:59:39 (8524): Stopping VM.
2023-01-18 23:59:39 (8524): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_9708164831f3b68c" savestate
Output:

2023-01-18 23:59:39 (8524): VM did not stop when requested.
2023-01-18 23:59:39 (8524): VM was successfully terminated.
2023-01-19 18:43:58 (26868): Detected: vboxwrapper 26206
2023-01-19 18:43:58 (26868): Detected: BOINC client v7.20.2
2023-01-19 18:43:58 (26868): Detected: VirtualBox VboxManage Interface (Version: 7.0.4)
2023-01-19 18:43:59 (26868): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-19 18:43:59 (26868): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-19 18:43:59 (26868): Guest Log: CPUID EDX: 0x178bfbff
2023-01-19 18:43:59 (26868): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-19 18:43:59 (26868): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-19 18:43:59 (26868): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-19 18:43:59 (26868): Guest Log: BIOS: Booting from Hard Disk...
2023-01-19 18:43:59 (26868): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-19 18:43:59 (26868): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-19 18:43:59 (26868): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-19 18:43:59 (26868): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-19 18:43:59 (26868): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-19 18:43:59 (26868): Guest Log: 00:00:00.000297 main     Log opened 2023-01-18T13:09:12.100369000Z
2023-01-19 18:43:59 (26868): Guest Log: 00:00:00.000499 main     OS Product: Linux
2023-01-19 18:43:59 (26868): Guest Log: 00:00:00.000619 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-19 18:43:59 (26868): Guest Log: 00:00:00.000675 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-19 18:43:59 (26868): Guest Log: 00:00:00.000725 main     Executable: /usr/sbin/VBoxService
2023-01-19 18:43:59 (26868): Guest Log: 00:00:00.000726 main     Process ID: 3215
2023-01-19 18:43:59 (26868): Guest Log: 00:00:00.000727 main     Package type: LINUX_64BITS_GENERIC
2023-01-19 18:43:59 (26868): Guest Log: 00:00:00.004039 main     5.2.6 r120293 started. Verbose level = 0
2023-01-19 18:43:59 (26868): Guest Log: 08:09:35 EST -05:00 2023-01-18: cranky: [INFO] Detected Theory App
2023-01-19 18:43:59 (26868): Guest Log: 08:09:35 EST -05:00 2023-01-18: cranky: [INFO] Checking CVMFS.
2023-01-19 18:43:59 (26868): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-19 18:43:59 (26868): 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
2023-01-19 18:43:59 (26868): Guest Log: 2.5.2.0 4096 0 28236 24844 3 1 1313644 4096000 0 65024 0 0 n/a 5 8 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2023-01-19 18:43:59 (26868): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-19 18:43:59 (26868): 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
2023-01-19 18:43:59 (26868): Guest Log: 2.5.2.0 4070 0 27660 20289 3 2 1313644 4096000 0 65024 0 2 0 5673 2971 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-19 18:43:59 (26868): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-19 18:43:59 (26868): 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
2023-01-19 18:43:59 (26868): Guest Log: 2.5.2.0 4049 0 25168 268 3 1 1313644 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-19 18:43:59 (26868): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-19 18:43:59 (26868): 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
2023-01-19 18:43:59 (26868): Guest Log: 2.5.2.0 4119 0 33320 14393 3 1 1313644 4096000 0 65024 0 0 n/a 3335 4446 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-19 18:43:59 (26868): Guest Log: 08:09:40 EST -05:00 2023-01-18: cranky: [INFO] Checking runc.
2023-01-19 18:43:59 (26868): Guest Log: 08:09:40 EST -05:00 2023-01-18: cranky: [INFO] Creating the filesystem.
2023-01-19 18:43:59 (26868): Guest Log: 08:09:40 EST -05:00 2023-01-18: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-19 18:43:59 (26868): Guest Log: 08:09:40 EST -05:00 2023-01-18: cranky: [INFO] Updating config.json.
2023-01-19 18:43:59 (26868): Guest Log: 08:09:40 EST -05:00 2023-01-18: cranky: [INFO] Running Container 'runc'.
2023-01-19 18:43:59 (26868): Guest Log: job: htmld=/shared/html/job
2023-01-19 18:43:59 (26868): Guest Log: job: unpack exitcode=0
2023-01-19 18:43:59 (26868): Guest Log: 08:09:42 EST -05:00 2023-01-18: cranky: [INFO] ===> [runRivet] Wed Jan 18 13:09:41 UTC 2023 [boinc pp jets 13000 170,-,2960 - pythia8 8.301 tune-4cx 100000 322]
2023-01-19 18:43:59 (26868): Guest Log: 08:01:43.094963 timesync vgsvcTimeSyncWorker: Radical host time change: 23 328 805 000 000ns (HostNow=1 674 101 824 350 000 000 ns HostLast=1 674 078 495 545 000 000 ns)
2023-01-19 18:43:59 (26868): Guest Log: 08:01:53.117484 timesync vgsvcTimeSyncWorker: Radical guest time change: 23 965 531 639 000ns (GuestNow=1 674 101 834 397 697 000 ns GuestLast=1 674 077 868 866 058 000 ns fSetTimeLastLoop=true )
2023-01-19 18:43:59 (26868): Starting VM using VBoxManage interface. (boinc_9708164831f3b68c, slot#28)
2023-01-19 18:44:11 (26868): Successfully started VM. (PID = '18552')
2023-01-19 18:44:11 (26868): Reporting VM Process ID to BOINC.
2023-01-19 18:44:11 (26868): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-19 18:44:11 (26868): Guest Log: CPUID EDX: 0x178bfbff
2023-01-19 18:44:11 (26868): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-19 18:44:11 (26868): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-19 18:44:11 (26868): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-19 18:44:11 (26868): Detected: Web Application Enabled (http://localhost:58435)
2023-01-19 18:44:11 (26868): Status Report: Job Duration: '864000.000000'
2023-01-19 18:44:11 (26868): Status Report: Elapsed Time: '263873.070217'
2023-01-19 18:44:11 (26868): Status Report: CPU Time: '237451.734375'
2023-01-19 18:44:11 (26868): Preference change detected
2023-01-19 18:44:11 (26868): Setting CPU throttle for VM. (90%)
2023-01-19 18:44:11 (26868): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-19 18:44:13 (26868): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-19 18:44:13 (26868): Guest Log: BIOS: Booting from Hard Disk...
2023-01-19 18:44:15 (26868): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-19 18:44:15 (26868): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-19 18:44:41 (26868): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-19 18:44:41 (26868): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-19 18:44:45 (26868): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-19 18:44:45 (26868): Guest Log: 00:00:00.000235 main     Log opened 2023-01-20T01:44:51.094327000Z
2023-01-19 18:44:45 (26868): Guest Log: 00:00:00.000423 main     OS Product: Linux
2023-01-19 18:44:45 (26868): Guest Log: 00:00:00.000465 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-19 18:44:45 (26868): Guest Log: 00:00:00.000498 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-19 18:44:45 (26868): Guest Log: 00:00:00.000544 main     Executable: /usr/sbin/VBoxService
2023-01-19 18:44:45 (26868): Guest Log: 00:00:00.000545 main     Process ID: 3215
2023-01-19 18:44:45 (26868): Guest Log: 00:00:00.000546 main     Package type: LINUX_64BITS_GENERIC
2023-01-19 18:44:45 (26868): Guest Log: 00:00:00.003845 main     5.2.6 r120293 started. Verbose level = 0
2023-01-19 18:45:08 (26868): Guest Log: 20:45:14 EST -05:00 2023-01-19: cranky: [INFO] Detected Theory App
2023-01-19 18:45:08 (26868): Guest Log: 20:45:14 EST -05:00 2023-01-19: cranky: [INFO] Checking CVMFS.
2023-01-19 18:45:09 (26868): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-19 18:45:09 (26868): 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
2023-01-19 18:45:09 (26868): Guest Log: 2.5.2.0 4097 0 27208 24859 3 1 1334149 4096000 0 65024 0 0 n/a 5 8 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/sft.cern.ch DIRECT 1
2023-01-19 18:45:10 (26868): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-19 18:45:10 (26868): 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
2023-01-19 18:45:10 (26868): Guest Log: 2.5.2.0 4055 0 27524 20301 3 2 1334149 4096000 0 65024 0 2 0 5674 5778 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-19 18:45:11 (26868): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-19 18:45:12 (26868): 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
2023-01-19 18:45:12 (26868): Guest Log: 2.5.2.0 4076 0 25164 268 3 1 1334149 4096000 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-19 18:45:12 (26868): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-19 18:45:13 (26868): 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
2023-01-19 18:45:13 (26868): Guest Log: 2.5.2.0 4122 0 34224 14405 3 1 1334149 4096000 0 65024 0 0 n/a 3340 4360 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-19 18:45:13 (26868): Guest Log: 20:45:19 EST -05:00 2023-01-19: cranky: [INFO] Checking runc.
2023-01-19 18:45:13 (26868): Guest Log: 20:45:19 EST -05:00 2023-01-19: cranky: [INFO] Creating the filesystem.
2023-01-19 18:45:13 (26868): Guest Log: 20:45:19 EST -05:00 2023-01-19: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-19 18:45:13 (26868): Guest Log: 20:45:19 EST -05:00 2023-01-19: cranky: [INFO] Updating config.json.
2023-01-19 18:45:13 (26868): Guest Log: 20:45:19 EST -05:00 2023-01-19: cranky: [INFO] Running Container 'runc'.
2023-01-19 18:45:14 (26868): Guest Log: job: htmld=/shared/html/job
2023-01-19 18:45:14 (26868): Guest Log: job: unpack exitcode=0
2023-01-19 18:45:15 (26868): Guest Log: 20:45:21 EST -05:00 2023-01-19: cranky: [INFO] ===> [runRivet] Fri Jan 20 01:45:20 UTC 2023 [boinc pp jets 13000 170,-,2960 - pythia8 8.301 tune-4cx 100000 322]
2023-01-19 20:24:17 (26868): Status Report: Job Duration: '864000.000000'
2023-01-19 20:24:17 (26868): Status Report: Elapsed Time: '269873.070217'
2023-01-19 20:24:17 (26868): Status Report: CPU Time: '242836.921875'
2023-01-19 22:04:23 (26868): Status Report: Job Duration: '864000.000000'
2023-01-19 22:04:23 (26868): Status Report: Elapsed Time: '275873.070217'
2023-01-19 22:04:23 (26868): Status Report: CPU Time: '248250.125000'
2023-01-19 23:44:30 (26868): Status Report: Job Duration: '864000.000000'
2023-01-19 23:44:30 (26868): Status Report: Elapsed Time: '281873.070217'
2023-01-19 23:44:30 (26868): Status Report: CPU Time: '253664.203125'
2023-01-19 23:59:43 (26868): VM state change detected. (old = 'running', new = 'saving')
23:59:44 (26868): BOINC client no longer exists - exiting
23:59:44 (26868): timer handler: client dead, exiting
2023-01-19 23:59:44 (26868): Stopping VM.
2023-01-19 23:59:44 (26868): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_9708164831f3b68c" savestate
Output:

2023-01-19 23:59:44 (26868): VM did not stop when requested.
2023-01-19 23:59:44 (26868): VM was successfully terminated.
2023-01-20 10:41:46 (13596): Detected: vboxwrapper 26206
2023-01-20 10:41:46 (13596): Detected: BOINC client v7.20.2
2023-01-20 10:41:48 (13596): Detected: VirtualBox VboxManage Interface (Version: 7.0.4)
2023-01-20 10:41:49 (13596): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-20 10:41:50 (13596): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-20 10:41:50 (13596): Guest Log: CPUID EDX: 0x178bfbff
2023-01-20 10:41:50 (13596): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-20 10:41:50 (13596): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-20 10:41:50 (13596): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-20 10:41:50 (13596): Guest Log: BIOS: Booting from Hard Disk...
2023-01-20 10:41:50 (13596): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-20 10:41:50 (13596): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-20 10:41:50 (13596): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-20 10:41:50 (13596): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-20 10:41:50 (13596): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-20 10:41:50 (13596): Guest Log: 00:00:00.000235 main     Log opened 2023-01-20T01:44:51.094327000Z
2023-01-20 10:41:50 (13596): Guest Log: 00:00:00.000423 main     OS Product: Linux
2023-01-20 10:41:50 (13596): Guest Log: 00:00:00.000465 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-20 10:41:50 (13596): Guest Log: 00:00:00.000498 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-20 10:41:50 (13596): Guest Log: 00:00:00.000544 main     Executable: /usr/sbin/VBoxService
2023-01-20 10:41:50 (13596): Guest Log: 00:00:00.000545 main     Process ID: 3215
2023-01-20 10:41:50 (13596): Guest Log: 00:00:00.000546 main     Package type: LINUX_64BITS_GENERIC
2023-01-20 10:41:50 (13596): Guest Log: 00:00:00.003845 main     5.2.6 r120293 started. Verbose level = 0
2023-01-20 10:41:50 (13596): Guest Log: 20:45:14 EST -05:00 2023-01-19: cranky: [INFO] Detected Theory App
2023-01-20 10:41:50 (13596): Guest Log: 20:45:14 EST -05:00 2023-01-19: cranky: [INFO] Checking CVMFS.
2023-01-20 10:41:50 (13596): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-20 10:41:50 (13596): 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
2023-01-20 10:41:50 (13596): Guest Log: 2.5.2.0 4097 0 27208 24859 3 1 1334149 4096000 0 65024 0 0 n/a 5 8 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/sft.cern.ch DIRECT 1
2023-01-20 10:41:50 (13596): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-20 10:41:50 (13596): 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
2023-01-20 10:41:50 (13596): Guest Log: 2.5.2.0 4055 0 27524 20301 3 2 1334149 4096000 0 65024 0 2 0 5674 5778 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-20 10:41:50 (13596): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-20 10:41:50 (13596): 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
2023-01-20 10:41:50 (13596): Guest Log: 2.5.2.0 4076 0 25164 268 3 1 1334149 4096000 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-20 10:41:50 (13596): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-20 10:41:50 (13596): 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
2023-01-20 10:41:50 (13596): Guest Log: 2.5.2.0 4122 0 34224 14405 3 1 1334149 4096000 0 65024 0 0 n/a 3340 4360 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-20 10:41:50 (13596): Guest Log: 20:45:19 EST -05:00 2023-01-19: cranky: [INFO] Checking runc.
2023-01-20 10:41:50 (13596): Guest Log: 20:45:19 EST -05:00 2023-01-19: cranky: [INFO] Creating the filesystem.
2023-01-20 10:41:50 (13596): Guest Log: 20:45:19 EST -05:00 2023-01-19: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-20 10:41:50 (13596): Guest Log: 20:45:19 EST -05:00 2023-01-19: cranky: [INFO] Updating config.json.
2023-01-20 10:41:50 (13596): Guest Log: 20:45:19 EST -05:00 2023-01-19: cranky: [INFO] Running Container 'runc'.
2023-01-20 10:41:50 (13596): Guest Log: job: htmld=/shared/html/job
2023-01-20 10:41:50 (13596): Guest Log: job: unpack exitcode=0
2023-01-20 10:41:50 (13596): Guest Log: 20:45:21 EST -05:00 2023-01-19: cranky: [INFO] ===> [runRivet] Fri Jan 20 01:45:20 UTC 2023 [boinc pp jets 13000 170,-,2960 - pythia8 8.301 tune-4cx 100000 322]
2023-01-20 10:41:50 (13596): Starting VM using VBoxManage interface. (boinc_9708164831f3b68c, slot#28)
2023-01-20 10:42:02 (13596): Successfully started VM. (PID = '24828')
2023-01-20 10:42:02 (13596): Reporting VM Process ID to BOINC.
2023-01-20 10:42:02 (13596): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-20 10:42:02 (13596): Guest Log: CPUID EDX: 0x178bfbff
2023-01-20 10:42:02 (13596): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-20 10:42:02 (13596): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-20 10:42:02 (13596): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-20 10:42:02 (13596): Detected: Web Application Enabled (http://localhost:58435)
2023-01-20 10:42:02 (13596): Status Report: Job Duration: '864000.000000'
2023-01-20 10:42:02 (13596): Status Report: Elapsed Time: '282786.070217'
2023-01-20 10:42:02 (13596): Status Report: CPU Time: '254485.406250'
2023-01-20 10:42:02 (13596): Preference change detected
2023-01-20 10:42:02 (13596): Setting CPU throttle for VM. (90%)
2023-01-20 10:42:03 (13596): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-20 10:42:04 (13596): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-20 10:42:04 (13596): Guest Log: BIOS: Booting from Hard Disk...
2023-01-20 10:42:06 (13596): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-20 10:42:06 (13596): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-20 10:42:26 (13596): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-20 10:42:26 (13596): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-20 10:42:29 (13596): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-20 10:42:29 (13596): Guest Log: 00:00:00.000101 main     Log opened 2023-01-20T17:42:28.783657000Z
2023-01-20 10:42:29 (13596): Guest Log: 00:00:00.000248 main     OS Product: Linux
2023-01-20 10:42:29 (13596): Guest Log: 00:00:00.000312 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-20 10:42:29 (13596): Guest Log: 00:00:00.000346 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-20 10:42:29 (13596): Guest Log: 00:00:00.000388 main     Executable: /usr/sbin/VBoxService
2023-01-20 10:42:29 (13596): Guest Log: 00:00:00.000389 main     Process ID: 3212
2023-01-20 10:42:29 (13596): Guest Log: 00:00:00.000390 main     Package type: LINUX_64BITS_GENERIC
2023-01-20 10:42:29 (13596): Guest Log: 00:00:00.002475 main     5.2.6 r120293 started. Verbose level = 0
2023-01-20 10:42:52 (13596): Guest Log: 12:42:51 EST -05:00 2023-01-20: cranky: [INFO] Detected Theory App
2023-01-20 10:42:52 (13596): Guest Log: 12:42:51 EST -05:00 2023-01-20: cranky: [INFO] Checking CVMFS.
2023-01-20 10:42:52 (13596): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-20 10:42:53 (13596): 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
2023-01-20 10:42:53 (13596): Guest Log: 2.5.2.0 4067 0 27176 24862 3 1 1354611 4096000 0 65024 0 0 n/a 5 2 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/sft.cern.ch DIRECT 1
2023-01-20 10:42:53 (13596): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-20 10:42:54 (13596): 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
2023-01-20 10:42:54 (13596): Guest Log: 2.5.2.0 4091 0 27536 20307 3 2 1354611 4096000 0 65024 0 2 0 5674 4959 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-20 10:42:54 (13596): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-20 10:42:55 (13596): 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
2023-01-20 10:42:55 (13596): Guest Log: 2.5.2.0 4045 0 27220 268 3 1 1354611 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-20 10:42:55 (13596): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-20 10:42:56 (13596): 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
2023-01-20 10:42:56 (13596): Guest Log: 2.5.2.0 4116 0 32204 14413 3 1 1354611 4096000 0 65024 0 0 n/a 3345 4563 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-20 10:42:56 (13596): Guest Log: 12:42:55 EST -05:00 2023-01-20: cranky: [INFO] Checking runc.
2023-01-20 10:42:56 (13596): Guest Log: 12:42:55 EST -05:00 2023-01-20: cranky: [INFO] Creating the filesystem.
2023-01-20 10:42:56 (13596): Guest Log: 12:42:55 EST -05:00 2023-01-20: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-20 10:42:56 (13596): Guest Log: 12:42:55 EST -05:00 2023-01-20: cranky: [INFO] Updating config.json.
2023-01-20 10:42:56 (13596): Guest Log: 12:42:55 EST -05:00 2023-01-20: cranky: [INFO] Running Container 'runc'.
2023-01-20 10:42:56 (13596): Guest Log: job: htmld=/shared/html/job
2023-01-20 10:42:56 (13596): Guest Log: job: unpack exitcode=0
2023-01-20 10:42:58 (13596): Guest Log: 12:42:57 EST -05:00 2023-01-20: cranky: [INFO] ===> [runRivet] Fri Jan 20 17:42:56 UTC 2023 [boinc pp jets 13000 170,-,2960 - pythia8 8.301 tune-4cx 100000 322]
2023-01-20 10:55:58 (13596): VM state change detected. (old = 'running', new = 'paused')
2023-01-20 11:14:50 (13596): VM state change detected. (old = 'paused', new = 'running')
2023-01-20 12:41:02 (13596): Status Report: Job Duration: '864000.000000'
2023-01-20 12:41:02 (13596): Status Report: Elapsed Time: '288786.070217'
2023-01-20 12:41:02 (13596): Status Report: CPU Time: '259872.546875'
2023-01-20 14:21:08 (13596): Status Report: Job Duration: '864000.000000'
2023-01-20 14:21:08 (13596): Status Report: Elapsed Time: '294786.070217'
2023-01-20 14:21:08 (13596): Status Report: CPU Time: '265286.062500'
2023-01-20 14:36:30 (13596): VM state change detected. (old = 'running', new = 'paused')
2023-01-20 14:53:45 (13596): VM state change detected. (old = 'paused', new = 'running')
2023-01-20 14:53:56 (13596): Guest Log: 03:35:26.122162 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 171 222 852 000ns (GuestNow=1 674 251 636 084 822 000 ns GuestLast=1 674 249 464 861 970 000 ns fSetTimeLastLoop=true )
2023-01-20 15:31:46 (13596): VM state change detected. (old = 'running', new = 'paused')
2023-01-20 15:46:07 (13596): VM state change detected. (old = 'paused', new = 'running')
2023-01-20 15:52:09 (13596): VM state change detected. (old = 'running', new = 'paused')
2023-01-20 15:57:19 (13596): VM state change detected. (old = 'paused', new = 'running')
2023-01-20 15:58:46 (13596): VM state change detected. (old = 'running', new = 'paused')
2023-01-20 16:16:28 (13596): VM state change detected. (old = 'paused', new = 'running')
2023-01-20 16:16:40 (13596): Guest Log: 04:20:57.609398 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 242 470 215 000ns (GuestNow=1 674 256 600 083 574 000 ns GuestLast=1 674 254 357 613 359 000 ns fSetTimeLastLoop=true )
2023-01-20 16:27:50 (13596): VM state change detected. (old = 'running', new = 'paused')
2023-01-20 16:46:17 (13596): VM state change detected. (old = 'paused', new = 'running')
2023-01-20 17:14:15 (13596): Status Report: Job Duration: '864000.000000'
2023-01-20 17:14:15 (13596): Status Report: Elapsed Time: '300786.070217'
2023-01-20 17:14:15 (13596): Status Report: CPU Time: '270698.671875'
2023-01-20 18:54:21 (13596): Status Report: Job Duration: '864000.000000'
2023-01-20 18:54:21 (13596): Status Report: Elapsed Time: '306786.070217'
2023-01-20 18:54:21 (13596): Status Report: CPU Time: '276110.859375'
2023-01-20 20:34:27 (13596): Status Report: Job Duration: '864000.000000'
2023-01-20 20:34:27 (13596): Status Report: Elapsed Time: '312786.070217'
2023-01-20 20:34:27 (13596): Status Report: CPU Time: '281523.500000'
2023-01-20 22:05:36 (13596): Guest Log: job: run exitcode=0
2023-01-20 22:05:36 (13596): Guest Log: job: diskusage=2504
2023-01-20 22:05:36 (13596): Guest Log: job: logsize=76 k
2023-01-20 22:05:36 (13596): Guest Log: job: times=
2023-01-20 22:05:36 (13596): Guest Log: 0m0.005s 0m0.009s
2023-01-20 22:05:36 (13596): Guest Log: 584m48.716s 2m1.935s
2023-01-20 22:05:36 (13596): Guest Log: job: cpuusage=35211
2023-01-20 22:05:36 (13596): Guest Log: 23:47:18 EST -05:00 2023-01-20: cranky: [INFO] Container 'runc' finished with status code 0.
2023-01-20 22:05:36 (13596): Guest Log: 23:47:18 EST -05:00 2023-01-20: cranky: [INFO] Preparing output.
2023-01-20 22:05:37 (13596): Guest Log: [INFO] Job Finished
2023-01-20 22:05:37 (13596): Guest Log: [INFO] Shutting Down.
2023-01-20 22:05:37 (13596): VM Completion File Detected.
2023-01-20 22:05:37 (13596): VM Completion Message: Job Finished
.
2023-01-20 22:05:37 (13596): Powering off VM.
2023-01-20 22:05:38 (13596): Successfully stopped VM.
2023-01-20 22:05:38 (13596): Deregistering VM. (boinc_9708164831f3b68c, slot#28)
2023-01-20 22:05:38 (13596): Removing network bandwidth throttle group from VM.
2023-01-20 22:05:39 (13596): Removing VM from VirtualBox.
22:05:44 (13596): called boinc_finish(0)

</stderr_txt>
]]>


©2024 CERN