Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-07-13 05:49:33 (1620): vboxwrapper (7.9.26200): starting
2019-07-13 05:49:34 (1620): Feature: Checkpoint interval offset (97 seconds)
2019-07-13 05:49:34 (1620): Detected: VirtualBox COM Interface (Version: 5.2.12)
2019-07-13 05:49:34 (1620): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-07-13 05:49:34 (1620): Successfully copied 'init_data.xml' to the shared directory.
2019-07-13 05:49:34 (1620): Create VM. (boinc_7717eab2365f85bd, slot#6)
2019-07-13 05:49:34 (1620): Setting Memory Size for VM. (3900MB)
2019-07-13 05:49:34 (1620): Setting CPU Count for VM. (1)
2019-07-13 05:49:34 (1620): Setting Chipset Options for VM.
2019-07-13 05:49:34 (1620): Setting Boot Options for VM.
2019-07-13 05:49:34 (1620): Enabling VM Network Access.
2019-07-13 05:49:35 (1620): Setting Network Configuration for NAT.
2019-07-13 05:49:35 (1620): Disabling USB Support for VM.
2019-07-13 05:49:35 (1620): Disabling COM Port Support for VM.
2019-07-13 05:49:35 (1620): Disabling LPT Port Support for VM.
2019-07-13 05:49:35 (1620): Disabling Audio Support for VM.
2019-07-13 05:49:35 (1620): Disabling Clipboard Support for VM.
2019-07-13 05:49:35 (1620): Disabling Drag and Drop Support for VM.
2019-07-13 05:49:35 (1620): Adding storage controller(s) to VM.
2019-07-13 05:49:35 (1620): Adding virtual disk drive to VM. (vm_image.vdi)
2019-07-13 05:49:35 (1620): Adding VirtualBox Guest Additions to VM.
2019-07-13 05:49:35 (1620): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-07-13 05:49:35 (1620): forwarding host port 61879 to guest port 80
2019-07-13 05:49:35 (1620): Enabling remote desktop for VM.
2019-07-13 05:49:35 (1620): Required extension pack not installed, remote desktop not enabled.
2019-07-13 05:49:35 (1620): Enabling shared directory for VM.
2019-07-13 05:49:35 (1620): Starting VM. (boinc_7717eab2365f85bd, slot#6)
2019-07-13 05:49:46 (1620): Guest Log: BIOS: VirtualBox 5.2.12

2019-07-13 05:49:46 (1620): Guest Log: CPUID EDX: 0x078bfbff

2019-07-13 05:49:46 (1620): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-07-13 05:49:46 (1620): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-13 05:49:46 (1620): Guest Log: BIOS: Booting from Hard Disk...

2019-07-13 05:49:47 (1620): Successfully started VM. (PID = '18044')
2019-07-13 05:49:47 (1620): Reporting VM Process ID to BOINC.
2019-07-13 05:49:52 (1620): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-07-13 05:49:52 (1620): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-07-13 05:49:52 (1620): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-13 05:49:57 (1620): Detected: Web Application Enabled (http://localhost:61879)
2019-07-13 05:50:02 (1620): Preference change detected
2019-07-13 05:50:02 (1620): Setting CPU throttle for VM. (100%)
2019-07-13 05:50:02 (1620): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-07-13 05:50:14 (1620): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-07-13 05:50:24 (1620): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88010712c410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-13 05:50:24 (1620): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88010712c810), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-13 05:50:24 (1620): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88010712c610), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-13 05:50:25 (1620): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88010712c210), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-13 05:51:03 (1620): Guest Log: Copying input files into RunAtlas.

2019-07-13 05:51:18 (1620): Guest Log: Copied input files into RunAtlas.

2019-07-13 05:51:28 (1620): Guest Log: copied the webapp to /var/www

2019-07-13 05:51:28 (1620): Guest Log: This vm does not need to setup http proxy

2019-07-13 05:51:28 (1620): Guest Log: core_number=1

2019-07-13 05:51:28 (1620): Guest Log: Starting ATLAS job. (PandaID=4414948981 taskID=18514527)

2019-07-13 06:02:38 (1620): VM state change detected. (old = 'running', new = 'paused')
2019-07-13 06:08:29 (1620): VM state change detected. (old = 'paused', new = 'running')
2019-07-13 06:09:00 (1620): VM state change detected. (old = 'running', new = 'paused')
2019-07-13 09:22:29 (1620): VM state change detected. (old = 'paused', new = 'running')
2019-07-13 09:22:39 (1620): VM state change detected. (old = 'running', new = 'paused')
2019-07-13 09:48:52 (1620): VM state change detected. (old = 'paused', new = 'running')
2019-07-13 09:49:03 (1620): VM state change detected. (old = 'running', new = 'paused')
2019-07-13 10:27:58 (1620): VM state change detected. (old = 'paused', new = 'running')
2019-07-13 11:54:56 (1620): Status Report: Elapsed Time: '6004.138826'
2019-07-13 11:54:56 (1620): Status Report: CPU Time: '5424.343750'
2019-07-13 12:40:52 (1620): VM state change detected. (old = 'running', new = 'paused')
2019-07-13 12:41:02 (1620): VM state change detected. (old = 'paused', new = 'running')
2019-07-13 13:35:12 (1620): Status Report: Elapsed Time: '12004.943254'
2019-07-13 13:35:12 (1620): Status Report: CPU Time: '11356.140625'
2019-07-13 15:15:16 (1620): Status Report: Elapsed Time: '18008.745575'
2019-07-13 15:15:16 (1620): Status Report: CPU Time: '17321.203125'
2019-07-13 16:58:28 (1620): Status Report: Elapsed Time: '24008.771185'
2019-07-13 16:58:28 (1620): Status Report: CPU Time: '23296.921875'
2019-07-13 18:38:32 (1620): Status Report: Elapsed Time: '30013.121645'
2019-07-13 18:38:32 (1620): Status Report: CPU Time: '29267.140625'
2019-07-13 20:18:36 (1620): Status Report: Elapsed Time: '36017.174622'
2019-07-13 20:18:36 (1620): Status Report: CPU Time: '35251.359375'
2019-07-13 21:58:41 (1620): Status Report: Elapsed Time: '42021.333914'
2019-07-13 21:58:41 (1620): Status Report: CPU Time: '41181.500000'
2019-07-13 23:38:44 (1620): Status Report: Elapsed Time: '48025.184025'
2019-07-13 23:38:44 (1620): Status Report: CPU Time: '47138.812500'
2019-07-14 01:18:49 (1620): Status Report: Elapsed Time: '54029.642746'
2019-07-14 01:18:49 (1620): Status Report: CPU Time: '53132.859375'
2019-07-14 02:58:51 (1620): Status Report: Elapsed Time: '60032.142015'
2019-07-14 02:58:51 (1620): Status Report: CPU Time: '59099.500000'
2019-07-14 04:38:53 (1620): Status Report: Elapsed Time: '66033.660093'
2019-07-14 04:38:53 (1620): Status Report: CPU Time: '65032.687500'
2019-07-14 06:18:56 (1620): Status Report: Elapsed Time: '72036.253969'
2019-07-14 06:18:56 (1620): Status Report: CPU Time: '70928.609375'
2019-07-14 07:19:38 (1620): VM state change detected. (old = 'running', new = 'paused')
2019-07-14 07:54:24 (1620): Stopping VM.
2019-07-14 07:54:24 (1620): Error 0x80070005 in vbox52::VBOX_VM::stop (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1452)
2019-07-14 07:54:24 (1620): Error Source     : SessionMachine
2019-07-14 07:54:24 (1620): Error Description: The object is not ready
2019-07-14 09:45:02 (4456): vboxwrapper (7.9.26200): starting
2019-07-14 09:45:02 (4456): Feature: Checkpoint interval offset (267 seconds)
2019-07-14 09:45:02 (4456): Detected: VirtualBox COM Interface (Version: 5.2.12)
2019-07-14 09:45:02 (4456): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-07-14 09:45:02 (4456): Guest Log: BIOS: VirtualBox 5.2.12

2019-07-14 09:45:02 (4456): Guest Log: CPUID EDX: 0x078bfbff

2019-07-14 09:45:02 (4456): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-07-14 09:45:02 (4456): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-14 09:45:02 (4456): Guest Log: BIOS: Booting from Hard Disk...

2019-07-14 09:45:02 (4456): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-07-14 09:45:02 (4456): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-07-14 09:45:02 (4456): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-07-14 09:45:02 (4456): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88010712c410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-14 09:45:02 (4456): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88010712c810), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-14 09:45:02 (4456): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88010712c610), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-14 09:45:02 (4456): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88010712c210), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-14 09:45:02 (4456): Guest Log: Copying input files into RunAtlas.

2019-07-14 09:45:02 (4456): Guest Log: Copied input files into RunAtlas.

2019-07-14 09:45:02 (4456): Guest Log: copied the webapp to /var/www

2019-07-14 09:45:02 (4456): Guest Log: This vm does not need to setup http proxy

2019-07-14 09:45:02 (4456): Guest Log: core_number=1

2019-07-14 09:45:02 (4456): Guest Log: Starting ATLAS job. (PandaID=4414948981 taskID=18514527)

2019-07-14 09:45:02 (4456): Starting VM. (boinc_7717eab2365f85bd, slot#6)
2019-07-14 09:45:13 (4456): Successfully started VM. (PID = '11732')
2019-07-14 09:45:13 (4456): Reporting VM Process ID to BOINC.
2019-07-14 09:45:18 (4456): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-14 09:45:23 (4456): Detected: Web Application Enabled (http://localhost:61879)
2019-07-14 09:45:28 (4456): Status Report: Elapsed Time: '75676.946102'
2019-07-14 09:45:28 (4456): Status Report: CPU Time: '74580.750000'
2019-07-14 09:45:28 (4456): Preference change detected
2019-07-14 09:45:28 (4456): Setting CPU throttle for VM. (100%)
2019-07-14 09:45:29 (4456): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-07-14 09:46:59 (4456): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107301e10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-14 09:46:59 (4456): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107747410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-14 09:46:59 (4456): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107747010), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-14 09:46:59 (4456): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107747210), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-14 09:49:19 (4456): Guest Log: Copying input files into RunAtlas.

2019-07-14 09:49:59 (4456): Guest Log: Copied input files into RunAtlas.

2019-07-14 09:50:19 (4456): Guest Log: copied the webapp to /var/www

2019-07-14 09:50:19 (4456): Guest Log: This vm does not need to setup http proxy

2019-07-14 09:50:19 (4456): Guest Log: core_number=1

2019-07-14 09:50:19 (4456): Guest Log: Starting ATLAS job. (Binary file (standard input) matches Binary file (standard input) matches)

