Name CMS_1231784_1624495622.523019_0
Workunit 2092268
Created 24 Jun 2021, 0:47:04 UTC
Sent 27 Jun 2021, 17:22:24 UTC
Report deadline 4 Jul 2021, 17:22:24 UTC
Received 27 Jun 2021, 18:07:30 UTC
Server state Over
Outcome Computation error
Client state Aborted by user
Exit status 203 (0x000000CB) EXIT_ABORTED_VIA_GUI
Computer ID 3816
Run time 23 min 23 sec
CPU time 23 min 11 sec
Validate state Invalid
Credit 0.00
Device peak FLOPS 22.15 GFLOPS
Application version CMS Simulation v60.20 (vbox64_mt_mcore_cms)
x86_64-pc-linux-gnu
Peak working set size 105.33 MB
Peak swap size 6.19 GB
Peak disk usage 3.36 GB

Stderr output

<core_client_version>7.16.6</core_client_version>
<![CDATA[
<message>
aborted by user</message>
<stderr_txt>
2021-06-27 13:42:58 (3393902): vboxwrapper (7.7.26196): starting
2021-06-27 13:43:00 (3393902): Feature: Checkpoint interval offset (325 seconds)
2021-06-27 13:43:00 (3393902): Detected: VirtualBox VboxManage Interface (Version: 6.1.16)
2021-06-27 13:43:00 (3393902): Detected: Minimum checkpoint interval (600.000000 seconds)
2021-06-27 13:43:00 (3393902): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-06-27 13:43:00 (3393902): Successfully copied 'init_data.xml' to the shared directory.
2021-06-27 13:43:01 (3393902): Create VM. (boinc_2196e2a58a51dda2, slot#3)
2021-06-27 13:43:02 (3393902): Setting Memory Size for VM. (4584MB)
2021-06-27 13:43:02 (3393902): Setting CPU Count for VM. (4)
2021-06-27 13:43:03 (3393902): Setting Chipset Options for VM.
2021-06-27 13:43:03 (3393902): Setting Boot Options for VM.
2021-06-27 13:43:03 (3393902): Setting Network Configuration for NAT.
2021-06-27 13:43:04 (3393902): Enabling VM Network Access.
2021-06-27 13:43:04 (3393902): Disabling USB Support for VM.
2021-06-27 13:43:05 (3393902): Disabling COM Port Support for VM.
2021-06-27 13:43:05 (3393902): Disabling LPT Port Support for VM.
2021-06-27 13:43:05 (3393902): Disabling Audio Support for VM.
2021-06-27 13:43:06 (3393902): Disabling Clipboard Support for VM.
2021-06-27 13:43:06 (3393902): Disabling Drag and Drop Support for VM.
2021-06-27 13:43:06 (3393902): Adding storage controller(s) to VM.
2021-06-27 13:43:06 (3393902): Adding virtual disk drive to VM. (vm_image.vdi)
2021-06-27 13:43:06 (3393902): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2021-06-27 13:43:06 (3393902): forwarding host port 53117 to guest port 80
2021-06-27 13:43:06 (3393902): Enabling remote desktop for VM.
2021-06-27 13:43:06 (3393902): Required extension pack not installed, remote desktop not enabled.
2021-06-27 13:43:06 (3393902): Enabling shared directory for VM.
2021-06-27 13:43:06 (3393902): Starting VM. (boinc_2196e2a58a51dda2, slot#3)
2021-06-27 13:43:07 (3393902): Successfully started VM. (PID = '3394296')
2021-06-27 13:43:07 (3393902): Reporting VM Process ID to BOINC.
2021-06-27 13:43:07 (3393902): Guest Log: BIOS: VirtualBox 6.1.15
2021-06-27 13:43:07 (3393902): Guest Log: CPUID EDX: 0x178bfbff
2021-06-27 13:43:07 (3393902): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2021-06-27 13:43:07 (3393902): VM state change detected. (old = 'poweroff', new = 'running')
2021-06-27 13:43:07 (3393902): Detected: Web Application Enabled (http://localhost:53117)
2021-06-27 13:43:07 (3393902): Preference change detected
2021-06-27 13:43:07 (3393902): Setting CPU throttle for VM. (100%)
2021-06-27 13:43:08 (3393902): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2021-06-27 13:43:09 (3393902): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2021-06-27 13:43:09 (3393902): Guest Log: BIOS: Booting from Hard Disk...
2021-06-27 13:43:12 (3393902): Guest Log: BIOS: KBD: unsupported int 16h function 03
2021-06-27 13:43:12 (3393902): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2021-06-27 13:43:41 (3393902): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2021-06-27 13:43:41 (3393902): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2021-06-27 13:43:45 (3393902): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2021-06-27 13:43:45 (3393902): Guest Log: 00:00:00.000845 main     Log opened 2021-06-27T17:43:43.788669000Z
2021-06-27 13:43:45 (3393902): Guest Log: 00:00:00.000938 main     OS Product: Linux
2021-06-27 13:43:45 (3393902): Guest Log: 00:00:00.000963 main     OS Release: 4.14.232-19.cernvm.x86_64
2021-06-27 13:43:45 (3393902): Guest Log: 00:00:00.000980 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2021-06-27 13:43:45 (3393902): Guest Log: 00:00:00.001005 main     Executable: /usr/sbin/VBoxService
2021-06-27 13:43:45 (3393902): Guest Log: 00:00:00.001006 main     Process ID: 2232
2021-06-27 13:43:45 (3393902): Guest Log: 00:00:00.001007 main     Package type: LINUX_64BITS_GENERIC
2021-06-27 13:43:45 (3393902): Guest Log: 00:00:00.087130 main     5.2.6 r120293 started. Verbose level = 0
2021-06-27 13:43:57 (3393902): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80
2021-06-27 13:43:58 (3393902): Guest Log: [DEBUG] Ncat: Version 7.50 ( https://nmap.org/ncat )
2021-06-27 13:43:58 (3393902): Guest Log: Ncat: Connected to 188.184.9.234:80.
2021-06-27 13:43:58 (3393902): Guest Log: Ncat: 0 bytes sent, 0 bytes received in 0.33 seconds.
2021-06-27 13:43:58 (3393902): Guest Log: [DEBUG] 0
2021-06-27 13:43:58 (3393902): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443
2021-06-27 13:43:59 (3393902): Guest Log: [DEBUG] Ncat: Version 7.50 ( https://nmap.org/ncat )
2021-06-27 13:43:59 (3393902): Guest Log: Ncat: Connected to 137.138.120.99:443.
2021-06-27 13:43:59 (3393902): Guest Log: Ncat: 0 bytes sent, 0 bytes received in 0.33 seconds.
2021-06-27 13:43:59 (3393902): Guest Log: [DEBUG] 0
2021-06-27 13:43:59 (3393902): Guest Log: [DEBUG] Testing connection to Condor server on port 9618
2021-06-27 13:43:59 (3393902): Guest Log: [DEBUG] Ncat: Version 7.50 ( https://nmap.org/ncat )
2021-06-27 13:43:59 (3393902): Guest Log: Ncat: Connected to 137.138.156.85:9618.
2021-06-27 13:43:59 (3393902): Guest Log: Ncat: 0 bytes sent, 0 bytes received in 0.29 seconds.
2021-06-27 13:43:59 (3393902): Guest Log: [DEBUG] 0
2021-06-27 13:43:59 (3393902): Guest Log: [DEBUG] Testing connection to WMAgent server on port 4080
2021-06-27 13:44:00 (3393902): Guest Log: [DEBUG] Ncat: Version 7.50 ( https://nmap.org/ncat )
2021-06-27 13:44:00 (3393902): Guest Log: [DEBUG] 0
2021-06-27 13:44:00 (3393902): Guest Log: [DEBUG] Testing connection to Frontier server on port 8080
2021-06-27 13:44:00 (3393902): Guest Log: [DEBUG] Ncat: Version 7.50 ( https://nmap.org/ncat )
2021-06-27 13:44:00 (3393902): Guest Log: Ncat: Connected to 104.21.15.171:8080.
2021-06-27 13:44:00 (3393902): Guest Log: Ncat: 0 bytes sent, 0 bytes received in 0.07 seconds.
2021-06-27 13:44:00 (3393902): Guest Log: [DEBUG] 0
2021-06-27 13:44:00 (3393902): Guest Log: [INFO] Mounting the shared directory
2021-06-27 13:44:00 (3393902): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2021-06-27 13:44:01 (3393902): Guest Log: [DEBUG] Probing CVMFS ...
2021-06-27 13:44:02 (3393902): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2021-06-27 13:44:03 (3393902): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2021-06-27 13:44:03 (3393902): Guest Log: 2.7.2.0 2950 0 27208 15565 3 1 2120789 10240001 2 65024 0 23 91.3043 4176 7187 http://cvmfs-s1bnl.opensciencegrid.org/cvmfs/grid.cern.ch DIRECT 1
2021-06-27 13:44:06 (3393902): Guest Log: [INFO] Reading volunteer information
2021-06-27 13:44:07 (3393902): Guest Log: [INFO] Volunteer: Sphynx (401)
2021-06-27 13:44:07 (3393902): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2021-06-27 13:44:08 (3393902): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2021-06-27 13:44:09 (3393902): Guest Log: [INFO] CMS application starting. Check log files.
2021-06-27 14:06:24 (3393902): Capturing screenshot.
2021-06-27 14:06:26 (3393902): Screenshot completed.
2021-06-27 14:06:26 (3393902): Powering off VM.
2021-06-27 14:06:29 (3393902): Successfully stopped VM.
2021-06-27 14:06:29 (3393902): Deregistering VM. (boinc_2196e2a58a51dda2, slot#3)
2021-06-27 14:06:29 (3393902): Removing network bandwidth throttle group from VM.
2021-06-27 14:06:30 (3393902): Removing storage controller(s) from VM.
2021-06-27 14:06:31 (3393902): Removing VM from VirtualBox.
2021-06-27 14:06:31 (3393902): Removing virtual disk drive from VirtualBox.
2021-06-27 14:06:37 (3393902): Failed to open screenshot image file. (/boinc-client/projects/lhcathomedev.cern.ch_lhcathome-dev/scratch)

    Hypervisor System Log:


    VM Execution Log:


    VM Startup Log:


    VM Trace Log:

Webservice auth. library:        VBoxAuth
Remote desktop ExtPack:          VNC
Log history count:               3
Default frontend:                
Default audio driver:            Pulse
Autostart database path:         
Default Guest Additions ISO:     
Logging Level:                   all
Proxy Mode:                      System
Proxy URL:                       

2021-06-27 13:43:00 (3393902): 
Command: VBoxManage -q list hostinfo 
Exit Code: 0
Output:
Host Information:

Host time: 2021-06-27T17:43:00.412000000Z
Processor online count: 4
Processor count: 4
Processor online core count: 4
Processor core count: 4
Processor supports HW virtualization: yes
Processor supports PAE: yes
Processor supports long mode: yes
Processor supports nested paging: yes
Processor supports unrestricted guest: yes
Processor supports nested HW virtualization: yes
Processor#0 speed: 3600 MHz
Processor#0 description: Intel(R) Core(TM) i5-4570 CPU @ 3.20GHz
Processor#1 speed: 3600 MHz
Processor#1 description: Intel(R) Core(TM) i5-4570 CPU @ 3.20GHz
Processor#2 speed: 3600 MHz
Processor#2 description: Intel(R) Core(TM) i5-4570 CPU @ 3.20GHz
Processor#3 speed: 3600 MHz
Processor#3 description: Intel(R) Core(TM) i5-4570 CPU @ 3.20GHz
Memory size: 7841 MByte
Memory available: 6820 MByte
Operating system: Linux
Operating system version: 5.4.0-72-generic

2021-06-27 13:43:00 (3393902): 
Command: VBoxManage -q showvminfo "boinc_2196e2a58a51dda2" --machinereadable 
Exit Code: -2135228415
Output:
VBoxManage: error: Could not find a registered machine named 'boinc_2196e2a58a51dda2'
VBoxManage: error: Details: code VBOX_E_OBJECT_NOT_FOUND (0x80bb0001), component VirtualBoxWrap, interface IVirtualBox, callee nsISupports
VBoxManage: error: Context: "FindMachine(Bstr(VMNameOrUuid).raw(), machine.asOutParam())" at line 2714 of file VBoxManageInfo.cpp

2021-06-27 13:43:00 (3393902): 
Command: VBoxManage -q showhdinfo "/var/lib/boinc-client/slots/3/vm_image.vdi" 
Exit Code: 0
Output:
UUID:           40a6e933-90ca-452a-afda-74c6086fdebb
Parent UUID:    base
State:          created
Type:           normal (base)
Location:       /var/lib/boinc-client/slots/3/vm_image.vdi
Storage format: VDI
Format variant: dynamic default
Capacity:       20480 MBytes
Size on disk:   3268 MBytes
Encryption:     disabled
Property:       AllocationBlockSize=1048576

2021-06-27 13:43:00 (3393902): 
Command: VBoxManage -q showhdinfo "/var/lib/boinc-client/slots/3/vm_image.vdi" 
Exit Code: 0
Output:
UUID:           40a6e933-90ca-452a-afda-74c6086fdebb
Parent UUID:    base
State:          created
Type:           normal (base)
Location:       /var/lib/boinc-client/slots/3/vm_image.vdi
Storage format: VDI
Format variant: dynamic default
Capacity:       20480 MBytes
Size on disk:   3268 MBytes
Encryption:     disabled
Property:       AllocationBlockSize=1048576

2021-06-27 13:43:01 (3393902): 
Command: VBoxManage -q closemedium disk "/var/lib/boinc-client/slots/3/vm_image.vdi" 
Exit Code: 0
Output:

2021-06-27 13:43:02 (3393902): 
Command: VBoxManage -q createvm --name "boinc_2196e2a58a51dda2" --basefolder "/var/lib/boinc-client/slots/3" --ostype "Linux26_64" --register
Exit Code: 0
Output:
Virtual machine 'boinc_2196e2a58a51dda2' is created and registered.
UUID: 6109b9bd-01cd-4791-b653-ae7141a86047
Settings file: '/var/lib/boinc-client/slots/3/boinc_2196e2a58a51dda2/boinc_2196e2a58a51dda2.vbox'

2021-06-27 13:43:02 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --description "CMS_1231784_1624495622.523019_0" 
Exit Code: 0
Output:

2021-06-27 13:43:02 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --memory 4584 
Exit Code: 0
Output:

2021-06-27 13:43:03 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --cpus 4 
Exit Code: 0
Output:

2021-06-27 13:43:03 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --acpi on --ioapic on 
Exit Code: 0
Output:

2021-06-27 13:43:03 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --boot1 disk --boot2 dvd --boot3 none --boot4 none 
Exit Code: 0
Output:

2021-06-27 13:43:04 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --nic1 nat --natdnsproxy1 on --cableconnected1 off 
Exit Code: 0
Output:

2021-06-27 13:43:04 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --cableconnected1 on 
Exit Code: 0
Output:

2021-06-27 13:43:05 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --usb off 
Exit Code: 0
Output:

2021-06-27 13:43:05 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --uart1 off --uart2 off 
Exit Code: 0
Output:

2021-06-27 13:43:05 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --lpt1 off --lpt2 off 
Exit Code: 0
Output:

2021-06-27 13:43:06 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --audio none 
Exit Code: 0
Output:

2021-06-27 13:43:06 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --clipboard disabled 
Exit Code: 0
Output:

2021-06-27 13:43:06 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --draganddrop disabled 
Exit Code: 0
Output:

2021-06-27 13:43:06 (3393902): 
Command: VBoxManage -q storagectl "boinc_2196e2a58a51dda2" --name "Hard Disk Controller" --add "ide" --controller "PIIX4" 
Exit Code: 0
Output:

2021-06-27 13:43:06 (3393902): 
Command: VBoxManage -q storageattach "boinc_2196e2a58a51dda2" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --setuuid "" --medium "/var/lib/boinc-client/slots/3/vm_image.vdi" 
Exit Code: 0
Output:

2021-06-27 13:43:06 (3393902): 
Command: VBoxManage -q bandwidthctl "boinc_2196e2a58a51dda2" add "boinc_2196e2a58a51dda2_net" --type network --limit 1024G 
Exit Code: 0
Output:

2021-06-27 13:43:06 (3393902): 
Command: VBoxManage -q modifyvm "boinc_2196e2a58a51dda2" --natpf1 ",tcp,127.0.0.1,53117,,80" 
Exit Code: 0
Output:

2021-06-27 13:43:06 (3393902): 
Command: VBoxManage -q list extpacks
Exit Code: 0
Output:
Extension Packs: 1
Pack no. 0:   VNC
Version:      6.1.16
Revision:     140961
Edition:      
Description:  VNC plugin module
VRDE Module:  VBoxVNC
Usable:       true 
Why unusable: 

2021-06-27 13:43:06 (3393902): 
Command: VBoxManage -q sharedfolder add "boinc_2196e2a58a51dda2" --name "shared" --hostpath "/var/lib/boinc-client/slots/3/shared"
Exit Code: 0
Output:

2021-06-27 13:43:06 (3393902): 
Command: VBoxManage -q startvm "boinc_2196e2a58a51dda2" --type headless
Exit Code: 0
Output:
Waiting for VM "boinc_2196e2a58a51dda2" to power on...
VM "boinc_2196e2a58a51dda2" has been successfully started.

2021-06-27 13:43:08 (3393902): 
Command: VBoxManage -q controlvm "boinc_2196e2a58a51dda2" cpuexecutioncap 100 
Exit Code: 0
Output:

2021-06-27 14:06:24 (3393902): 
Command: VBoxManage -q controlvm "boinc_2196e2a58a51dda2" keyboardputscancode 0x39
Exit Code: 0
Output:
VBoxManage: error: Error: '0x39' is not a hex byte!

2021-06-27 14:06:26 (3393902): 
Command: VBoxManage -q controlvm "boinc_2196e2a58a51dda2" screenshotpng "/var/lib/boinc-client/slots/3/vbox_screenshot.png"
Exit Code: 0
Output:

2021-06-27 14:06:27 (3393902): 
Command: VBoxManage -q controlvm "boinc_2196e2a58a51dda2" poweroff
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

2021-06-27 14:06:29 (3393902): 
Command: VBoxManage -q snapshot "boinc_2196e2a58a51dda2" list 
Exit Code: 0
Output:
This machine does not have any snapshots

2021-06-27 14:06:30 (3393902): 
Command: VBoxManage -q bandwidthctl "boinc_2196e2a58a51dda2" remove "boinc_2196e2a58a51dda2_net" 
Exit Code: 0
Output:

2021-06-27 14:06:31 (3393902): 
Command: VBoxManage -q storagectl "boinc_2196e2a58a51dda2" --name "Hard Disk Controller" --remove 
Exit Code: 0
Output:

2021-06-27 14:06:31 (3393902): 
Command: VBoxManage -q unregistervm "boinc_2196e2a58a51dda2" --delete 
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

2021-06-27 14:06:32 (3393902): 
Command: VBoxManage -q closemedium disk "/var/lib/boinc-client/slots/3/vm_image.vdi" --delete 
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

14:06:37 (3393902): called boinc_finish(194)

</stderr_txt>
]]>


©2024 CERN