Message boards :
ATLAS application :
atlas task
Message board moderation
Author | Message |
---|---|
Send message Joined: 13 Jul 05 Posts: 6 Credit: 191,565 RAC: 0 ![]() ![]() |
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 |
![]() Send message Joined: 15 Jun 08 Posts: 2471 Credit: 243,690,643 RAC: 161,677 ![]() ![]() ![]() |
The stderr.txt from your slots folder (slots/0 in this case) may contain useful information. Try to post it here. |
Send message Joined: 13 Jul 05 Posts: 6 Credit: 191,565 RAC: 0 ![]() ![]() |
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' |
![]() Send message Joined: 15 Jun 08 Posts: 2471 Credit: 243,690,643 RAC: 161,677 ![]() ![]() ![]() |
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. |
Send message Joined: 13 Jul 05 Posts: 6 Credit: 191,565 RAC: 0 ![]() ![]() |
OK Thanks for looking ! Regards Mark |
©2024 CERN