Name CMS_1923275_1610116876.877549_0
Workunit 2051094
Created 8 Jan 2021, 14:41:18 UTC
Sent 9 Jan 2021, 14:20:20 UTC
Report deadline 16 Jan 2021, 14:20:20 UTC
Received 10 Jan 2021, 16:06:48 UTC
Server state Over
Outcome Computation error
Client state Compute error
Exit status 1 (0x00000001) Unknown error code
Computer ID 4223
Run time 14 hours 56 min 58 sec
CPU time 13 hours 43 min 47 sec
Validate state Invalid
Credit 0.00
Device peak FLOPS 2.69 GFLOPS
Application version CMS Simulation v50.00 (vbox64_mt_mcore_cms)
windows_x86_64
Peak working set size 74.48 MB
Peak swap size 81.52 MB
Peak disk usage 3.13 GB

Stderr output

<core_client_version>7.16.7</core_client_version>
<![CDATA[
<message>
Incorrect function.
 (0x1) - exit code 1 (0x1)</message>
<stderr_txt>
2021-01-09 09:20:46 (6084): Detected: vboxwrapper 26197
2021-01-09 09:20:46 (6084): Detected: BOINC client v7.7
2021-01-09 09:20:47 (6084): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2021-01-09 09:20:47 (6084): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-01-09 09:20:47 (6084): Successfully copied 'init_data.xml' to the shared directory.
2021-01-09 09:20:49 (6084): Create VM. (boinc_f5dcb26091eedd3d, slot#2)
2021-01-09 09:20:50 (6084): Setting Memory Size for VM. (1896MB)
2021-01-09 09:20:50 (6084): Setting CPU Count for VM. (1)
2021-01-09 09:20:50 (6084): Setting Chipset Options for VM.
2021-01-09 09:20:50 (6084): Setting Boot Options for VM.
2021-01-09 09:20:51 (6084): Setting Network Configuration for NAT.
2021-01-09 09:20:51 (6084): Enabling VM Network Access.
2021-01-09 09:20:51 (6084): Disabling USB Support for VM.
2021-01-09 09:20:52 (6084): Disabling COM Port Support for VM.
2021-01-09 09:20:52 (6084): Disabling LPT Port Support for VM.
2021-01-09 09:20:52 (6084): Disabling Audio Support for VM.
2021-01-09 09:20:52 (6084): Disabling Clipboard Support for VM.
2021-01-09 09:20:53 (6084): Disabling Drag and Drop Support for VM.
2021-01-09 09:20:53 (6084): Adding storage controller(s) to VM.
2021-01-09 09:20:53 (6084): Adding virtual disk drive to VM. (vm_image.vdi)
2021-01-09 09:20:54 (6084): Adding VirtualBox Guest Additions to VM.
2021-01-09 09:20:54 (6084): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2021-01-09 09:20:54 (6084): forwarding host port 52590 to guest port 80
2021-01-09 09:20:54 (6084): Enabling remote desktop for VM.
2021-01-09 09:20:55 (6084): Required extension pack not installed, remote desktop not enabled.
2021-01-09 09:20:55 (6084): Enabling shared directory for VM.
2021-01-09 09:20:55 (6084): Starting VM using VBoxManage interface. (boinc_f5dcb26091eedd3d, slot#2)
2021-01-09 09:21:00 (6084): Successfully started VM. (PID = '3244')
2021-01-09 09:21:00 (6084): Reporting VM Process ID to BOINC.
2021-01-09 09:21:00 (6084): Guest Log: BIOS: VirtualBox 6.0.14

2021-01-09 09:21:00 (6084): Guest Log: CPUID EDX: 0x178bfbff

2021-01-09 09:21:00 (6084): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-01-09 09:21:00 (6084): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-01-09 09:21:00 (6084): Detected: Web Application Enabled (http://localhost:52590)
2021-01-09 09:21:00 (6084): Preference change detected
2021-01-09 09:21:00 (6084): Setting CPU throttle for VM. (100%)
2021-01-09 09:21:00 (6084): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 900 seconds) or (Vbox_job.xml: 600 seconds))
2021-01-09 09:21:02 (6084): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-01-09 09:21:02 (6084): Guest Log: BIOS: Booting from Hard Disk...

2021-01-09 09:21:09 (6084): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-01-09 09:21:09 (6084): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-01-09 09:21:32 (6084): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2021-01-09 09:21:32 (6084): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2021-01-09 09:22:44 (6084): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2021-01-09 09:22:45 (6084): Guest Log: 00:00:00.001177 main     Log opened 2021-01-09T14:22:36.910112000Z

2021-01-09 09:22:45 (6084): Guest Log: 00:00:00.002922 main     OS Product: Linux

2021-01-09 09:22:45 (6084): Guest Log: 00:00:00.003081 main     OS Release: 4.14.157-17.cernvm.x86_64

2021-01-09 09:22:45 (6084): Guest Log: 00:00:00.003157 main     OS Version: #1 SMP Wed Dec 4 17:26:45 CET 2019

2021-01-09 09:22:45 (6084): Guest Log: 00:00:00.003221 main     Executable: /usr/share/vboxguest52/usr/sbin/VBoxService

2021-01-09 09:22:45 (6084): Guest Log: 00:00:00.003228 main     Process ID: 2976

2021-01-09 09:22:45 (6084): Guest Log: 00:00:00.003236 main     Package type: LINUX_64BITS_GENERIC

2021-01-09 09:22:45 (6084): Guest Log: 00:00:00.006498 main     5.2.6 r120293 started. Verbose level = 0

2021-01-09 09:23:29 (6084): Guest Log: [INFO] Mounting the shared directory

2021-01-09 09:23:29 (6084): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2021-01-09 09:23:29 (6084): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80

2021-01-09 09:23:30 (6084): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!

2021-01-09 09:23:30 (6084): Guest Log: [DEBUG] 0

2021-01-09 09:23:30 (6084): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443

2021-01-09 09:23:32 (6084): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!

2021-01-09 09:23:32 (6084): Guest Log: [DEBUG] 0

2021-01-09 09:23:32 (6084): Guest Log: [DEBUG] Testing connection to Condor server on port 9618

2021-01-09 09:23:32 (6084): Guest Log: [DEBUG] Connection to vocms0840.cern.ch 9618 port [tcp/condor] succeeded!

2021-01-09 09:23:32 (6084): Guest Log: [DEBUG] 0

2021-01-09 09:23:32 (6084): Guest Log: [DEBUG] Testing connection to WMAgent server on port 4080

2021-01-09 09:23:33 (6084): Guest Log: [DEBUG] Connection to vocms0267.cern.ch 4080 port [tcp/lorica-in] succeeded!

2021-01-09 09:23:33 (6084): Guest Log: [DEBUG] 0

2021-01-09 09:23:33 (6084): Guest Log: [DEBUG] Testing connection to Frontier server on port 8080

2021-01-09 09:23:33 (6084): Guest Log: [DEBUG] Connection to cms-frontier.openhtc.io 8080 port [tcp/webcache] succeeded!

2021-01-09 09:23:33 (6084): Guest Log: [DEBUG] 0

2021-01-09 09:24:48 (6084): Guest Log: [DEBUG] Probing CVMFS ...

2021-01-09 09:24:48 (6084): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2021-01-09 09:24:49 (6084): 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-01-09 09:24:49 (6084): Guest Log: 2.4.4.0 3759 1 25864 14149 3 1 1242489 4096001 2 65024 0 3 100 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch http://131.225.188.245:3126 1

2021-01-09 09:25:07 (6084): Guest Log: [INFO] Reading volunteer information

2021-01-09 09:25:07 (6084): Guest Log: [INFO] Volunteer: mikey (419)

2021-01-09 09:25:07 (6084): Guest Log: [INFO] VMID: 49b2fac1-df25-48d2-a4ee-4612ca6a31f8

2021-01-09 09:25:09 (6084): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2021-01-09 09:25:10 (6084): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev

2021-01-09 09:25:14 (6084): Guest Log: [INFO] Running the fast benchmark.

2021-01-09 09:26:44 (6084): Guest Log: [INFO] Machine performance 6.02 HEPSPEC06

2021-01-09 09:26:44 (6084): Guest Log: [INFO] CMS application starting. Check log files.

2021-01-09 09:26:55 (6084): Guest Log: [DEBUG] HTCondor ping

2021-01-09 09:26:55 (6084): Guest Log: [DEBUG] 0

2021-01-09 11:11:48 (6084): Status Report: Job Duration: '64800.000000'
2021-01-09 11:11:53 (6084): Status Report: Elapsed Time: '6000.704414'
2021-01-09 11:11:53 (6084): Status Report: CPU Time: '5995.430432'
2021-01-09 12:56:22 (6084): Status Report: Job Duration: '64800.000000'
2021-01-09 12:56:22 (6084): Status Report: Elapsed Time: '12001.097208'
2021-01-09 12:56:22 (6084): Status Report: CPU Time: '12202.866223'
2021-01-09 14:40:55 (6084): Status Report: Job Duration: '64800.000000'
2021-01-09 14:40:55 (6084): Status Report: Elapsed Time: '18001.627306'
2021-01-09 14:40:55 (6084): Status Report: CPU Time: '17058.912151'
2021-01-09 16:25:35 (6084): Status Report: Job Duration: '64800.000000'
2021-01-09 16:25:35 (6084): Status Report: Elapsed Time: '24004.069708'
2021-01-09 16:25:35 (6084): Status Report: CPU Time: '23259.093896'
2021-01-09 18:10:58 (6084): Status Report: Job Duration: '64800.000000'
2021-01-09 18:10:58 (6084): Status Report: Elapsed Time: '30004.527029'
2021-01-09 18:10:58 (6084): Status Report: CPU Time: '29515.030398'
2021-01-09 19:56:19 (6084): Status Report: Job Duration: '64800.000000'
2021-01-09 19:56:19 (6084): Status Report: Elapsed Time: '36004.683889'
2021-01-09 19:56:19 (6084): Status Report: CPU Time: '34525.829318'
2021-01-09 21:41:53 (6084): Status Report: Job Duration: '64800.000000'
2021-01-09 21:41:53 (6084): Status Report: Elapsed Time: '42004.698650'
2021-01-09 21:41:53 (6084): Status Report: CPU Time: '40806.382778'
2021-01-09 23:26:25 (6084): Status Report: Job Duration: '64800.000000'
2021-01-09 23:26:25 (6084): Status Report: Elapsed Time: '48005.084017'
2021-01-09 23:26:25 (6084): Status Report: CPU Time: '47032.398288'
2021-01-10 09:01:09 (2276): Detected: vboxwrapper 26197
2021-01-10 09:01:09 (2276): Detected: BOINC client v7.7
2021-01-10 09:01:32 (2276): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2021-01-10 09:01:34 (2276): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-01-10 09:01:34 (2276): Starting VM using VBoxManage interface. (boinc_f5dcb26091eedd3d, slot#2)
2021-01-10 09:01:42 (2276): Successfully started VM. (PID = '5208')
2021-01-10 09:01:42 (2276): Reporting VM Process ID to BOINC.
2021-01-10 09:01:42 (2276): Guest Log: BIOS: VirtualBox 6.0.14

2021-01-10 09:01:42 (2276): Guest Log: CPUID EDX: 0x178bfbff

2021-01-10 09:01:42 (2276): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-01-10 09:01:42 (2276): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-01-10 09:01:42 (2276): Guest Log: BIOS: Booting from Hard Disk...

2021-01-10 09:01:42 (2276): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-01-10 09:01:42 (2276): Detected: Web Application Enabled (http://localhost:52590)
2021-01-10 09:01:42 (2276): Status Report: Job Duration: '64800.000000'
2021-01-10 09:01:42 (2276): Status Report: Elapsed Time: '49142.084017'
2021-01-10 09:01:42 (2276): Status Report: CPU Time: '47946.236546'
2021-01-10 09:01:42 (2276): Preference change detected
2021-01-10 09:01:42 (2276): Setting CPU throttle for VM. (100%)
2021-01-10 09:01:43 (2276): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 900 seconds) or (Vbox_job.xml: 600 seconds))
2021-01-10 09:01:49 (2276): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-01-10 09:01:49 (2276): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-01-10 09:02:12 (2276): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2021-01-10 09:02:12 (2276): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2021-01-10 09:03:28 (2276): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2021-01-10 09:03:28 (2276): Guest Log: 00:00:00.001281 main     Log opened 2021-01-10T14:03:35.901176000Z

2021-01-10 09:03:28 (2276): Guest Log: 00:00:00.002015 main     OS Product: Linux

2021-01-10 09:03:28 (2276): Guest Log: 00:00:00.002088 main     OS Release: 4.14.157-17.cernvm.x86_64

2021-01-10 09:03:28 (2276): Guest Log: 00:00:00.002995 main     OS Version: #1 SMP Wed Dec 4 17:26:45 CET 2019

2021-01-10 09:03:28 (2276): Guest Log: 00:00:00.003056 main     Executable: /usr/share/vboxguest52/usr/sbin/VBoxService

2021-01-10 09:03:28 (2276): Guest Log: 00:00:00.003063 main     Process ID: 2919

2021-01-10 09:03:28 (2276): Guest Log: 00:00:00.003070 main     Package type: LINUX_64BITS_GENERIC

2021-01-10 09:03:28 (2276): Guest Log: 00:00:00.005229 main     5.2.6 r120293 started. Verbose level = 0

2021-01-10 09:04:24 (2276): Guest Log: [INFO] Mounting the shared directory

2021-01-10 09:04:24 (2276): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2021-01-10 09:04:24 (2276): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80

2021-01-10 09:04:25 (2276): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!

2021-01-10 09:04:25 (2276): Guest Log: [DEBUG] 0

2021-01-10 09:04:25 (2276): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443

2021-01-10 09:04:26 (2276): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!

2021-01-10 09:04:26 (2276): Guest Log: [DEBUG] 0

2021-01-10 09:04:26 (2276): Guest Log: [DEBUG] Testing connection to Condor server on port 9618

2021-01-10 09:04:26 (2276): Guest Log: [DEBUG] Connection to vocms0840.cern.ch 9618 port [tcp/condor] succeeded!

2021-01-10 09:04:27 (2276): Guest Log: [DEBUG] 0

2021-01-10 09:04:27 (2276): Guest Log: [DEBUG] Testing connection to WMAgent server on port 4080

2021-01-10 09:04:27 (2276): Guest Log: [DEBUG] Connection to vocms0267.cern.ch 4080 port [tcp/lorica-in] succeeded!

2021-01-10 09:04:27 (2276): Guest Log: [DEBUG] 0

2021-01-10 09:04:27 (2276): Guest Log: [DEBUG] Testing connection to Frontier server on port 8080

2021-01-10 09:04:28 (2276): Guest Log: [DEBUG] Connection to cms-frontier.openhtc.io 8080 port [tcp/webcache] succeeded!

2021-01-10 09:04:28 (2276): Guest Log: [DEBUG] 0

2021-01-10 09:05:36 (2276): Guest Log: [DEBUG] Probing CVMFS ...

2021-01-10 09:05:39 (2276): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2021-01-10 09:05:41 (2276): 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-01-10 09:05:41 (2276): Guest Log: 2.4.4.0 3678 1 27372 14157 3 1 1695633 4096000 2 65024 0 3 100 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch http://128.142.33.31:3126 1

2021-01-10 09:05:47 (2276): Guest Log: [INFO] Reading volunteer information

2021-01-10 09:05:48 (2276): Guest Log: [INFO] Volunteer: mikey (419)

2021-01-10 09:05:48 (2276): Guest Log: [INFO] VMID: 49b2fac1-df25-48d2-a4ee-4612ca6a31f8

2021-01-10 09:05:50 (2276): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2021-01-10 09:05:51 (2276): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev

2021-01-10 09:05:53 (2276): Guest Log: [INFO] Running the fast benchmark.

2021-01-10 09:07:22 (2276): Guest Log: [INFO] Machine performance 6.07 HEPSPEC06

2021-01-10 09:07:23 (2276): Guest Log: [INFO] CMS application starting. Check log files.

2021-01-10 09:07:26 (2276): Guest Log: [DEBUG] HTCondor ping

2021-01-10 09:07:28 (2276): Guest Log: [DEBUG] 0

2021-01-10 09:36:59 (2276): Stopping VM.
2021-01-10 09:37:05 (2276): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_f5dcb26091eedd3d" savestate
Output:
0%...10%...20%...30%...40%...
2021-01-10 09:37:05 (2276): VM did not stop when requested.
2021-01-10 09:37:05 (2276): VM was successfully terminated.
2021-01-10 11:05:22 (3060): Detected: vboxwrapper 26197
2021-01-10 11:05:22 (3060): Detected: BOINC client v7.7
2021-01-10 11:05:37 (3060): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2021-01-10 11:05:39 (3060): WARNING: Communication with VM Hypervisor failed. (Possibly Out of Memory).
2021-01-10 11:05:39 (3060): WARNING: Communication with VM Hypervisor failed.
2021-01-10 11:05:39 (3060): ERROR: VBoxManage list hostinfo failed
11:05:39 (3060): called boinc_finish(1)

</stderr_txt>
]]>


©2024 CERN