Task 2922478

Name	CMS_1163612_1598630294.790430_0
Workunit	2031024
Created	28 Aug 2020, 15:58:19 UTC
Sent	4 Sep 2020, 11:35:42 UTC
Report deadline	11 Sep 2020, 11:35:42 UTC
Received	8 Sep 2020, 16:56:22 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	4170
Run time	18 hours 13 min 25 sec
CPU time	16 hours 53 min 12 sec
Validate state	Valid
Credit	714.16
Device peak FLOPS	3.95 GFLOPS
Application version	CMS Simulation v50.00 (vbox64_mt_mcore_cms) windows_x86_64
Peak working set size	78.92 MB
Peak swap size	88.46 MB
Peak disk usage	2.92 GB
Stderr output

<core_client_version>7.16.7</core_client_version>
<![CDATA[
<stderr_txt>
2020-09-08 00:36:49 (16452): Detected: vboxwrapper 26197
2020-09-08 00:36:49 (16452): Detected: BOINC client v7.7
2020-09-08 00:36:50 (16452): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2020-09-08 00:36:50 (16452): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-09-08 00:36:50 (16452): Successfully copied 'init_data.xml' to the shared directory.
2020-09-08 00:36:52 (16452): Create VM. (boinc_a494137cf81635ee, slot#6)
2020-09-08 00:36:52 (16452): Setting Memory Size for VM. (1896MB)
2020-09-08 00:36:53 (16452): Setting CPU Count for VM. (1)
2020-09-08 00:36:53 (16452): Setting Chipset Options for VM.
2020-09-08 00:36:53 (16452): Setting Boot Options for VM.
2020-09-08 00:36:53 (16452): Setting Network Configuration for NAT.
2020-09-08 00:36:54 (16452): Enabling VM Network Access.
2020-09-08 00:36:54 (16452): Disabling USB Support for VM.
2020-09-08 00:36:54 (16452): Disabling COM Port Support for VM.
2020-09-08 00:36:55 (16452): Disabling LPT Port Support for VM.
2020-09-08 00:36:55 (16452): Disabling Audio Support for VM.
2020-09-08 00:36:55 (16452): Disabling Clipboard Support for VM.
2020-09-08 00:36:56 (16452): Disabling Drag and Drop Support for VM.
2020-09-08 00:36:56 (16452): Adding storage controller(s) to VM.
2020-09-08 00:36:56 (16452): Adding virtual disk drive to VM. (vm_image.vdi)
2020-09-08 00:36:56 (16452): Adding VirtualBox Guest Additions to VM.
2020-09-08 00:36:57 (16452): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2020-09-08 00:36:57 (16452): forwarding host port 51790 to guest port 80
2020-09-08 00:36:57 (16452): Enabling remote desktop for VM.
2020-09-08 00:36:58 (16452): Required extension pack not installed, remote desktop not enabled.
2020-09-08 00:36:58 (16452): Enabling shared directory for VM.
2020-09-08 00:36:58 (16452): Starting VM using VBoxManage interface. (boinc_a494137cf81635ee, slot#6)
2020-09-08 00:37:03 (16452): Successfully started VM. (PID = '16996')
2020-09-08 00:37:03 (16452): Reporting VM Process ID to BOINC.
2020-09-08 00:37:03 (16452): Guest Log: BIOS: VirtualBox 6.0.14

2020-09-08 00:37:03 (16452): Guest Log: CPUID EDX: 0x178bfbff

2020-09-08 00:37:03 (16452): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-09-08 00:37:03 (16452): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-09-08 00:37:03 (16452): Detected: Web Application Enabled (http://localhost:51790)
2020-09-08 00:37:03 (16452): Preference change detected
2020-09-08 00:37:03 (16452): Setting CPU throttle for VM. (100%)
2020-09-08 00:37:03 (16452): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds))
2020-09-08 00:37:05 (16452): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-09-08 00:37:05 (16452): Guest Log: BIOS: Booting from Hard Disk...

2020-09-08 00:37:08 (16452): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-09-08 00:37:08 (16452): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-09-08 00:37:28 (16452): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-09-08 00:37:28 (16452): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-09-08 00:38:05 (16452): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-09-08 00:38:05 (16452): Guest Log: 00:00:00.000336 main     Log opened 2020-09-07T22:38:04.526854000Z

2020-09-08 00:38:05 (16452): Guest Log: 00:00:00.000754 main     OS Product: Linux

2020-09-08 00:38:05 (16452): Guest Log: 00:00:00.000906 main     OS Release: 4.14.157-17.cernvm.x86_64

2020-09-08 00:38:05 (16452): Guest Log: 00:00:00.000950 main     OS Version: #1 SMP Wed Dec 4 17:26:45 CET 2019

2020-09-08 00:38:05 (16452): Guest Log: 00:00:00.000988 main     Executable: /usr/share/vboxguest52/usr/sbin/VBoxService

2020-09-08 00:38:05 (16452): Guest Log: 00:00:00.000990 main     Process ID: 2963

2020-09-08 00:38:05 (16452): Guest Log: 00:00:00.000992 main     Package type: LINUX_64BITS_GENERIC

2020-09-08 00:38:05 (16452): Guest Log: 00:00:00.002330 main     5.2.6 r120293 started. Verbose level = 0

2020-09-08 00:38:39 (16452): Guest Log: [INFO] Mounting the shared directory

2020-09-08 00:38:39 (16452): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2020-09-08 00:38:39 (16452): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80

2020-09-08 00:38:39 (16452): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!

2020-09-08 00:38:39 (16452): Guest Log: [DEBUG] 0

2020-09-08 00:38:39 (16452): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443

2020-09-08 00:38:40 (16452): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!

2020-09-08 00:38:40 (16452): Guest Log: [DEBUG] 0

2020-09-08 00:38:40 (16452): Guest Log: [DEBUG] Testing connection to Condor server on port 9618

2020-09-08 00:38:40 (16452): Guest Log: [DEBUG] Connection to vocms0840.cern.ch 9618 port [tcp/condor] succeeded!

2020-09-08 00:38:40 (16452): Guest Log: [DEBUG] 0

2020-09-08 00:39:47 (16452): Guest Log: [DEBUG] Probing CVMFS ...

2020-09-08 00:39:48 (16452): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-09-08 00:39:49 (16452): 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

2020-09-08 00:39:49 (16452): Guest Log: 2.4.4.0 3726 1 25412 13157 3 1 1242456 4096000 2 65024 0 3 100 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-09-08 00:40:07 (16452): Guest Log: [INFO] Reading volunteer information

2020-09-08 00:40:08 (16452): Guest Log: [INFO] Volunteer: [SG]ATA-Rolf (50)

2020-09-08 00:40:08 (16452): Guest Log: [INFO] VMID: 49b2fac1-df25-48d2-a4ee-4612ca6a31f8

2020-09-08 00:40:10 (16452): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2020-09-08 00:40:10 (16452): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev

2020-09-08 00:40:12 (16452): Guest Log: [INFO] Running the fast benchmark.

2020-09-08 00:41:26 (16452): Guest Log: [INFO] Machine performance 7.17 HEPSPEC06

2020-09-08 00:41:26 (16452): Guest Log: [INFO] CMS application starting. Check log files.

2020-09-08 00:41:29 (16452): Guest Log: [DEBUG] HTCondor ping

2020-09-08 00:41:31 (16452): Guest Log: [DEBUG] 0

2020-09-08 02:18:15 (16452): Status Report: Job Duration: '64800.000000'
2020-09-08 02:18:15 (16452): Status Report: Elapsed Time: '6000.000000'
2020-09-08 02:18:15 (16452): Status Report: CPU Time: '5624.015625'
2020-09-08 03:59:29 (16452): Status Report: Job Duration: '64800.000000'
2020-09-08 03:59:29 (16452): Status Report: Elapsed Time: '12000.000000'
2020-09-08 03:59:29 (16452): Status Report: CPU Time: '11537.687500'
2020-09-08 05:40:57 (16452): Status Report: Job Duration: '64800.000000'
2020-09-08 05:40:57 (16452): Status Report: Elapsed Time: '18000.000000'
2020-09-08 05:40:57 (16452): Status Report: CPU Time: '17080.625000'
2020-09-08 07:22:15 (16452): Status Report: Job Duration: '64800.000000'
2020-09-08 07:22:15 (16452): Status Report: Elapsed Time: '24000.000000'
2020-09-08 07:22:15 (16452): Status Report: CPU Time: '22971.984375'
2020-09-08 09:03:18 (16452): Status Report: Job Duration: '64800.000000'
2020-09-08 09:03:18 (16452): Status Report: Elapsed Time: '30000.000000'
2020-09-08 09:03:18 (16452): Status Report: CPU Time: '28850.062500'
2020-09-08 10:44:23 (16452): Status Report: Job Duration: '64800.000000'
2020-09-08 10:44:23 (16452): Status Report: Elapsed Time: '36000.000000'
2020-09-08 10:44:23 (16452): Status Report: CPU Time: '34218.890625'
2020-09-08 11:40:38 (16452): Stopping VM.
2020-09-08 11:40:38 (16452): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_a494137cf81635ee" savestate
Output:

2020-09-08 11:40:38 (16452): VM did not stop when requested.
2020-09-08 11:40:38 (16452): VM was successfully terminated.
2020-09-08 11:41:47 (688): Detected: vboxwrapper 26197
2020-09-08 11:41:47 (688): Detected: BOINC client v7.7
2020-09-08 11:41:48 (688): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2020-09-08 11:41:48 (688): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-09-08 11:41:49 (688): Starting VM using VBoxManage interface. (boinc_a494137cf81635ee, slot#6)
2020-09-08 11:41:55 (688): Successfully started VM. (PID = '11944')
2020-09-08 11:41:55 (688): Reporting VM Process ID to BOINC.
2020-09-08 11:41:55 (688): Guest Log: BIOS: VirtualBox 6.0.14

2020-09-08 11:41:55 (688): Guest Log: CPUID EDX: 0x178bfbff

2020-09-08 11:41:55 (688): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-09-08 11:41:55 (688): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-09-08 11:41:55 (688): Detected: Web Application Enabled (http://localhost:51790)
2020-09-08 11:41:55 (688): Status Report: Job Duration: '64800.000000'
2020-09-08 11:41:55 (688): Status Report: Elapsed Time: '39331.000000'
2020-09-08 11:41:55 (688): Status Report: CPU Time: '37529.046875'
2020-09-08 11:41:55 (688): Preference change detected
2020-09-08 11:41:55 (688): Setting CPU throttle for VM. (100%)
2020-09-08 11:41:55 (688): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds))
2020-09-08 11:41:57 (688): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-09-08 11:41:57 (688): Guest Log: BIOS: Booting from Hard Disk...

2020-09-08 11:41:59 (688): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-09-08 11:41:59 (688): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-09-08 11:42:37 (688): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-09-08 11:42:37 (688): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-09-08 11:43:10 (688): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-09-08 11:43:10 (688): Guest Log: 00:00:00.000552 main     Log opened 2020-09-08T09:43:09.414149000Z

2020-09-08 11:43:10 (688): Guest Log: 00:00:00.001600 main     OS Product: Linux

2020-09-08 11:43:10 (688): Guest Log: 00:00:00.002487 main     OS Release: 4.14.157-17.cernvm.x86_64

2020-09-08 11:43:10 (688): Guest Log: 00:00:00.002619 main     OS Version: #1 SMP Wed Dec 4 17:26:45 CET 2019

2020-09-08 11:43:10 (688): Guest Log: 00:00:00.002679 main     Executable: /usr/share/vboxguest52/usr/sbin/VBoxService

2020-09-08 11:43:10 (688): Guest Log: 00:00:00.002680 main     Process ID: 2907

2020-09-08 11:43:10 (688): Guest Log: 00:00:00.002681 main     Package type: LINUX_64BITS_GENERIC

2020-09-08 11:43:10 (688): Guest Log: 00:00:00.005906 main     5.2.6 r120293 started. Verbose level = 0

2020-09-08 11:44:00 (688): Guest Log: [INFO] Mounting the shared directory

2020-09-08 11:44:00 (688): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2020-09-08 11:44:00 (688): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80

2020-09-08 11:44:00 (688): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!

2020-09-08 11:44:00 (688): Guest Log: [DEBUG] 0

2020-09-08 11:44:00 (688): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443

2020-09-08 11:44:01 (688): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!

2020-09-08 11:44:01 (688): Guest Log: [DEBUG] 0

2020-09-08 11:44:01 (688): Guest Log: [DEBUG] Testing connection to Condor server on port 9618

2020-09-08 11:44:01 (688): Guest Log: [DEBUG] Connection to vocms0840.cern.ch 9618 port [tcp/condor] succeeded!

2020-09-08 11:44:01 (688): Guest Log: [DEBUG] 0

2020-09-08 11:45:07 (688): Guest Log: [DEBUG] Probing CVMFS ...

2020-09-08 11:45:08 (688): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-09-08 11:45:09 (688): 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

2020-09-08 11:45:09 (688): Guest Log: 2.4.4.0 3647 1 25348 13161 3 1 1625139 4096000 2 65024 0 3 100 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-09-08 11:45:12 (688): Guest Log: [INFO] Reading volunteer information

2020-09-08 11:45:12 (688): Guest Log: [INFO] Volunteer: [SG]ATA-Rolf (50)

2020-09-08 11:45:12 (688): Guest Log: [INFO] VMID: 49b2fac1-df25-48d2-a4ee-4612ca6a31f8

2020-09-08 11:45:13 (688): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2020-09-08 11:45:14 (688): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev

2020-09-08 11:45:15 (688): Guest Log: [INFO] Running the fast benchmark.

2020-09-08 11:46:28 (688): Guest Log: [INFO] Machine performance 7.48 HEPSPEC06

2020-09-08 11:46:28 (688): Guest Log: [INFO] CMS application starting. Check log files.

2020-09-08 11:46:29 (688): Guest Log: [DEBUG] HTCondor ping

2020-09-08 11:46:30 (688): Guest Log: [DEBUG] 0

2020-09-08 13:23:00 (688): Status Report: Job Duration: '64800.000000'
2020-09-08 13:23:00 (688): Status Report: Elapsed Time: '45331.108438'
2020-09-08 13:23:00 (688): Status Report: CPU Time: '42921.968750'
2020-09-08 15:04:11 (688): Status Report: Job Duration: '64800.000000'
2020-09-08 15:04:11 (688): Status Report: Elapsed Time: '51331.108438'
2020-09-08 15:04:11 (688): Status Report: CPU Time: '48757.250000'
2020-09-08 16:45:15 (688): Status Report: Job Duration: '64800.000000'
2020-09-08 16:45:15 (688): Status Report: Elapsed Time: '57331.108438'
2020-09-08 16:45:15 (688): Status Report: CPU Time: '54034.093750'
2020-09-08 18:26:22 (688): Status Report: Job Duration: '64800.000000'
2020-09-08 18:26:22 (688): Status Report: Elapsed Time: '63331.108438'
2020-09-08 18:26:22 (688): Status Report: CPU Time: '59358.890625'
2020-09-08 18:51:08 (688): Powering off VM.
2020-09-08 18:56:10 (688): VM did not power off when requested.
2020-09-08 18:56:10 (688): VM was successfully terminated.
2020-09-08 18:56:10 (688): Deregistering VM. (boinc_a494137cf81635ee, slot#6)
2020-09-08 18:56:10 (688): Removing network bandwidth throttle group from VM.
2020-09-08 18:56:10 (688): Removing VM from VirtualBox.
18:56:15 (688): called boinc_finish(0)

</stderr_txt>
]]>
Development for LHC@home

Stderr output
