Name Theory_2390-1103959-326_1
Workunit 201366307
Created 8 Jan 2023, 15:34:12 UTC
Sent 9 Jan 2023, 4:51:53 UTC
Report deadline 20 Jan 2023, 4:51:53 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 13 hours 21 min 17 sec
CPU time 3 days 7 hours 12 min 47 sec
Validate state Valid
Credit 2,272.14
Device peak FLOPS 3.19 GFLOPS
Application version Theory Simulation v300.07 (vbox64_theory)
windows_x86_64
Peak working set size 85.99 MB
Peak swap size 78.09 MB
Peak disk usage 2.38 GB

Stderr output

<core_client_version>7.20.2</core_client_version>
<![CDATA[
<stderr_txt>
00'
2023-01-16 15:11:39 (20896): Preference change detected
2023-01-16 15:11:39 (20896): Setting CPU throttle for VM. (90%)
2023-01-16 15:11:39 (20896): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-16 15:11:39 (20896): VM state change detected. (old = 'paused', new = 'running')
2023-01-16 15:11:40 (20896): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-16 15:11:40 (20896): Guest Log: BIOS: Booting from Hard Disk...
2023-01-16 15:11:43 (20896): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-16 15:11:43 (20896): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-16 15:12:05 (20896): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-16 15:12:05 (20896): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-16 15:12:09 (20896): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-16 15:12:09 (20896): Guest Log: 00:00:00.000314 main     Log opened 2023-01-16T22:12:08.757513000Z
2023-01-16 15:12:09 (20896): Guest Log: 00:00:00.000526 main     OS Product: Linux
2023-01-16 15:12:09 (20896): Guest Log: 00:00:00.000598 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-16 15:12:09 (20896): Guest Log: 00:00:00.000661 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-16 15:12:09 (20896): Guest Log: 00:00:00.000722 main     Executable: /usr/sbin/VBoxService
2023-01-16 15:12:09 (20896): Guest Log: 00:00:00.000723 main     Process ID: 3215
2023-01-16 15:12:09 (20896): Guest Log: 00:00:00.000724 main     Package type: LINUX_64BITS_GENERIC
2023-01-16 15:12:09 (20896): Guest Log: 00:00:00.004254 main     5.2.6 r120293 started. Verbose level = 0
2023-01-16 15:12:37 (20896): Guest Log: 17:12:36 EST -05:00 2023-01-16: cranky: [INFO] Detected Theory App
2023-01-16 15:12:37 (20896): Guest Log: 17:12:36 EST -05:00 2023-01-16: cranky: [INFO] Checking CVMFS.
2023-01-16 15:12:37 (20896): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-16 15:12:38 (20896): 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 (20896): Guest Log: 2.5.2.0 4074 0 27180 24830 3 1 1257412 4096000 0 65024 0 0 n/a 5 2 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/sft.cern.ch DIRECT 1
2023-01-16 15:12:39 (20896): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-16 15:12:39 (20896): 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 (20896): Guest Log: 2.5.2.0 4099 0 27620 20277 3 2 1257412 4096000 0 65024 0 2 0 5671 4210 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-16 15:12:40 (20896): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-16 15:12:41 (20896): 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:41 (20896): Guest Log: 2.5.2.0 4051 0 27200 268 3 1 1257412 4096000 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-16 15:12:42 (20896): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-16 15:12:42 (20896): 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:42 (20896): Guest Log: 2.5.2.0 4125 0 34204 14376 3 1 1257412 4096000 0 65024 0 0 n/a 3325 249 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-16 15:12:42 (20896): Guest Log: 17:12:42 EST -05:00 2023-01-16: cranky: [INFO] Checking runc.
2023-01-16 15:12:42 (20896): Guest Log: 17:12:42 EST -05:00 2023-01-16: cranky: [INFO] Creating the filesystem.
2023-01-16 15:12:42 (20896): Guest Log: 17:12:42 EST -05:00 2023-01-16: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-16 15:12:43 (20896): Guest Log: 17:12:42 EST -05:00 2023-01-16: cranky: [INFO] Updating config.json.
2023-01-16 15:12:43 (20896): Guest Log: 17:12:42 EST -05:00 2023-01-16: cranky: [INFO] Running Container 'runc'.
2023-01-16 15:12:43 (20896): Guest Log: job: htmld=/shared/html/job
2023-01-16 15:12:43 (20896): Guest Log: job: unpack exitcode=0
2023-01-16 15:12:45 (20896): Guest Log: 17:12:44 EST -05:00 2023-01-16: cranky: [INFO] ===> [runRivet] Mon Jan 16 22:12:43 UTC 2023 [boinc pp jets 13000 150,-,2360 - pythia8 8.301 tune-AU2loxx 100000 326]
2023-01-16 15:15:05 (20896): VM state change detected. (old = 'running', new = 'paused')
2023-01-16 18:04:25 (20896): VM state change detected. (old = 'paused', new = 'running')
2023-01-16 18:04:30 (20896): Guest Log: 00:03:00.643560 timesync vgsvcTimeSyncWorker: Radical host time change: 10 170 588 000 000ns (HostNow=1 673 917 469 945 000 000 ns HostLast=1 673 907 299 357 000 000 ns)
2023-01-16 18:04:40 (20896): Guest Log: 00:03:10.678224 timesync vgsvcTimeSyncWorker: Radical guest time change: 10 170 779 386 000ns (GuestNow=1 673 917 480 032 282 000 ns GuestLast=1 673 907 309 252 896 000 ns fSetTimeLastLoop=true )
2023-01-16 18:25:21 (20896): VM state change detected. (old = 'running', new = 'paused')
2023-01-16 18:34:08 (20896): VM state change detected. (old = 'paused', new = 'running')
2023-01-16 19:49:54 (20896): Status Report: Job Duration: '864000.000000'
2023-01-16 19:49:54 (20896): Status Report: Elapsed Time: '190075.271939'
2023-01-16 19:49:54 (20896): Status Report: CPU Time: '170943.390625'
2023-01-16 21:30:00 (20896): Status Report: Job Duration: '864000.000000'
2023-01-16 21:30:00 (20896): Status Report: Elapsed Time: '196075.271939'
2023-01-16 21:30:00 (20896): Status Report: CPU Time: '176356.718750'
2023-01-16 23:10:06 (20896): Status Report: Job Duration: '864000.000000'
2023-01-16 23:10:06 (20896): Status Report: Elapsed Time: '202075.271939'
2023-01-16 23:10:06 (20896): Status Report: CPU Time: '181769.125000'
2023-01-16 23:59:39 (20896): Stopping VM.
2023-01-16 23:59:39 (20896): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_13653536d373377f" savestate
Output:

2023-01-16 23:59:39 (20896): VM did not stop when requested.
2023-01-16 23:59:39 (20896): VM was successfully terminated.
2023-01-17 11:42:23 (14096): Detected: vboxwrapper 26206
2023-01-17 11:42:23 (14096): Detected: BOINC client v7.20.2
2023-01-17 11:42:26 (14096): Detected: VirtualBox VboxManage Interface (Version: 7.0.4)
2023-01-17 11:42:27 (14096): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-17 11:42:28 (14096): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-17 11:42:28 (14096): Guest Log: CPUID EDX: 0x178bfbff
2023-01-17 11:42:28 (14096): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-17 11:42:28 (14096): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-17 11:42:28 (14096): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-17 11:42:29 (14096): Guest Log: BIOS: Booting from Hard Disk...
2023-01-17 11:42:29 (14096): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-17 11:42:29 (14096): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-17 11:42:29 (14096): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-17 11:42:29 (14096): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-17 11:42:29 (14096): 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 (14096): Guest Log: 00:00:00.000314 main     Log opened 2023-01-16T22:12:08.757513000Z
2023-01-17 11:42:29 (14096): Guest Log: 00:00:00.000526 main     OS Product: Linux
2023-01-17 11:42:29 (14096): Guest Log: 00:00:00.000598 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-17 11:42:29 (14096): Guest Log: 00:00:00.000661 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-17 11:42:29 (14096): Guest Log: 00:00:00.000722 main     Executable: /usr/sbin/VBoxService
2023-01-17 11:42:29 (14096): Guest Log: 00:00:00.000723 main     Process ID: 3215
2023-01-17 11:42:29 (14096): Guest Log: 00:00:00.000724 main     Package type: LINUX_64BITS_GENERIC
2023-01-17 11:42:29 (14096): Guest Log: 00:00:00.004254 main     5.2.6 r120293 started. Verbose level = 0
2023-01-17 11:42:29 (14096): Guest Log: 17:12:36 EST -05:00 2023-01-16: cranky: [INFO] Detected Theory App
2023-01-17 11:42:29 (14096): Guest Log: 17:12:36 EST -05:00 2023-01-16: cranky: [INFO] Checking CVMFS.
2023-01-17 11:42:29 (14096): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-17 11:42:29 (14096): 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 (14096): Guest Log: 2.5.2.0 4074 0 27180 24830 3 1 1257412 4096000 0 65024 0 0 n/a 5 2 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/sft.cern.ch DIRECT 1
2023-01-17 11:42:29 (14096): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-17 11:42:29 (14096): 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 (14096): Guest Log: 2.5.2.0 4099 0 27620 20277 3 2 1257412 4096000 0 65024 0 2 0 5671 4210 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-17 11:42:29 (14096): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-17 11:42:29 (14096): 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 (14096): Guest Log: 2.5.2.0 4051 0 27200 268 3 1 1257412 4096000 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-17 11:42:29 (14096): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-17 11:42:29 (14096): 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 (14096): Guest Log: 2.5.2.0 4125 0 34204 14376 3 1 1257412 4096000 0 65024 0 0 n/a 3325 249 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-17 11:42:29 (14096): Guest Log: 17:12:42 EST -05:00 2023-01-16: cranky: [INFO] Checking runc.
2023-01-17 11:42:29 (14096): Guest Log: 17:12:42 EST -05:00 2023-01-16: cranky: [INFO] Creating the filesystem.
2023-01-17 11:42:29 (14096): Guest Log: 17:12:42 EST -05:00 2023-01-16: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-17 11:42:29 (14096): Guest Log: 17:12:42 EST -05:00 2023-01-16: cranky: [INFO] Updating config.json.
2023-01-17 11:42:29 (14096): Guest Log: 17:12:42 EST -05:00 2023-01-16: cranky: [INFO] Running Container 'runc'.
2023-01-17 11:42:29 (14096): Guest Log: job: htmld=/shared/html/job
2023-01-17 11:42:29 (14096): Guest Log: job: unpack exitcode=0
2023-01-17 11:42:29 (14096): Guest Log: 17:12:44 EST -05:00 2023-01-16: cranky: [INFO] ===> [runRivet] Mon Jan 16 22:12:43 UTC 2023 [boinc pp jets 13000 150,-,2360 - pythia8 8.301 tune-AU2loxx 100000 326]
2023-01-17 11:42:29 (14096): Guest Log: 00:03:00.643560 timesync vgsvcTimeSyncWorker: Radical host time change: 10 170 588 000 000ns (HostNow=1 673 917 469 945 000 000 ns HostLast=1 673 907 299 357 000 000 ns)
2023-01-17 11:42:29 (14096): Guest Log: 00:03:10.678224 timesync vgsvcTimeSyncWorker: Radical guest time change: 10 170 779 386 000ns (GuestNow=1 673 917 480 032 282 000 ns GuestLast=1 673 907 309 252 896 000 ns fSetTimeLastLoop=true )
2023-01-17 11:42:29 (14096): Starting VM using VBoxManage interface. (boinc_13653536d373377f, slot#43)
2023-01-17 11:43:09 (14096): Successfully started VM. (PID = '26888')
2023-01-17 11:43:09 (14096): Reporting VM Process ID to BOINC.
2023-01-17 11:43:09 (14096): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-17 11:43:09 (14096): Guest Log: CPUID EDX: 0x178bfbff
2023-01-17 11:43:09 (14096): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-17 11:43:09 (14096): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-17 11:43:09 (14096): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-17 11:43:09 (14096): Detected: Web Application Enabled (http://localhost:60291)
2023-01-17 11:43:10 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 11:43:14 (14096): Status Report: Job Duration: '864000.000000'
2023-01-17 11:43:14 (14096): Status Report: Elapsed Time: '205044.271939'
2023-01-17 11:43:14 (14096): Status Report: CPU Time: '184447.921875'
2023-01-17 11:43:14 (14096): Preference change detected
2023-01-17 11:43:14 (14096): Setting CPU throttle for VM. (90%)
2023-01-17 11:43:15 (14096): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-17 11:43:15 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 11:43:16 (14096): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-17 11:43:16 (14096): Guest Log: BIOS: Booting from Hard Disk...
2023-01-17 11:43:19 (14096): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-17 11:43:19 (14096): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-17 11:43:46 (14096): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-17 11:43:46 (14096): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-17 11:43:50 (14096): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-17 11:43:50 (14096): Guest Log: 00:00:00.000151 main     Log opened 2023-01-17T18:43:50.255167000Z
2023-01-17 11:43:50 (14096): Guest Log: 00:00:00.000373 main     OS Product: Linux
2023-01-17 11:43:50 (14096): Guest Log: 00:00:00.000423 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-17 11:43:50 (14096): Guest Log: 00:00:00.000465 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-17 11:43:50 (14096): Guest Log: 00:00:00.000503 main     Executable: /usr/sbin/VBoxService
2023-01-17 11:43:50 (14096): Guest Log: 00:00:00.000504 main     Process ID: 3221
2023-01-17 11:43:50 (14096): Guest Log: 00:00:00.000505 main     Package type: LINUX_64BITS_GENERIC
2023-01-17 11:43:50 (14096): Guest Log: 00:00:00.003041 main     5.2.6 r120293 started. Verbose level = 0
2023-01-17 11:44:17 (14096): Guest Log: 13:44:16 EST -05:00 2023-01-17: cranky: [INFO] Detected Theory App
2023-01-17 11:44:17 (14096): Guest Log: 13:44:16 EST -05:00 2023-01-17: cranky: [INFO] Checking CVMFS.
2023-01-17 11:44:18 (14096): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-17 11:44:18 (14096): 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 (14096): Guest Log: 2.5.2.0 4057 0 28404 24837 3 1 1281129 4096000 0 65024 0 0 n/a 5 3 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2023-01-17 11:44:19 (14096): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-17 11:44:19 (14096): 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 (14096): Guest Log: 2.5.2.0 4127 0 27524 20283 3 2 1281129 4096000 0 65024 0 2 0 5671 6832 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-17 11:44:20 (14096): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-17 11:44:21 (14096): 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 (14096): Guest Log: 2.5.2.0 4105 0 27220 268 3 1 1281129 4096000 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-17 11:44:21 (14096): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-17 11:44:22 (14096): 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:22 (14096): Guest Log: 2.5.2.0 4079 0 34248 14387 3 1 1281129 4096000 0 65024 0 0 n/a 3331 3502 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-17 11:44:22 (14096): Guest Log: 13:44:21 EST -05:00 2023-01-17: cranky: [INFO] Checking runc.
2023-01-17 11:44:22 (14096): Guest Log: 13:44:21 EST -05:00 2023-01-17: cranky: [INFO] Creating the filesystem.
2023-01-17 11:44:22 (14096): Guest Log: 13:44:22 EST -05:00 2023-01-17: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-17 11:44:22 (14096): Guest Log: 13:44:22 EST -05:00 2023-01-17: cranky: [INFO] Updating config.json.
2023-01-17 11:44:22 (14096): Guest Log: 13:44:22 EST -05:00 2023-01-17: cranky: [INFO] Running Container 'runc'.
2023-01-17 11:44:24 (14096): Guest Log: job: htmld=/shared/html/job
2023-01-17 11:44:24 (14096): Guest Log: job: unpack exitcode=0
2023-01-17 11:44:24 (14096): Guest Log: 13:44:24 EST -05:00 2023-01-17: cranky: [INFO] ===> [runRivet] Tue Jan 17 18:44:24 UTC 2023 [boinc pp jets 13000 150,-,2360 - pythia8 8.301 tune-AU2loxx 100000 326]
2023-01-17 12:03:27 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 12:32:29 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 13:52:26 (14096): Status Report: Job Duration: '864000.000000'
2023-01-17 13:52:26 (14096): Status Report: Elapsed Time: '211044.271939'
2023-01-17 13:52:26 (14096): Status Report: CPU Time: '189799.671875'
2023-01-17 14:28:47 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 14:34:07 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 14:48:36 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 15:02:05 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 15:08:11 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 15:18:20 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 15:20:32 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 15:34:52 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 15:38:15 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 15:45:04 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 15:46:36 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 15:52:18 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 16:05:45 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 16:45:05 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 16:45:13 (14096): Guest Log: 02:57:15.047074 timesync vgsvcTimeSyncWorker: Radical host time change: 2 369 475 000 000ns (HostNow=1 673 999 112 773 000 000 ns HostLast=1 673 996 743 298 000 000 ns)
2023-01-17 16:45:23 (14096): Guest Log: 02:57:25.059873 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 711 421 601 000ns (GuestNow=1 673 999 122 821 940 000 ns GuestLast=1 673 996 411 400 339 000 ns fSetTimeLastLoop=true )
2023-01-17 16:46:12 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 17:11:19 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 17:12:17 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 17:23:08 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 17:35:46 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 17:41:04 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 17:42:37 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 17:55:02 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 18:01:34 (14096): Status Report: Job Duration: '864000.000000'
2023-01-17 18:01:34 (14096): Status Report: Elapsed Time: '217044.271939'
2023-01-17 18:01:34 (14096): Status Report: CPU Time: '195223.906250'
2023-01-17 18:09:29 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 18:14:47 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 19:00:34 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 19:08:09 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 19:50:09 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 20:03:43 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 20:05:22 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 20:12:55 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 20:15:45 (14096): Status Report: Job Duration: '864000.000000'
2023-01-17 20:15:45 (14096): Status Report: Elapsed Time: '223044.271939'
2023-01-17 20:15:45 (14096): Status Report: CPU Time: '200635.609375'
2023-01-17 20:31:12 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 20:41:58 (14096): VM state change detected. (old = 'paused', new = 'running')
2023-01-17 22:06:38 (14096): Status Report: Job Duration: '864000.000000'
2023-01-17 22:06:38 (14096): Status Report: Elapsed Time: '229044.271939'
2023-01-17 22:06:38 (14096): Status Report: CPU Time: '206051.140625'
2023-01-17 23:46:44 (14096): Status Report: Job Duration: '864000.000000'
2023-01-17 23:46:44 (14096): Status Report: Elapsed Time: '235044.271939'
2023-01-17 23:46:44 (14096): Status Report: CPU Time: '211462.156250'
2023-01-17 23:57:18 (14096): VM state change detected. (old = 'running', new = 'paused')
2023-01-17 23:59:37 (14096): Stopping VM.
2023-01-17 23:59:38 (14096): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_13653536d373377f" savestate
Output:

2023-01-17 23:59:38 (14096): VM did not stop when requested.
2023-01-17 23:59:38 (14096): VM was successfully terminated.
2023-01-18 06:08:09 (11800): Detected: vboxwrapper 26206
2023-01-18 06:08:09 (11800): Detected: BOINC client v7.20.2
2023-01-18 06:08:12 (11800): Detected: VirtualBox VboxManage Interface (Version: 7.0.4)
2023-01-18 06:08:13 (11800): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-18 06:08:14 (11800): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-18 06:08:14 (11800): Guest Log: CPUID EDX: 0x178bfbff
2023-01-18 06:08:14 (11800): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-18 06:08:14 (11800): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-18 06:08:14 (11800): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-18 06:08:14 (11800): Guest Log: BIOS: Booting from Hard Disk...
2023-01-18 06:08:14 (11800): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-18 06:08:14 (11800): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-18 06:08:14 (11800): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-18 06:08:14 (11800): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-18 06:08:14 (11800): 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 (11800): Guest Log: 00:00:00.000151 main     Log opened 2023-01-17T18:43:50.255167000Z
2023-01-18 06:08:14 (11800): Guest Log: 00:00:00.000373 main     OS Product: Linux
2023-01-18 06:08:14 (11800): Guest Log: 00:00:00.000423 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-18 06:08:14 (11800): Guest Log: 00:00:00.000465 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-18 06:08:14 (11800): Guest Log: 00:00:00.000503 main     Executable: /usr/sbin/VBoxService
2023-01-18 06:08:14 (11800): Guest Log: 00:00:00.000504 main     Process ID: 3221
2023-01-18 06:08:14 (11800): Guest Log: 00:00:00.000505 main     Package type: LINUX_64BITS_GENERIC
2023-01-18 06:08:14 (11800): Guest Log: 00:00:00.003041 main     5.2.6 r120293 started. Verbose level = 0
2023-01-18 06:08:14 (11800): Guest Log: 13:44:16 EST -05:00 2023-01-17: cranky: [INFO] Detected Theory App
2023-01-18 06:08:14 (11800): Guest Log: 13:44:16 EST -05:00 2023-01-17: cranky: [INFO] Checking CVMFS.
2023-01-18 06:08:14 (11800): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-18 06:08:14 (11800): 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 (11800): Guest Log: 2.5.2.0 4057 0 28404 24837 3 1 1281129 4096000 0 65024 0 0 n/a 5 3 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2023-01-18 06:08:14 (11800): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-18 06:08:14 (11800): 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 (11800): Guest Log: 2.5.2.0 4127 0 27524 20283 3 2 1281129 4096000 0 65024 0 2 0 5671 6832 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-18 06:08:14 (11800): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-18 06:08:14 (11800): 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 (11800): Guest Log: 2.5.2.0 4105 0 27220 268 3 1 1281129 4096000 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-18 06:08:14 (11800): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-18 06:08:14 (11800): 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 (11800): Guest Log: 2.5.2.0 4079 0 34248 14387 3 1 1281129 4096000 0 65024 0 0 n/a 3331 3502 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-18 06:08:14 (11800): Guest Log: 13:44:21 EST -05:00 2023-01-17: cranky: [INFO] Checking runc.
2023-01-18 06:08:14 (11800): Guest Log: 13:44:21 EST -05:00 2023-01-17: cranky: [INFO] Creating the filesystem.
2023-01-18 06:08:14 (11800): Guest Log: 13:44:22 EST -05:00 2023-01-17: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-18 06:08:14 (11800): Guest Log: 13:44:22 EST -05:00 2023-01-17: cranky: [INFO] Updating config.json.
2023-01-18 06:08:14 (11800): Guest Log: 13:44:22 EST -05:00 2023-01-17: cranky: [INFO] Running Container 'runc'.
2023-01-18 06:08:14 (11800): Guest Log: job: htmld=/shared/html/job
2023-01-18 06:08:14 (11800): Guest Log: job: unpack exitcode=0
2023-01-18 06:08:14 (11800): Guest Log: 13:44:24 EST -05:00 2023-01-17: cranky: [INFO] ===> [runRivet] Tue Jan 17 18:44:24 UTC 2023 [boinc pp jets 13000 150,-,2360 - pythia8 8.301 tune-AU2loxx 100000 326]
2023-01-18 06:08:14 (11800): Guest Log: 02:57:15.047074 timesync vgsvcTimeSyncWorker: Radical host time change: 2 369 475 000 000ns (HostNow=1 673 999 112 773 000 000 ns HostLast=1 673 996 743 298 000 000 ns)
2023-01-18 06:08:14 (11800): Guest Log: 02:57:25.059873 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 711 421 601 000ns (GuestNow=1 673 999 122 821 940 000 ns GuestLast=1 673 996 411 400 339 000 ns fSetTimeLastLoop=true )
2023-01-18 06:08:14 (11800): Starting VM using VBoxManage interface. (boinc_13653536d373377f, slot#43)
2023-01-18 06:08:35 (11800): Successfully started VM. (PID = '31400')
2023-01-18 06:08:35 (11800): Reporting VM Process ID to BOINC.
2023-01-18 06:08:35 (11800): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-18 06:08:35 (11800): Guest Log: CPUID EDX: 0x178bfbff
2023-01-18 06:08:35 (11800): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-18 06:08:35 (11800): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-18 06:08:35 (11800): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-18 06:08:35 (11800): Detected: Web Application Enabled (http://localhost:60291)
2023-01-18 06:08:36 (11800): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 06:08:40 (11800): Status Report: Job Duration: '864000.000000'
2023-01-18 06:08:40 (11800): Status Report: Elapsed Time: '235675.271939'
2023-01-18 06:08:40 (11800): Status Report: CPU Time: '212030.343750'
2023-01-18 06:08:40 (11800): Preference change detected
2023-01-18 06:08:40 (11800): Setting CPU throttle for VM. (90%)
2023-01-18 06:08:40 (11800): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-18 06:08:41 (11800): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 06:08:42 (11800): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-18 06:08:42 (11800): Guest Log: BIOS: Booting from Hard Disk...
2023-01-18 06:08:45 (11800): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-18 06:08:45 (11800): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-18 06:09:06 (11800): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-18 06:09:06 (11800): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-18 06:09:10 (11800): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-18 06:09:10 (11800): Guest Log: 00:00:00.000149 main     Log opened 2023-01-18T13:09:10.757917000Z
2023-01-18 06:09:10 (11800): Guest Log: 00:00:00.000379 main     OS Product: Linux
2023-01-18 06:09:10 (11800): Guest Log: 00:00:00.000443 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-18 06:09:10 (11800): Guest Log: 00:00:00.000488 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-18 06:09:10 (11800): Guest Log: 00:00:00.000555 main     Executable: /usr/sbin/VBoxService
2023-01-18 06:09:10 (11800): Guest Log: 00:00:00.000556 main     Process ID: 3222
2023-01-18 06:09:10 (11800): Guest Log: 00:00:00.000557 main     Package type: LINUX_64BITS_GENERIC
2023-01-18 06:09:10 (11800): Guest Log: 00:00:00.004048 main     5.2.6 r120293 started. Verbose level = 0
2023-01-18 06:09:37 (11800): Guest Log: 08:09:37 EST -05:00 2023-01-18: cranky: [INFO] Detected Theory App
2023-01-18 06:09:37 (11800): Guest Log: 08:09:37 EST -05:00 2023-01-18: cranky: [INFO] Checking CVMFS.
2023-01-18 06:09:38 (11800): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-18 06:09:38 (11800): 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 (11800): Guest Log: 2.5.2.0 4127 0 27204 24844 3 1 1302198 4096000 0 65024 0 0 n/a 5 7 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2023-01-18 06:09:39 (11800): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-18 06:09:40 (11800): 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 (11800): Guest Log: 2.5.2.0 4107 0 27660 20289 3 2 1302198 4096000 0 65024 0 2 0 5673 6476 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-18 06:09:40 (11800): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-18 06:09:41 (11800): 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:41 (11800): Guest Log: 2.5.2.0 4086 0 27212 268 3 1 1302198 4096000 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-18 06:09:42 (11800): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-18 06:09:42 (11800): 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:42 (11800): Guest Log: 2.5.2.0 4064 0 33512 14393 3 1 1302198 4096000 0 65024 0 0 n/a 3335 4353 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-18 06:09:42 (11800): Guest Log: 08:09:42 EST -05:00 2023-01-18: cranky: [INFO] Checking runc.
2023-01-18 06:09:42 (11800): Guest Log: 08:09:42 EST -05:00 2023-01-18: cranky: [INFO] Creating the filesystem.
2023-01-18 06:09:43 (11800): Guest Log: 08:09:42 EST -05:00 2023-01-18: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-18 06:09:43 (11800): Guest Log: 08:09:43 EST -05:00 2023-01-18: cranky: [INFO] Updating config.json.
2023-01-18 06:09:43 (11800): Guest Log: 08:09:43 EST -05:00 2023-01-18: cranky: [INFO] Running Container 'runc'.
2023-01-18 06:09:44 (11800): Guest Log: job: htmld=/shared/html/job
2023-01-18 06:09:44 (11800): Guest Log: job: unpack exitcode=0
2023-01-18 06:09:45 (11800): Guest Log: 08:09:45 EST -05:00 2023-01-18: cranky: [INFO] ===> [runRivet] Wed Jan 18 13:09:44 UTC 2023 [boinc pp jets 13000 150,-,2360 - pythia8 8.301 tune-AU2loxx 100000 326]
2023-01-18 07:48:46 (11800): Status Report: Job Duration: '864000.000000'
2023-01-18 07:48:46 (11800): Status Report: Elapsed Time: '241675.271939'
2023-01-18 07:48:46 (11800): Status Report: CPU Time: '217405.125000'
2023-01-18 07:54:30 (11800): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 07:59:54 (11800): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 09:15:26 (11800): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 09:24:35 (11800): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 09:43:29 (11800): Status Report: Job Duration: '864000.000000'
2023-01-18 09:43:29 (11800): Status Report: Elapsed Time: '247675.271939'
2023-01-18 09:43:29 (11800): Status Report: CPU Time: '222825.250000'
2023-01-18 10:35:36 (11800): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 10:40:49 (11800): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 11:28:49 (11800): Status Report: Job Duration: '864000.000000'
2023-01-18 11:28:49 (11800): Status Report: Elapsed Time: '253675.271939'
2023-01-18 11:28:49 (11800): Status Report: CPU Time: '228236.437500'
2023-01-18 11:38:25 (11800): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 11:45:37 (11800): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 12:31:56 (11800): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 12:37:36 (11800): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 12:44:57 (11800): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 12:49:57 (11800): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 13:26:51 (11800): Status Report: Job Duration: '864000.000000'
2023-01-18 13:26:51 (11800): Status Report: Elapsed Time: '259675.271939'
2023-01-18 13:26:51 (11800): Status Report: CPU Time: '233657.125000'
2023-01-18 14:49:01 (11800): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 21:16:55 (11800): VM state change detected. (old = 'paused', new = 'running')
2023-01-18 21:16:58 (11800): Guest Log: 08:02:23.288216 timesync vgsvcTimeSyncWorker: Radical host time change: 23 283 783 000 000ns (HostNow=1 674 101 818 251 000 000 ns HostLast=1 674 078 534 468 000 000 ns)
2023-01-18 21:17:08 (11800): Guest Log: 08:02:33.290650 timesync vgsvcTimeSyncWorker: Radical guest time change: 23 920 490 288 000ns (GuestNow=1 674 101 828 256 218 000 ns GuestLast=1 674 077 907 765 930 000 ns fSetTimeLastLoop=true )
2023-01-18 21:34:52 (11800): Status Report: Job Duration: '864000.000000'
2023-01-18 21:34:52 (11800): Status Report: Elapsed Time: '265675.271939'
2023-01-18 21:34:52 (11800): Status Report: CPU Time: '239070.437500'
2023-01-18 23:14:59 (11800): Status Report: Job Duration: '864000.000000'
2023-01-18 23:14:59 (11800): Status Report: Elapsed Time: '271675.271939'
2023-01-18 23:14:59 (11800): Status Report: CPU Time: '244483.906250'
2023-01-18 23:15:03 (11800): VM state change detected. (old = 'running', new = 'paused')
2023-01-18 23:59:39 (11800): Stopping VM.
2023-01-18 23:59:40 (11800): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_13653536d373377f" savestate
Output:

2023-01-18 23:59:40 (11800): VM did not stop when requested.
2023-01-18 23:59:40 (11800): VM was successfully terminated.
2023-01-19 18:43:58 (26376): Detected: vboxwrapper 26206
2023-01-19 18:43:58 (26376): Detected: BOINC client v7.20.2
2023-01-19 18:43:58 (26376): Detected: VirtualBox VboxManage Interface (Version: 7.0.4)
2023-01-19 18:43:59 (26376): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-19 18:43:59 (26376): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-19 18:43:59 (26376): Guest Log: CPUID EDX: 0x178bfbff
2023-01-19 18:43:59 (26376): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-19 18:43:59 (26376): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-19 18:43:59 (26376): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-19 18:43:59 (26376): Guest Log: BIOS: Booting from Hard Disk...
2023-01-19 18:43:59 (26376): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-19 18:43:59 (26376): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-19 18:43:59 (26376): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-19 18:43:59 (26376): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-19 18:43:59 (26376): 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 (26376): Guest Log: 00:00:00.000149 main     Log opened 2023-01-18T13:09:10.757917000Z
2023-01-19 18:43:59 (26376): Guest Log: 00:00:00.000379 main     OS Product: Linux
2023-01-19 18:43:59 (26376): Guest Log: 00:00:00.000443 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-19 18:43:59 (26376): Guest Log: 00:00:00.000488 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-19 18:43:59 (26376): Guest Log: 00:00:00.000555 main     Executable: /usr/sbin/VBoxService
2023-01-19 18:43:59 (26376): Guest Log: 00:00:00.000556 main     Process ID: 3222
2023-01-19 18:43:59 (26376): Guest Log: 00:00:00.000557 main     Package type: LINUX_64BITS_GENERIC
2023-01-19 18:43:59 (26376): Guest Log: 00:00:00.004048 main     5.2.6 r120293 started. Verbose level = 0
2023-01-19 18:43:59 (26376): Guest Log: 08:09:37 EST -05:00 2023-01-18: cranky: [INFO] Detected Theory App
2023-01-19 18:43:59 (26376): Guest Log: 08:09:37 EST -05:00 2023-01-18: cranky: [INFO] Checking CVMFS.
2023-01-19 18:43:59 (26376): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-19 18:43:59 (26376): 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 (26376): Guest Log: 2.5.2.0 4127 0 27204 24844 3 1 1302198 4096000 0 65024 0 0 n/a 5 7 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2023-01-19 18:43:59 (26376): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-19 18:43:59 (26376): 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 (26376): Guest Log: 2.5.2.0 4107 0 27660 20289 3 2 1302198 4096000 0 65024 0 2 0 5673 6476 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-19 18:43:59 (26376): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-19 18:43:59 (26376): 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 (26376): Guest Log: 2.5.2.0 4086 0 27212 268 3 1 1302198 4096000 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2023-01-19 18:43:59 (26376): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-19 18:43:59 (26376): 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 (26376): Guest Log: 2.5.2.0 4064 0 33512 14393 3 1 1302198 4096000 0 65024 0 0 n/a 3335 4353 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-19 18:43:59 (26376): Guest Log: 08:09:42 EST -05:00 2023-01-18: cranky: [INFO] Checking runc.
2023-01-19 18:43:59 (26376): Guest Log: 08:09:42 EST -05:00 2023-01-18: cranky: [INFO] Creating the filesystem.
2023-01-19 18:43:59 (26376): Guest Log: 08:09:42 EST -05:00 2023-01-18: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-19 18:43:59 (26376): Guest Log: 08:09:43 EST -05:00 2023-01-18: cranky: [INFO] Updating config.json.
2023-01-19 18:43:59 (26376): Guest Log: 08:09:43 EST -05:00 2023-01-18: cranky: [INFO] Running Container 'runc'.
2023-01-19 18:43:59 (26376): Guest Log: job: htmld=/shared/html/job
2023-01-19 18:43:59 (26376): Guest Log: job: unpack exitcode=0
2023-01-19 18:43:59 (26376): Guest Log: 08:09:45 EST -05:00 2023-01-18: cranky: [INFO] ===> [runRivet] Wed Jan 18 13:09:44 UTC 2023 [boinc pp jets 13000 150,-,2360 - pythia8 8.301 tune-AU2loxx 100000 326]
2023-01-19 18:43:59 (26376): Guest Log: 08:02:23.288216 timesync vgsvcTimeSyncWorker: Radical host time change: 23 283 783 000 000ns (HostNow=1 674 101 818 251 000 000 ns HostLast=1 674 078 534 468 000 000 ns)
2023-01-19 18:43:59 (26376): Guest Log: 08:02:33.290650 timesync vgsvcTimeSyncWorker: Radical guest time change: 23 920 490 288 000ns (GuestNow=1 674 101 828 256 218 000 ns GuestLast=1 674 077 907 765 930 000 ns fSetTimeLastLoop=true )
2023-01-19 18:43:59 (26376): Starting VM using VBoxManage interface. (boinc_13653536d373377f, slot#43)
2023-01-19 18:44:12 (26376): Successfully started VM. (PID = '16052')
2023-01-19 18:44:12 (26376): Reporting VM Process ID to BOINC.
2023-01-19 18:44:12 (26376): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-19 18:44:12 (26376): Guest Log: CPUID EDX: 0x178bfbff
2023-01-19 18:44:12 (26376): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-19 18:44:12 (26376): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-19 18:44:12 (26376): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-19 18:44:12 (26376): Detected: Web Application Enabled (http://localhost:60291)
2023-01-19 18:44:12 (26376): Status Report: Job Duration: '864000.000000'
2023-01-19 18:44:12 (26376): Status Report: Elapsed Time: '271677.271939'
2023-01-19 18:44:12 (26376): Status Report: CPU Time: '244483.906250'
2023-01-19 18:44:12 (26376): Preference change detected
2023-01-19 18:44:12 (26376): Setting CPU throttle for VM. (90%)
2023-01-19 18:44:13 (26376): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-19 18:44:14 (26376): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-19 18:44:14 (26376): Guest Log: BIOS: Booting from Hard Disk...
2023-01-19 18:44:17 (26376): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-19 18:44:17 (26376): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-19 18:44:43 (26376): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-19 18:44:43 (26376): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-19 18:44:46 (26376): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-19 18:44:46 (26376): Guest Log: 00:00:00.000182 main     Log opened 2023-01-20T01:44:52.295175000Z
2023-01-19 18:44:46 (26376): Guest Log: 00:00:00.000407 main     OS Product: Linux
2023-01-19 18:44:46 (26376): Guest Log: 00:00:00.000455 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-19 18:44:46 (26376): Guest Log: 00:00:00.000494 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-19 18:44:46 (26376): Guest Log: 00:00:00.000530 main     Executable: /usr/sbin/VBoxService
2023-01-19 18:44:46 (26376): Guest Log: 00:00:00.000531 main     Process ID: 3214
2023-01-19 18:44:46 (26376): Guest Log: 00:00:00.000532 main     Package type: LINUX_64BITS_GENERIC
2023-01-19 18:44:46 (26376): Guest Log: 00:00:00.004255 main     5.2.6 r120293 started. Verbose level = 0
2023-01-19 18:45:11 (26376): Guest Log: 20:45:17 EST -05:00 2023-01-19: cranky: [INFO] Detected Theory App
2023-01-19 18:45:11 (26376): Guest Log: 20:45:17 EST -05:00 2023-01-19: cranky: [INFO] Checking CVMFS.
2023-01-19 18:45:12 (26376): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-19 18:45:13 (26376): 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 (26376): Guest Log: 2.5.2.0 4100 0 27204 24859 3 1 1322703 4096000 0 65024 0 0 n/a 5 5 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2023-01-19 18:45:13 (26376): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-19 18:45:14 (26376): 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:14 (26376): Guest Log: 2.5.2.0 4078 0 27704 20301 3 2 1322703 4096000 0 65024 0 2 0 5674 5708 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-19 18:45:15 (26376): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-19 18:45:15 (26376): 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:15 (26376): Guest Log: 2.5.2.0 4052 0 27208 268 3 1 1322703 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:16 (26376): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-19 18:45:17 (26376): 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:17 (26376): Guest Log: 2.5.2.0 4121 0 34240 14405 3 1 1322703 4096000 0 65024 0 0 n/a 3340 4093 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-19 18:45:17 (26376): Guest Log: 20:45:22 EST -05:00 2023-01-19: cranky: [INFO] Checking runc.
2023-01-19 18:45:17 (26376): Guest Log: 20:45:23 EST -05:00 2023-01-19: cranky: [INFO] Creating the filesystem.
2023-01-19 18:45:17 (26376): Guest Log: 20:45:23 EST -05:00 2023-01-19: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-19 18:45:17 (26376): Guest Log: 20:45:23 EST -05:00 2023-01-19: cranky: [INFO] Updating config.json.
2023-01-19 18:45:17 (26376): Guest Log: 20:45:23 EST -05:00 2023-01-19: cranky: [INFO] Running Container 'runc'.
2023-01-19 18:45:17 (26376): Guest Log: job: htmld=/shared/html/job
2023-01-19 18:45:17 (26376): Guest Log: job: unpack exitcode=0
2023-01-19 18:45:19 (26376): Guest Log: 20:45:25 EST -05:00 2023-01-19: cranky: [INFO] ===> [runRivet] Fri Jan 20 01:45:23 UTC 2023 [boinc pp jets 13000 150,-,2360 - pythia8 8.301 tune-AU2loxx 100000 326]
2023-01-19 20:24:17 (26376): Status Report: Job Duration: '864000.000000'
2023-01-19 20:24:17 (26376): Status Report: Elapsed Time: '277677.271939'
2023-01-19 20:24:17 (26376): Status Report: CPU Time: '249865.437500'
2023-01-19 22:04:23 (26376): Status Report: Job Duration: '864000.000000'
2023-01-19 22:04:23 (26376): Status Report: Elapsed Time: '283677.271939'
2023-01-19 22:04:23 (26376): Status Report: CPU Time: '255277.656250'
2023-01-19 23:44:29 (26376): Status Report: Job Duration: '864000.000000'
2023-01-19 23:44:29 (26376): Status Report: Elapsed Time: '289677.271939'
2023-01-19 23:44:29 (26376): Status Report: CPU Time: '260690.406250'
2023-01-19 23:59:43 (26376): VM state change detected. (old = 'running', new = 'saving')
23:59:44 (26376): BOINC client no longer exists - exiting
23:59:44 (26376): timer handler: client dead, exiting
2023-01-19 23:59:44 (26376): Stopping VM.
2023-01-19 23:59:44 (26376): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_13653536d373377f" savestate
Output:

2023-01-19 23:59:44 (26376): VM did not stop when requested.
2023-01-19 23:59:44 (26376): VM was successfully terminated.
2023-01-20 10:41:46 (7376): Detected: vboxwrapper 26206
2023-01-20 10:41:46 (7376): Detected: BOINC client v7.20.2
2023-01-20 10:41:48 (7376): Detected: VirtualBox VboxManage Interface (Version: 7.0.4)
2023-01-20 10:41:49 (7376): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-20 10:41:50 (7376): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-20 10:41:50 (7376): Guest Log: CPUID EDX: 0x178bfbff
2023-01-20 10:41:50 (7376): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-20 10:41:50 (7376): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-20 10:41:50 (7376): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-20 10:41:50 (7376): Guest Log: BIOS: Booting from Hard Disk...
2023-01-20 10:41:50 (7376): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-20 10:41:50 (7376): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-20 10:41:50 (7376): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-20 10:41:50 (7376): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-20 10:41:50 (7376): 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 (7376): Guest Log: 00:00:00.000182 main     Log opened 2023-01-20T01:44:52.295175000Z
2023-01-20 10:41:50 (7376): Guest Log: 00:00:00.000407 main     OS Product: Linux
2023-01-20 10:41:50 (7376): Guest Log: 00:00:00.000455 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-20 10:41:50 (7376): Guest Log: 00:00:00.000494 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-20 10:41:50 (7376): Guest Log: 00:00:00.000530 main     Executable: /usr/sbin/VBoxService
2023-01-20 10:41:50 (7376): Guest Log: 00:00:00.000531 main     Process ID: 3214
2023-01-20 10:41:50 (7376): Guest Log: 00:00:00.000532 main     Package type: LINUX_64BITS_GENERIC
2023-01-20 10:41:50 (7376): Guest Log: 00:00:00.004255 main     5.2.6 r120293 started. Verbose level = 0
2023-01-20 10:41:50 (7376): Guest Log: 20:45:17 EST -05:00 2023-01-19: cranky: [INFO] Detected Theory App
2023-01-20 10:41:50 (7376): Guest Log: 20:45:17 EST -05:00 2023-01-19: cranky: [INFO] Checking CVMFS.
2023-01-20 10:41:50 (7376): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-20 10:41:50 (7376): 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 (7376): Guest Log: 2.5.2.0 4100 0 27204 24859 3 1 1322703 4096000 0 65024 0 0 n/a 5 5 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2023-01-20 10:41:50 (7376): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-20 10:41:50 (7376): 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 (7376): Guest Log: 2.5.2.0 4078 0 27704 20301 3 2 1322703 4096000 0 65024 0 2 0 5674 5708 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-20 10:41:50 (7376): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-20 10:41:50 (7376): 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 (7376): Guest Log: 2.5.2.0 4052 0 27208 268 3 1 1322703 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 (7376): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-20 10:41:50 (7376): 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 (7376): Guest Log: 2.5.2.0 4121 0 34240 14405 3 1 1322703 4096000 0 65024 0 0 n/a 3340 4093 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-20 10:41:50 (7376): Guest Log: 20:45:22 EST -05:00 2023-01-19: cranky: [INFO] Checking runc.
2023-01-20 10:41:50 (7376): Guest Log: 20:45:23 EST -05:00 2023-01-19: cranky: [INFO] Creating the filesystem.
2023-01-20 10:41:50 (7376): Guest Log: 20:45:23 EST -05:00 2023-01-19: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-20 10:41:50 (7376): Guest Log: 20:45:23 EST -05:00 2023-01-19: cranky: [INFO] Updating config.json.
2023-01-20 10:41:50 (7376): Guest Log: 20:45:23 EST -05:00 2023-01-19: cranky: [INFO] Running Container 'runc'.
2023-01-20 10:41:50 (7376): Guest Log: job: htmld=/shared/html/job
2023-01-20 10:41:50 (7376): Guest Log: job: unpack exitcode=0
2023-01-20 10:41:50 (7376): Guest Log: 20:45:25 EST -05:00 2023-01-19: cranky: [INFO] ===> [runRivet] Fri Jan 20 01:45:23 UTC 2023 [boinc pp jets 13000 150,-,2360 - pythia8 8.301 tune-AU2loxx 100000 326]
2023-01-20 10:41:50 (7376): Starting VM using VBoxManage interface. (boinc_13653536d373377f, slot#43)
2023-01-20 10:42:03 (7376): Successfully started VM. (PID = '24928')
2023-01-20 10:42:03 (7376): Reporting VM Process ID to BOINC.
2023-01-20 10:42:03 (7376): Guest Log: BIOS: VirtualBox 7.0.4
2023-01-20 10:42:03 (7376): Guest Log: CPUID EDX: 0x178bfbff
2023-01-20 10:42:03 (7376): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-20 10:42:03 (7376): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-20 10:42:03 (7376): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-20 10:42:03 (7376): Detected: Web Application Enabled (http://localhost:60291)
2023-01-20 10:42:03 (7376): Status Report: Job Duration: '864000.000000'
2023-01-20 10:42:03 (7376): Status Report: Elapsed Time: '290591.271939'
2023-01-20 10:42:03 (7376): Status Report: CPU Time: '261511.359375'
2023-01-20 10:42:03 (7376): Preference change detected
2023-01-20 10:42:03 (7376): Setting CPU throttle for VM. (90%)
2023-01-20 10:42:04 (7376): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-20 10:42:05 (7376): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-20 10:42:05 (7376): Guest Log: BIOS: Booting from Hard Disk...
2023-01-20 10:42:07 (7376): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-20 10:42:07 (7376): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-20 10:42:31 (7376): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-20 10:42:31 (7376): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-20 10:42:34 (7376): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-20 10:42:34 (7376): Guest Log: 00:00:00.000108 main     Log opened 2023-01-20T17:42:40.895036000Z
2023-01-20 10:42:34 (7376): Guest Log: 00:00:00.000246 main     OS Product: Linux
2023-01-20 10:42:34 (7376): Guest Log: 00:00:00.000285 main     OS Release: 4.14.76-13.cernvm.x86_64
2023-01-20 10:42:34 (7376): Guest Log: 00:00:00.000331 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2023-01-20 10:42:34 (7376): Guest Log: 00:00:00.000364 main     Executable: /usr/sbin/VBoxService
2023-01-20 10:42:34 (7376): Guest Log: 00:00:00.000365 main     Process ID: 3212
2023-01-20 10:42:34 (7376): Guest Log: 00:00:00.000365 main     Package type: LINUX_64BITS_GENERIC
2023-01-20 10:42:34 (7376): Guest Log: 00:00:00.002693 main     5.2.6 r120293 started. Verbose level = 0
2023-01-20 10:42:52 (7376): Guest Log: 12:42:58 EST -05:00 2023-01-20: cranky: [INFO] Detected Theory App
2023-01-20 10:42:52 (7376): Guest Log: 12:42:58 EST -05:00 2023-01-20: cranky: [INFO] Checking CVMFS.
2023-01-20 10:42:53 (7376): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2023-01-20 10:42:53 (7376): 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 (7376): Guest Log: 2.5.2.0 4070 0 27204 24862 3 1 1343165 4096000 0 65024 0 0 n/a 5 8 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2023-01-20 10:42:53 (7376): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2023-01-20 10:42:54 (7376): 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 (7376): Guest Log: 2.5.2.0 4092 0 27548 20307 3 2 1343165 4096000 0 65024 0 2 0 5674 6778 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2023-01-20 10:42:55 (7376): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2023-01-20 10:42:55 (7376): 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 (7376): Guest Log: 2.5.2.0 4112 0 27180 268 3 1 1343165 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:56 (7376): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2023-01-20 10:42:56 (7376): 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 (7376): Guest Log: 2.5.2.0 4046 0 34528 14413 3 1 1343165 4096000 0 65024 0 0 n/a 3345 2266 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2023-01-20 10:42:56 (7376): Guest Log: 12:43:02 EST -05:00 2023-01-20: cranky: [INFO] Checking runc.
2023-01-20 10:42:56 (7376): Guest Log: 12:43:02 EST -05:00 2023-01-20: cranky: [INFO] Creating the filesystem.
2023-01-20 10:42:56 (7376): Guest Log: 12:43:02 EST -05:00 2023-01-20: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2023-01-20 10:42:56 (7376): Guest Log: 12:43:02 EST -05:00 2023-01-20: cranky: [INFO] Updating config.json.
2023-01-20 10:42:56 (7376): Guest Log: 12:43:02 EST -05:00 2023-01-20: cranky: [INFO] Running Container 'runc'.
2023-01-20 10:42:57 (7376): Guest Log: job: htmld=/shared/html/job
2023-01-20 10:42:57 (7376): Guest Log: job: unpack exitcode=0
2023-01-20 10:42:58 (7376): Guest Log: 12:43:04 EST -05:00 2023-01-20: cranky: [INFO] ===> [runRivet] Fri Jan 20 17:43:03 UTC 2023 [boinc pp jets 13000 150,-,2360 - pythia8 8.301 tune-AU2loxx 100000 326]
2023-01-20 10:55:59 (7376): VM state change detected. (old = 'running', new = 'paused')
2023-01-20 11:14:50 (7376): VM state change detected. (old = 'paused', new = 'running')
2023-01-20 12:41:01 (7376): Status Report: Job Duration: '864000.000000'
2023-01-20 12:41:01 (7376): Status Report: Elapsed Time: '296591.271939'
2023-01-20 12:41:01 (7376): Status Report: CPU Time: '266900.765625'
2023-01-20 14:21:07 (7376): Status Report: Job Duration: '864000.000000'
2023-01-20 14:21:07 (7376): Status Report: Elapsed Time: '302591.271939'
2023-01-20 14:21:07 (7376): Status Report: CPU Time: '272313.437500'
2023-01-20 14:36:30 (7376): VM state change detected. (old = 'running', new = 'paused')
2023-01-20 14:53:45 (7376): VM state change detected. (old = 'paused', new = 'running')
2023-01-20 14:54:01 (7376): Guest Log: 03:35:26.209361 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 164 322 438 000ns (GuestNow=1 674 251 641 298 138 000 ns GuestLast=1 674 249 476 975 700 000 ns fSetTimeLastLoop=true )
2023-01-20 15:31:47 (7376): VM state change detected. (old = 'running', new = 'paused')
2023-01-20 15:46:07 (7376): VM state change detected. (old = 'paused', new = 'running')
2023-01-20 15:52:09 (7376): VM state change detected. (old = 'running', new = 'paused')
2023-01-20 15:57:19 (7376): VM state change detected. (old = 'paused', new = 'running')
2023-01-20 15:58:46 (7376): VM state change detected. (old = 'running', new = 'paused')
2023-01-20 16:16:29 (7376): VM state change detected. (old = 'paused', new = 'running')
2023-01-20 16:16:45 (7376): Guest Log: 04:20:57.613684 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 242 467 621 000ns (GuestNow=1 674 256 605 184 194 000 ns GuestLast=1 674 254 362 716 573 000 ns fSetTimeLastLoop=true )
2023-01-20 16:27:50 (7376): VM state change detected. (old = 'running', new = 'paused')
2023-01-20 16:46:18 (7376): VM state change detected. (old = 'paused', new = 'running')
2023-01-20 17:14:13 (7376): Status Report: Job Duration: '864000.000000'
2023-01-20 17:14:13 (7376): Status Report: Elapsed Time: '308591.271939'
2023-01-20 17:14:13 (7376): Status Report: CPU Time: '277726.312500'
2023-01-20 18:54:19 (7376): Status Report: Job Duration: '864000.000000'
2023-01-20 18:54:19 (7376): Status Report: Elapsed Time: '314591.271939'
2023-01-20 18:54:19 (7376): Status Report: CPU Time: '283138.187500'
2023-01-20 20:34:25 (7376): Status Report: Job Duration: '864000.000000'
2023-01-20 20:34:25 (7376): Status Report: Elapsed Time: '320591.271939'
2023-01-20 20:34:25 (7376): Status Report: CPU Time: '288549.390625'
2023-01-20 21:40:44 (7376): Guest Log: job: run exitcode=0
2023-01-20 21:40:44 (7376): Guest Log: job: diskusage=2504
2023-01-20 21:40:44 (7376): Guest Log: job: logsize=76 k
2023-01-20 21:40:44 (7376): Guest Log: job: times=
2023-01-20 21:40:44 (7376): Guest Log: 0m0.008s 0m0.006s
2023-01-20 21:40:44 (7376): Guest Log: 560m29.123s 1m54.675s
2023-01-20 21:40:44 (7376): Guest Log: job: cpuusage=33744
2023-01-20 21:40:44 (7376): Guest Log: 23:22:24 EST -05:00 2023-01-20: cranky: [INFO] Container 'runc' finished with status code 0.
2023-01-20 21:40:44 (7376): Guest Log: 23:22:24 EST -05:00 2023-01-20: cranky: [INFO] Preparing output.
2023-01-20 21:40:45 (7376): Guest Log: [INFO] Job Finished
2023-01-20 21:40:45 (7376): Guest Log: [INFO] Shutting Down.
2023-01-20 21:40:45 (7376): VM Completion File Detected.
2023-01-20 21:40:45 (7376): VM Completion Message: Job Finished
.
2023-01-20 21:40:45 (7376): Powering off VM.
2023-01-20 21:40:45 (7376): Successfully stopped VM.
2023-01-20 21:40:45 (7376): Deregistering VM. (boinc_13653536d373377f, slot#43)
2023-01-20 21:40:46 (7376): Removing network bandwidth throttle group from VM.
2023-01-20 21:40:46 (7376): Removing VM from VirtualBox.
21:40:51 (7376): called boinc_finish(0)

</stderr_txt>
]]>


©2024 CERN