Message boards : ATLAS application : atlas task
Message board moderation

To post messages, you must log in.

AuthorMessage
S@NL Mark

Send message
Joined: 13 Jul 05
Posts: 6
Credit: 191,565
RAC: 0
Message 35342 - Posted: 22 May 2018, 19:41:08 UTC

Hi I'm running an atlas task for days with almost zero cpu utilisation with a remaining time of 3 minutes :-\ What can be wrong? Regards Mark


Application
ATLAS Simulation 1.01 (vbox64_mt_mcore_atlas)
Name
MbaLDmLJbcsnlyackoJh5iwnABFKDmABFKDmHKQRDmABFKDma5csTo
State
Running
Received
Tue 15 May 2018 04:03:57 PM CEST
Report deadline
Tue 22 May 2018 04:03:57 PM CEST
Resources
6 CPUs
Estimated computation size
43,200 GFLOPs
CPU time
5d 01:01:02
CPU time since checkpoint
00:00:00
Elapsed time
3d 11:24:29
Estimated time remaining
00:02:32
Fraction done
99.949%
Virtual memory size
2.33 GB
Working set size
7.81 GB
Directory
slots/0
Process ID
1521
Progress rate
1.080% per hour
Executable
vboxwrapper_26196_x86_64-pc-linux-gnu
ID: 35342 · Report as offensive     Reply Quote
computezrmle
Volunteer moderator
Volunteer developer
Volunteer tester
Avatar

Send message
Joined: 15 Jun 08
Posts: 1965
Credit: 139,431,664
RAC: 86,177
Message 35343 - Posted: 22 May 2018, 20:18:19 UTC - in response to Message 35342.  

The stderr.txt from your slots folder (slots/0 in this case) may contain useful information.
Try to post it here.
ID: 35343 · Report as offensive     Reply Quote
S@NL Mark

Send message
Joined: 13 Jul 05
Posts: 6
Credit: 191,565
RAC: 0
Message 35344 - Posted: 22 May 2018, 20:31:26 UTC - in response to Message 35343.  

OK here's the paste of my stderr.txt...

