Task 286533221

Name	Theory_2390-1134619-50_1
Workunit	146708782
Created	4 Oct 2020, 14:57:21 UTC
Sent	4 Oct 2020, 15:05:40 UTC
Report deadline	15 Oct 2020, 15:05:40 UTC
Received	16 Oct 2020, 11:06:10 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	10656353
Run time	1 days 4 hours 38 min 58 sec
CPU time	4 hours 3 min 28 sec
Validate state	Valid
Credit	1,007.99
Device peak FLOPS	4.22 GFLOPS
Application version	Theory Simulation v300.06 (vbox64_theory) windows_x86_64
Peak working set size	60.13 MB
Peak swap size	55.59 MB
Peak disk usage	1.92 GB
Stderr output

<core_client_version>7.16.7</core_client_version>
<![CDATA[
<stderr_txt>
2020-10-08 22:39:41 (56700): Detected: vboxwrapper 26197
2020-10-08 22:39:41 (56700): Detected: BOINC client v7.7
2020-10-08 22:39:41 (56700): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2020-10-08 22:39:41 (56700): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-08 22:39:41 (56700): Successfully copied 'init_data.xml' to the shared directory.
2020-10-08 22:39:41 (56700): Successfully copied 'input' to the shared directory.
2020-10-08 22:39:42 (56700): Create VM. (boinc_0a205fdd5d214448, slot#1)
2020-10-08 22:39:43 (56700): Setting Memory Size for VM. (630MB)
2020-10-08 22:39:43 (56700): Setting CPU Count for VM. (1)
2020-10-08 22:39:44 (56700): Setting Chipset Options for VM.
2020-10-08 22:39:44 (56700): Setting Boot Options for VM.
2020-10-08 22:39:44 (56700): Setting Network Configuration for NAT.
2020-10-08 22:39:44 (56700): Enabling VM Network Access.
2020-10-08 22:39:45 (56700): Disabling USB Support for VM.
2020-10-08 22:39:45 (56700): Disabling COM Port Support for VM.
2020-10-08 22:39:45 (56700): Disabling LPT Port Support for VM.
2020-10-08 22:39:45 (56700): Disabling Audio Support for VM.
2020-10-08 22:39:46 (56700): Disabling Clipboard Support for VM.
2020-10-08 22:39:46 (56700): Disabling Drag and Drop Support for VM.
2020-10-08 22:39:46 (56700): Adding storage controller(s) to VM.
2020-10-08 22:39:46 (56700): Adding virtual disk drive to VM. (vm_image.vdi)
2020-10-08 22:39:47 (56700): Adding VirtualBox Guest Additions to VM.
2020-10-08 22:39:47 (56700): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2020-10-08 22:39:47 (56700): forwarding host port 62589 to guest port 80
2020-10-08 22:39:47 (56700): Enabling remote desktop for VM.
2020-10-08 22:39:48 (56700): Required extension pack not installed, remote desktop not enabled.
2020-10-08 22:39:48 (56700): Enabling shared directory for VM.
2020-10-08 22:39:48 (56700): Starting VM using VBoxManage interface. (boinc_0a205fdd5d214448, slot#1)
2020-10-08 22:39:53 (56700): Successfully started VM. (PID = '36340')
2020-10-08 22:39:53 (56700): Reporting VM Process ID to BOINC.
2020-10-08 22:39:53 (56700): Guest Log: BIOS: VirtualBox 6.1.4

2020-10-08 22:39:53 (56700): Guest Log: CPUID EDX: 0x178bfbff

2020-10-08 22:39:53 (56700): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-10-08 22:39:53 (56700): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-08 22:39:53 (56700): Detected: Web Application Enabled (http://localhost:62589)
2020-10-08 22:39:53 (56700): Preference change detected
2020-10-08 22:39:53 (56700): Setting CPU throttle for VM. (100%)
2020-10-08 22:39:54 (56700): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-08 22:39:55 (56700): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-10-08 22:39:55 (56700): Guest Log: BIOS: Booting from Hard Disk...

2020-10-08 22:39:57 (56700): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-10-08 22:39:57 (56700): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-10-08 22:40:40 (56700): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-10-08 22:40:40 (56700): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-10-08 22:40:43 (56700): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-10-08 22:40:43 (56700): Guest Log: 00:00:00.000127 main     Log opened 2020-10-08T20:40:43.157794000Z

2020-10-08 22:40:43 (56700): Guest Log: 00:00:00.000240 main     OS Product: Linux

2020-10-08 22:40:43 (56700): Guest Log: 00:00:00.000276 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-10-08 22:40:43 (56700): Guest Log: 00:00:00.000323 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-10-08 22:40:43 (56700): Guest Log: 00:00:00.000355 main     Executable: /usr/sbin/VBoxService

2020-10-08 22:40:43 (56700): Guest Log: 00:00:00.000356 main     Process ID: 3199

2020-10-08 22:40:43 (56700): Guest Log: 00:00:00.000358 main     Package type: LINUX_64BITS_GENERIC

2020-10-08 22:40:43 (56700): Guest Log: 00:00:00.003858 main     5.2.6 r120293 started. Verbose level = 0

2020-10-08 22:41:40 (56700): Guest Log: 22:41:40 CEST +02:00 2020-10-08: cranky: [INFO] Detected Theory App

2020-10-08 22:41:40 (56700): Guest Log: 22:41:40 CEST +02:00 2020-10-08: cranky: [INFO] Checking CVMFS.

2020-10-08 22:41:41 (56700): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-10-08 22:41:42 (56700): 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

2020-10-08 22:41:42 (56700): Guest Log: 2.5.2.0 4096 0 27876 15697 2 1 244090 4096000 0 65024 1 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 0

2020-10-08 22:41:43 (56700): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-10-08 22:41:44 (56700): 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

2020-10-08 22:41:44 (56700): Guest Log: 2.5.2.0 4043 0 27280 10299 2 2 244090 4096000 0 65024 3 2 0 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 0

2020-10-08 22:41:45 (56700): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-10-08 22:41:46 (56700): 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

2020-10-08 22:41:46 (56700): Guest Log: 2.5.2.0 4120 0 25060 245 2 1 244090 4096000 0 65024 1 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 0

2020-10-08 22:41:47 (56700): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-10-08 22:41:47 (56700): 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

2020-10-08 22:41:47 (56700): Guest Log: 2.5.2.0 4069 0 28688 5181 2 1 244090 4096000 0 65024 1 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 0

2020-10-08 22:41:47 (56700): Guest Log: 22:41:47 CEST +02:00 2020-10-08: cranky: [INFO] Checking runc.

2020-10-08 22:41:48 (56700): Guest Log: 22:41:47 CEST +02:00 2020-10-08: cranky: [INFO] Creating the filesystem.

2020-10-08 22:41:48 (56700): Guest Log: 22:41:47 CEST +02:00 2020-10-08: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-10-08 22:41:48 (56700): Guest Log: 22:41:47 CEST +02:00 2020-10-08: cranky: [INFO] Updating config.json.

2020-10-08 22:41:48 (56700): Guest Log: 22:41:47 CEST +02:00 2020-10-08: cranky: [INFO] Running Container 'runc'.

2020-10-08 22:41:48 (56700): Guest Log: job: htmld=/shared/html/job

2020-10-08 22:41:48 (56700): Guest Log: job: unpack exitcode=0

2020-10-08 22:41:49 (56700): Guest Log: job: run exitcode=1

2020-10-08 22:41:49 (56700): Guest Log: job: diskusage=1452

2020-10-08 22:41:49 (56700): Guest Log: job: logsize=4 k

2020-10-08 22:41:49 (56700): Guest Log: job: times=

2020-10-08 22:41:49 (56700): Guest Log: 0m0.014s 0m0.010s

2020-10-08 22:41:49 (56700): Guest Log: 0m0.041s 0m0.065s

2020-10-08 22:41:49 (56700): Guest Log: job: cpuusage=0

2020-10-08 22:41:50 (56700): Guest Log: 22:41:49 CEST +02:00 2020-10-08: cranky: [INFO] Container 'runc' finished with status code 1.

2020-10-08 22:41:50 (56700): Guest Log: 22:41:49 CEST +02:00 2020-10-08: cranky: [INFO] Preparing output.

2020-10-08 23:47:01 (56700): Stopping VM.
2020-10-13 12:33:12 (13816): Detected: vboxwrapper 26197
2020-10-13 12:33:12 (13816): Detected: BOINC client v7.7
2020-10-13 12:33:12 (13816): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2020-10-13 12:33:13 (13816): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-13 12:33:13 (13816): Starting VM using VBoxManage interface. (boinc_0a205fdd5d214448, slot#1)
2020-10-13 12:33:21 (13816): Successfully started VM. (PID = '19944')
2020-10-13 12:33:21 (13816): Reporting VM Process ID to BOINC.
2020-10-13 12:33:21 (13816): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-13 12:33:21 (13816): Detected: Web Application Enabled (http://localhost:62589)
2020-10-13 12:33:21 (13816): Preference change detected
2020-10-13 12:33:21 (13816): Setting CPU throttle for VM. (100%)
2020-10-13 12:33:22 (13816): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-13 12:33:22 (13816): Guest Log: 01:06:20.194711 timesync vgsvcTimeSyncWorker: Radical host time change: 391 588 785 000 000ns (HostNow=1 602 585 202 138 000 000 ns HostLast=1 602 193 613 353 000 000 ns)

2020-10-13 12:33:32 (13816): Guest Log: 01:06:30.196586 timesync vgsvcTimeSyncWorker: Radical guest time change: 391 588 787 906 000ns (GuestNow=1 602 585 212 140 022 000 ns GuestLast=1 602 193 623 352 116 000 ns fSetTimeLastLoop=true )

2020-10-13 13:06:18 (13816): Status Report: Job Duration: '864000.000000'
2020-10-13 13:06:18 (13816): Status Report: Elapsed Time: '6000.000000'
2020-10-13 13:06:18 (13816): Status Report: CPU Time: '88.796875'
2020-10-13 14:45:24 (13816): Stopping VM.
2020-10-13 16:22:33 (16844): Detected: vboxwrapper 26197
2020-10-13 16:22:33 (16844): Detected: BOINC client v7.7
2020-10-13 16:22:34 (16844): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2020-10-13 16:22:34 (16844): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-13 16:22:34 (16844): Starting VM using VBoxManage interface. (boinc_0a205fdd5d214448, slot#1)
2020-10-13 16:22:42 (16844): Successfully started VM. (PID = '8744')
2020-10-13 16:22:42 (16844): Reporting VM Process ID to BOINC.
2020-10-13 16:22:42 (16844): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-13 16:22:42 (16844): Detected: Web Application Enabled (http://localhost:62589)
2020-10-13 16:22:42 (16844): Status Report: Job Duration: '864000.000000'
2020-10-13 16:22:42 (16844): Status Report: Elapsed Time: '11941.000000'
2020-10-13 16:22:42 (16844): Status Report: CPU Time: '128.796875'
2020-10-13 16:22:42 (16844): Preference change detected
2020-10-13 16:22:42 (16844): Setting CPU throttle for VM. (100%)
2020-10-13 16:22:43 (16844): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-13 16:22:50 (16844): Guest Log: 03:18:30.565813 timesync vgsvcTimeSyncWorker: Radical host time change: 5 847 539 000 000ns (HostNow=1 602 598 970 042 000 000 ns HostLast=1 602 593 122 503 000 000 ns)

2020-10-13 16:23:00 (16844): Guest Log: 03:18:40.566364 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 847 533 303 000ns (GuestNow=1 602 598 980 042 555 000 ns GuestLast=1 602 593 132 509 252 000 ns fSetTimeLastLoop=true )

2020-10-13 17:40:23 (16844): Stopping VM.
2020-10-13 18:34:50 (18420): Detected: vboxwrapper 26197
2020-10-13 18:34:50 (18420): Detected: BOINC client v7.7
2020-10-13 18:34:50 (18420): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2020-10-13 18:34:51 (18420): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-13 18:34:51 (18420): ERROR: VM failed to start
2020-10-13 18:34:56 (18420): 
   BOINC will be notified that it needs to clean up the environment.
 		    This is a temporary problem and so this job will be rescheduled for another time.

2020-10-13 18:39:43 (23344): Detected: vboxwrapper 26197
2020-10-13 18:39:43 (23344): Detected: BOINC client v7.7
2020-10-13 18:39:44 (23344): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2020-10-13 18:39:44 (23344): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-13 18:39:45 (23344): Starting VM using VBoxManage interface. (boinc_0a205fdd5d214448, slot#1)
2020-10-13 18:39:54 (23344): Successfully started VM. (PID = '27332')
2020-10-13 18:39:54 (23344): Reporting VM Process ID to BOINC.
2020-10-13 18:39:54 (23344): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-13 18:39:54 (23344): Detected: Web Application Enabled (http://localhost:62589)
2020-10-13 18:39:54 (23344): Status Report: Job Duration: '864000.000000'
2020-10-13 18:39:54 (23344): Status Report: Elapsed Time: '16596.862583'
2020-10-13 18:39:54 (23344): Status Report: CPU Time: '164.359375'
2020-10-13 18:39:54 (23344): Preference change detected
2020-10-13 18:39:54 (23344): Setting CPU throttle for VM. (100%)
2020-10-13 18:39:54 (23344): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-13 18:40:02 (23344): Guest Log: 04:36:12.644434 timesync vgsvcTimeSyncWorker: Radical host time change: 3 579 804 000 000ns (HostNow=1 602 607 201 920 000 000 ns HostLast=1 602 603 622 116 000 000 ns)

2020-10-13 18:40:12 (23344): Guest Log: 04:36:22.645482 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 579 800 581 000ns (GuestNow=1 602 607 211 921 097 000 ns GuestLast=1 602 603 632 120 516 000 ns fSetTimeLastLoop=true )

2020-10-13 19:40:29 (23344): Stopping VM.
2020-10-13 19:43:07 (12064): Detected: vboxwrapper 26197
2020-10-13 19:43:07 (12064): Detected: BOINC client v7.7
2020-10-13 19:43:08 (12064): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2020-10-13 19:43:08 (12064): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-13 19:43:08 (12064): Starting VM using VBoxManage interface. (boinc_0a205fdd5d214448, slot#1)
2020-10-13 19:43:16 (12064): Successfully started VM. (PID = '38388')
2020-10-13 19:43:16 (12064): Reporting VM Process ID to BOINC.
2020-10-13 19:43:16 (12064): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-13 19:43:16 (12064): Detected: Web Application Enabled (http://localhost:62589)
2020-10-13 19:43:16 (12064): Status Report: Job Duration: '864000.000000'
2020-10-13 19:43:16 (12064): Status Report: Elapsed Time: '20228.862583'
2020-10-13 19:43:16 (12064): Status Report: CPU Time: '199.171875'
2020-10-13 19:43:16 (12064): Preference change detected
2020-10-13 19:43:16 (12064): Setting CPU throttle for VM. (100%)
2020-10-13 19:43:16 (12064): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-13 21:02:13 (12064): Stopping VM.
2020-10-13 21:25:16 (46312): Detected: vboxwrapper 26197
2020-10-13 21:25:16 (46312): Detected: BOINC client v7.7
2020-10-13 21:25:16 (46312): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2020-10-13 21:25:17 (46312): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-13 21:25:17 (46312): Starting VM using VBoxManage interface. (boinc_0a205fdd5d214448, slot#1)
2020-10-13 21:25:24 (46312): Successfully started VM. (PID = '20512')
2020-10-13 21:25:24 (46312): Reporting VM Process ID to BOINC.
2020-10-13 21:25:24 (46312): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-13 21:25:24 (46312): Detected: Web Application Enabled (http://localhost:62589)
2020-10-13 21:25:24 (46312): Status Report: Job Duration: '864000.000000'
2020-10-13 21:25:24 (46312): Status Report: Elapsed Time: '24961.862583'
2020-10-13 21:25:24 (46312): Status Report: CPU Time: '240.515625'
2020-10-13 21:25:24 (46312): Preference change detected
2020-10-13 21:25:24 (46312): Setting CPU throttle for VM. (100%)
2020-10-13 21:25:24 (46312): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-13 23:05:28 (46312): Status Report: Job Duration: '864000.000000'
2020-10-13 23:05:28 (46312): Status Report: Elapsed Time: '30961.862583'
2020-10-13 23:05:28 (46312): Status Report: CPU Time: '285.328125'
2020-10-13 23:30:54 (46312): Stopping VM.
2020-10-14 11:01:37 (11212): Detected: vboxwrapper 26197
2020-10-14 11:01:37 (11212): Detected: BOINC client v7.7
2020-10-14 11:01:38 (11212): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2020-10-14 11:01:38 (11212): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-14 11:01:38 (11212): Starting VM using VBoxManage interface. (boinc_0a205fdd5d214448, slot#1)
2020-10-14 11:01:47 (11212): Successfully started VM. (PID = '34872')
2020-10-14 11:01:47 (11212): Reporting VM Process ID to BOINC.
2020-10-14 11:01:47 (11212): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-14 11:01:47 (11212): Detected: Web Application Enabled (http://localhost:62589)
2020-10-14 11:01:47 (11212): Status Report: Job Duration: '864000.000000'
2020-10-14 11:01:47 (11212): Status Report: Elapsed Time: '32486.862583'
2020-10-14 11:01:47 (11212): Status Report: CPU Time: '295.171875'
2020-10-14 11:01:47 (11212): Preference change detected
2020-10-14 11:01:47 (11212): Setting CPU throttle for VM. (100%)
2020-10-14 11:01:47 (11212): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-14 11:01:49 (11212): Guest Log: 09:01:13.489299 timesync vgsvcTimeSyncWorker: Radical host time change: 41 460 765 000 000ns (HostNow=1 602 666 108 579 000 000 ns HostLast=1 602 624 647 814 000 000 ns)

2020-10-14 11:01:59 (11212): Guest Log: 09:01:23.489690 timesync vgsvcTimeSyncWorker: Radical guest time change: 41 460 757 605 000ns (GuestNow=1 602 666 118 579 401 000 ns GuestLast=1 602 624 657 821 796 000 ns fSetTimeLastLoop=true )

2020-10-14 12:41:52 (11212): Status Report: Job Duration: '864000.000000'
2020-10-14 12:41:52 (11212): Status Report: Elapsed Time: '38487.862583'
2020-10-14 12:41:52 (11212): Status Report: CPU Time: '340.578125'
2020-10-14 12:52:25 (11212): Stopping VM.
2020-10-14 12:53:28 (55540): Detected: vboxwrapper 26197
2020-10-14 12:53:28 (55540): Detected: BOINC client v7.7
2020-10-14 12:53:29 (55540): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2020-10-14 12:53:29 (55540): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-14 12:53:30 (55540): Starting VM using VBoxManage interface. (boinc_0a205fdd5d214448, slot#1)
2020-10-14 12:53:45 (55540): Successfully started VM. (PID = '42232')
2020-10-14 12:53:45 (55540): Reporting VM Process ID to BOINC.
2020-10-14 12:53:45 (55540): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-14 12:53:45 (55540): Detected: Web Application Enabled (http://localhost:62589)
2020-10-14 12:53:45 (55540): Status Report: Job Duration: '864000.000000'
2020-10-14 12:53:45 (55540): Status Report: Elapsed Time: '39119.862583'
2020-10-14 12:53:45 (55540): Status Report: CPU Time: '346.109375'
2020-10-14 12:53:45 (55540): Preference change detected
2020-10-14 12:53:45 (55540): Setting CPU throttle for VM. (100%)
2020-10-14 12:53:45 (55540): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-14 14:33:49 (55540): Status Report: Job Duration: '864000.000000'
2020-10-14 14:33:49 (55540): Status Report: Elapsed Time: '45119.862583'
2020-10-14 14:33:49 (55540): Status Report: CPU Time: '389.796875'
2020-10-14 16:13:54 (55540): Status Report: Job Duration: '864000.000000'
2020-10-14 16:13:54 (55540): Status Report: Elapsed Time: '51119.862583'
2020-10-14 16:13:54 (55540): Status Report: CPU Time: '427.468750'
2020-10-14 17:53:58 (55540): Status Report: Job Duration: '864000.000000'
2020-10-14 17:53:58 (55540): Status Report: Elapsed Time: '57119.862583'
2020-10-14 17:53:58 (55540): Status Report: CPU Time: '465.234375'
2020-10-14 19:34:03 (55540): Status Report: Job Duration: '864000.000000'
2020-10-14 19:34:03 (55540): Status Report: Elapsed Time: '63119.862583'
2020-10-14 19:34:03 (55540): Status Report: CPU Time: '504.859375'
2020-10-14 21:14:08 (55540): Status Report: Job Duration: '864000.000000'
2020-10-14 21:14:08 (55540): Status Report: Elapsed Time: '69119.862583'
2020-10-14 21:14:08 (55540): Status Report: CPU Time: '544.625000'
2020-10-14 22:54:12 (55540): Status Report: Job Duration: '864000.000000'
2020-10-14 22:54:12 (55540): Status Report: Elapsed Time: '75119.862583'
2020-10-14 22:54:12 (55540): Status Report: CPU Time: '583.078125'
2020-10-15 00:34:17 (55540): Status Report: Job Duration: '864000.000000'
2020-10-15 00:34:17 (55540): Status Report: Elapsed Time: '81119.862583'
2020-10-15 00:34:17 (55540): Status Report: CPU Time: '618.671875'
2020-10-15 02:14:21 (55540): Status Report: Job Duration: '864000.000000'
2020-10-15 02:14:21 (55540): Status Report: Elapsed Time: '87119.862583'
2020-10-15 02:14:21 (55540): Status Report: CPU Time: '653.343750'
2020-10-15 02:41:04 (55540): Stopping VM.
2020-10-15 02:41:04 (55540): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_0a205fdd5d214448" savestate
Output:

2020-10-15 02:41:04 (55540): VM did not stop when requested.
2020-10-15 02:41:04 (55540): VM was NOT successfully terminated.
2020-10-15 02:45:15 (11172): Detected: vboxwrapper 26197
2020-10-15 02:45:16 (11172): Detected: BOINC client v7.7
2020-10-15 02:45:17 (11172): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2020-10-15 02:45:17 (11172): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-15 02:45:18 (11172): Starting VM using VBoxManage interface. (boinc_0a205fdd5d214448, slot#1)
2020-10-15 02:45:25 (11172): Successfully started VM. (PID = '10696')
2020-10-15 02:45:25 (11172): Reporting VM Process ID to BOINC.
2020-10-15 02:45:25 (11172): Guest Log: BIOS: VirtualBox 6.1.4

2020-10-15 02:45:25 (11172): Guest Log: CPUID EDX: 0x178bfbff

2020-10-15 02:45:25 (11172): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-10-15 02:45:25 (11172): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-15 02:45:25 (11172): Detected: Web Application Enabled (http://localhost:62589)
2020-10-15 02:45:25 (11172): Status Report: Job Duration: '864000.000000'
2020-10-15 02:45:25 (11172): Status Report: Elapsed Time: '88720.862583'
2020-10-15 02:45:25 (11172): Status Report: CPU Time: '662.953125'
2020-10-15 02:45:25 (11172): Preference change detected
2020-10-15 02:45:25 (11172): Setting CPU throttle for VM. (100%)
2020-10-15 02:45:25 (11172): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-15 02:45:27 (11172): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-10-15 02:45:27 (11172): Guest Log: BIOS: Booting from Hard Disk...

2020-10-15 02:45:29 (11172): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-10-15 02:45:29 (11172): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-10-15 02:46:04 (11172): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-10-15 02:46:04 (11172): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-10-15 02:46:09 (11172): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-10-15 02:46:09 (11172): Guest Log: 00:00:00.000393 main     Log opened 2020-10-15T00:46:08.427791000Z

2020-10-15 02:46:09 (11172): Guest Log: 00:00:00.001102 main     OS Product: Linux

2020-10-15 02:46:09 (11172): Guest Log: 00:00:00.001231 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-10-15 02:46:09 (11172): Guest Log: 00:00:00.001280 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-10-15 02:46:09 (11172): Guest Log: 00:00:00.001328 main     Executable: /usr/sbin/VBoxService

2020-10-15 02:46:09 (11172): Guest Log: 00:00:00.001330 main     Process ID: 3199

2020-10-15 02:46:09 (11172): Guest Log: 00:00:00.001332 main     Package type: LINUX_64BITS_GENERIC

2020-10-15 02:46:09 (11172): Guest Log: 00:00:00.005050 main     5.2.6 r120293 started. Verbose level = 0

2020-10-15 02:46:43 (11172): Guest Log: 02:46:42 CEST +02:00 2020-10-15: cranky: [INFO] Detected Theory App

2020-10-15 02:46:43 (11172): Guest Log: 02:46:42 CEST +02:00 2020-10-15: cranky: [INFO] Checking CVMFS.

2020-10-15 02:46:44 (11172): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-10-15 02:46:45 (11172): 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

2020-10-15 02:46:45 (11172): Guest Log: 2.5.2.0 4082 0 26708 19103 3 1 272746 4096000 0 65024 0 0 n/a 5 13 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-10-15 02:46:46 (11172): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-10-15 02:46:46 (11172): 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

2020-10-15 02:46:46 (11172): Guest Log: 2.5.2.0 4054 0 25476 13453 3 2 272746 4096000 0 65024 0 2 -100 6411 5536 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-10-15 02:46:48 (11172): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-10-15 02:46:48 (11172): 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

2020-10-15 02:46:48 (11172): Guest Log: 2.5.2.0 4029 0 25196 259 3 1 272746 4096000 0 65024 0 0 n/a 4 8 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-10-15 02:46:49 (11172): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-10-15 02:46:50 (11172): 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

2020-10-15 02:46:50 (11172): Guest Log: 2.5.2.0 4105 0 28192 7702 3 1 272746 4096000 0 65024 0 0 n/a 4448 2918 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-10-15 02:46:50 (11172): Guest Log: 02:46:48 CEST +02:00 2020-10-15: cranky: [INFO] Checking runc.

2020-10-15 02:46:50 (11172): Guest Log: 02:46:48 CEST +02:00 2020-10-15: cranky: [INFO] Creating the filesystem.

2020-10-15 02:46:50 (11172): Guest Log: 02:46:48 CEST +02:00 2020-10-15: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-10-15 02:46:50 (11172): Guest Log: 02:46:49 CEST +02:00 2020-10-15: cranky: [INFO] Updating config.json.

2020-10-15 02:46:50 (11172): Guest Log: 02:46:49 CEST +02:00 2020-10-15: cranky: [INFO] Running Container 'runc'.

2020-10-15 02:46:52 (11172): Guest Log: job: htmld=/shared/html/job

2020-10-15 02:46:52 (11172): Guest Log: job: unpack exitcode=0

2020-10-15 02:46:52 (11172): Guest Log: 02:46:51 CEST +02:00 2020-10-15: cranky: [INFO] ===> [runRivet] Thu Oct 15 00:46:50 UTC 2020 [boinc pp jets 8000 600 - pythia6 6.428 392 100000 50]

2020-10-15 03:49:05 (11172): Stopping VM.
2020-10-15 18:54:50 (5476): Detected: vboxwrapper 26197
2020-10-15 18:54:50 (5476): Detected: BOINC client v7.7
2020-10-15 18:54:51 (5476): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2020-10-15 18:54:51 (5476): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-15 18:54:51 (5476): Starting VM using VBoxManage interface. (boinc_0a205fdd5d214448, slot#1)
2020-10-15 18:55:01 (5476): Successfully started VM. (PID = '7396')
2020-10-15 18:55:01 (5476): Reporting VM Process ID to BOINC.
2020-10-15 18:55:01 (5476): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-15 18:55:01 (5476): Detected: Web Application Enabled (http://localhost:62589)
2020-10-15 18:55:01 (5476): Status Report: Job Duration: '864000.000000'
2020-10-15 18:55:01 (5476): Status Report: Elapsed Time: '92529.862583'
2020-10-15 18:55:01 (5476): Status Report: CPU Time: '4317.796875'
2020-10-15 18:55:01 (5476): Preference change detected
2020-10-15 18:55:01 (5476): Setting CPU throttle for VM. (100%)
2020-10-15 18:55:02 (5476): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-15 18:55:06 (5476): Guest Log: 01:03:02.576168 timesync vgsvcTimeSyncWorker: Radical host time change: 54 364 633 000 000ns (HostNow=1 602 780 905 531 000 000 ns HostLast=1 602 726 540 898 000 000 ns)

2020-10-15 18:55:16 (5476): Guest Log: 01:03:12.621784 timesync vgsvcTimeSyncWorker: Radical guest time change: 54 364 705 308 000ns (GuestNow=1 602 780 915 616 625 000 ns GuestLast=1 602 726 550 911 317 000 ns fSetTimeLastLoop=true )

2020-10-15 19:55:33 (5476): Stopping VM.
2020-10-15 21:43:18 (11848): Detected: vboxwrapper 26197
2020-10-15 21:43:18 (11848): Detected: BOINC client v7.7
2020-10-15 21:43:19 (11848): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2020-10-15 21:43:19 (11848): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-15 21:43:20 (11848): Starting VM using VBoxManage interface. (boinc_0a205fdd5d214448, slot#1)
2020-10-15 21:43:28 (11848): Successfully started VM. (PID = '2972')
2020-10-15 21:43:28 (11848): Reporting VM Process ID to BOINC.
2020-10-15 21:43:28 (11848): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-15 21:43:28 (11848): Detected: Web Application Enabled (http://localhost:62589)
2020-10-15 21:43:28 (11848): Status Report: Job Duration: '864000.000000'
2020-10-15 21:43:28 (11848): Status Report: Elapsed Time: '96148.862583'
2020-10-15 21:43:28 (11848): Status Report: CPU Time: '7922.640625'
2020-10-15 21:43:28 (11848): Preference change detected
2020-10-15 21:43:28 (11848): Setting CPU throttle for VM. (100%)
2020-10-15 21:43:29 (11848): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-15 21:43:32 (11848): Guest Log: 02:03:35.017689 timesync vgsvcTimeSyncWorker: Radical host time change: 6 483 937 000 000ns (HostNow=1 602 791 011 993 000 000 ns HostLast=1 602 784 528 056 000 000 ns)

2020-10-15 21:43:42 (11848): Guest Log: 02:03:45.271359 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 484 234 184 000ns (GuestNow=1 602 791 022 246 687 000 ns GuestLast=1 602 784 538 012 503 000 ns fSetTimeLastLoop=true )

2020-10-15 22:44:14 (11848): Stopping VM.
2020-10-16 12:09:37 (272): Detected: vboxwrapper 26197
2020-10-16 12:09:37 (272): Detected: BOINC client v7.7
2020-10-16 12:09:38 (272): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2020-10-16 12:09:38 (272): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-16 12:09:38 (272): Starting VM using VBoxManage interface. (boinc_0a205fdd5d214448, slot#1)
2020-10-16 12:09:48 (272): Successfully started VM. (PID = '4528')
2020-10-16 12:09:48 (272): Reporting VM Process ID to BOINC.
2020-10-16 12:09:48 (272): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-16 12:09:48 (272): Detected: Web Application Enabled (http://localhost:62589)
2020-10-16 12:09:48 (272): Status Report: Job Duration: '864000.000000'
2020-10-16 12:09:48 (272): Status Report: Elapsed Time: '99789.862583'
2020-10-16 12:09:48 (272): Status Report: CPU Time: '11559.296875'
2020-10-16 12:09:48 (272): Preference change detected
2020-10-16 12:09:48 (272): Setting CPU throttle for VM. (100%)
2020-10-16 12:09:49 (272): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-16 12:09:57 (272): Guest Log: 03:04:27.639197 timesync vgsvcTimeSyncWorker: Radical host time change: 48 342 112 000 000ns (HostNow=1 602 842 996 715 000 000 ns HostLast=1 602 794 654 603 000 000 ns)

2020-10-16 12:10:07 (272): Guest Log: 03:04:37.646805 timesync vgsvcTimeSyncWorker: Radical guest time change: 48 342 108 184 000ns (GuestNow=1 602 843 006 722 650 000 ns GuestLast=1 602 794 664 614 466 000 ns fSetTimeLastLoop=true )

2020-10-16 13:00:51 (272): Guest Log: job: run exitcode=0

2020-10-16 13:00:51 (272): Guest Log: job: diskusage=2204

2020-10-16 13:00:51 (272): Guest Log: job: logsize=60 k

2020-10-16 13:00:51 (272): Guest Log: job: times=

2020-10-16 13:00:51 (272): Guest Log: 0m0.012s 0m0.015s

2020-10-16 13:00:51 (272): Guest Log: 227m53.545s 2m1.927s

2020-10-16 13:00:51 (272): Guest Log: job: cpuusage=13795

2020-10-16 13:00:51 (272): Guest Log: 13:00:51 CEST +02:00 2020-10-16: cranky: [INFO] Container 'runc' finished with status code 0.

2020-10-16 13:00:51 (272): Guest Log: 13:00:51 CEST +02:00 2020-10-16: cranky: [INFO] Preparing output.

2020-10-16 13:00:52 (272): Guest Log: [INFO] Job Finished

2020-10-16 13:00:52 (272): Guest Log: [INFO] Shutting Down.

2020-10-16 13:00:52 (272): VM Completion File Detected.
2020-10-16 13:00:52 (272): VM Completion Message: Job Finished
.
2020-10-16 13:00:52 (272): Powering off VM.
2020-10-16 13:05:53 (272): VM did not power off when requested.
2020-10-16 13:05:53 (272): VM was successfully terminated.
2020-10-16 13:05:53 (272): Deregistering VM. (boinc_0a205fdd5d214448, slot#1)
2020-10-16 13:05:53 (272): Removing network bandwidth throttle group from VM.
2020-10-16 13:05:53 (272): Removing VM from VirtualBox.
13:05:59 (272): called boinc_finish(0)

</stderr_txt>
]]>
LHC@home

Stderr output