2019-07-14 11:25:33 (4456): Status Report: Elapsed Time: '81681.559044'
2019-07-14 11:25:33 (4456): Status Report: CPU Time: '79601.515625'
2019-07-14 12:18:51 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-14 13:16:18 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-14 13:48:00 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-14 14:25:54 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-14 14:41:08 (4456): Status Report: Elapsed Time: '87685.935959'
2019-07-14 14:41:08 (4456): Status Report: CPU Time: '85551.593750'
2019-07-14 14:45:49 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-14 16:03:25 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-14 16:03:35 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-14 16:03:45 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-14 16:37:41 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-14 16:58:05 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-14 17:00:58 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-14 17:03:47 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-14 17:39:26 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-14 17:41:07 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-14 17:41:17 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-14 18:22:09 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-14 18:45:13 (4456): Status Report: Elapsed Time: '93687.810255'
2019-07-14 18:45:13 (4456): Status Report: CPU Time: '91410.828125'
2019-07-14 20:25:15 (4456): Status Report: Elapsed Time: '99689.880754'
2019-07-14 20:25:15 (4456): Status Report: CPU Time: '97121.718750'
2019-07-14 21:20:42 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-14 21:58:17 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-14 22:08:46 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-14 22:59:34 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-14 23:33:53 (4456): Status Report: Elapsed Time: '105694.731632'
2019-07-14 23:33:53 (4456): Status Report: CPU Time: '102949.484375'
2019-07-15 00:35:15 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 00:41:46 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 01:03:46 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 01:06:38 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 01:08:50 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 01:09:15 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 01:10:30 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 01:10:40 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 01:19:58 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 01:20:58 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 01:25:21 (4456): Status Report: Elapsed Time: '111697.214825'
2019-07-15 01:25:21 (4456): Status Report: CPU Time: '108901.875000'
2019-07-15 01:26:20 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 01:27:00 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 01:43:41 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 01:44:01 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 01:46:12 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 01:46:42 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 01:48:33 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 01:48:47 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 01:50:35 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 01:59:04 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 02:50:34 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 02:50:54 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 03:16:28 (4456): Status Report: Elapsed Time: '117699.023968'
2019-07-15 03:16:28 (4456): Status Report: CPU Time: '114838.187500'
2019-07-15 03:17:58 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 03:18:18 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 03:19:59 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 03:20:29 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 03:23:41 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 03:25:16 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 03:50:22 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 03:50:32 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 03:52:53 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 03:53:13 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 04:59:52 (4456): Status Report: Elapsed Time: '123701.259237'
2019-07-15 04:59:52 (4456): Status Report: CPU Time: '120778.343750'
2019-07-15 06:39:53 (4456): Status Report: Elapsed Time: '129702.162851'
2019-07-15 06:39:53 (4456): Status Report: CPU Time: '126603.484375'
2019-07-15 07:04:26 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 08:49:27 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 08:59:48 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 09:45:23 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 09:45:28 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 09:47:53 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 10:53:11 (4456): Status Report: Elapsed Time: '135703.509432'
2019-07-15 10:53:11 (4456): Status Report: CPU Time: '132537.453125'
2019-07-15 10:58:25 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 11:09:26 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 11:09:56 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 11:21:28 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 11:21:58 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 12:27:25 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 13:07:49 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 13:19:45 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 13:20:05 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 14:23:38 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 14:43:05 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 15:21:54 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 15:22:47 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 17:02:17 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 17:09:46 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 17:13:42 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 17:17:22 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 17:46:11 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 18:08:32 (4456): Status Report: Elapsed Time: '141705.155947'
2019-07-15 18:08:32 (4456): Status Report: CPU Time: '138482.156250'
2019-07-15 18:15:40 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 18:18:00 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 18:25:01 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 18:43:02 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 18:49:11 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 19:07:03 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 19:07:13 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 19:49:08 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 20:08:31 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 20:48:34 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 21:21:15 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 21:21:46 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 21:23:54 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-15 21:25:54 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-15 21:56:48 (4456): Status Report: Elapsed Time: '147713.047613'
2019-07-15 21:56:48 (4456): Status Report: CPU Time: '144345.218750'
2019-07-15 23:36:51 (4456): Status Report: Elapsed Time: '153716.027218'
2019-07-15 23:36:51 (4456): Status Report: CPU Time: '150053.562500'
2019-07-16 00:33:11 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-16 00:52:08 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-16 01:09:32 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-16 01:12:27 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-16 01:39:31 (4456): Status Report: Elapsed Time: '159722.608747'
2019-07-16 01:39:31 (4456): Status Report: CPU Time: '155859.921875'
2019-07-16 01:43:13 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-16 02:23:16 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-16 03:59:33 (4456): Status Report: Elapsed Time: '165727.523283'
2019-07-16 03:59:33 (4456): Status Report: CPU Time: '161844.937500'
2019-07-16 04:00:08 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-16 04:01:03 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-16 04:02:13 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-16 04:02:38 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-16 05:39:25 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-16 05:41:19 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-16 05:43:24 (4456): Status Report: Elapsed Time: '171727.585342'
2019-07-16 05:43:25 (4456): Status Report: CPU Time: '167850.828125'
2019-07-16 06:44:13 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-16 07:46:42 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-16 08:19:48 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-16 10:03:30 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-16 10:03:35 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-16 12:26:47 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-16 12:33:20 (4456): Status Report: Elapsed Time: '177733.002823'
2019-07-16 12:33:20 (4456): Status Report: CPU Time: '173851.875000'
2019-07-16 13:59:02 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-16 14:06:52 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-16 14:20:45 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-16 14:40:06 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-16 14:40:47 (4456): Status Report: Elapsed Time: '183734.530437'
2019-07-16 14:40:47 (4456): Status Report: CPU Time: '179831.875000'
2019-07-16 15:12:17 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-16 15:21:18 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-16 15:21:28 (4456): VM state change detected. (old = 'running', new = 'paused')
2019-07-16 15:26:34 (4456): VM state change detected. (old = 'paused', new = 'running')
2019-07-16 15:28:20 (4456): Guest Log: The last 10 lines of the pilot log.

2019-07-16 15:28:25 (4456): Guest Log: -rw-------  1 atlas01 atlas01 11357 Jun  3 19:18 LICENSE

2019-07-16 15:28:25 (4456): Guest Log: drwx------ 14 atlas01 atlas01  4096 Jul 14 16:51 pilot

2019-07-16 15:28:25 (4456): Guest Log: -rwx------  1 atlas01 atlas01 21428 Jul 11 17:38 pilot.py

2019-07-16 15:28:25 (4456): Guest Log: -rw-------  1 atlas01 atlas01     8 Jul 11 17:38 PILOTVERSION

2019-07-16 15:28:25 (4456): Guest Log: -rw-------  1 atlas01 atlas01  2251 Jun  3 19:18 README.md

2019-07-16 15:28:25 (4456): Guest Log: -rw-------  1 atlas01 atlas01   221 Jun  3 19:18 TODO.md

2019-07-16 15:28:25 (4456): Guest Log: 2019-07-16 20:13:25 UTC [wrapper] Test setup, not cleaning

2019-07-16 15:28:25 (4456): Guest Log: 2019-07-16 20:13:25 UTC [wrapper] ==== wrapper stdout END ====

2019-07-16 15:28:25 (4456): Guest Log: 2019-07-16 20:13:25 UTC [wrapper] ==== wrapper stderr END ====

2019-07-16 15:28:25 (4456): Guest Log: Listing of results directory

2019-07-16 15:28:25 (4456): Guest Log: total 516612

2019-07-16 15:28:25 (4456): Guest Log: drwx------ 5 atlas01 atlas01      4096 Jul 11 17:38 pilot2

2019-07-16 15:28:25 (4456): Guest Log: -rw-r--r-- 1 atlas01 atlas01   3989187 Jul 12 15:19 pilot2.tar.gz

2019-07-16 15:28:25 (4456): Guest Log: -rw-r--r-- 1 atlas01 atlas01      4468 Jul 13 12:21 queuedata.json

2019-07-16 15:28:25 (4456): Guest Log: -rwx------ 1 atlas01 atlas01     15232 Jul 13 12:24 runpilot2-wrapper.sh

2019-07-16 15:28:25 (4456): Guest Log: -rw------- 1 atlas01 atlas01      2872 Jul 13 12:24 pandaJobData.out

2019-07-16 15:28:31 (4456): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 378455209 Jul 14 16:49 EVNT.17323636._000757.pool.root.1

2019-07-16 15:28:31 (4456): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8753 Jul 14 16:49 init_data.xml

2019-07-16 15:28:31 (4456): Guest Log: -rwxr-xr-x 1 atlas01 atlas01   3999012 Jul 14 16:49 input.tar.gz

2019-07-16 15:28:31 (4456): Guest Log: -rwxr-xr-x 1 atlas01 atlas01       815 Jul 14 16:49 RTE.tar.gz

