Name CMS_538741_1568114009.217707_0
Workunit 1935602
Created 10 Sep 2019, 11:13:34 UTC
Sent 10 Sep 2019, 13:39:13 UTC
Report deadline 17 Sep 2019, 13:39:13 UTC
Received 11 Sep 2019, 21:52:41 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 3584
Run time 17 hours 33 min 37 sec
CPU time 16 hours 41 min 40 sec
Validate state Valid
Credit 452.12
Device peak FLOPS 2.85 GFLOPS
Application version CMS Simulation v49.00 (vbox64_mt_mcore_cms)
windows_x86_64
Peak working set size 81.37 MB
Peak swap size 112.51 MB
Peak disk usage 3.87 GB

Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-09-10 08:40:19 (14116): Detected: vboxwrapper 26197
2019-09-10 08:40:19 (14116): Detected: BOINC client v7.7
2019-09-10 08:40:19 (14116): Status Report: Launching vboxsvc.exe. (PID = '3864')
2019-09-10 08:40:22 (14116): Detected: VirtualBox VboxManage Interface (Version: 5.2.18)
2019-09-10 08:40:22 (14116): Detected: Sandbox Configuration Enabled
2019-09-10 08:40:23 (14116): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-09-10 08:40:23 (14116): Successfully copied 'init_data.xml' to the shared directory.
2019-09-10 08:40:24 (14116): Create VM. (boinc_7503d82ced25e9ba, slot#1)
2019-09-10 08:40:24 (14116): Setting Memory Size for VM. (1896MB)
2019-09-10 08:40:25 (14116): Setting CPU Count for VM. (1)
2019-09-10 08:40:25 (14116): Setting Chipset Options for VM.
2019-09-10 08:40:25 (14116): Setting Boot Options for VM.
2019-09-10 08:40:26 (14116): Setting Network Configuration for NAT.
2019-09-10 08:40:26 (14116): Enabling VM Network Access.
2019-09-10 08:40:26 (14116): Disabling USB Support for VM.
2019-09-10 08:40:27 (14116): Disabling COM Port Support for VM.
2019-09-10 08:40:27 (14116): Disabling LPT Port Support for VM.
2019-09-10 08:40:27 (14116): Disabling Audio Support for VM.
2019-09-10 08:40:27 (14116): Disabling Clipboard Support for VM.
2019-09-10 08:40:28 (14116): Disabling Drag and Drop Support for VM.
2019-09-10 08:40:28 (14116): Adding storage controller(s) to VM.
2019-09-10 08:40:28 (14116): Adding virtual disk drive to VM. (vm_image.vdi)
2019-09-10 08:40:29 (14116): Adding VirtualBox Guest Additions to VM.
2019-09-10 08:40:29 (14116): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-09-10 08:40:29 (14116): forwarding host port 52794 to guest port 80
2019-09-10 08:40:30 (14116): Enabling remote desktop for VM.
2019-09-10 08:40:30 (14116): Enabling shared directory for VM.
2019-09-10 08:40:31 (14116): Starting VM using VBoxManage interface. (boinc_7503d82ced25e9ba, slot#1)
2019-09-10 08:40:41 (14116): Successfully started VM. (PID = '13020')
2019-09-10 08:40:41 (14116): Reporting VM Process ID to BOINC.
2019-09-10 08:40:41 (14116): Guest Log: BIOS: VirtualBox 5.2.18

2019-09-10 08:40:41 (14116): Guest Log: CPUID EDX: 0x078bfbff

2019-09-10 08:40:41 (14116): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-09-10 08:40:41 (14116): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-09-10 08:40:41 (14116): Detected: Web Application Enabled (http://localhost:52794)
2019-09-10 08:40:41 (14116): Detected: Remote Desktop Enabled (localhost:52795)
2019-09-10 08:40:42 (14116): Preference change detected
2019-09-10 08:40:42 (14116): Setting CPU throttle for VM. (100%)
2019-09-10 08:40:42 (14116): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-09-10 08:40:44 (14116): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-09-10 08:40:44 (14116): Guest Log: BIOS: Booting from Hard Disk...

2019-09-10 08:40:47 (14116): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-09-10 08:40:47 (14116): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-09-10 08:41:15 (14116): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-09-10 08:41:15 (14116): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-09-10 08:41:54 (14116): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-09-10 08:41:54 (14116): Guest Log: 00:00:00.000352 main     Log opened 2019-09-10T13:41:56.001124000Z

2019-09-10 08:41:54 (14116): Guest Log: 00:00:00.000641 main     OS Product: Linux

2019-09-10 08:41:54 (14116): Guest Log: 00:00:00.000702 main     OS Release: 4.14.44-11.cernvm.x86_64

2019-09-10 08:41:54 (14116): Guest Log: 00:00:00.000804 main     OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018

2019-09-10 08:41:54 (14116): Guest Log: 00:00:00.000886 main     Executable: /usr/share/vboxguest52/usr/sbin/VBoxService

2019-09-10 08:41:54 (14116): Guest Log: 00:00:00.000889 main     Process ID: 2962

2019-09-10 08:41:54 (14116): Guest Log: 00:00:00.000891 main     Package type: LINUX_64BITS_GENERIC

2019-09-10 08:41:54 (14116): Guest Log: 00:00:00.003306 main     5.2.6 r120293 started. Verbose level = 0

2019-09-10 08:42:37 (14116): Guest Log: [INFO] Mounting the shared directory

2019-09-10 08:42:38 (14116): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2019-09-10 08:42:38 (14116): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80

2019-09-10 08:42:38 (14116): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!

2019-09-10 08:42:38 (14116): Guest Log: [DEBUG] 0

2019-09-10 08:42:38 (14116): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443

2019-09-10 08:42:38 (14116): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!

2019-09-10 08:42:38 (14116): Guest Log: [DEBUG] 0

2019-09-10 08:42:39 (14116): Guest Log: [DEBUG] Testing connection to Condor server on port 9618

2019-09-10 08:42:39 (14116): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded!

2019-09-10 08:42:39 (14116): Guest Log: [DEBUG] 0

2019-09-10 08:43:48 (14116): Guest Log: [DEBUG] Probing CVMFS ...

2019-09-10 08:43:52 (14116): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2019-09-10 08:43:54 (14116): 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

2019-09-10 08:43:54 (14116): Guest Log: 2.4.4.0 3701 1 25832 10207 3 1 1454385 10240001 2 65024 0 3 100 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2019-09-10 08:44:11 (14116): Guest Log: [INFO] Reading volunteer information

2019-09-10 08:44:11 (14116): Guest Log: [INFO] Volunteer: Coleslaw (55)

2019-09-10 08:44:11 (14116): Guest Log: [INFO] VMID: c5ab4400-4ef6-44a9-aaed-bee78432355d

2019-09-10 08:44:12 (14116): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2019-09-10 08:44:13 (14116): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev

2019-09-10 08:44:15 (14116): Guest Log: [INFO] Running the fast benchmark.

2019-09-10 08:49:02 (14116): Guest Log: [INFO] Machine performance 1.79 HEPSPEC06

2019-09-10 08:49:02 (14116): Guest Log: [INFO] CMS application starting. Check log files.

2019-09-10 08:49:04 (14116): Guest Log: [DEBUG] HTCondor ping

2019-09-10 08:49:06 (14116): Guest Log: [DEBUG] 0

2019-09-10 10:21:35 (14116): Status Report: Job Duration: '64800.000000'
2019-09-10 10:21:35 (14116): Status Report: Elapsed Time: '6000.000000'
2019-09-10 10:21:35 (14116): Status Report: CPU Time: '5460.265625'
2019-09-10 11:35:24 (14116): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-10 11:53:52 (14116): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-10 12:21:03 (14116): Status Report: Job Duration: '64800.000000'
2019-09-10 12:21:03 (14116): Status Report: Elapsed Time: '12000.421482'
2019-09-10 12:21:03 (14116): Status Report: CPU Time: '11289.593750'
2019-09-10 12:54:17 (14116): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-10 13:55:12 (14116): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-10 13:55:15 (14116): Guest Log: 03:54:00.939890 timesync vgsvcTimeSyncWorker: Radical host time change: 3 664 843 000 000ns (HostNow=1 568 141 714 583 000 000 ns HostLast=1 568 138 049 740 000 000 ns)

2019-09-10 13:55:25 (14116): Guest Log: 03:54:10.941243 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 766 068 192 000ns (GuestNow=1 568 141 724 587 959 000 ns GuestLast=1 568 136 958 519 767 000 ns fSetTimeLastLoop=true )

2019-09-10 14:11:41 (14116): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-10 15:29:08 (14116): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-10 15:29:12 (14116): Guest Log: 04:10:31.002681 timesync vgsvcTimeSyncWorker: Radical host time change: 4 658 000 000 000ns (HostNow=1 568 147 352 445 000 000 ns HostLast=1 568 142 694 445 000 000 ns)

2019-09-10 15:29:23 (14116): Guest Log: 04:10:41.003645 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 657 796 658 000ns (GuestNow=1 568 147 362 446 014 000 ns GuestLast=1 568 142 704 649 356 000 ns fSetTimeLastLoop=true )

2019-09-10 16:20:34 (14116): Status Report: Job Duration: '64800.000000'
2019-09-10 16:20:34 (14116): Status Report: Elapsed Time: '18000.421482'
2019-09-10 16:20:34 (14116): Status Report: CPU Time: '17128.125000'
2019-09-10 17:16:01 (14116): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-10 17:55:33 (14116): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-10 17:55:36 (14116): Guest Log: 05:57:21.507103 timesync vgsvcTimeSyncWorker: Radical host time change: 2 382 564 000 000ns (HostNow=1 568 156 136 468 000 000 ns HostLast=1 568 153 753 904 000 000 ns)

2019-09-10 17:55:47 (14116): Guest Log: 05:57:31.508158 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 382 419 954 000ns (GuestNow=1 568 156 146 532 227 000 ns GuestLast=1 568 153 764 112 273 000 ns fSetTimeLastLoop=true )

2019-09-10 18:41:30 (14116): Status Report: Job Duration: '64800.000000'
2019-09-10 18:41:30 (14116): Status Report: Elapsed Time: '24001.214529'
2019-09-10 18:41:30 (14116): Status Report: CPU Time: '22952.671875'
2019-09-10 20:22:32 (14116): Status Report: Job Duration: '64800.000000'
2019-09-10 20:22:32 (14116): Status Report: Elapsed Time: '30001.214529'
2019-09-10 20:22:32 (14116): Status Report: CPU Time: '28980.562500'
2019-09-10 20:26:07 (14116): Stopping VM.
2019-09-10 20:26:10 (14116): Error in stop VM for VM: -2147024891
Command:
VBoxManage -q controlvm "boinc_7503d82ced25e9ba" savestate
Output:
VBoxManage.exe: error: Failed to create the VirtualBox object!
VBoxManage.exe: error: The object is not ready
VBoxManage.exe: error: Details: code E_ACCESSDENIED (0x80070005), component VirtualBoxClientWrap, interface IVirtualBoxClient

2019-09-10 20:26:10 (14116): VM did not stop when requested.
2019-09-10 20:26:10 (14116): VM was successfully terminated.
2019-09-10 20:35:59 (10888): Detected: vboxwrapper 26197
2019-09-10 20:35:59 (10888): Detected: BOINC client v7.7
2019-09-10 20:35:59 (10888): Status Report: Launching vboxsvc.exe. (PID = '11256')
2019-09-10 20:36:01 (10888): Detected: VirtualBox VboxManage Interface (Version: 5.2.18)
2019-09-10 20:36:01 (10888): Detected: Sandbox Configuration Enabled
2019-09-10 20:36:02 (10888): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-09-10 20:36:03 (10888): Starting VM using VBoxManage interface. (boinc_7503d82ced25e9ba, slot#1)
2019-09-10 20:36:16 (10888): Successfully started VM. (PID = '8012')
2019-09-10 20:36:16 (10888): Reporting VM Process ID to BOINC.
2019-09-10 20:36:16 (10888): Guest Log: BIOS: VirtualBox 5.2.18

2019-09-10 20:36:16 (10888): Guest Log: CPUID EDX: 0x078bfbff

2019-09-10 20:36:16 (10888): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-09-10 20:36:16 (10888): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-09-10 20:36:16 (10888): Detected: Web Application Enabled (http://localhost:52794)
2019-09-10 20:36:16 (10888): Detected: Remote Desktop Enabled (localhost:52795)
2019-09-10 20:36:16 (10888): Status Report: Job Duration: '64800.000000'
2019-09-10 20:36:16 (10888): Status Report: Elapsed Time: '30213.214529'
2019-09-10 20:36:16 (10888): Status Report: CPU Time: '29192.218750'
2019-09-10 20:36:16 (10888): Preference change detected
2019-09-10 20:36:16 (10888): Setting CPU throttle for VM. (100%)
2019-09-10 20:36:16 (10888): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-09-10 20:36:18 (10888): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-09-10 20:36:18 (10888): Guest Log: BIOS: Booting from Hard Disk...

2019-09-10 20:36:21 (10888): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-09-10 20:36:21 (10888): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-09-10 20:36:50 (10888): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-09-10 20:36:50 (10888): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-09-10 20:37:37 (10888): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-09-10 20:37:37 (10888): Guest Log: 00:00:00.000492 main     Log opened 2019-09-11T01:37:35.229788000Z

2019-09-10 20:37:37 (10888): Guest Log: 00:00:00.000779 main     OS Product: Linux

2019-09-10 20:37:37 (10888): Guest Log: 00:00:00.000844 main     OS Release: 4.14.44-11.cernvm.x86_64

2019-09-10 20:37:37 (10888): Guest Log: 00:00:00.000903 main     OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018

2019-09-10 20:37:37 (10888): Guest Log: 00:00:00.001228 main     Executable: /usr/share/vboxguest52/usr/sbin/VBoxService

2019-09-10 20:37:37 (10888): Guest Log: 00:00:00.001269 main     Process ID: 2907

2019-09-10 20:37:37 (10888): Guest Log: 00:00:00.001272 main     Package type: LINUX_64BITS_GENERIC

2019-09-10 20:37:37 (10888): Guest Log: 00:00:00.003583 main     5.2.6 r120293 started. Verbose level = 0

2019-09-10 20:38:31 (10888): Guest Log: [INFO] Mounting the shared directory

2019-09-10 20:38:31 (10888): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2019-09-10 20:38:31 (10888): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80

2019-09-10 20:38:32 (10888): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!

2019-09-10 20:38:32 (10888): Guest Log: [DEBUG] 0

2019-09-10 20:38:32 (10888): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443

2019-09-10 20:38:32 (10888): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!

2019-09-10 20:38:32 (10888): Guest Log: [DEBUG] 0

2019-09-10 20:38:32 (10888): Guest Log: [DEBUG] Testing connection to Condor server on port 9618

2019-09-10 20:38:33 (10888): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded!

2019-09-10 20:38:33 (10888): Guest Log: [DEBUG] 0

2019-09-10 20:39:45 (10888): Guest Log: [DEBUG] Probing CVMFS ...

2019-09-10 20:39:48 (10888): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2019-09-10 20:39:50 (10888): 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

2019-09-10 20:39:50 (10888): Guest Log: 2.4.4.0 3626 1 25560 10211 3 1 2453480 10240001 2 65024 0 3 100 1 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2019-09-10 20:40:01 (10888): Guest Log: [INFO] Reading volunteer information

2019-09-10 20:40:02 (10888): Guest Log: [INFO] Volunteer: Coleslaw (55)

2019-09-10 20:40:02 (10888): Guest Log: [INFO] VMID: c5ab4400-4ef6-44a9-aaed-bee78432355d

2019-09-10 20:40:02 (10888): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2019-09-10 20:40:04 (10888): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev

2019-09-10 20:40:07 (10888): Guest Log: [INFO] Running the fast benchmark.

2019-09-10 20:45:23 (10888): Guest Log: [INFO] Machine performance 1.62 HEPSPEC06

2019-09-10 20:45:23 (10888): Guest Log: [INFO] CMS application starting. Check log files.

2019-09-10 20:45:26 (10888): Guest Log: [DEBUG] HTCondor ping

2019-09-10 20:45:28 (10888): Guest Log: [DEBUG] 0

2019-09-10 22:17:20 (10888): Status Report: Job Duration: '64800.000000'
2019-09-10 22:17:20 (10888): Status Report: Elapsed Time: '36213.214529'
2019-09-10 22:17:20 (10888): Status Report: CPU Time: '34556.781250'
2019-09-10 23:35:47 (10888): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-11 00:05:07 (10888): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-11 00:27:47 (10888): Status Report: Job Duration: '64800.000000'
2019-09-11 00:27:47 (10888): Status Report: Elapsed Time: '42213.214529'
2019-09-11 00:27:47 (10888): Status Report: CPU Time: '40578.640625'
2019-09-11 01:26:25 (10888): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-11 03:15:14 (10888): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-11 03:15:16 (10888): Guest Log: 04:19:31.180776 timesync vgsvcTimeSyncWorker: Radical host time change: 6 538 560 000 000ns (HostNow=1 568 189 715 433 000 000 ns HostLast=1 568 183 176 873 000 000 ns)

2019-09-11 03:15:26 (10888): Guest Log: 04:19:41.181349 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 538 447 508 000ns (GuestNow=1 568 189 725 587 377 000 ns GuestLast=1 568 183 187 139 869 000 ns fSetTimeLastLoop=true )

2019-09-11 03:57:39 (10888): Status Report: Job Duration: '64800.000000'
2019-09-11 03:57:39 (10888): Status Report: Elapsed Time: '48213.214529'
2019-09-11 03:57:39 (10888): Status Report: CPU Time: '46426.296875'
2019-09-11 04:33:47 (10888): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-11 05:02:18 (10888): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-11 06:02:44 (10888): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-11 06:58:31 (10888): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-11 06:58:34 (10888): Guest Log: 06:38:31.650699 timesync vgsvcTimeSyncWorker: Radical host time change: 3 356 907 000 000ns (HostNow=1 568 203 113 871 000 000 ns HostLast=1 568 199 756 964 000 000 ns)

2019-09-11 06:58:44 (10888): Guest Log: 06:38:41.651694 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 356 909 860 000ns (GuestNow=1 568 203 123 915 015 000 ns GuestLast=1 568 199 767 005 155 000 ns fSetTimeLastLoop=true )

2019-09-11 07:03:08 (10888): Status Report: Job Duration: '64800.000000'
2019-09-11 07:03:08 (10888): Status Report: Elapsed Time: '54213.214529'
2019-09-11 07:03:08 (10888): Status Report: CPU Time: '52454.640625'
2019-09-11 07:59:13 (10888): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-11 08:00:23 (10888): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-11 08:45:25 (10888): Status Report: Job Duration: '64800.000000'
2019-09-11 08:45:25 (10888): Status Report: Elapsed Time: '60213.214529'
2019-09-11 08:45:25 (10888): Status Report: CPU Time: '58492.328125'
2019-09-11 09:00:48 (10888): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-11 15:50:10 (10888): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-11 15:50:13 (10888): Guest Log: 08:39:42.169077 timesync vgsvcTimeSyncWorker: Radical host time change: 24 571 977 000 000ns (HostNow=1 568 235 013 408 000 000 ns HostLast=1 568 210 441 431 000 000 ns)

2019-09-11 15:50:23 (10888): Guest Log: 08:39:52.169708 timesync vgsvcTimeSyncWorker: Radical guest time change: 24 636 960 570 000ns (GuestNow=1 568 235 023 414 987 000 ns GuestLast=1 568 210 386 454 417 000 ns fSetTimeLastLoop=true )

2019-09-11 15:55:12 (10888): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-11 16:29:08 (10888): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-11 16:29:09 (10888): Guest Log: 08:44:42.181024 timesync vgsvcTimeSyncWorker: Radical host time change: 2 045 212 000 000ns (HostNow=1 568 237 348 493 000 000 ns HostLast=1 568 235 303 281 000 000 ns)

2019-09-11 16:29:19 (10888): Guest Log: 08:44:52.182054 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 045 067 886 000ns (GuestNow=1 568 237 358 494 104 000 ns GuestLast=1 568 235 313 426 218 000 ns fSetTimeLastLoop=true )

2019-09-11 16:46:14 (10888): Guest Log: [INFO] Condor exited with return value N/A.

2019-09-11 16:46:14 (10888): Guest Log: [INFO] Shutting Down.

2019-09-11 16:46:14 (10888): VM Completion File Detected.
2019-09-11 16:46:14 (10888): VM Completion Message: Condor exited with return value N/A.
.
2019-09-11 16:46:14 (10888): Powering off VM.
2019-09-11 16:51:15 (10888): VM did not power off when requested.
2019-09-11 16:51:15 (10888): VM was successfully terminated.
2019-09-11 16:51:15 (10888): Deregistering VM. (boinc_7503d82ced25e9ba, slot#1)
2019-09-11 16:51:46 (10888): Removing network bandwidth throttle group from VM.
2019-09-11 16:52:17 (10888): Removing VM from VirtualBox.
16:52:29 (10888): called boinc_finish(0)

</stderr_txt>
]]>


©2024 CERN