2018-05-15 21:45:20 (15483): vboxwrapper (7.7.26196): starting
2018-05-15 21:45:23 (15483): Feature: Checkpoint interval offset (402 seconds)
2018-05-15 21:45:23 (15483): Detected: VirtualBox VboxManage Interface (Version: 5.2.10)
2018-05-15 21:45:23 (15483): Detected: Minimum checkpoint interval (900.000000 seconds)
2018-05-15 21:45:23 (15483): Successfully copied 'init_data.xml' to the shared directory.
2018-05-15 21:45:23 (15483): Create VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-15 21:45:24 (15483): Setting Memory Size for VM. (8000MB)
2018-05-15 21:45:24 (15483): Setting CPU Count for VM. (6)
2018-05-15 21:45:24 (15483): Setting Chipset Options for VM.
2018-05-15 21:45:24 (15483): Setting Boot Options for VM.
2018-05-15 21:45:24 (15483): Setting Network Configuration for NAT.
2018-05-15 21:45:24 (15483): Enabling VM Network Access.
2018-05-15 21:45:24 (15483): Disabling USB Support for VM.
2018-05-15 21:45:24 (15483): Disabling COM Port Support for VM.
2018-05-15 21:45:25 (15483): Disabling LPT Port Support for VM.
2018-05-15 21:45:25 (15483): Disabling Audio Support for VM.
2018-05-15 21:45:25 (15483): Disabling Clipboard Support for VM.
2018-05-15 21:45:25 (15483): Disabling Drag and Drop Support for VM.
2018-05-15 21:45:25 (15483): Adding storage controller(s) to VM.
2018-05-15 21:45:26 (15483): Adding virtual disk drive to VM. (vm_image.vdi)
2018-05-15 21:45:26 (15483): Adding VirtualBox Guest Additions to VM.
2018-05-15 21:45:26 (15483): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2018-05-15 21:45:27 (15483): forwarding host port 58561 to guest port 80
2018-05-15 21:45:27 (15483): Enabling remote desktop for VM.
2018-05-15 21:45:27 (15483): Enabling shared directory for VM.
2018-05-15 21:45:28 (15483): Starting VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-15 21:45:30 (15483): Successfully started VM. (PID = '15882')
2018-05-15 21:45:30 (15483): Reporting VM Process ID to BOINC.
2018-05-15 21:45:30 (15483): Guest Log: BIOS: VirtualBox 5.2.10
2018-05-15 21:45:30 (15483): Guest Log: CPUID EDX: 0x178bfbff
2018-05-15 21:45:30 (15483): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2018-05-15 21:45:30 (15483): VM state change detected. (old = 'poweroff', new = 'running')
2018-05-15 21:45:30 (15483): Detected: Web Application Enabled (http://localhost:58561)
2018-05-15 21:45:30 (15483): Detected: Remote Desktop Enabled (localhost:45661)
2018-05-15 21:45:30 (15483): Preference change detected
2018-05-15 21:45:30 (15483): Setting CPU throttle for VM. (75%)
2018-05-15 21:45:30 (15483): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-15 21:45:32 (15483): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-05-15 21:45:32 (15483): Guest Log: BIOS: Booting from Hard Disk...
2018-05-15 21:45:34 (15483): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-15 21:45:34 (15483): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-15 21:46:07 (15483): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-15 21:46:26 (15483): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880207e83e10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-15 21:46:27 (15483): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880204142c10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-15 21:46:27 (15483): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880207e82c10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-15 21:46:27 (15483): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880204143010), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-15 21:47:28 (15483): Guest Log: Copying input files into RunAtlas.
2018-05-15 21:47:38 (15483): Guest Log: Copied input files into RunAtlas.
2018-05-15 21:47:51 (15483): Guest Log: copied the webapp to /var/www
2018-05-15 21:47:51 (15483): Guest Log: This vm does not need to setup http proxy
2018-05-15 21:47:52 (15483): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-15 21:47:52 (15483): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-15 21:59:25 (15483): VM state change detected. (old = 'running', new = 'paused')
2018-05-15 22:01:36 (15483): VM state change detected. (old = 'paused', new = 'running')
2018-05-15 23:26:44 (15483): Status Report: Elapsed Time: '6000.400162'
2018-05-15 23:26:44 (15483): Status Report: CPU Time: '21391.500000'
2018-05-16 01:05:48 (15483): Status Report: Elapsed Time: '12000.610453'
2018-05-16 01:05:48 (15483): Status Report: CPU Time: '44792.900000'
2018-05-16 09:33:22 (2480): vboxwrapper (7.7.26196): starting
2018-05-16 09:33:30 (2480): Feature: Checkpoint interval offset (220 seconds)
2018-05-16 09:33:30 (2480): Detected: VirtualBox VboxManage Interface (Version: 5.2.10)
2018-05-16 09:33:30 (2480): Detected: Minimum checkpoint interval (900.000000 seconds)
2018-05-16 09:33:30 (2480): Guest Log: BIOS: VirtualBox 5.2.10
2018-05-16 09:33:30 (2480): Guest Log: CPUID EDX: 0x178bfbff
2018-05-16 09:33:30 (2480): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2018-05-16 09:33:30 (2480): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-05-16 09:33:30 (2480): Guest Log: BIOS: Booting from Hard Disk...
2018-05-16 09:33:30 (2480): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-16 09:33:30 (2480): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-16 09:33:30 (2480): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-16 09:33:30 (2480): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880207e83e10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-16 09:33:30 (2480): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880204142c10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-16 09:33:30 (2480): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880207e82c10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-16 09:33:30 (2480): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880204143010), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-16 09:33:30 (2480): Guest Log: Copying input files into RunAtlas.
2018-05-16 09:33:30 (2480): Guest Log: Copied input files into RunAtlas.
2018-05-16 09:33:30 (2480): Guest Log: copied the webapp to /var/www
2018-05-16 09:33:30 (2480): Guest Log: This vm does not need to setup http proxy
2018-05-16 09:33:30 (2480): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-16 09:33:30 (2480): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-16 09:33:30 (2480): Starting VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-16 09:33:32 (2480): Successfully started VM. (PID = '2606')
2018-05-16 09:33:32 (2480): Reporting VM Process ID to BOINC.
2018-05-16 09:33:32 (2480): VM state change detected. (old = 'poweroff', new = 'running')
2018-05-16 09:33:32 (2480): Detected: Web Application Enabled (http://localhost:58561)
2018-05-16 09:33:32 (2480): Detected: Remote Desktop Enabled (localhost:45661)
2018-05-16 09:33:33 (2480): Status Report: Elapsed Time: '14207.657500'
2018-05-16 09:33:33 (2480): Status Report: CPU Time: '53453.990000'
2018-05-16 09:33:33 (2480): Preference change detected
2018-05-16 09:33:33 (2480): Setting CPU throttle for VM. (75%)
2018-05-16 09:33:33 (2480): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-16 09:37:41 (2480): VM state change detected. (old = 'running', new = 'paused')
2018-05-16 09:38:36 (2480): VM state change detected. (old = 'paused', new = 'running')
2018-05-16 09:38:50 (2480): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88020141ae10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-16 09:38:50 (2480): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201023c10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-16 09:38:51 (2480): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88020141aa10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-16 09:38:51 (2480): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201023a10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-16 09:41:18 (2480): Guest Log: Copying input files into RunAtlas.
2018-05-16 09:41:29 (2480): Guest Log: Copied input files into RunAtlas.
2018-05-16 09:41:33 (2480): Guest Log: copied the webapp to /var/www
2018-05-16 09:41:33 (2480): Guest Log: This vm does not need to setup http proxy
2018-05-16 09:41:33 (2480): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-16 09:41:34 (2480): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-16 11:13:36 (2480): Status Report: Elapsed Time: '20207.965960'
2018-05-16 11:13:36 (2480): Status Report: CPU Time: '74249.650000'
2018-05-16 12:52:37 (2480): Status Report: Elapsed Time: '26208.147281'
2018-05-16 12:52:37 (2480): Status Report: CPU Time: '99854.250000'
2018-05-16 14:31:41 (2480): Status Report: Elapsed Time: '32209.046996'
2018-05-16 14:31:41 (2480): Status Report: CPU Time: '124983.230000'
2018-05-16 16:10:44 (2480): Status Report: Elapsed Time: '38209.317056'
2018-05-16 16:10:48 (2480): Status Report: CPU Time: '149719.000000'
2018-05-16 17:49:45 (2480): Status Report: Elapsed Time: '44209.548881'
2018-05-16 17:49:45 (2480): Status Report: CPU Time: '175092.950000'
2018-05-16 19:28:46 (2480): Status Report: Elapsed Time: '50210.064069'
2018-05-16 19:28:46 (2480): Status Report: CPU Time: '200847.080000'
2018-05-16 19:55:11 (1568): vboxwrapper (7.7.26196): starting
2018-05-16 19:55:12 (1568): Feature: Checkpoint interval offset (254 seconds)
2018-05-16 19:55:12 (1568): Detected: VirtualBox VboxManage Interface (Version: 5.2.10)
2018-05-16 19:55:12 (1568): Detected: Minimum checkpoint interval (900.000000 seconds)
2018-05-16 19:55:12 (1568): Guest Log: BIOS: VirtualBox 5.2.10
2018-05-16 19:55:12 (1568): Guest Log: CPUID EDX: 0x178bfbff
2018-05-16 19:55:12 (1568): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2018-05-16 19:55:12 (1568): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-05-16 19:55:12 (1568): Guest Log: BIOS: Booting from Hard Disk...
2018-05-16 19:55:12 (1568): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-16 19:55:12 (1568): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-16 19:55:12 (1568): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-16 19:55:12 (1568): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88020141ae10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-16 19:55:12 (1568): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201023c10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-16 19:55:12 (1568): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88020141aa10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-16 19:55:12 (1568): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201023a10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-16 19:55:12 (1568): Guest Log: Copying input files into RunAtlas.
2018-05-16 19:55:12 (1568): Guest Log: Copied input files into RunAtlas.
2018-05-16 19:55:12 (1568): Guest Log: copied the webapp to /var/www
2018-05-16 19:55:12 (1568): Guest Log: This vm does not need to setup http proxy
2018-05-16 19:55:12 (1568): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-16 19:55:12 (1568): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-16 19:55:12 (1568): Starting VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-16 19:55:15 (1568): Successfully started VM. (PID = '1659')
2018-05-16 19:55:15 (1568): Reporting VM Process ID to BOINC.
2018-05-16 19:55:15 (1568): VM state change detected. (old = 'poweroff', new = 'running')
2018-05-16 19:55:15 (1568): Detected: Web Application Enabled (http://localhost:58561)
2018-05-16 19:55:15 (1568): Detected: Remote Desktop Enabled (localhost:45661)
2018-05-16 19:55:15 (1568): Status Report: Elapsed Time: '50973.064069'
2018-05-16 19:55:15 (1568): Status Report: CPU Time: '204117.720000'
2018-05-16 19:55:15 (1568): Preference change detected
2018-05-16 19:55:15 (1568): Setting CPU throttle for VM. (75%)
2018-05-16 19:55:15 (1568): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-16 20:38:23 (1568): VM state change detected. (old = 'running', new = 'paused')
2018-05-16 20:40:17 (1568): VM state change detected. (old = 'paused', new = 'running')
2018-05-16 21:36:29 (1568): Status Report: Elapsed Time: '56973.189370'
2018-05-16 21:36:29 (1568): Status Report: CPU Time: '212741.010000'
2018-05-16 23:15:29 (1568): Status Report: Elapsed Time: '62973.189370'
2018-05-16 23:15:29 (1568): Status Report: CPU Time: '212857.530000'
2018-05-17 09:28:31 (2440): vboxwrapper (7.7.26196): starting
2018-05-17 09:28:34 (2440): Feature: Checkpoint interval offset (296 seconds)
2018-05-17 09:28:34 (2440): Detected: VirtualBox VboxManage Interface (Version: 5.2.10)
2018-05-17 09:28:34 (2440): Detected: Minimum checkpoint interval (900.000000 seconds)
2018-05-17 09:28:34 (2440): Guest Log: BIOS: VirtualBox 5.2.10
2018-05-17 09:28:34 (2440): Guest Log: CPUID EDX: 0x178bfbff
2018-05-17 09:28:34 (2440): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-05-17 09:28:34 (2440): Guest Log: BIOS: Booting from Hard Disk...
2018-05-17 09:28:34 (2440): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-17 09:28:34 (2440): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-17 09:28:34 (2440): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-17 09:28:34 (2440): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880202d61810), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-17 09:28:34 (2440): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802017b8010), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-17 09:28:34 (2440): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880202e76210), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-17 09:28:34 (2440): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802026e7a10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-17 09:28:34 (2440): Guest Log: Copying input files into RunAtlas.
2018-05-17 09:28:34 (2440): Guest Log: Copied input files into RunAtlas.
2018-05-17 09:28:34 (2440): Guest Log: copied the webapp to /var/www
2018-05-17 09:28:34 (2440): Guest Log: This vm does not need to setup http proxy
2018-05-17 09:28:34 (2440): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-17 09:28:34 (2440): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-17 09:28:34 (2440): Starting VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-17 09:28:44 (2440): Successfully started VM. (PID = '2580')
2018-05-17 09:28:44 (2440): Reporting VM Process ID to BOINC.
2018-05-17 09:28:44 (2440): VM state change detected. (old = 'poweroff', new = 'running')
2018-05-17 09:28:44 (2440): Detected: Web Application Enabled (http://localhost:58561)
2018-05-17 09:28:44 (2440): Detected: Remote Desktop Enabled (localhost:45661)
2018-05-17 09:28:44 (2440): Status Report: Elapsed Time: '68308.189370'
2018-05-17 09:28:44 (2440): Status Report: CPU Time: '212959.640000'
2018-05-17 09:28:44 (2440): Preference change detected
2018-05-17 09:28:44 (2440): Setting CPU throttle for VM. (75%)
2018-05-17 09:28:45 (2440): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-17 09:32:14 (2440): VM state change detected. (old = 'running', new = 'paused')
2018-05-17 09:34:35 (2440): VM state change detected. (old = 'paused', new = 'running')
2018-05-17 09:34:35 (2440): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-17 09:34:35 (2440): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-17 09:35:07 (2440): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-17 09:35:32 (2440): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802041ede10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-17 09:35:32 (2440): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201d5f210), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-17 09:35:33 (2440): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201226810), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-17 09:35:33 (2440): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201226610), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-17 09:38:19 (2440): Guest Log: Copying input files into RunAtlas.
2018-05-17 09:38:30 (2440): Guest Log: Copied input files into RunAtlas.
2018-05-17 09:38:32 (2440): Guest Log: copied the webapp to /var/www
2018-05-17 09:38:33 (2440): Guest Log: This vm does not need to setup http proxy
2018-05-17 09:38:33 (2440): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-17 09:38:34 (2440): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-17 13:57:24 (1554): vboxwrapper (7.7.26196): starting
2018-05-17 13:57:25 (1554): Feature: Checkpoint interval offset (219 seconds)
2018-05-17 13:57:25 (1554): Detected: VirtualBox VboxManage Interface (Version: 5.2.10)
2018-05-17 13:57:25 (1554): Detected: Minimum checkpoint interval (900.000000 seconds)
2018-05-17 13:57:25 (1554): Guest Log: BIOS: VirtualBox 5.2.10
2018-05-17 13:57:25 (1554): Guest Log: CPUID EDX: 0x178bfbff
2018-05-17 13:57:25 (1554): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2018-05-17 13:57:25 (1554): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-05-17 13:57:25 (1554): Guest Log: BIOS: Booting from Hard Disk...
2018-05-17 13:57:25 (1554): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-17 13:57:25 (1554): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-17 13:57:25 (1554): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-17 13:57:25 (1554): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802041ede10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-17 13:57:25 (1554): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201d5f210), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-17 13:57:25 (1554): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201226810), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-17 13:57:25 (1554): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201226610), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-17 13:57:25 (1554): Guest Log: Copying input files into RunAtlas.
2018-05-17 13:57:25 (1554): Guest Log: Copied input files into RunAtlas.
2018-05-17 13:57:25 (1554): Guest Log: copied the webapp to /var/www
2018-05-17 13:57:25 (1554): Guest Log: This vm does not need to setup http proxy
2018-05-17 13:57:25 (1554): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-17 13:57:25 (1554): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-17 13:57:25 (1554): Starting VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-17 13:57:28 (1554): Successfully started VM. (PID = '1644')
2018-05-17 13:57:28 (1554): Reporting VM Process ID to BOINC.
2018-05-17 13:57:28 (1554): VM state change detected. (old = 'poweroff', new = 'running')
2018-05-17 13:57:28 (1554): Detected: Web Application Enabled (http://localhost:58561)
2018-05-17 13:57:28 (1554): Detected: Remote Desktop Enabled (localhost:45661)
2018-05-17 13:57:28 (1554): Status Report: Elapsed Time: '69703.145166'
2018-05-17 13:57:28 (1554): Status Report: CPU Time: '214783.010000'
2018-05-17 13:57:28 (1554): Preference change detected
2018-05-17 13:57:28 (1554): Setting CPU throttle for VM. (75%)
2018-05-17 13:57:28 (1554): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-17 14:00:47 (1554): VM state change detected. (old = 'running', new = 'paused')
2018-05-17 14:00:57 (1554): VM state change detected. (old = 'paused', new = 'running')
2018-05-17 14:01:07 (1554): VM state change detected. (old = 'running', new = 'paused')
2018-05-17 14:02:28 (1554): VM state change detected. (old = 'paused', new = 'running')
2018-05-17 15:11:38 (1554): VM state change detected. (old = 'running', new = 'paused')
2018-05-17 15:11:48 (1554): VM state change detected. (old = 'paused', new = 'running')
2018-05-17 15:38:13 (1554): Status Report: Elapsed Time: '75703.462262'
2018-05-17 15:38:13 (1554): Status Report: CPU Time: '214882.000000'
2018-05-17 17:17:14 (1554): Status Report: Elapsed Time: '81703.462262'
2018-05-17 17:17:14 (1554): Status Report: CPU Time: '214960.240000'
2018-05-17 18:56:14 (1554): Status Report: Elapsed Time: '87703.462262'
2018-05-17 18:56:14 (1554): Status Report: CPU Time: '215039.370000'
2018-05-17 20:35:15 (1554): Status Report: Elapsed Time: '93703.462262'
2018-05-17 20:35:15 (1554): Status Report: CPU Time: '215116.780000'
2018-05-17 22:14:15 (1554): Status Report: Elapsed Time: '99703.462262'
2018-05-17 22:14:15 (1554): Status Report: CPU Time: '215193.760000'
2018-05-17 23:53:16 (1554): Status Report: Elapsed Time: '105703.462262'
2018-05-17 23:53:16 (1554): Status Report: CPU Time: '215263.180000'
2018-05-18 09:38:22 (2439): vboxwrapper (7.7.26196): starting
2018-05-18 09:38:24 (2439): Feature: Checkpoint interval offset (477 seconds)
2018-05-18 09:38:24 (2439): Detected: VirtualBox VboxManage Interface (Version: 5.2.10)
2018-05-18 09:38:24 (2439): Detected: Minimum checkpoint interval (900.000000 seconds)
2018-05-18 09:38:27 (2439): Guest Log: BIOS: VirtualBox 5.2.10
2018-05-18 09:38:27 (2439): Guest Log: CPUID EDX: 0x178bfbff
2018-05-18 09:38:27 (2439): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2018-05-18 09:38:27 (2439): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-05-18 09:38:27 (2439): Guest Log: BIOS: Booting from Hard Disk...
2018-05-18 09:38:27 (2439): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-18 09:38:27 (2439): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-18 09:38:27 (2439): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-18 09:38:27 (2439): Starting VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-18 09:38:31 (2439): Successfully started VM. (PID = '2557')
2018-05-18 09:38:31 (2439): Reporting VM Process ID to BOINC.
2018-05-18 09:38:31 (2439): VM state change detected. (old = 'poweroff', new = 'running')
2018-05-18 09:38:31 (2439): Detected: Web Application Enabled (http://localhost:58561)
2018-05-18 09:38:31 (2439): Detected: Remote Desktop Enabled (localhost:45661)
2018-05-18 09:38:31 (2439): Status Report: Elapsed Time: '108724.462262'
2018-05-18 09:38:31 (2439): Status Report: CPU Time: '215293.590000'
2018-05-18 09:38:31 (2439): Preference change detected
2018-05-18 09:38:31 (2439): Setting CPU throttle for VM. (75%)
2018-05-18 09:38:31 (2439): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-18 09:39:47 (2439): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-18 09:40:01 (2439): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201ac6810), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-18 09:40:01 (2439): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802014d5a10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-18 09:40:01 (2439): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802014d5810), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-18 09:40:01 (2439): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201ac6c10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-18 09:42:22 (2439): Guest Log: Copying input files into RunAtlas.
2018-05-18 09:42:29 (2439): Guest Log: Copied input files into RunAtlas.
2018-05-18 09:42:31 (2439): Guest Log: copied the webapp to /var/www
2018-05-18 09:42:31 (2439): Guest Log: This vm does not need to setup http proxy
2018-05-18 09:42:31 (2439): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-18 09:42:32 (2439): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-18 09:47:36 (2439): VM state change detected. (old = 'running', new = 'paused')
2018-05-18 09:47:43 (2439): VM state change detected. (old = 'paused', new = 'running')
2018-05-18 09:47:52 (2439): VM state change detected. (old = 'running', new = 'paused')
2018-05-18 09:49:53 (2439): VM state change detected. (old = 'paused', new = 'running')
2018-05-18 11:20:38 (2439): Status Report: Elapsed Time: '114725.438639'
2018-05-18 11:20:38 (2439): Status Report: CPU Time: '236505.800000'
2018-05-18 12:59:41 (2439): Status Report: Elapsed Time: '120726.059297'
2018-05-18 12:59:41 (2439): Status Report: CPU Time: '261801.420000'
2018-05-18 14:28:41 (1530): vboxwrapper (7.7.26196): starting
2018-05-18 14:28:42 (1530): Feature: Checkpoint interval offset (201 seconds)
2018-05-18 14:28:42 (1530): Detected: VirtualBox VboxManage Interface (Version: 5.2.10)
2018-05-18 14:28:42 (1530): Detected: Minimum checkpoint interval (900.000000 seconds)
2018-05-18 14:28:42 (1530): Guest Log: BIOS: VirtualBox 5.2.10
2018-05-18 14:28:42 (1530): Guest Log: CPUID EDX: 0x178bfbff
2018-05-18 14:28:42 (1530): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2018-05-18 14:28:42 (1530): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-05-18 14:28:42 (1530): Guest Log: BIOS: Booting from Hard Disk...
2018-05-18 14:28:42 (1530): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-18 14:28:42 (1530): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-18 14:28:42 (1530): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-18 14:28:42 (1530): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201ac6810), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-18 14:28:42 (1530): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802014d5a10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-18 14:28:42 (1530): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802014d5810), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-18 14:28:42 (1530): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201ac6c10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-18 14:28:42 (1530): Guest Log: Copying input files into RunAtlas.
2018-05-18 14:28:42 (1530): Guest Log: Copied input files into RunAtlas.
2018-05-18 14:28:42 (1530): Guest Log: copied the webapp to /var/www
2018-05-18 14:28:42 (1530): Guest Log: This vm does not need to setup http proxy
2018-05-18 14:28:42 (1530): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-18 14:28:42 (1530): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-18 14:28:42 (1530): Starting VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-18 14:28:45 (1530): Successfully started VM. (PID = '1620')
2018-05-18 14:28:45 (1530): Reporting VM Process ID to BOINC.
2018-05-18 14:28:45 (1530): VM state change detected. (old = 'poweroff', new = 'running')
2018-05-18 14:28:45 (1530): Detected: Web Application Enabled (http://localhost:58561)
2018-05-18 14:28:45 (1530): Detected: Remote Desktop Enabled (localhost:45661)
2018-05-18 14:28:45 (1530): Status Report: Elapsed Time: '124539.789429'
2018-05-18 14:28:45 (1530): Status Report: CPU Time: '276860.370000'
2018-05-18 14:28:45 (1530): Preference change detected
2018-05-18 14:28:45 (1530): Setting CPU throttle for VM. (75%)
2018-05-18 14:28:45 (1530): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-18 14:37:28 (1530): VM state change detected. (old = 'running', new = 'paused')
2018-05-18 14:37:38 (1530): VM state change detected. (old = 'paused', new = 'running')
2018-05-18 16:11:43 (1530): Status Report: Elapsed Time: '130540.518941'
2018-05-18 16:11:43 (1530): Status Report: CPU Time: '276932.740000'
2018-05-18 16:11:59 (1530): VM state change detected. (old = 'running', new = 'paused')
2018-05-18 16:14:09 (1530): VM state change detected. (old = 'paused', new = 'running')
2018-05-18 17:52:55 (1530): Status Report: Elapsed Time: '136540.518941'
2018-05-18 17:52:55 (1530): Status Report: CPU Time: '276997.460000'
2018-05-18 19:31:55 (1530): Status Report: Elapsed Time: '142540.518941'
2018-05-18 19:31:55 (1530): Status Report: CPU Time: '277064.780000'
2018-05-18 20:53:42 (1530): VM state change detected. (old = 'running', new = 'paused')
2018-05-18 20:53:52 (1530): VM state change detected. (old = 'paused', new = 'running')
2018-05-18 21:11:07 (1530): Status Report: Elapsed Time: '148540.518941'
2018-05-18 21:11:07 (1530): Status Report: CPU Time: '277130.800000'
2018-05-18 22:50:07 (1530): Status Report: Elapsed Time: '154540.518941'
2018-05-18 22:50:07 (1530): Status Report: CPU Time: '277194.790000'
2018-05-18 22:59:01 (1530): VM state change detected. (old = 'running', new = 'paused')
2018-05-18 22:59:11 (1530): VM state change detected. (old = 'paused', new = 'running')
2018-05-19 00:29:19 (1530): Status Report: Elapsed Time: '160540.518941'
2018-05-19 00:29:19 (1530): Status Report: CPU Time: '277261.770000'
2018-05-19 00:29:31 (1530): VM state change detected. (old = 'running', new = 'paused')
2018-05-19 00:29:41 (1530): VM state change detected. (old = 'paused', new = 'running')
2018-05-19 01:05:39 (1530): VM state change detected. (old = 'running', new = 'paused')
2018-05-19 01:05:49 (1530): VM state change detected. (old = 'paused', new = 'running')
2018-05-19 11:07:07 (1569): vboxwrapper (7.7.26196): starting
2018-05-19 11:07:08 (1569): Feature: Checkpoint interval offset (202 seconds)
2018-05-19 11:07:08 (1569): Detected: VirtualBox VboxManage Interface (Version: 5.2.10)
2018-05-19 11:07:08 (1569): Detected: Minimum checkpoint interval (900.000000 seconds)
2018-05-19 11:07:09 (1569): Guest Log: BIOS: VirtualBox 5.2.10
2018-05-19 11:07:09 (1569): Guest Log: CPUID EDX: 0x178bfbff
2018-05-19 11:07:09 (1569): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2018-05-19 11:07:09 (1569): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-05-19 11:07:09 (1569): Guest Log: BIOS: Booting from Hard Disk...
2018-05-19 11:07:09 (1569): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-19 11:07:09 (1569): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-19 11:07:09 (1569): Starting VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-19 11:07:10 (1569): Successfully started VM. (PID = '1660')
2018-05-19 11:07:10 (1569): Reporting VM Process ID to BOINC.
2018-05-19 11:07:11 (1569): VM state change detected. (old = 'poweroff', new = 'running')
2018-05-19 11:07:11 (1569): Detected: Web Application Enabled (http://localhost:58561)
2018-05-19 11:07:11 (1569): Detected: Remote Desktop Enabled (localhost:45661)
2018-05-19 11:07:11 (1569): Status Report: Elapsed Time: '163397.518941'
2018-05-19 11:07:11 (1569): Status Report: CPU Time: '277294.280000'
2018-05-19 11:07:11 (1569): Preference change detected
2018-05-19 11:07:11 (1569): Setting CPU throttle for VM. (75%)
2018-05-19 11:07:11 (1569): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-19 11:07:40 (1569): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-19 11:07:55 (1569): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802087b2210), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-19 11:07:55 (1569): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880205e40410), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-19 11:07:56 (1569): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88020438b410), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-19 11:07:56 (1569): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88020149ec10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-19 11:10:09 (1569): Guest Log: Copying input files into RunAtlas.
2018-05-19 11:10:17 (1569): Guest Log: Copied input files into RunAtlas.
2018-05-19 11:10:18 (1569): Guest Log: copied the webapp to /var/www
2018-05-19 11:10:18 (1569): Guest Log: This vm does not need to setup http proxy
2018-05-19 11:10:18 (1569): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-19 11:10:19 (1569): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-19 11:16:35 (1569): VM state change detected. (old = 'running', new = 'paused')
2018-05-19 11:17:23 (1569): VM state change detected. (old = 'paused', new = 'running')
2018-05-19 11:40:34 (1569): VM state change detected. (old = 'running', new = 'paused')
2018-05-19 11:40:35 (1569): VM state change detected. (old = 'paused', new = 'running')
2018-05-19 12:47:45 (1569): Status Report: Elapsed Time: '169397.763969'
2018-05-19 12:47:45 (1569): Status Report: CPU Time: '298339.180000'
2018-05-19 18:48:07 (2410): vboxwrapper (7.7.26196): starting
2018-05-19 18:48:07 (2410): Feature: Checkpoint interval offset (455 seconds)
2018-05-19 18:48:07 (2410): Detected: VirtualBox VboxManage Interface (Version: 5.2.10)
2018-05-19 18:48:07 (2410): Detected: Minimum checkpoint interval (900.000000 seconds)
2018-05-19 18:48:08 (2410): Guest Log: BIOS: VirtualBox 5.2.10
2018-05-19 18:48:08 (2410): Guest Log: CPUID EDX: 0x178bfbff
2018-05-19 18:48:08 (2410): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2018-05-19 18:48:08 (2410): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-05-19 18:48:08 (2410): Guest Log: BIOS: Booting from Hard Disk...
2018-05-19 18:48:08 (2410): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-19 18:48:08 (2410): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-19 18:48:08 (2410): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-19 18:48:08 (2410): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802087b2210), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-19 18:48:08 (2410): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880205e40410), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-19 18:48:08 (2410): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88020438b410), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-19 18:48:08 (2410): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88020149ec10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-19 18:48:08 (2410): Guest Log: Copying input files into RunAtlas.
2018-05-19 18:48:08 (2410): Guest Log: Copied input files into RunAtlas.
2018-05-19 18:48:08 (2410): Guest Log: copied the webapp to /var/www
2018-05-19 18:48:08 (2410): Guest Log: This vm does not need to setup http proxy
2018-05-19 18:48:08 (2410): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-19 18:48:08 (2410): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-19 18:48:08 (2410): Starting VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-19 18:48:09 (2410): Successfully started VM. (PID = '2499')
2018-05-19 18:48:09 (2410): Reporting VM Process ID to BOINC.
2018-05-19 18:48:10 (2410): VM state change detected. (old = 'poweroff', new = 'running')
2018-05-19 18:48:10 (2410): Detected: Web Application Enabled (http://localhost:58561)
2018-05-19 18:48:10 (2410): Detected: Remote Desktop Enabled (localhost:45661)
2018-05-19 18:48:10 (2410): Status Report: Elapsed Time: '169584.763969'
2018-05-19 18:48:10 (2410): Status Report: CPU Time: '299158.090000'
2018-05-19 18:48:10 (2410): Preference change detected
2018-05-19 18:48:10 (2410): Setting CPU throttle for VM. (75%)
2018-05-19 18:48:12 (2410): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-19 18:51:30 (2410): VM state change detected. (old = 'running', new = 'paused')
2018-05-19 18:52:51 (2410): VM state change detected. (old = 'paused', new = 'running')
2018-05-19 18:52:51 (2410): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-19 18:52:51 (2410): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-19 18:53:18 (2410): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-19 18:53:42 (2410): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802029f5e10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-19 18:53:43 (2410): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201236410), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-19 18:53:43 (2410): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802048ba810), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-19 18:53:43 (2410): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880208287610), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-19 18:56:17 (2410): Guest Log: Copying input files into RunAtlas.
2018-05-19 18:56:28 (2410): Guest Log: Copied input files into RunAtlas.
2018-05-19 18:56:30 (2410): Guest Log: copied the webapp to /var/www
2018-05-19 18:56:30 (2410): Guest Log: This vm does not need to setup http proxy
2018-05-19 18:56:31 (2410): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-19 18:56:31 (2410): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-19 20:29:48 (2410): Status Report: Elapsed Time: '175585.602397'
2018-05-19 20:29:48 (2410): Status Report: CPU Time: '320843.060000'
2018-05-19 22:08:49 (2410): Status Report: Elapsed Time: '181585.723787'
2018-05-19 22:08:49 (2410): Status Report: CPU Time: '346397.520000'
2018-05-19 23:47:50 (2410): Status Report: Elapsed Time: '187585.985065'
2018-05-19 23:47:50 (2410): Status Report: CPU Time: '372128.580000'
2018-05-20 01:26:51 (2410): Status Report: Elapsed Time: '193586.292628'
2018-05-20 01:26:51 (2410): Status Report: CPU Time: '398012.520000'
2018-05-20 11:27:50 (1569): vboxwrapper (7.7.26196): starting
2018-05-20 11:27:50 (1569): Feature: Checkpoint interval offset (245 seconds)
2018-05-20 11:27:50 (1569): Detected: VirtualBox VboxManage Interface (Version: 5.2.10)
2018-05-20 11:27:51 (1569): Detected: Minimum checkpoint interval (900.000000 seconds)
2018-05-20 11:27:51 (1569): Guest Log: BIOS: VirtualBox 5.2.10
2018-05-20 11:27:51 (1569): Guest Log: CPUID EDX: 0x178bfbff
2018-05-20 11:27:51 (1569): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2018-05-20 11:27:51 (1569): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-05-20 11:27:51 (1569): Guest Log: BIOS: Booting from Hard Disk...
2018-05-20 11:27:51 (1569): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-20 11:27:51 (1569): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-20 11:27:51 (1569): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-20 11:27:51 (1569): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802029f5e10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-20 11:27:51 (1569): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880201236410), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-20 11:27:51 (1569): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802048ba810), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-20 11:27:51 (1569): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880208287610), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-20 11:27:51 (1569): Guest Log: Copying input files into RunAtlas.
2018-05-20 11:27:51 (1569): Guest Log: Copied input files into RunAtlas.
2018-05-20 11:27:51 (1569): Guest Log: copied the webapp to /var/www
2018-05-20 11:27:51 (1569): Guest Log: This vm does not need to setup http proxy
2018-05-20 11:27:51 (1569): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-20 11:27:51 (1569): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-20 11:27:51 (1569): Starting VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-20 11:27:52 (1569): Successfully started VM. (PID = '1659')
2018-05-20 11:27:52 (1569): Reporting VM Process ID to BOINC.
2018-05-20 11:27:52 (1569): VM state change detected. (old = 'poweroff', new = 'running')
2018-05-20 11:27:52 (1569): Detected: Web Application Enabled (http://localhost:58561)
2018-05-20 11:27:52 (1569): Detected: Remote Desktop Enabled (localhost:45661)
2018-05-20 11:27:52 (1569): Status Report: Elapsed Time: '193816.292628'
2018-05-20 11:27:52 (1569): Status Report: CPU Time: '398996.990000'
2018-05-20 11:27:52 (1569): Preference change detected
2018-05-20 11:27:52 (1569): Setting CPU throttle for VM. (75%)
2018-05-20 11:27:53 (1569): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-20 11:34:14 (1569): VM state change detected. (old = 'running', new = 'paused')
2018-05-20 11:35:05 (1569): VM state change detected. (old = 'paused', new = 'running')
2018-05-20 13:07:50 (1569): Status Report: Elapsed Time: '199817.270972'
2018-05-20 13:07:50 (1569): Status Report: CPU Time: '418207.850000'
2018-05-21 00:18:54 (2408): vboxwrapper (7.7.26196): starting
2018-05-21 00:18:54 (2408): Feature: Checkpoint interval offset (27 seconds)
2018-05-21 00:18:54 (2408): Detected: VirtualBox VboxManage Interface (Version: 5.2.10)
2018-05-21 00:18:54 (2408): Detected: Minimum checkpoint interval (900.000000 seconds)
2018-05-21 00:18:54 (2408): Guest Log: BIOS: VirtualBox 5.2.10
2018-05-21 00:18:54 (2408): Guest Log: CPUID EDX: 0x178bfbff
2018-05-21 00:18:54 (2408): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2018-05-21 00:18:54 (2408): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-05-21 00:18:54 (2408): Guest Log: BIOS: Booting from Hard Disk...
2018-05-21 00:18:54 (2408): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-21 00:18:54 (2408): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-21 00:18:54 (2408): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-21 00:18:54 (2408): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880202a5c210), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-21 00:18:54 (2408): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802012c4810), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-21 00:18:54 (2408): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802011e0610), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-21 00:18:54 (2408): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8802083f2e10), OR(0x0), NOT(0xffffffff), flags(0x0)
2018-05-21 00:18:54 (2408): Guest Log: Copying input files into RunAtlas.
2018-05-21 00:18:54 (2408): Guest Log: Copied input files into RunAtlas.
2018-05-21 00:18:54 (2408): Guest Log: copied the webapp to /var/www
2018-05-21 00:18:54 (2408): Guest Log: This vm does not need to setup http proxy
2018-05-21 00:18:54 (2408): Guest Log: ATHENA_PROC_NUMBER=6
2018-05-21 00:18:54 (2408): Guest Log: Starting ATLAS job. (PandaID=3928170796 taskID=14073742)
2018-05-21 00:18:54 (2408): Starting VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-21 00:18:56 (2408): Successfully started VM. (PID = '2497')
2018-05-21 00:18:56 (2408): Reporting VM Process ID to BOINC.
2018-05-21 00:18:56 (2408): VM state change detected. (old = 'poweroff', new = 'running')
2018-05-21 00:18:56 (2408): Detected: Web Application Enabled (http://localhost:58561)
2018-05-21 00:18:56 (2408): Detected: Remote Desktop Enabled (localhost:45661)
2018-05-21 00:18:56 (2408): Status Report: Elapsed Time: '203553.972710'
2018-05-21 00:18:56 (2408): Status Report: CPU Time: '434212.640000'
2018-05-21 00:18:56 (2408): Preference change detected
2018-05-21 00:18:56 (2408): Setting CPU throttle for VM. (75%)
2018-05-21 00:18:57 (2408): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-21 00:26:49 (2408): VM state change detected. (old = 'running', new = 'paused')
2018-05-21 00:26:59 (2408): VM state change detected. (old = 'paused', new = 'running')
2018-05-21 01:19:53 (2408): VM state change detected. (old = 'running', new = 'paused')
2018-05-21 01:21:18 (2408): VM state change detected. (old = 'paused', new = 'running')
2018-05-21 01:34:26 (2408): Preference change detected
2018-05-21 01:34:26 (2408): Setting CPU throttle for VM. (75%)
2018-05-21 01:34:26 (2408): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-21 10:13:24 (1903): vboxwrapper (7.7.26196): starting
2018-05-21 10:13:24 (1903): Feature: Checkpoint interval offset (0 seconds)
2018-05-21 10:13:24 (1903): Detected: VirtualBox VboxManage Interface (Version: 5.2.10)
2018-05-21 10:13:24 (1903): Detected: Minimum checkpoint interval (900.000000 seconds)
2018-05-21 10:13:24 (1903): Guest Log: BIOS: VirtualBox 5.2.10
2018-05-21 10:13:24 (1903): Guest Log: CPUID EDX: 0x178bfbff
2018-05-21 10:13:24 (1903): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2018-05-21 10:13:24 (1903): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-05-21 10:13:24 (1903): Guest Log: BIOS: Booting from Hard Disk...
2018-05-21 10:13:24 (1903): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-21 10:13:24 (1903): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-21 10:13:24 (1903): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-21 10:13:24 (1903): Starting VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-21 10:13:26 (1903): Successfully started VM. (PID = '1992')
2018-05-21 10:13:26 (1903): Reporting VM Process ID to BOINC.
2018-05-21 10:13:26 (1903): VM state change detected. (old = 'poweroff', new = 'running')
2018-05-21 10:13:26 (1903): Detected: Web Application Enabled (http://localhost:58561)
2018-05-21 10:13:26 (1903): Detected: Remote Desktop Enabled (localhost:45661)
2018-05-21 10:13:26 (1903): Status Report: Elapsed Time: '208638.827376'
2018-05-21 10:13:26 (1903): Status Report: CPU Time: '434308.860000'
2018-05-21 10:13:26 (1903): Preference change detected
2018-05-21 10:13:26 (1903): Setting CPU throttle for VM. (75%)
2018-05-21 10:13:26 (1903): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-21 10:17:41 (1903): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2018-05-21 10:19:39 (1903): VM state change detected. (old = 'running', new = 'paused')
2018-05-21 10:19:44 (1903): VM state change detected. (old = 'paused', new = 'running')
2018-05-21 10:21:28 (1903): VM state change detected. (old = 'running', new = 'paused')
2018-05-21 10:22:07 (1903): VM state change detected. (old = 'paused', new = 'running')
2018-05-21 11:54:38 (1903): Status Report: Elapsed Time: '214639.250658'
2018-05-21 11:54:38 (1903): Status Report: CPU Time: '434533.100000'
2018-05-21 13:33:38 (1903): Status Report: Elapsed Time: '220639.250658'
2018-05-21 13:33:38 (1903): Status Report: CPU Time: '434642.460000'
2018-05-21 15:12:39 (1903): Status Report: Elapsed Time: '226639.250658'
2018-05-21 15:12:39 (1903): Status Report: CPU Time: '434720.590000'
2018-05-21 16:51:39 (1903): Status Report: Elapsed Time: '232639.250658'
2018-05-21 16:51:39 (1903): Status Report: CPU Time: '434798.250000'
2018-05-21 18:30:40 (1903): Status Report: Elapsed Time: '238639.250658'
2018-05-21 18:30:40 (1903): Status Report: CPU Time: '434876.710000'
2018-05-21 20:09:41 (1903): Status Report: Elapsed Time: '244639.250658'
2018-05-21 20:09:41 (1903): Status Report: CPU Time: '434954.600000'
2018-05-21 21:48:41 (1903): Status Report: Elapsed Time: '250639.250658'
2018-05-21 21:48:41 (1903): Status Report: CPU Time: '435033.510000'
2018-05-21 23:27:42 (1903): Status Report: Elapsed Time: '256639.250658'
2018-05-21 23:27:42 (1903): Status Report: CPU Time: '435112.410000'
2018-05-21 23:40:40 (1903): VM state change detected. (old = 'running', new = 'paused')
2018-05-21 23:40:50 (1903): VM state change detected. (old = 'paused', new = 'running')
2018-05-22 01:06:53 (1903): Status Report: Elapsed Time: '262639.250658'
2018-05-22 01:06:53 (1903): Status Report: CPU Time: '435191.130000'
2018-05-22 01:21:03 (1903): VM state change detected. (old = 'running', new = 'paused')
2018-05-22 10:43:32 (1903): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_4e05af57c182c5c5" resume
Output:
VBoxManage: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee nsISupports
VBoxManage: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2018-05-22 10:43:33 (1903): VM state change detected. (old = 'paused', new = 'running')
2018-05-22 10:44:27 (1903): VM state change detected. (old = 'running', new = 'paused')
2018-05-22 10:46:18 (1903): VM state change detected. (old = 'paused', new = 'running')
2018-05-22 12:10:16 (1903): Status Report: Elapsed Time: '268639.250658'
2018-05-22 12:10:16 (1903): Status Report: CPU Time: '435270.050000'
2018-05-22 13:28:00 (1521): vboxwrapper (7.7.26196): starting
2018-05-22 13:28:01 (1521): Feature: Checkpoint interval offset (132 seconds)
2018-05-22 13:28:01 (1521): Detected: VirtualBox VboxManage Interface (Version: 5.2.10)
2018-05-22 13:28:01 (1521): Detected: Minimum checkpoint interval (900.000000 seconds)
2018-05-22 13:28:01 (1521): Starting VM. (boinc_4e05af57c182c5c5, slot#0)
2018-05-22 13:28:03 (1521): Successfully started VM. (PID = '1610')
2018-05-22 13:28:03 (1521): Reporting VM Process ID to BOINC.
2018-05-22 13:28:03 (1521): Guest Log: BIOS: VirtualBox 5.2.10
2018-05-22 13:28:03 (1521): Guest Log: CPUID EDX: 0x178bfbff
2018-05-22 13:28:03 (1521): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2018-05-22 13:28:03 (1521): VM state change detected. (old = 'poweroff', new = 'running')
2018-05-22 13:28:03 (1521): Detected: Web Application Enabled (http://localhost:58561)
2018-05-22 13:28:03 (1521): Detected: Remote Desktop Enabled (localhost:45661)
2018-05-22 13:28:03 (1521): Status Report: Elapsed Time: '273256.226799'
2018-05-22 13:28:03 (1521): Status Report: CPU Time: '435329.440000'
2018-05-22 13:28:03 (1521): Preference change detected
2018-05-22 13:28:03 (1521): Setting CPU throttle for VM. (75%)
2018-05-22 13:28:03 (1521): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2018-05-22 13:28:05 (1521): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2018-05-22 13:28:05 (1521): Guest Log: BIOS: Booting from Hard Disk...
2018-05-22 13:28:07 (1521): Guest Log: BIOS: KBD: unsupported int 16h function 03
2018-05-22 13:28:07 (1521): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2018-05-22 13:31:39 (1521): VM state change detected. (old = 'running', new = 'paused')
2018-05-22 13:31:49 (1521): VM state change detected. (old = 'paused', new = 'running')
2018-05-22 13:35:42 (1521): VM state change detected. (old = 'running', new = 'paused')
2018-05-22 13:35:43 (1521): VM state change detected. (old = 'paused', new = 'running')
2018-05-22 15:08:56 (1521): Status Report: Elapsed Time: '279257.040379'
2018-05-22 15:08:56 (1521): Status Report: CPU Time: '435402.090000'
2018-05-22 16:47:56 (1521): Status Report: Elapsed Time: '285257.040379'
2018-05-22 16:47:56 (1521): Status Report: CPU Time: '435468.420000'
2018-05-22 18:26:57 (1521): Status Report: Elapsed Time: '291257.040379'
2018-05-22 18:26:57 (1521): Status Report: CPU Time: '435533.660000'
2018-05-22 20:05:57 (1521): Status Report: Elapsed Time: '297257.040379'
2018-05-22 20:05:57 (1521): Status Report: CPU Time: '435600.740000'
2018-05-22 21:44:58 (1521): Status Report: Elapsed Time: '303257.040379'
2018-05-22 21:44:58 (1521): Status Report: CPU Time: '435665.180000'
ID: 35344 · Report as offensive     Reply Quote
computezrmle
Volunteer moderator
Volunteer developer
Volunteer tester
Avatar

Send message
Joined: 15 Jun 08
Posts: 1965
Credit: 139,431,664
RAC: 86,177
Message 35345 - Posted: 22 May 2018, 20:45:35 UTC - in response to Message 35344.  

There are lots of lines like this in your log:
2018-05-17 14:00:47 (1554): VM state change detected. (old = 'running', new = 'paused')
2018-05-17 14:00:57 (1554): VM state change detected. (old = 'paused', new = 'running')

The timespan between "paused" and "running" is very short.
This points out a possible resource problem.

Beside that you try to reduce the load by throttling your CPU:
2018-05-15 21:45:30 (15483): Setting CPU throttle for VM. (75%)


It would be better to set your CPU usage to 100% (BOINC manager) and run ATLAS tasks with not more than 4 (better 2 or even 1) cores.
ID: 35345 · Report as offensive     Reply Quote
S@NL Mark

Send message
Joined: 13 Jul 05
Posts: 6
Credit: 191,565
RAC: 0
Message 35346 - Posted: 22 May 2018, 21:55:33 UTC - in response to Message 35345.  

OK Thanks for looking ! Regards Mark
ID: 35346 · Report as offensive     Reply Quote

Message boards : ATLAS application : atlas task


©2022 CERN