Name CMS_2156648_1626005420.826330_0
Workunit 2102753
Created 11 Jul 2021, 12:10:21 UTC
Sent 11 Jul 2021, 23:39:35 UTC
Report deadline 18 Jul 2021, 23:39:35 UTC
Received 15 Jul 2021, 8:43:01 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 4446
Run time 5 hours 39 min 18 sec
CPU time 6 hours 8 min 24 sec
Validate state Valid
Credit 256.13
Device peak FLOPS 6.00 GFLOPS
Application version CMS Simulation v60.50 (vbox64_mt_mcore_cms)
windows_x86_64
Peak working set size 68.36 MB
Peak swap size 68.67 MB
Peak disk usage 7.38 GB

Stderr output

<core_client_version>7.16.11</core_client_version>
<![CDATA[
<stderr_txt>
2021-07-12 15:11:44 (4840): Detected: vboxwrapper 26197
2021-07-12 15:11:44 (4840): Detected: BOINC client v7.7
2021-07-12 15:11:51 (4840): Detected: VirtualBox VboxManage Interface (Version: 6.1.12)
2021-07-12 15:11:51 (4840): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-07-12 15:11:51 (4840): Successfully copied 'init_data.xml' to the shared directory.
2021-07-12 15:11:52 (4840): Create VM. (boinc_cd52d4227e16c4fb, slot#5)
2021-07-12 15:11:53 (4840): Setting Memory Size for VM. (6376MB)
2021-07-12 15:11:53 (4840): Setting CPU Count for VM. (6)
2021-07-12 15:11:53 (4840): Setting Chipset Options for VM.
2021-07-12 15:11:54 (4840): Setting Boot Options for VM.
2021-07-12 15:11:54 (4840): Setting Network Configuration for NAT.
2021-07-12 15:11:54 (4840): Enabling VM Network Access.
2021-07-12 15:11:54 (4840): Disabling USB Support for VM.
2021-07-12 15:11:55 (4840): Disabling COM Port Support for VM.
2021-07-12 15:11:55 (4840): Disabling LPT Port Support for VM.
2021-07-12 15:11:55 (4840): Disabling Audio Support for VM.
2021-07-12 15:11:55 (4840): Disabling Clipboard Support for VM.
2021-07-12 15:11:56 (4840): Disabling Drag and Drop Support for VM.
2021-07-12 15:11:56 (4840): Adding storage controller(s) to VM.
2021-07-12 15:11:56 (4840): Adding virtual disk drive to VM. (vm_image.vdi)
2021-07-12 15:11:57 (4840): Adding VirtualBox Guest Additions to VM.
2021-07-12 15:11:57 (4840): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2021-07-12 15:11:58 (4840): forwarding host port 57742 to guest port 80
2021-07-12 15:11:58 (4840): Enabling remote desktop for VM.
2021-07-12 15:11:58 (4840): Required extension pack not installed, remote desktop not enabled.
2021-07-12 15:11:58 (4840): Enabling shared directory for VM.
2021-07-12 15:11:58 (4840): Starting VM using VBoxManage interface. (boinc_cd52d4227e16c4fb, slot#5)
2021-07-12 15:12:08 (4840): Successfully started VM. (PID = '3356')
2021-07-12 15:12:08 (4840): Reporting VM Process ID to BOINC.
2021-07-12 15:12:08 (4840): Guest Log: BIOS: VirtualBox 6.1.12

2021-07-12 15:12:08 (4840): Guest Log: CPUID EDX: 0x178bfbff

2021-07-12 15:12:08 (4840): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-07-12 15:12:08 (4840): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-07-12 15:12:08 (4840): Detected: Web Application Enabled (http://localhost:57742)
2021-07-12 15:12:08 (4840): Preference change detected
2021-07-12 15:12:08 (4840): Setting CPU throttle for VM. (90%)
2021-07-12 15:12:08 (4840): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2021-07-12 15:12:10 (4840): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-07-12 15:12:10 (4840): Guest Log: BIOS: Booting from Hard Disk...

2021-07-12 15:12:14 (4840): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-07-12 15:12:14 (4840): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-07-12 15:13:12 (4840): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2021-07-12 15:13:12 (4840): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2021-07-12 15:13:18 (4840): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2021-07-12 15:13:18 (4840): Guest Log: 00:00:00.000366 main     Log opened 2021-07-12T05:13:55.014387000Z

2021-07-12 15:13:18 (4840): Guest Log: 00:00:00.000523 main     OS Product: Linux

2021-07-12 15:13:18 (4840): Guest Log: 00:00:00.000588 main     OS Release: 4.14.232-19.cernvm.x86_64

2021-07-12 15:13:18 (4840): Guest Log: 00:00:00.000647 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021

2021-07-12 15:13:18 (4840): Guest Log: 00:00:00.000702 main     Executable: /usr/sbin/VBoxService

2021-07-12 15:13:18 (4840): Guest Log: 00:00:00.000704 main     Process ID: 2372

2021-07-12 15:13:18 (4840): Guest Log: 00:00:00.000705 main     Package type: LINUX_64BITS_GENERIC

2021-07-12 15:13:18 (4840): Guest Log: 00:00:00.002591 main     5.2.6 r120293 started. Verbose level = 0

2021-07-12 15:13:55 (4840): Guest Log: [INFO] Mounting the shared directory

2021-07-12 15:13:55 (4840): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2021-07-12 15:13:55 (4840): Guest Log: [INFO] Testing connection to cern.ch

2021-07-12 15:13:56 (4840): Guest Log: [INFO] Testing connection to VCCS

2021-07-12 15:13:57 (4840): Guest Log: [INFO] Testing connection to HTCondor

2021-07-12 15:13:58 (4840): Guest Log: [INFO] Testing connection to WMAgent

2021-07-12 15:14:00 (4840): Guest Log: [INFO] CVMFS and Frontier will use DIRECT instead of an HTTP proxy.

2021-07-12 15:14:00 (4840): Guest Log: [INFO] A local HTTP proxy could help making the network usage

2021-07-12 15:14:00 (4840): Guest Log: [INFO] of this application more efficient. It would also help to

2021-07-12 15:14:00 (4840): Guest Log: [INFO] offload the project servers.

2021-07-12 15:14:01 (4840): Guest Log: [INFO] Details can be found in the project forum.

2021-07-12 15:14:01 (4840): Guest Log: [INFO] Reloading the CVMFS configuration (can take a while) ...

2021-07-12 15:14:09 (4840): Guest Log: [INFO] Probing CVMFS ...

2021-07-12 15:14:12 (4840): Guest Log: Probing /cvmfs/cvmfs-config.cern.ch... OK

2021-07-12 15:14:15 (4840): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2021-07-12 15:14:27 (4840): Guest Log: Probing /cvmfs/cms.cern.ch... OK

2021-07-12 15:14:39 (4840): Guest Log: Probing /cvmfs/cms-ib.cern.ch... OK

2021-07-12 15:14:49 (4840): Guest Log: Probing /cvmfs/oasis.opensciencegrid.org... OK

2021-07-12 15:14:58 (4840): Guest Log: Probing /cvmfs/singularity.opensciencegrid.org... OK

2021-07-12 15:15:00 (4840): 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-07-12 15:15:00 (4840): Guest Log: 2.7.2.0 3075 1 28148 15683 3 1 2079748 10240000 2 65024 0 8 100 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1

2021-07-12 15:15:20 (4840): Guest Log: [INFO] Reading volunteer information

2021-07-12 15:15:20 (4840): Guest Log: [INFO] Volunteer: Bunteck (302)

2021-07-12 15:15:21 (4840): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2021-07-12 15:15:24 (4840): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev

2021-07-12 15:15:27 (4840): Guest Log: [INFO] CMS application starting. Check log files.

2021-07-12 15:38:55 (4840): Stopping VM.
2021-07-12 15:41:41 (4840): 2021-07-13 23:26:20 (3492): Detected: vboxwrapper 26197
2021-07-13 23:26:20 (3492): Detected: BOINC client v7.7
2021-07-13 23:26:29 (3492): Detected: VirtualBox VboxManage Interface (Version: 6.1.12)
2021-07-13 23:26:31 (3492): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-07-13 23:26:32 (3492): Register VM. (boinc_cd52d4227e16c4fb, slot#5)
2021-07-13 23:26:37 (3492): Starting VM using VBoxManage interface. (boinc_cd52d4227e16c4fb, slot#5)
2021-07-13 23:27:20 (3492): Successfully started VM. (PID = '5012')
2021-07-13 23:27:20 (3492): Reporting VM Process ID to BOINC.
2021-07-13 23:27:20 (3492): Guest Log: BIOS: VirtualBox 6.1.12

2021-07-13 23:27:20 (3492): Guest Log: CPUID EDX: 0x178bfbff

2021-07-13 23:27:20 (3492): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-07-13 23:27:20 (3492): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-07-13 23:27:20 (3492): Detected: Web Application Enabled (http://localhost:57742)
2021-07-13 23:27:20 (3492): Preference change detected
2021-07-13 23:27:20 (3492): Setting CPU throttle for VM. (90%)
2021-07-13 23:27:21 (3492): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2021-07-13 23:27:22 (3492): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-07-13 23:27:22 (3492): Guest Log: BIOS: Booting from Hard Disk...

2021-07-13 23:27:29 (3492): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-07-13 23:27:29 (3492): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-07-13 23:28:39 (3492): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2021-07-13 23:28:39 (3492): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2021-07-13 23:28:48 (3492): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2021-07-13 23:28:48 (3492): Guest Log: 00:00:00.000659 main     Log opened 2021-07-13T13:29:29.397152000Z

2021-07-13 23:28:48 (3492): Guest Log: 00:00:00.001354 main     OS Product: Linux

2021-07-13 23:28:48 (3492): Guest Log: 00:00:00.001437 main     OS Release: 4.14.232-19.cernvm.x86_64

2021-07-13 23:28:48 (3492): Guest Log: 00:00:00.001494 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021

2021-07-13 23:28:48 (3492): Guest Log: 00:00:00.001547 main     Executable: /usr/sbin/VBoxService

2021-07-13 23:28:48 (3492): Guest Log: 00:00:00.001548 main     Process ID: 2341

2021-07-13 23:28:48 (3492): Guest Log: 00:00:00.001549 main     Package type: LINUX_64BITS_GENERIC

2021-07-13 23:28:48 (3492): Guest Log: 00:00:00.003271 main     5.2.6 r120293 started. Verbose level = 0

2021-07-13 23:29:43 (3492): Guest Log: [INFO] Mounting the shared directory

2021-07-13 23:29:44 (3492): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2021-07-13 23:29:44 (3492): Guest Log: [INFO] Testing connection to cern.ch

2021-07-13 23:29:45 (3492): Guest Log: [INFO] Testing connection to VCCS

2021-07-13 23:29:46 (3492): Guest Log: [INFO] Testing connection to HTCondor

2021-07-13 23:29:47 (3492): Guest Log: [INFO] Testing connection to WMAgent

2021-07-13 23:29:50 (3492): Guest Log: [INFO] CVMFS and Frontier will use DIRECT instead of an HTTP proxy.

2021-07-13 23:29:50 (3492): Guest Log: [INFO] A local HTTP proxy could help making the network usage

2021-07-13 23:29:50 (3492): Guest Log: [INFO] of this application more efficient. It would also help to

2021-07-13 23:29:50 (3492): Guest Log: [INFO] offload the project servers.

2021-07-13 23:29:50 (3492): Guest Log: [INFO] Details can be found in the project forum.

2021-07-13 23:29:51 (3492): Guest Log: [INFO] Reloading the CVMFS configuration (can take a while) ...

2021-07-13 23:29:58 (3492): Guest Log: [INFO] Probing CVMFS ...

2021-07-13 23:30:00 (3492): Guest Log: Probing /cvmfs/cvmfs-config.cern.ch... OK

2021-07-13 23:30:03 (3492): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2021-07-13 23:30:15 (3492): Guest Log: Probing /cvmfs/cms.cern.ch... OK

2021-07-13 23:30:27 (3492): Guest Log: Probing /cvmfs/cms-ib.cern.ch... OK

2021-07-13 23:30:38 (3492): Guest Log: Probing /cvmfs/oasis.opensciencegrid.org... OK

2021-07-13 23:30:49 (3492): Guest Log: Probing /cvmfs/singularity.opensciencegrid.org... OK

2021-07-13 23:30:51 (3492): 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-07-13 23:30:51 (3492): Guest Log: 2.7.2.0 3098 1 28176 15697 3 1 2239054 10240001 2 65024 0 8 100 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2021-07-13 23:31:15 (3492): Guest Log: [INFO] Reading volunteer information

2021-07-13 23:31:17 (3492): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2021-07-13 23:31:22 (3492): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev

2021-07-13 23:31:25 (3492): Guest Log: [INFO] CMS application starting. Check log files.

2021-07-14 00:40:34 (3492): Status Report: Job Duration: '64800.000000'
2021-07-14 00:40:34 (3492): Status Report: Elapsed Time: '6000.061144'
2021-07-14 00:40:34 (3492): Status Report: CPU Time: '6566.986896'
2021-07-14 01:44:01 (3492): Stopping VM.
2021-07-14 01:44:48 (3492): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_cd52d4227e16c4fb" savestate
Output:
0%...10%...20%...30%...40%...
2021-07-14 01:44:48 (3492): VM did not stop when requested.
2021-07-14 01:44:48 (3492): VM was successfully terminated.
2021-07-14 03:42:08 (4932): Detected: vboxwrapper 26197
2021-07-14 03:42:08 (4932): Detected: BOINC client v7.7
2021-07-14 03:42:08 (4932): Detected: VirtualBox VboxManage Interface (Version: 6.1.12)
2021-07-14 03:42:09 (4932): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-07-14 03:42:09 (4932): Starting VM using VBoxManage interface. (boinc_cd52d4227e16c4fb, slot#5)
2021-07-14 03:42:13 (4932): Successfully started VM. (PID = '1028')
2021-07-14 03:42:13 (4932): Reporting VM Process ID to BOINC.
2021-07-14 03:42:13 (4932): Guest Log: BIOS: VirtualBox 6.1.12

2021-07-14 03:42:13 (4932): Guest Log: CPUID EDX: 0x178bfbff

2021-07-14 03:42:13 (4932): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-07-14 03:42:13 (4932): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-07-14 03:42:13 (4932): Detected: Web Application Enabled (http://localhost:57742)
2021-07-14 03:42:13 (4932): Status Report: Job Duration: '64800.000000'
2021-07-14 03:42:13 (4932): Status Report: Elapsed Time: '9806.061144'
2021-07-14 03:42:13 (4932): Status Report: CPU Time: '10948.118980'
2021-07-14 03:42:13 (4932): Preference change detected
2021-07-14 03:42:13 (4932): Setting CPU throttle for VM. (90%)
2021-07-14 03:42:13 (4932): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2021-07-14 03:42:15 (4932): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-07-14 03:42:15 (4932): Guest Log: BIOS: Booting from Hard Disk...

2021-07-14 03:42:18 (4932): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-07-14 03:42:18 (4932): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-07-14 03:43:01 (4932): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2021-07-14 03:43:01 (4932): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2021-07-14 03:43:03 (4932): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2021-07-14 03:43:03 (4932): Guest Log: 00:00:00.000964 main     Log opened 2021-07-13T17:43:41.654410000Z

2021-07-14 03:43:03 (4932): Guest Log: 00:00:00.001298 main     OS Product: Linux

2021-07-14 03:43:03 (4932): Guest Log: 00:00:00.001556 main     OS Release: 4.14.232-19.cernvm.x86_64

2021-07-14 03:43:03 (4932): Guest Log: 00:00:00.001626 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021

2021-07-14 03:43:03 (4932): Guest Log: 00:00:00.001690 main     Executable: /usr/sbin/VBoxService

2021-07-14 03:43:03 (4932): Guest Log: 00:00:00.001692 main     Process ID: 2354

2021-07-14 03:43:03 (4932): Guest Log: 00:00:00.001694 main     Package type: LINUX_64BITS_GENERIC

2021-07-14 03:43:03 (4932): Guest Log: 00:00:00.006424 main     5.2.6 r120293 started. Verbose level = 0

2021-07-14 03:43:43 (4932): Guest Log: [INFO] Mounting the shared directory

2021-07-14 03:43:43 (4932): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2021-07-14 03:43:43 (4932): Guest Log: [INFO] Testing connection to cern.ch

2021-07-14 03:43:44 (4932): Guest Log: [INFO] Testing connection to VCCS

2021-07-14 03:43:45 (4932): Guest Log: [INFO] Testing connection to HTCondor

2021-07-14 03:43:46 (4932): Guest Log: [INFO] Testing connection to WMAgent

2021-07-14 03:43:47 (4932): Guest Log: [INFO] Testing connection to Frontier

2021-07-14 03:43:48 (4932): Guest Log: [INFO] CVMFS and Frontier will use DIRECT instead of an HTTP proxy.

2021-07-14 03:43:49 (4932): Guest Log: [INFO] A local HTTP proxy could help making the network usage

2021-07-14 03:43:49 (4932): Guest Log: [INFO] of this application more efficient. It would also help to

2021-07-14 03:43:49 (4932): Guest Log: [INFO] offload the project servers.

2021-07-14 03:43:49 (4932): Guest Log: [INFO] Details can be found in the project forum.

2021-07-14 03:43:49 (4932): Guest Log: [INFO] Reloading the CVMFS configuration (can take a while) ...

2021-07-14 03:43:57 (4932): Guest Log: [INFO] Probing CVMFS ...

2021-07-14 03:44:00 (4932): Guest Log: Probing /cvmfs/cvmfs-config.cern.ch... OK

2021-07-14 03:44:03 (4932): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2021-07-14 03:44:15 (4932): Guest Log: Probing /cvmfs/cms.cern.ch... OK

2021-07-14 03:44:28 (4932): Guest Log: Probing /cvmfs/cms-ib.cern.ch... OK

2021-07-14 03:44:36 (4932): Guest Log: Probing /cvmfs/oasis.opensciencegrid.org... OK

2021-07-14 03:44:47 (4932): Guest Log: Probing /cvmfs/singularity.opensciencegrid.org... OK

2021-07-14 03:44:49 (4932): 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-07-14 03:44:49 (4932): Guest Log: 2.7.2.0 3066 1 28176 15701 3 1 2370026 10240001 2 65024 0 8 100 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1

2021-07-14 03:44:55 (4932): Guest Log: [INFO] Reading volunteer information

2021-07-14 03:44:56 (4932): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2021-07-14 03:44:58 (4932): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev

2021-07-14 03:45:01 (4932): Guest Log: [INFO] CMS application starting. Check log files.

2021-07-14 04:42:46 (4932): Stopping VM.
2021-07-14 04:45:03 (4932): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_cd52d4227e16c4fb" savestate
Output:
0%...10%...20%...30%...40%...
2021-07-14 04:45:06 (4932): VM did not stop when requested.
2021-07-14 04:45:10 (4932): VM was successfully terminated.
2021-07-14 13:23:58 (6400): Detected: vboxwrapper 26197
2021-07-14 13:23:58 (6400): Detected: BOINC client v7.7
2021-07-14 13:23:59 (6400): Detected: VirtualBox VboxManage Interface (Version: 6.1.12)
2021-07-14 13:24:00 (6400): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-07-14 13:24:00 (6400): Starting VM using VBoxManage interface. (boinc_cd52d4227e16c4fb, slot#5)
2021-07-14 13:24:10 (6400): Successfully started VM. (PID = '4664')
2021-07-14 13:24:10 (6400): Reporting VM Process ID to BOINC.
2021-07-14 13:24:10 (6400): Guest Log: BIOS: VirtualBox 6.1.12

2021-07-14 13:24:10 (6400): Guest Log: CPUID EDX: 0x178bfbff

2021-07-14 13:24:10 (6400): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-07-14 13:24:10 (6400): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-07-14 13:24:10 (6400): Detected: Web Application Enabled (http://localhost:57742)
2021-07-14 13:24:10 (6400): Status Report: Job Duration: '64800.000000'
2021-07-14 13:24:10 (6400): Status Report: Elapsed Time: '13439.061144'
2021-07-14 13:24:10 (6400): Status Report: CPU Time: '14598.901182'
2021-07-14 13:24:10 (6400): Preference change detected
2021-07-14 13:24:10 (6400): Setting CPU throttle for VM. (90%)
2021-07-14 13:24:11 (6400): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2021-07-14 13:24:12 (6400): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-07-14 13:24:12 (6400): Guest Log: BIOS: Booting from Hard Disk...

2021-07-14 13:24:15 (6400): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-07-14 13:24:15 (6400): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-07-14 13:25:07 (6400): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2021-07-14 13:25:07 (6400): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2021-07-14 13:25:10 (6400): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2021-07-14 13:25:10 (6400): Guest Log: 00:00:00.000313 main     Log opened 2021-07-14T03:25:10.854122000Z

2021-07-14 13:25:10 (6400): Guest Log: 00:00:00.000553 main     OS Product: Linux

2021-07-14 13:25:10 (6400): Guest Log: 00:00:00.000638 main     OS Release: 4.14.232-19.cernvm.x86_64

2021-07-14 13:25:10 (6400): Guest Log: 00:00:00.000716 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021

2021-07-14 13:25:10 (6400): Guest Log: 00:00:00.000790 main     Executable: /usr/sbin/VBoxService

2021-07-14 13:25:10 (6400): Guest Log: 00:00:00.000792 main     Process ID: 2369

2021-07-14 13:25:10 (6400): Guest Log: 00:00:00.000794 main     Package type: LINUX_64BITS_GENERIC

2021-07-14 13:25:10 (6400): Guest Log: 00:00:00.002308 main     5.2.6 r120293 started. Verbose level = 0

2021-07-14 13:25:50 (6400): Guest Log: [INFO] Mounting the shared directory

2021-07-14 13:25:50 (6400): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2021-07-14 13:25:51 (6400): Guest Log: [INFO] Testing connection to cern.ch

2021-07-14 13:25:52 (6400): Guest Log: [INFO] Testing connection to VCCS

2021-07-14 13:25:53 (6400): Guest Log: [INFO] Testing connection to HTCondor

2021-07-14 13:25:54 (6400): Guest Log: [INFO] Testing connection to WMAgent

2021-07-14 13:25:54 (6400): Guest Log: [INFO] Testing connection to Frontier

2021-07-14 13:25:57 (6400): Guest Log: [INFO] CVMFS and Frontier will use DIRECT instead of an HTTP proxy.

2021-07-14 13:25:57 (6400): Guest Log: [INFO] A local HTTP proxy could help making the network usage

2021-07-14 13:25:57 (6400): Guest Log: [INFO] of this application more efficient. It would also help to

2021-07-14 13:25:57 (6400): Guest Log: [INFO] offload the project servers.

2021-07-14 13:25:57 (6400): Guest Log: [INFO] Details can be found in the project forum.

2021-07-14 13:25:58 (6400): Guest Log: [INFO] Reloading the CVMFS configuration (can take a while) ...

2021-07-14 13:26:05 (6400): Guest Log: [INFO] Probing CVMFS ...

2021-07-14 13:26:08 (6400): Guest Log: Probing /cvmfs/cvmfs-config.cern.ch... OK

2021-07-14 13:26:11 (6400): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2021-07-14 13:26:23 (6400): Guest Log: Probing /cvmfs/cms.cern.ch... OK

2021-07-14 13:26:34 (6400): Guest Log: Probing /cvmfs/cms-ib.cern.ch... OK

2021-07-14 13:26:45 (6400): Guest Log: Probing /cvmfs/oasis.opensciencegrid.org... OK

2021-07-14 13:26:56 (6400): Guest Log: Probing /cvmfs/singularity.opensciencegrid.org... OK

2021-07-14 13:26:58 (6400): 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-07-14 13:26:58 (6400): Guest Log: 2.7.2.0 3072 1 28128 15705 3 1 2393581 10240001 2 65024 0 8 100 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1

2021-07-14 13:27:13 (6400): Guest Log: [INFO] Reading volunteer information

2021-07-14 13:27:15 (6400): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2021-07-14 13:27:18 (6400): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev

2021-07-14 13:27:21 (6400): Guest Log: [INFO] CMS application starting. Check log files.

2021-07-14 14:24:33 (6400): Stopping VM.
2021-07-15 16:42:55 (3976): Detected: vboxwrapper 26197
2021-07-15 16:42:55 (3976): Detected: BOINC client v7.7
2021-07-15 16:42:56 (3976): Detected: VirtualBox VboxManage Interface (Version: 6.1.12)
2021-07-15 16:42:57 (3976): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-07-15 16:42:57 (3976): Starting VM using VBoxManage interface. (boinc_cd52d4227e16c4fb, slot#5)
2021-07-15 16:43:07 (3976): Successfully started VM. (PID = '4796')
2021-07-15 16:43:07 (3976): Reporting VM Process ID to BOINC.
2021-07-15 16:43:07 (3976): Guest Log: BIOS: VirtualBox 6.1.12

2021-07-15 16:43:07 (3976): Guest Log: CPUID EDX: 0x178bfbff

2021-07-15 16:43:07 (3976): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-07-15 16:43:07 (3976): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-07-15 16:43:07 (3976): Detected: Web Application Enabled (http://localhost:57742)
2021-07-15 16:43:07 (3976): Status Report: Job Duration: '64800.000000'
2021-07-15 16:43:07 (3976): Status Report: Elapsed Time: '17061.061144'
2021-07-15 16:43:07 (3976): Status Report: CPU Time: '19111.604509'
2021-07-15 16:43:07 (3976): Preference change detected
2021-07-15 16:43:07 (3976): Setting CPU throttle for VM. (90%)
2021-07-15 16:43:07 (3976): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2021-07-15 16:43:09 (3976): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-07-15 16:43:09 (3976): Guest Log: BIOS: Booting from Hard Disk...

2021-07-15 16:43:13 (3976): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-07-15 16:43:13 (3976): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-07-15 16:44:01 (3976): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2021-07-15 16:44:01 (3976): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2021-07-15 16:44:03 (3976): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2021-07-15 16:44:03 (3976): Guest Log: 00:00:00.001102 main     Log opened 2021-07-15T06:45:41.644682000Z

2021-07-15 16:44:03 (3976): Guest Log: 00:00:00.001296 main     OS Product: Linux

2021-07-15 16:44:03 (3976): Guest Log: 00:00:00.001365 main     OS Release: 4.14.232-19.cernvm.x86_64

2021-07-15 16:44:03 (3976): Guest Log: 00:00:00.001422 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021

2021-07-15 16:44:03 (3976): Guest Log: 00:00:00.001474 main     Executable: /usr/sbin/VBoxService

2021-07-15 16:44:03 (3976): Guest Log: 00:00:00.001476 main     Process ID: 2361

2021-07-15 16:44:03 (3976): Guest Log: 00:00:00.001477 main     Package type: LINUX_64BITS_GENERIC

2021-07-15 16:44:03 (3976): Guest Log: 00:00:00.002775 main     5.2.6 r120293 started. Verbose level = 0

2021-07-15 16:46:16 (3976): Guest Log: [INFO] Mounting the shared directory

2021-07-15 16:46:16 (3976): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2021-07-15 16:46:16 (3976): Guest Log: [INFO] Testing connection to cern.ch

2021-07-15 16:46:17 (3976): Guest Log: [INFO] Testing connection to VCCS

2021-07-15 16:46:18 (3976): Guest Log: [INFO] Testing connection to HTCondor

2021-07-15 16:46:19 (3976): Guest Log: [INFO] Testing connection to WMAgent

2021-07-15 16:46:20 (3976): Guest Log: [INFO] Testing connection to Frontier

2021-07-15 16:46:22 (3976): Guest Log: [INFO] Could not find a local HTTP proxy.

2021-07-15 16:46:22 (3976): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections.

2021-07-15 16:46:23 (3976): Guest Log: [INFO] This makes the application less efficient.

2021-07-15 16:46:23 (3976): Guest Log: [INFO] It also puts higher load on the project servers.

2021-07-15 16:46:23 (3976): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended.

2021-07-15 16:46:23 (3976): Guest Log: [INFO] Advice can be found in the project forum.

2021-07-15 16:46:23 (3976): Guest Log: [INFO] Reloading the CVMFS configuration (can take a while) ...

2021-07-15 16:46:32 (3976): Guest Log: [INFO] Probing CVMFS ...

2021-07-15 16:46:36 (3976): Guest Log: Probing /cvmfs/cvmfs-config.cern.ch... OK

2021-07-15 16:46:39 (3976): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2021-07-15 16:46:51 (3976): Guest Log: Probing /cvmfs/cms.cern.ch... OK

2021-07-15 16:47:04 (3976): Guest Log: Probing /cvmfs/cms-ib.cern.ch... OK

2021-07-15 16:47:14 (3976): Guest Log: Probing /cvmfs/oasis.opensciencegrid.org... OK

2021-07-15 16:47:24 (3976): Guest Log: Probing /cvmfs/singularity.opensciencegrid.org... OK

2021-07-15 16:47:27 (3976): 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-07-15 16:47:27 (3976): Guest Log: 2.7.2.0 3075 1 30184 15717 3 1 2422323 10240000 2 65024 0 8 100 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1

2021-07-15 16:47:52 (3976): Guest Log: [INFO] Reading volunteer information

2021-07-15 16:47:53 (3976): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2021-07-15 16:47:56 (3976): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev

2021-07-15 16:48:00 (3976): Guest Log: [INFO] CMS application starting. Check log files.

2021-07-15 17:00:22 (3976): Stopping VM.
2021-07-15 18:01:25 (3316): Detected: vboxwrapper 26197
2021-07-15 18:01:25 (3316): Detected: BOINC client v7.7
2021-07-15 18:01:26 (3316): Detected: VirtualBox VboxManage Interface (Version: 6.1.12)
2021-07-15 18:01:26 (3316): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-07-15 18:01:26 (3316): Starting VM using VBoxManage interface. (boinc_cd52d4227e16c4fb, slot#5)
2021-07-15 18:01:45 (3316): Successfully started VM. (PID = '2928')
2021-07-15 18:01:45 (3316): Reporting VM Process ID to BOINC.
2021-07-15 18:01:45 (3316): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-07-15 18:01:45 (3316): Detected: Web Application Enabled (http://localhost:57742)
2021-07-15 18:01:46 (3316): VM state change detected. (old = 'Running', new = 'Paused')
2021-07-15 18:01:50 (3316): Status Report: Job Duration: '64800.000000'
2021-07-15 18:01:50 (3316): Status Report: Elapsed Time: '17997.061144'
2021-07-15 18:01:50 (3316): Status Report: CPU Time: '20172.676511'
2021-07-15 18:01:50 (3316): Preference change detected
2021-07-15 18:01:50 (3316): Setting CPU throttle for VM. (90%)
2021-07-15 18:01:51 (3316): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2021-07-15 18:01:51 (3316): VM state change detected. (old = 'Paused', new = 'Running')
2021-07-15 18:01:57 (3316): Guest Log: 00:14:50.302515 timesync vgsvcTimeSyncWorker: Radical host time change: 3 695 498 000 000ns (HostNow=1 626 336 117 445 000 000 ns HostLast=1 626 332 421 947 000 000 ns)

2021-07-15 18:02:07 (3316): Guest Log: 00:15:00.302844 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 695 499 674 000ns (GuestNow=1 626 336 127 445 534 000 ns GuestLast=1 626 332 431 945 860 000 ns fSetTimeLastLoop=true )

2021-07-15 18:37:29 (3316): Guest Log: [INFO] glidein exited with return value 0.

2021-07-15 18:37:29 (3316): Guest Log: [INFO] Shutting Down.

2021-07-15 18:37:29 (3316): VM Completion File Detected.
2021-07-15 18:37:29 (3316): VM Completion Message: glidein exited with return value 0.
.
2021-07-15 18:37:29 (3316): Powering off VM.
2021-07-15 18:42:30 (3316): VM did not power off when requested.
2021-07-15 18:42:30 (3316): VM was successfully terminated.
2021-07-15 18:42:30 (3316): Deregistering VM. (boinc_cd52d4227e16c4fb, slot#5)
2021-07-15 18:42:30 (3316): Removing network bandwidth throttle group from VM.
2021-07-15 18:42:30 (3316): Removing VM from VirtualBox.
18:42:36 (3316): called boinc_finish(0)

</stderr_txt>
]]>


©2024 CERN