2019-07-16 15:28:31 (4456): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8659 Jul 14 16:49 start_atlas.sh

2019-07-16 15:28:31 (4456): Guest Log: drwxr-xr-x 3 atlas01 atlas01      4096 Jul 14 16:50 APPS

2019-07-16 15:28:31 (4456): Guest Log: -rw------- 1 atlas01 atlas01   4650364 Jul 14 16:51 agis_schedconf.cvmfs.json

2019-07-16 15:28:31 (4456): Guest Log: -rw------- 1 atlas01 atlas01   7330415 Jul 14 16:51 agis_ddmendpoints.json

2019-07-16 15:28:31 (4456): Guest Log: -rw------- 1 atlas01 atlas01 109888911 Jul 16 22:09 HITS.18514527._036814.pool.root.1

2019-07-16 15:28:31 (4456): Guest Log: -rw------- 1 atlas01 atlas01    476803 Jul 16 22:12 log.18514527._036814.job.log.tgz.1

2019-07-16 15:28:31 (4456): Guest Log: drwxrwx--- 2 atlas01 atlas01      4096 Jul 16 22:12 PanDA_Pilot-4414948981

2019-07-16 15:28:31 (4456): Guest Log: -rw------- 1 atlas01 atlas01     55205 Jul 16 22:12 heartbeat.json

2019-07-16 15:28:31 (4456): Guest Log: -rw------- 1 atlas01 atlas01   4858301 Jul 16 22:13 pilotlog.txt

2019-07-16 15:28:31 (4456): Guest Log: -rw------- 1 atlas01 atlas01   4874836 Jul 16 22:13 log.18514527._036814.job.log.1

2019-07-16 15:28:31 (4456): Guest Log: -rw------- 1 atlas01 atlas01       507 Jul 16 22:13 n6yMDmEOU6unShfckohDCDFpABFKDmABFKDm7uyWDmABFKDmOvyRFn.diag

2019-07-16 15:28:31 (4456): Guest Log: -rw------- 1 atlas01 atlas01       391 Jul 16 22:13 output.list

2019-07-16 15:28:31 (4456): Guest Log: -rw-r--r-- 1 atlas01 atlas01      6802 Jul 16 22:13 runtime_log.err

2019-07-16 15:28:31 (4456): Guest Log: -rw-r--r-- 1 atlas01 atlas01       692 Jul 16 22:13 runtime_log

2019-07-16 15:28:31 (4456): Guest Log: -rw------- 1 atlas01 atlas01  10291200 Jul 16 22:13 result.tar.gz

2019-07-16 15:28:31 (4456): Guest Log: HITS file was successfully produced

2019-07-16 15:28:31 (4456): Guest Log: -rw------- 1 atlas01 atlas01 109888911 Jul 16 22:09 /home/atlas01/RunAtlas/HITS.18514527._036814.pool.root.1

2019-07-16 15:28:36 (4456): Guest Log: Successfully finished the ATLAS job!

2019-07-16 15:28:36 (4456): Guest Log: Copying the results back to the shared directory!

2019-07-16 15:28:36 (4456): Guest Log: Copied the result file back to the shared directory and created atlas_done file!

2019-07-16 15:28:36 (4456): Guest Log: Success! Shutting down the machine.

2019-07-16 15:28:36 (4456): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107949410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-16 15:28:36 (4456): VM Completion File Detected.
2019-07-16 15:28:36 (4456): Powering off VM.
2019-07-16 15:28:38 (4456): Successfully stopped VM.
2019-07-16 15:28:43 (4456): Deregistering VM. (boinc_7717eab2365f85bd, slot#6)
2019-07-16 15:28:48 (4456): Removing virtual disk drive(s) from VM.
2019-07-16 15:28:48 (4456): Removing network bandwidth throttle group from VM.
2019-07-16 15:28:48 (4456): Removing storage controller(s) from VM.
2019-07-16 15:28:54 (4456): Removing VM from VirtualBox.
15:29:13 (4456): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN