Name | CMS_1594548_1727653172.980336_0 |
Workunit | 225732031 |
Created | 29 Sep 2024, 23:39:38 UTC |
Sent | 30 Sep 2024, 1:00:57 UTC |
Report deadline | 31 Oct 2024, 1:00:57 UTC |
Received | 1 Oct 2024, 9:05:31 UTC |
Server state | Over |
Outcome | Success |
Client state | Done |
Exit status | 0 (0x00000000) |
Computer ID | 10846724 |
Run time | 18 hours 57 min 55 sec |
CPU time | 2 days 9 hours 26 min 9 sec |
Validate state | Valid |
Credit | 2,613.92 |
Device peak FLOPS | 20.24 GFLOPS |
Application version | CMS Simulation v70.30 (vbox64_mt_mcore_cms) windows_x86_64 |
Peak working set size | 85.51 MB |
Peak swap size | 90.29 MB |
Peak disk usage | 1.82 GB |
<core_client_version>8.0.2</core_client_version> <![CDATA[ <stderr_txt> 2024-09-29 20:55:02 (76404): vboxwrapper version 26207 2024-09-29 20:55:02 (76404): BOINC client version: 8.0.2 2024-09-29 20:55:09 (76404): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2024-09-29 20:55:10 (76404): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-09-29 20:55:10 (76404): Successfully copied 'init_data.xml' to the shared directory. 2024-09-29 20:55:12 (76404): Create VM. (boinc_7508bc3e2aae9a84, slot#15) 2024-09-29 20:55:16 (76404): Setting Memory Size for VM. (4584MB) 2024-09-29 20:55:18 (76404): Setting CPU Count for VM. (4) 2024-09-29 20:55:21 (76404): Setting Chipset Options for VM. 2024-09-29 20:55:22 (76404): Setting Graphics Controller Options for VM. 2024-09-29 20:55:23 (76404): Setting Boot Options for VM. 2024-09-29 20:55:25 (76404): Setting Network Configuration for NAT. 2024-09-29 20:55:26 (76404): Enabling VM Network Access. 2024-09-29 20:55:27 (76404): Disabling USB Support for VM. 2024-09-29 20:55:29 (76404): Disabling COM Port Support for VM. 2024-09-29 20:55:31 (76404): Disabling LPT Port Support for VM. 2024-09-29 20:55:34 (76404): Disabling Audio Support for VM. 2024-09-29 20:55:35 (76404): Disabling Clipboard Support for VM. 2024-09-29 20:55:37 (76404): Disabling Drag and Drop Support for VM. 2024-09-29 20:55:38 (76404): Adding storage controller(s) to VM. 2024-09-29 20:55:39 (76404): Adding virtual disk drive to VM. (CMS_2024_04_29_prod.vdi) 2024-09-29 20:55:42 (76404): Adding VirtualBox Guest Additions to VM. 2024-09-29 20:55:44 (76404): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2024-09-29 20:55:46 (76404): forwarding host port 55760 to guest port 80 2024-09-29 20:55:47 (76404): Enabling remote desktop for VM. 2024-09-29 20:55:48 (76404): Required extension pack not installed, remote desktop not enabled. 2024-09-29 20:55:48 (76404): Enabling shared directory for VM. 2024-09-29 20:55:51 (76404): Starting VM using VBoxManage interface. (boinc_7508bc3e2aae9a84, slot#15) 2024-09-29 20:56:03 (76404): Successfully started VM. (PID = '63768') 2024-09-29 20:56:03 (76404): Reporting VM Process ID to BOINC. 2024-09-29 20:56:03 (76404): Guest Log: BIOS: VirtualBox 7.0.6 2024-09-29 20:56:03 (76404): Guest Log: CPUID EDX: 0x178bfbff 2024-09-29 20:56:03 (76404): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-09-29 20:56:03 (76404): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-09-29 20:56:03 (76404): VM state change detected. (old = 'poweredoff', new = 'running') 2024-09-29 20:56:03 (76404): Detected: Web Application Enabled (http://localhost:55760) 2024-09-29 20:56:03 (76404): Preference change detected 2024-09-29 20:56:03 (76404): Setting CPU throttle for VM. (100%) 2024-09-29 20:56:04 (76404): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds)) 2024-09-29 20:56:04 (76404): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-09-29 20:56:04 (76404): Guest Log: BIOS: Booting from Hard Disk... 2024-09-29 20:56:06 (76404): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-09-29 20:56:06 (76404): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-09-29 20:56:41 (76404): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-09-29 20:56:41 (76404): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-09-29 20:56:45 (76404): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-09-29 20:56:45 (76404): Guest Log: 00:00:00.000185 main Log opened 2024-09-30T01:56:45.193787000Z 2024-09-29 20:56:45 (76404): Guest Log: 00:00:00.000600 main OS Product: Linux 2024-09-29 20:56:45 (76404): Guest Log: 00:00:00.000730 main OS Release: 4.14.232-19.cernvm.x86_64 2024-09-29 20:56:45 (76404): Guest Log: 00:00:00.000791 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2024-09-29 20:56:45 (76404): Guest Log: 00:00:00.000849 main Executable: /usr/sbin/VBoxService 2024-09-29 20:56:45 (76404): Guest Log: 00:00:00.000850 main Process ID: 2288 2024-09-29 20:56:45 (76404): Guest Log: 00:00:00.000851 main Package type: LINUX_64BITS_GENERIC 2024-09-29 20:56:45 (76404): Guest Log: 00:00:00.015099 main 5.2.6 r120293 started. Verbose level = 0 2024-09-29 20:57:16 (76404): Guest Log: [INFO] Mounting the shared directory 2024-09-29 20:57:16 (76404): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2024-09-29 20:57:17 (76404): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2024-09-29 20:57:17 (76404): Guest Log: [INFO] Testing connection to cern.ch 2024-09-29 20:57:18 (76404): Guest Log: [INFO] Testing connection to VCCS 2024-09-29 20:57:19 (76404): Guest Log: [INFO] Testing connection to HTCondor 2024-09-29 20:57:20 (76404): Guest Log: [INFO] Testing connection to WMAgent 2024-09-29 20:57:20 (76404): Guest Log: [INFO] Testing connection to EOSCMS 2024-09-29 20:57:21 (76404): Guest Log: [INFO] Testing connection to CMS-Factory 2024-09-29 20:57:22 (76404): Guest Log: [INFO] Testing connection to CMS-Frontier 2024-09-29 20:57:23 (76404): Guest Log: [INFO] Testing connection to Frontier 2024-09-29 20:57:24 (76404): Guest Log: [INFO] Could not find a local HTTP proxy 2024-09-29 20:57:24 (76404): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2024-09-29 20:57:24 (76404): Guest Log: [INFO] This makes the application less efficient 2024-09-29 20:57:25 (76404): Guest Log: [INFO] It also puts higher load on the project servers 2024-09-29 20:57:25 (76404): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2024-09-29 20:57:26 (76404): Guest Log: [INFO] Advice can be found in the project forum 2024-09-29 20:57:27 (76404): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2024-09-29 20:57:40 (76404): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2024-09-29 20:57:46 (76404): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2024-09-29 20:57:47 (76404): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2024-09-29 20:57:47 (76404): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2024-09-29 20:57:47 (76404): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2024-09-29 20:57:49 (76404): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2024-09-29 20:57:49 (76404): Guest Log: [INFO] 2.7.2.0 http://s1swinburne-cvmfs.openhtc.io:8080 DIRECT 2024-09-29 20:57:50 (76404): Guest Log: [INFO] Environment HTTP proxy: not set 2024-09-29 20:57:51 (76404): Guest Log: [INFO] Reading volunteer information 2024-09-29 20:58:01 (76404): Guest Log: [INFO] Using weak account key. 2024-09-29 20:58:01 (76404): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2024-09-29 20:58:04 (76404): Guest Log: [INFO] Using weak account key. 2024-09-29 20:58:05 (76404): Guest Log: [INFO] Requesting an idtoken from LHC@home 2024-09-29 20:58:06 (76404): Guest Log: [INFO] CMS application starting. Check log files. 2024-09-29 22:45:26 (76404): Status Report: Job Duration: '64800.000000' 2024-09-29 22:45:26 (76404): Status Report: Elapsed Time: '6000.014597' 2024-09-29 22:45:26 (76404): Status Report: CPU Time: '15866.500000' 2024-09-30 00:31:39 (76404): Status Report: Job Duration: '64800.000000' 2024-09-30 00:31:39 (76404): Status Report: Elapsed Time: '12000.840832' 2024-09-30 00:31:39 (76404): Status Report: CPU Time: '34440.406250' 2024-09-30 02:14:38 (76404): Status Report: Job Duration: '64800.000000' 2024-09-30 02:14:38 (76404): Status Report: Elapsed Time: '18001.536741' 2024-09-30 02:14:38 (76404): Status Report: CPU Time: '52320.109375' 2024-09-30 03:59:56 (76404): Status Report: Job Duration: '64800.000000' 2024-09-30 03:59:56 (76404): Status Report: Elapsed Time: '24001.630440' 2024-09-30 03:59:56 (76404): Status Report: CPU Time: '70871.531250' 2024-09-30 05:42:57 (76404): Status Report: Job Duration: '64800.000000' 2024-09-30 05:42:57 (76404): Status Report: Elapsed Time: '30001.916988' 2024-09-30 05:42:57 (76404): Status Report: CPU Time: '88121.921875' 2024-09-30 07:25:42 (76404): Status Report: Job Duration: '64800.000000' 2024-09-30 07:25:42 (76404): Status Report: Elapsed Time: '36001.916988' 2024-09-30 07:25:42 (76404): Status Report: CPU Time: '106321.593750' 2024-09-30 08:15:04 (76404): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 08:52:44 (76404): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 08:52:46 (76404): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 08:58:02 (76404): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 08:58:09 (76404): Guest Log: 11:18:27.807843 timesync vgsvcTimeSyncWorker: Radical host time change: 2 586 099 000 000ns (HostNow=1 727 704 689 103 000 000 ns HostLast=1 727 702 103 004 000 000 ns) 2024-09-30 08:58:19 (76404): Guest Log: 11:18:37.809081 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 586 103 398 000ns (GuestNow=1 727 704 699 104 518 000 ns GuestLast=1 727 702 113 001 120 000 ns fSetTimeLastLoop=true ) 2024-09-30 09:05:14 (76404): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 09:10:10 (76404): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 09:13:36 (76404): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 09:53:18 (76404): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 09:53:27 (76404): Guest Log: 11:29:08.303011 timesync vgsvcTimeSyncWorker: Radical host time change: 2 391 432 000 000ns (HostNow=1 727 708 006 564 000 000 ns HostLast=1 727 705 615 132 000 000 ns) 2024-09-30 09:53:36 (76404): Guest Log: 11:29:18.304191 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 686 979 035 000ns (GuestNow=1 727 708 016 571 323 000 ns GuestLast=1 727 705 329 592 288 000 ns fSetTimeLastLoop=true ) 2024-09-30 09:57:30 (76404): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 10:17:12 (76404): Stopping VM. 2024-09-30 10:17:13 (76404): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_7508bc3e2aae9a84" savestate Output: 2024-09-30 10:17:13 (76404): VM did not stop when requested. 2024-09-30 10:17:13 (76404): VM was NOT successfully terminated. 2024-09-30 11:12:10 (24008): vboxwrapper version 26207 2024-09-30 11:12:10 (24008): BOINC client version: 8.0.2 2024-09-30 11:12:12 (24008): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2024-09-30 11:12:12 (24008): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-09-30 11:12:13 (24008): Guest Log: BIOS: VirtualBox 7.0.6 2024-09-30 11:12:13 (24008): Guest Log: CPUID EDX: 0x178bfbff 2024-09-30 11:12:13 (24008): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-09-30 11:12:13 (24008): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-09-30 11:12:13 (24008): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-09-30 11:12:13 (24008): Guest Log: BIOS: Booting from Hard Disk... 2024-09-30 11:12:13 (24008): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-09-30 11:12:13 (24008): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-09-30 11:12:13 (24008): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-09-30 11:12:13 (24008): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-09-30 11:12:13 (24008): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-09-30 11:12:13 (24008): Guest Log: 00:00:00.000185 main Log opened 2024-09-30T01:56:45.193787000Z 2024-09-30 11:12:13 (24008): Guest Log: 00:00:00.000600 main OS Product: Linux 2024-09-30 11:12:13 (24008): Guest Log: 00:00:00.000730 main OS Release: 4.14.232-19.cernvm.x86_64 2024-09-30 11:12:13 (24008): Guest Log: 00:00:00.000791 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2024-09-30 11:12:13 (24008): Guest Log: 00:00:00.000849 main Executable: /usr/sbin/VBoxService 2024-09-30 11:12:13 (24008): Guest Log: 00:00:00.000850 main Process ID: 2288 2024-09-30 11:12:13 (24008): Guest Log: 00:00:00.000851 main Package type: LINUX_64BITS_GENERIC 2024-09-30 11:12:13 (24008): Guest Log: 00:00:00.015099 main 5.2.6 r120293 started. Verbose level = 0 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Mounting the shared directory 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Testing connection to cern.ch 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Testing connection to VCCS 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Testing connection to HTCondor 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Testing connection to WMAgent 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Testing connection to EOSCMS 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Testing connection to CMS-Factory 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Testing connection to CMS-Frontier 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Testing connection to Frontier 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Could not find a local HTTP proxy 2024-09-30 11:12:13 (24008): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2024-09-30 11:12:13 (24008): Guest Log: [INFO] This makes the application less efficient 2024-09-30 11:12:13 (24008): Guest Log: [INFO] It also puts higher load on the project servers 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Advice can be found in the project forum 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2024-09-30 11:12:13 (24008): Guest Log: [INFO] 2.7.2.0 http://s1swinburne-cvmfs.openhtc.io:8080 DIRECT 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Environment HTTP proxy: not set 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Reading volunteer information 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Using weak account key. 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Using weak account key. 2024-09-30 11:12:13 (24008): Guest Log: [INFO] Requesting an idtoken from LHC@home 2024-09-30 11:12:13 (24008): Guest Log: [INFO] CMS application starting. Check log files. 2024-09-30 11:12:13 (24008): Guest Log: 11:18:27.807843 timesync vgsvcTimeSyncWorker: Radical host time change: 2 586 099 000 000ns (HostNow=1 727 704 689 103 000 000 ns HostLast=1 727 702 103 004 000 000 ns) 2024-09-30 11:12:13 (24008): Guest Log: 11:18:37.809081 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 586 103 398 000ns (GuestNow=1 727 704 699 104 518 000 ns GuestLast=1 727 702 113 001 120 000 ns fSetTimeLastLoop=true ) 2024-09-30 11:12:13 (24008): Guest Log: 11:29:08.303011 timesync vgsvcTimeSyncWorker: Radical host time change: 2 391 432 000 000ns (HostNow=1 727 708 006 564 000 000 ns HostLast=1 727 705 615 132 000 000 ns) 2024-09-30 11:12:13 (24008): Guest Log: 11:29:18.304191 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 686 979 035 000ns (GuestNow=1 727 708 016 571 323 000 ns GuestLast=1 727 705 329 592 288 000 ns fSetTimeLastLoop=true ) 2024-09-30 11:12:13 (24008): Starting VM using VBoxManage interface. (boinc_7508bc3e2aae9a84, slot#15) 2024-09-30 11:12:20 (24008): Successfully started VM. (PID = '28944') 2024-09-30 11:12:20 (24008): Reporting VM Process ID to BOINC. 2024-09-30 11:12:20 (24008): Guest Log: BIOS: VirtualBox 7.0.6 2024-09-30 11:12:20 (24008): Guest Log: CPUID EDX: 0x178bfbff 2024-09-30 11:12:20 (24008): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-09-30 11:12:20 (24008): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-09-30 11:12:20 (24008): VM state change detected. (old = 'poweredoff', new = 'running') 2024-09-30 11:12:20 (24008): Detected: Web Application Enabled (http://localhost:55760) 2024-09-30 11:12:20 (24008): Status Report: Job Duration: '64800.000000' 2024-09-30 11:12:20 (24008): Status Report: Elapsed Time: '39701.369448' 2024-09-30 11:12:20 (24008): Status Report: CPU Time: '117614.484375' 2024-09-30 11:12:20 (24008): Preference change detected 2024-09-30 11:12:20 (24008): Setting CPU throttle for VM. (100%) 2024-09-30 11:12:20 (24008): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds)) 2024-09-30 11:12:22 (24008): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-09-30 11:12:22 (24008): Guest Log: BIOS: Booting from Hard Disk... 2024-09-30 11:12:24 (24008): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-09-30 11:12:24 (24008): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-09-30 11:12:49 (24008): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-09-30 11:12:49 (24008): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-09-30 11:12:50 (24008): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-09-30 11:12:50 (24008): Guest Log: 00:00:00.000174 main Log opened 2024-09-30T16:12:50.595191000Z 2024-09-30 11:12:50 (24008): Guest Log: 00:00:00.000328 main OS Product: Linux 2024-09-30 11:12:50 (24008): Guest Log: 00:00:00.000390 main OS Release: 4.14.232-19.cernvm.x86_64 2024-09-30 11:12:50 (24008): Guest Log: 00:00:00.000443 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2024-09-30 11:12:50 (24008): Guest Log: 00:00:00.000494 main Executable: /usr/sbin/VBoxService 2024-09-30 11:12:50 (24008): Guest Log: 00:00:00.000496 main Process ID: 2276 2024-09-30 11:12:50 (24008): Guest Log: 00:00:00.000497 main Package type: LINUX_64BITS_GENERIC 2024-09-30 11:12:50 (24008): Guest Log: 00:00:00.002556 main 5.2.6 r120293 started. Verbose level = 0 2024-09-30 11:13:08 (24008): Guest Log: [INFO] Mounting the shared directory 2024-09-30 11:13:08 (24008): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2024-09-30 11:13:09 (24008): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2024-09-30 11:13:09 (24008): Guest Log: [INFO] Testing connection to cern.ch 2024-09-30 11:13:09 (24008): Guest Log: [INFO] Testing connection to VCCS 2024-09-30 11:13:09 (24008): Guest Log: [INFO] Testing connection to HTCondor 2024-09-30 11:13:10 (24008): Guest Log: [INFO] Testing connection to WMAgent 2024-09-30 11:13:10 (24008): Guest Log: [INFO] Testing connection to EOSCMS 2024-09-30 11:13:11 (24008): Guest Log: [INFO] Testing connection to CMS-Factory 2024-09-30 11:13:11 (24008): Guest Log: [INFO] Testing connection to CMS-Frontier 2024-09-30 11:13:11 (24008): Guest Log: [INFO] Testing connection to Frontier 2024-09-30 11:13:12 (24008): Guest Log: [INFO] Could not find a local HTTP proxy 2024-09-30 11:13:12 (24008): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2024-09-30 11:13:12 (24008): Guest Log: [INFO] This makes the application less efficient 2024-09-30 11:13:13 (24008): Guest Log: [INFO] It also puts higher load on the project servers 2024-09-30 11:13:13 (24008): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2024-09-30 11:13:13 (24008): Guest Log: [INFO] Advice can be found in the project forum 2024-09-30 11:13:13 (24008): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2024-09-30 11:13:21 (24008): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2024-09-30 11:13:25 (24008): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2024-09-30 11:13:26 (24008): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2024-09-30 11:13:26 (24008): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2024-09-30 11:13:27 (24008): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2024-09-30 11:13:28 (24008): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2024-09-30 11:13:29 (24008): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2024-09-30 11:13:29 (24008): Guest Log: [INFO] Environment HTTP proxy: not set 2024-09-30 11:13:29 (24008): Guest Log: [INFO] Reading volunteer information 2024-09-30 11:13:31 (24008): Guest Log: [INFO] Using weak account key. 2024-09-30 11:13:31 (24008): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2024-09-30 11:13:33 (24008): Guest Log: [INFO] Using weak account key. 2024-09-30 11:13:33 (24008): Guest Log: [INFO] Requesting an idtoken from LHC@home 2024-09-30 11:13:35 (24008): Guest Log: [INFO] CMS application starting. Check log files. 2024-09-30 11:15:54 (24008): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 11:26:48 (24008): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 11:29:42 (24008): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 12:16:02 (24008): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 12:16:05 (24008): Guest Log: 00:06:00.050136 timesync vgsvcTimeSyncWorker: Radical host time change: 2 789 476 000 000ns (HostNow=1 727 716 564 603 000 000 ns HostLast=1 727 713 775 127 000 000 ns) 2024-09-30 12:16:15 (24008): Guest Log: 00:06:10.051675 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 443 959 546 000ns (GuestNow=1 727 716 574 604 600 000 ns GuestLast=1 727 713 130 645 054 000 ns fSetTimeLastLoop=true ) 2024-09-30 12:24:05 (24008): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 13:55:13 (24008): Guest Log: 00:14:00.261066 timesync vgsvcTimeSyncWorker: Radical host time change: 5 477 754 000 000ns (HostNow=1 727 722 512 573 000 000 ns HostLast=1 727 717 034 819 000 000 ns) 2024-09-30 13:55:13 (24008): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 13:55:23 (24008): Guest Log: 00:14:10.262732 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 477 761 206 000ns (GuestNow=1 727 722 522 574 936 000 ns GuestLast=1 727 717 044 813 730 000 ns fSetTimeLastLoop=true ) 2024-09-30 13:58:10 (24008): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 14:59:24 (24008): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 14:59:26 (24008): Guest Log: 00:17:00.332851 timesync vgsvcTimeSyncWorker: Radical host time change: 3 683 776 000 000ns (HostNow=1 727 726 366 422 000 000 ns HostLast=1 727 722 682 646 000 000 ns) 2024-09-30 14:59:36 (24008): Guest Log: 00:17:10.338697 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 683 782 950 000ns (GuestNow=1 727 726 376 427 922 000 ns GuestLast=1 727 722 692 644 972 000 ns fSetTimeLastLoop=true ) 2024-09-30 15:02:05 (24008): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 15:10:13 (24008): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 15:21:59 (24008): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 15:48:28 (24008): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 15:48:43 (24008): Guest Log: 00:31:40.779813 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 085 634 614 000ns (GuestNow=1 727 729 322 493 311 000 ns GuestLast=1 727 727 236 858 697 000 ns fSetTimeLastLoop=true ) 2024-09-30 15:49:46 (24008): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 15:57:11 (24008): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 16:07:08 (24008): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 16:24:01 (24008): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 17:22:40 (24008): Status Report: Job Duration: '64800.000000' 2024-09-30 17:22:40 (24008): Status Report: Elapsed Time: '45701.682780' 2024-09-30 17:22:40 (24008): Status Report: CPU Time: '135434.859375' 2024-09-30 18:40:00 (24008): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 23:39:55 (24008): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 23:39:59 (24008): Guest Log: 02:58:46.846301 timesync vgsvcTimeSyncWorker: Radical host time change: 18 005 112 000 000ns (HostNow=1 727 757 599 376 000 000 ns HostLast=1 727 739 594 264 000 000 ns) 2024-09-30 23:40:10 (24008): Guest Log: 02:58:56.848520 timesync vgsvcTimeSyncWorker: Radical guest time change: 18 005 182 101 000ns (GuestNow=1 727 757 609 378 298 000 ns GuestLast=1 727 739 604 196 197 000 ns fSetTimeLastLoop=true ) 2024-10-01 00:04:32 (24008): Status Report: Job Duration: '64800.000000' 2024-10-01 00:04:32 (24008): Status Report: Elapsed Time: '51702.625820' 2024-10-01 00:04:32 (24008): Status Report: CPU Time: '153850.406250' 2024-10-01 02:03:48 (24008): Status Report: Job Duration: '64800.000000' 2024-10-01 02:03:48 (24008): Status Report: Elapsed Time: '57703.318962' 2024-10-01 02:03:48 (24008): Status Report: CPU Time: '180210.531250' 2024-10-01 04:05:12 (24008): Guest Log: [INFO] glidein exited with return value 0. 2024-10-01 04:05:13 (24008): Guest Log: [INFO] Shutting Down. 2024-10-01 04:05:13 (24008): VM Completion File Detected. 2024-10-01 04:05:13 (24008): VM Completion Message: glidein exited with return value 0. . 2024-10-01 04:05:13 (24008): Powering off VM. 2024-10-01 04:05:16 (24008): Successfully stopped VM. 2024-10-01 04:05:16 (24008): Deregistering VM. (boinc_7508bc3e2aae9a84, slot#15) 2024-10-01 04:05:16 (24008): Removing network bandwidth throttle group from VM. 2024-10-01 04:05:17 (24008): Removing VM from VirtualBox. 2024-10-01 04:05:23 (24008): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN