Name Theory_2390-1105102-196_0
Workunit 2116493
Created 4 Aug 2021, 4:20:11 UTC
Sent 6 Aug 2021, 6:25:08 UTC
Report deadline 16 Aug 2021, 6:25:08 UTC
Received 9 Aug 2021, 22:16:39 UTC
Server state Over
Outcome Computation error
Client state Aborted by user
Exit status 203 (0x000000CB) EXIT_ABORTED_VIA_GUI
Computer ID 4463
Run time 51 min 15 sec
CPU time 51 min 15 sec
Validate state Invalid
Credit 0.00
Device peak FLOPS 4.82 GFLOPS
Application version Theory Simulation v5.21 (vbox64_theory)
x86_64-pc-linux-gnu
Peak working set size 701.72 MB
Peak swap size 2.52 GB
Peak disk usage 2.15 GB

Stderr output

<core_client_version>7.16.6</core_client_version>
<![CDATA[
<message>
aborted by user</message>
<stderr_txt>
2021-08-06 02:25:21 (87786): vboxwrapper (7.7.26196): starting
2021-08-06 02:25:21 (87786): Feature: Checkpoint interval offset (569 seconds)
2021-08-06 02:25:21 (87786): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2021-08-06 02:25:21 (87786): VBOX_JOB::parse(): unexpected text enable_screenshots_on_error/
2021-08-06 02:25:21 (87786): Detected: Minimum checkpoint interval (600.000000 seconds)
2021-08-06 02:25:21 (87786): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-08-06 02:25:21 (87786): Successfully copied 'init_data.xml' to the shared directory.
2021-08-06 02:25:21 (87786): Successfully copied 'input' to the shared directory.
2021-08-06 02:25:21 (87786): Create VM. (boinc_68fd8eb4176e3098, slot#5)
2021-08-06 02:25:22 (87786): Setting Memory Size for VM. (630MB)
2021-08-06 02:25:22 (87786): Setting CPU Count for VM. (1)
2021-08-06 02:25:22 (87786): Setting Chipset Options for VM.
2021-08-06 02:25:22 (87786): Setting Boot Options for VM.
2021-08-06 02:25:22 (87786): Setting Network Configuration for NAT.
2021-08-06 02:25:22 (87786): Enabling VM Network Access.
2021-08-06 02:25:22 (87786): Disabling USB Support for VM.
2021-08-06 02:25:22 (87786): Disabling COM Port Support for VM.
2021-08-06 02:25:22 (87786): Disabling LPT Port Support for VM.
2021-08-06 02:25:23 (87786): Disabling Audio Support for VM.
2021-08-06 02:25:23 (87786): Disabling Clipboard Support for VM.
2021-08-06 02:25:23 (87786): Disabling Drag and Drop Support for VM.
2021-08-06 02:25:23 (87786): Adding storage controller(s) to VM.
2021-08-06 02:25:23 (87786): Adding virtual disk drive to VM. (vm_image.vdi)
2021-08-06 02:25:23 (87786): Adding VirtualBox Guest Additions to VM.
2021-08-06 02:25:23 (87786): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2021-08-06 02:25:23 (87786): forwarding host port 55925 to guest port 80
2021-08-06 02:25:23 (87786): Enabling remote desktop for VM.
2021-08-06 02:25:23 (87786): Enabling shared directory for VM.
2021-08-06 02:25:24 (87786): Starting VM. (boinc_68fd8eb4176e3098, slot#5)
2021-08-06 02:25:25 (87786): Successfully started VM. (PID = '89840')
2021-08-06 02:25:25 (87786): Reporting VM Process ID to BOINC.
2021-08-06 02:25:25 (87786): Guest Log: BIOS: VirtualBox 6.1.26
2021-08-06 02:25:25 (87786): Guest Log: CPUID EDX: 0x178bfbff
2021-08-06 02:25:25 (87786): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2021-08-06 02:25:25 (87786): VM state change detected. (old = 'poweroff', new = 'running')
2021-08-06 02:25:25 (87786): Detected: Web Application Enabled (http://localhost:55925)
2021-08-06 02:25:25 (87786): Detected: Remote Desktop Enabled (localhost:56137)
2021-08-06 02:25:25 (87786): Preference change detected
2021-08-06 02:25:25 (87786): Setting CPU throttle for VM. (100%)
2021-08-06 02:25:25 (87786): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds))
2021-08-06 02:25:27 (87786): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2021-08-06 02:25:27 (87786): Guest Log: BIOS: Booting from Hard Disk...
2021-08-06 02:25:29 (87786): Guest Log: BIOS: KBD: unsupported int 16h function 03
2021-08-06 02:25:29 (87786): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2021-08-06 02:25:48 (87786): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2021-08-06 02:25:48 (87786): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2021-08-06 02:25:51 (87786): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2021-08-06 02:25:51 (87786): Guest Log: 00:00:00.000143 main     Log opened 2021-08-06T06:25:50.605770000Z
2021-08-06 02:25:51 (87786): Guest Log: 00:00:00.000233 main     OS Product: Linux
2021-08-06 02:25:51 (87786): Guest Log: 00:00:00.000255 main     OS Release: 4.14.76-13.cernvm.x86_64
2021-08-06 02:25:51 (87786): Guest Log: 00:00:00.000274 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2021-08-06 02:25:51 (87786): Guest Log: 00:00:00.000292 main     Executable: /usr/sbin/VBoxService
2021-08-06 02:25:51 (87786): Guest Log: 00:00:00.000293 main     Process ID: 3194
2021-08-06 02:25:51 (87786): Guest Log: 00:00:00.000294 main     Package type: LINUX_64BITS_GENERIC
2021-08-06 02:25:51 (87786): Guest Log: 00:00:00.002812 main     5.2.6 r120293 started. Verbose level = 0
2021-08-06 02:26:13 (87786): Guest Log: [INFO] Detected local proxy http://192.168.1.100:3128 in init_data.xml
2021-08-06 02:26:13 (87786): Guest Log: [INFO] Testing connection to 192.168.1.100 on port 3128
2021-08-06 02:26:14 (87786): Guest Log: [INFO] Ncat: Version 7.50 ( https://nmap.org/ncat )
2021-08-06 02:26:14 (87786): Guest Log: Ncat: Connected to 192.168.1.100:3128.
2021-08-06 02:26:14 (87786): Guest Log: Ncat: 0 bytes sent, 0 bytes received in 0.02 seconds.
2021-08-06 02:26:14 (87786): Guest Log: [INFO] 0
2021-08-06 02:26:17 (87786): Guest Log: 02:26:16 EDT -04:00 2021-08-06: cranky: [INFO] Detected Theory App
2021-08-06 02:26:17 (87786): Guest Log: 02:26:16 EDT -04:00 2021-08-06: cranky: [INFO] Checking CVMFS.
2021-08-06 02:26:17 (87786): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2021-08-06 02:26:18 (87786): 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-08-06 02:26:18 (87786): Guest Log: 2.5.2.0 4093 0 28104 20727 3 1 266149 4096000 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/sft.cern.ch http://192.168.1.100:3128 1
2021-08-06 02:26:18 (87786): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2021-08-06 02:26:19 (87786): 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-08-06 02:26:19 (87786): Guest Log: 2.5.2.0 4021 0 28444 15895 3 1 266149 4096000 0 65024 0 2 0 2138 6744 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch http://192.168.1.100:3128 1
2021-08-06 02:26:19 (87786): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2021-08-06 02:26:20 (87786): 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-08-06 02:26:20 (87786): Guest Log: 2.5.2.0 4043 0 26096 267 3 1 266149 4096000 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch http://192.168.1.100:3128 1
2021-08-06 02:26:20 (87786): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2021-08-06 02:26:21 (87786): VM state change detected. (old = 'running', new = 'paused')
2021-08-09 17:41:31 (87786): VM state change detected. (old = 'paused', new = 'running')
2021-08-09 17:41:31 (87786): 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-08-09 17:41:31 (87786): Guest Log: 2.5.2.0 4065 0 39296 9926 3 1 266149 4096000 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/alice.cern.ch http://192.168.1.100:3128 1
2021-08-09 17:41:31 (87786): Guest Log: 02:26:20 EDT -04:00 2021-08-06: cranky: [INFO] Checking runc.
2021-08-09 17:41:31 (87786): Guest Log: 00:00:30.016747 timesync vgsvcTimeSyncWorker: Radical host time change: 314 120 740 000 000ns (HostNow=1 628 545 291 420 000 000 ns HostLast=1 628 231 170 680 000 000 ns)
2021-08-09 17:41:31 (87786): Guest Log: 17:41:31 EDT -04:00 2021-08-09: cranky: [INFO] Creating the filesystem.
2021-08-09 17:41:31 (87786): Guest Log: 17:41:31 EDT -04:00 2021-08-09: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2021-08-09 17:41:31 (87786): Guest Log: 17:41:31 EDT -04:00 2021-08-09: cranky: [INFO] Updating config.json.
2021-08-09 17:41:31 (87786): Guest Log: 17:41:31 EDT -04:00 2021-08-09: cranky: [INFO] Running Container 'runc'.
2021-08-09 17:41:35 (87786): Guest Log: job: htmld=/shared/html/job
2021-08-09 17:41:35 (87786): Guest Log: job: unpack exitcode=0
2021-08-09 17:41:35 (87786): Guest Log: 17:41:35 EDT -04:00 2021-08-09: cranky: [INFO] ===> [runRivet] Mon Aug  9 21:41:35 UTC 2021 [boinc pp jets 13000 180,-,3560 - pythia8 8.240 default-MBR 100000 196]
2021-08-09 17:41:41 (87786): Guest Log: 00:00:40.017141 timesync vgsvcTimeSyncWorker: Radical guest time change: 314 120 801 167 000ns (GuestNow=1 628 545 301 421 920 000 ns GuestLast=1 628 231 180 620 753 000 ns fSetTimeLastLoop=true )
2021-08-09 18:00:10 (87786): VM state change detected. (old = 'running', new = 'paused')
2021-08-09 18:16:23 (87786): Capturing screenshot.
2021-08-09 18:16:24 (87786): Screenshot completed.
2021-08-09 18:16:24 (87786): Powering off VM.
2021-08-09 18:16:24 (87786): Successfully stopped VM.
2021-08-09 18:16:24 (87786): Deregistering VM. (boinc_68fd8eb4176e3098, slot#5)
2021-08-09 18:16:24 (87786): Removing network bandwidth throttle group from VM.
2021-08-09 18:16:24 (87786): Removing storage controller(s) from VM.
2021-08-09 18:16:25 (87786): Removing VM from VirtualBox.
2021-08-09 18:16:25 (87786): Removing virtual disk drive from VirtualBox.
2021-08-09 18:16:30 (87786): Failed to open screenshot image file. ()

    Hypervisor System Log:


    VM Execution Log:


    VM Startup Log:


    VM Trace Log:

Processor#41 speed: 3000 MHz
Processor#41 description: AMD Ryzen Threadripper 2970WX 24-Core Processor
Processor#42 speed: 3000 MHz
Processor#42 description: AMD Ryzen Threadripper 2970WX 24-Core Processor
Processor#43 speed: 3000 MHz
Processor#43 description: AMD Ryzen Threadripper 2970WX 24-Core Processor
Processor#44 speed: 3000 MHz
Processor#44 description: AMD Ryzen Threadripper 2970WX 24-Core Processor
Processor#45 speed: 3000 MHz
Processor#45 description: AMD Ryzen Threadripper 2970WX 24-Core Processor
Processor#46 speed: 3000 MHz
Processor#46 description: AMD Ryzen Threadripper 2970WX 24-Core Processor
Processor#47 speed: 3000 MHz
Processor#47 description: AMD Ryzen Threadripper 2970WX 24-Core Processor
Memory size: 64327 MByte
Memory available: 62582 MByte
Operating system: Linux
Operating system version: 5.11.0-26-generic

2021-08-06 02:25:21 (87786): 
Command: VBoxManage -q showvminfo "boinc_68fd8eb4176e3098" --machinereadable 
Exit Code: -2135228415
Output:
VBoxManage: error: Could not find a registered machine named 'boinc_68fd8eb4176e3098'
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 2726 of file VBoxManageInfo.cpp

2021-08-06 02:25:21 (87786): 
Command: VBoxManage -q showhdinfo "/var/lib/boinc-client/slots/5/vm_image.vdi" 
Exit Code: -2147024809
Output:
VBoxManage: error: Cannot register the hard disk '/var/lib/boinc-client/slots/5/vm_image.vdi' {c7cbbeeb-c984-467e-9b6e-0d2e670bed58} because a hard disk '/var/lib/boinc-client/slots/6/vm_image.vdi' with UUID {c7cbbeeb-c984-467e-9b6e-0d2e670bed58} already exists
VBoxManage: error: Details: code NS_ERROR_INVALID_ARG (0x80070057), component VirtualBoxWrap, interface IVirtualBox, callee nsISupports
VBoxManage: error: Context: "OpenMedium(Bstr(pszFilenameOrUuid).raw(), enmDevType, enmAccessMode, fForceNewUuidOnOpen, pMedium.asOutParam())" at line 187 of file VBoxManageDisk.cpp

2021-08-06 02:25:22 (87786): 
Command: VBoxManage -q createvm --name "boinc_68fd8eb4176e3098" --basefolder "/var/lib/boinc-client/slots/5" --ostype "Linux26_64" --register
Exit Code: 0
Output:
Virtual machine 'boinc_68fd8eb4176e3098' is created and registered.
UUID: a8cc158f-acf2-40ed-b158-5aaa8bea3861
Settings file: '/var/lib/boinc-client/slots/5/boinc_68fd8eb4176e3098/boinc_68fd8eb4176e3098.vbox'

2021-08-06 02:25:22 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --description "Theory_2390-1105102-196_0" 
Exit Code: 0
Output:

2021-08-06 02:25:22 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --memory 630 
Exit Code: 0
Output:

2021-08-06 02:25:22 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --cpus 1 
Exit Code: 0
Output:

2021-08-06 02:25:22 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --acpi on --ioapic on 
Exit Code: 0
Output:

2021-08-06 02:25:22 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --boot1 disk --boot2 dvd --boot3 none --boot4 none 
Exit Code: 0
Output:

2021-08-06 02:25:22 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --nic1 nat --natdnsproxy1 on --cableconnected1 off 
Exit Code: 0
Output:

2021-08-06 02:25:22 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --cableconnected1 on 
Exit Code: 0
Output:

2021-08-06 02:25:22 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --usb off 
Exit Code: 0
Output:

2021-08-06 02:25:22 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --uart1 off --uart2 off 
Exit Code: 0
Output:

2021-08-06 02:25:23 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --lpt1 off --lpt2 off 
Exit Code: 0
Output:

2021-08-06 02:25:23 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --audio none 
Exit Code: 0
Output:

2021-08-06 02:25:23 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --clipboard disabled 
Exit Code: 0
Output:

2021-08-06 02:25:23 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --draganddrop disabled 
Exit Code: 0
Output:

2021-08-06 02:25:23 (87786): 
Command: VBoxManage -q storagectl "boinc_68fd8eb4176e3098" --name "Hard Disk Controller" --add "ide" --controller "PIIX4" 
Exit Code: 0
Output:

2021-08-06 02:25:23 (87786): 
Command: VBoxManage -q storageattach "boinc_68fd8eb4176e3098" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --setuuid "" --medium "/var/lib/boinc-client/slots/5/vm_image.vdi" 
Exit Code: 0
Output:

2021-08-06 02:25:23 (87786): 
Command: VBoxManage -q storageattach "boinc_68fd8eb4176e3098" --storagectl "Hard Disk Controller" --port 1 --device 0 --type dvddrive --medium "/usr/share/virtualbox/VBoxGuestAdditions.iso" 
Exit Code: 0
Output:

2021-08-06 02:25:23 (87786): 
Command: VBoxManage -q bandwidthctl "boinc_68fd8eb4176e3098" add "boinc_68fd8eb4176e3098_net" --type network --limit 1024G 
Exit Code: 0
Output:

2021-08-06 02:25:23 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --natpf1 ",tcp,127.0.0.1,55925,,80" 
Exit Code: 0
Output:

2021-08-06 02:25:23 (87786): 
Command: VBoxManage -q list extpacks
Exit Code: 0
Output:
Extension Packs: 1
Pack no. 0:   Oracle VM VirtualBox Extension Pack
Version:      6.1.18
Revision:     142142
Edition:      
Description:  Oracle Cloud Infrastructure integration, USB 2.0 and USB 3.0 Host Controller, Host Webcam, VirtualBox RDP, PXE ROM, Disk Encryption, NVMe.
VRDE Module:  VBoxVRDP
Usable:       true 
Why unusable: 

2021-08-06 02:25:23 (87786): 
Command: VBoxManage -q modifyvm "boinc_68fd8eb4176e3098" --vrde on --vrdeextpack default --vrdeauthlibrary default --vrdeauthtype null --vrdeport 56137 
Exit Code: 0
Output:

2021-08-06 02:25:23 (87786): 
Command: VBoxManage -q sharedfolder add "boinc_68fd8eb4176e3098" --name "shared" --hostpath "/var/lib/boinc-client/slots/5/shared"
Exit Code: 0
Output:

2021-08-06 02:25:24 (87786): 
Command: VBoxManage -q startvm "boinc_68fd8eb4176e3098" --type headless
Exit Code: 0
Output:
Waiting for VM "boinc_68fd8eb4176e3098" to power on...
VM "boinc_68fd8eb4176e3098" has been successfully started.

2021-08-06 02:25:25 (87786): 
Command: VBoxManage -q controlvm "boinc_68fd8eb4176e3098" cpuexecutioncap 100 
Exit Code: 0
Output:

2021-08-06 02:26:20 (87786): 
Command: VBoxManage -q controlvm "boinc_68fd8eb4176e3098" pause
Exit Code: 0
Output:

2021-08-09 17:41:31 (87786): 
Command: VBoxManage -q controlvm "boinc_68fd8eb4176e3098" resume
Exit Code: 0
Output:

2021-08-09 18:00:10 (87786): 
Command: VBoxManage -q controlvm "boinc_68fd8eb4176e3098" pause
Exit Code: 0
Output:

2021-08-09 18:16:23 (87786): 
Command: VBoxManage -q controlvm "boinc_68fd8eb4176e3098" keyboardputscancode 0x39
Exit Code: 0
Output:
VBoxManage: error: Error: '0x39' is not a hex byte!

2021-08-09 18:16:24 (87786): 
Command: VBoxManage -q controlvm "boinc_68fd8eb4176e3098" screenshotpng "/var/lib/boinc-client/slots/5/vbox_screenshot.png"
Exit Code: 0
Output:

2021-08-09 18:16:24 (87786): 
Command: VBoxManage -q controlvm "boinc_68fd8eb4176e3098" poweroff
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

2021-08-09 18:16:24 (87786): 
Command: VBoxManage -q snapshot "boinc_68fd8eb4176e3098" list 
Exit Code: 0
Output:
This machine does not have any snapshots

2021-08-09 18:16:24 (87786): 
Command: VBoxManage -q bandwidthctl "boinc_68fd8eb4176e3098" remove "boinc_68fd8eb4176e3098_net" 
Exit Code: 0
Output:

2021-08-09 18:16:25 (87786): 
Command: VBoxManage -q storagectl "boinc_68fd8eb4176e3098" --name "Hard Disk Controller" --remove 
Exit Code: 0
Output:

2021-08-09 18:16:25 (87786): 
Command: VBoxManage -q unregistervm "boinc_68fd8eb4176e3098" --delete 
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

2021-08-09 18:16:25 (87786): 
Command: VBoxManage -q closemedium disk "/var/lib/boinc-client/slots/5/vm_image.vdi" --delete 
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

18:16:30 (87786): called boinc_finish(194)

</stderr_txt>
]]>


©2024 CERN