Name | CMS_3862526_1749401624.795120_0 |
Workunit | 232938878 |
Created | 8 Jun 2025, 16:53:46 UTC |
Sent | 8 Jun 2025, 17:36:15 UTC |
Report deadline | 9 Jul 2025, 17:36:15 UTC |
Received | 9 Jun 2025, 15:43:33 UTC |
Server state | Over |
Outcome | Success |
Client state | Done |
Exit status | 0 (0x00000000) |
Computer ID | 10871187 |
Run time | 30 min 55 sec |
CPU time | 2 min 50 sec |
Validate state | Valid |
Credit | 337.75 |
Device peak FLOPS | 21.17 GFLOPS |
Application version | CMS Simulation v70.91 (vbox64_mt_mcore_cms) windows_x86_64 |
Peak working set size | 87.39 MB |
Peak swap size | 87.35 MB |
Peak disk usage | 1,011.52 MB |
<core_client_version>8.0.2</core_client_version> <![CDATA[ <stderr_txt> 2025-06-09 02:25:18 (41864): vboxwrapper version 26210 2025-06-09 02:25:18 (41864): BOINC client version: 8.0.2 2025-06-09 02:25:19 (41864): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2025-06-09 02:25:19 (41864): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-06-09 02:25:19 (41864): Successfully copied 'init_data.xml' to the shared directory. 2025-06-09 02:25:19 (41864): Create VM. (boinc_ace868c6a998deac, slot#0) 2025-06-09 02:25:20 (41864): Setting Memory Size for VM. (4584MB) 2025-06-09 02:25:20 (41864): Setting CPU Count for VM. (4) 2025-06-09 02:25:20 (41864): Setting Chipset Options for VM. 2025-06-09 02:25:21 (41864): Setting Graphics Controller Options for VM. (Driver: VBoxVGA, 16MB) 2025-06-09 02:25:21 (41864): Setting Boot Options for VM. 2025-06-09 02:25:22 (41864): Setting Network Configuration for NAT. (Driver: virtio) 2025-06-09 02:25:22 (41864): Enabling VM Network Access. 2025-06-09 02:25:22 (41864): Disabling USB Support for VM. 2025-06-09 02:25:22 (41864): Disabling COM Port Support for VM. 2025-06-09 02:25:23 (41864): Disabling LPT Port Support for VM. 2025-06-09 02:25:23 (41864): Disabling Audio Support for VM. 2025-06-09 02:25:23 (41864): Disabling Clipboard Support for VM. 2025-06-09 02:25:24 (41864): Disabling Drag and Drop Support for VM. 2025-06-09 02:25:24 (41864): Adding storage controller(s) to VM. 2025-06-09 02:25:24 (41864): Adding virtual disk drive to VM. (CMS_2025_04_08_prod.vdi) 2025-06-09 02:25:27 (41864): Adding VirtualBox Guest Additions to VM. 2025-06-09 02:25:27 (41864): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2025-06-09 02:25:27 (41864): forwarding host port 59738 to guest port 80 2025-06-09 02:25:27 (41864): Enabling remote desktop for VM. 2025-06-09 02:25:28 (41864): Required extension pack not installed, remote desktop not enabled. 2025-06-09 02:25:28 (41864): Enabling shared directory for VM. 2025-06-09 02:25:28 (41864): Starting VM using VBoxManage interface. (boinc_ace868c6a998deac, slot#0) 2025-06-09 02:25:34 (41864): Successfully started VM. (PID = '36028') 2025-06-09 02:25:34 (41864): Reporting VM Process ID to BOINC. 2025-06-09 02:25:34 (41864): Guest Log: BIOS: VirtualBox 7.0.6 2025-06-09 02:25:34 (41864): Guest Log: CPUID EDX: 0x178bfbff 2025-06-09 02:25:34 (41864): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-06-09 02:25:34 (41864): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-06-09 02:25:34 (41864): VM state change detected. (old = 'poweredoff', new = 'running') 2025-06-09 02:25:34 (41864): Detected: Web Application Enabled (http://localhost:59738) 2025-06-09 02:25:35 (41864): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 02:25:49 (41864): Preference change detected 2025-06-09 02:25:49 (41864): Setting CPU throttle for VM. (100%) 2025-06-09 02:25:49 (41864): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds)) 2025-06-09 02:25:50 (41864): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 02:25:51 (41864): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-06-09 02:25:51 (41864): Guest Log: BIOS: Booting from Hard Disk... 2025-06-09 02:25:53 (41864): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-06-09 02:25:53 (41864): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-06-09 02:26:00 (41864): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 02:26:20 (41864): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 02:26:41 (41864): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-06-09 02:26:41 (41864): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-06-09 02:26:42 (41864): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-06-09 02:26:42 (41864): Guest Log: 00:00:00.000075 main Log opened 2025-06-08T18:26:41.570039000Z 2025-06-09 02:26:42 (41864): Guest Log: 00:00:00.000175 main OS Product: Linux 2025-06-09 02:26:42 (41864): Guest Log: 00:00:00.000194 main OS Release: 4.14.232-19.cernvm.x86_64 2025-06-09 02:26:42 (41864): Guest Log: 00:00:00.000210 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-06-09 02:26:42 (41864): Guest Log: 00:00:00.000225 main Executable: /usr/sbin/VBoxService 2025-06-09 02:26:42 (41864): Guest Log: 00:00:00.000226 main Process ID: 2291 2025-06-09 02:26:42 (41864): Guest Log: 00:00:00.000226 main Package type: LINUX_64BITS_GENERIC 2025-06-09 02:26:42 (41864): Guest Log: 00:00:00.000606 main 5.2.6 r120293 started. Verbose level = 0 2025-06-09 02:26:50 (41864): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 02:27:50 (41864): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 02:27:56 (41864): Guest Log: [INFO] Mounting the shared directory 2025-06-09 02:27:56 (41864): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-06-09 02:27:56 (41864): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-06-09 02:27:57 (41864): Guest Log: [INFO] Testing connection to cern.ch 2025-06-09 02:27:57 (41864): Guest Log: [INFO] Testing connection to VCCS 2025-06-09 02:27:57 (41864): Guest Log: [INFO] Testing connection to HTCondor 2025-06-09 02:27:58 (41864): Guest Log: [INFO] Testing connection to WMAgent 2025-06-09 02:27:59 (41864): Guest Log: [INFO] Testing connection to EOSCMS 2025-06-09 02:27:59 (41864): Guest Log: [INFO] Testing connection to CMS-Factory 2025-06-09 02:28:00 (41864): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 02:28:20 (41864): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-06-09 02:28:20 (41864): Guest Log: [INFO] Testing connection to Frontier 2025-06-09 02:28:20 (41864): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 02:28:21 (41864): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2025-06-09 02:28:23 (41864): Guest Log: [INFO] Could not find a local HTTP proxy 2025-06-09 02:28:23 (41864): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-06-09 02:28:23 (41864): Guest Log: [INFO] This makes the application less efficient 2025-06-09 02:28:23 (41864): Guest Log: [INFO] It also puts higher load on the project servers 2025-06-09 02:28:23 (41864): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-06-09 02:28:23 (41864): Guest Log: [INFO] Advice can be found in the project forum 2025-06-09 02:28:25 (41864): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-06-09 02:28:30 (41864): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 02:29:10 (41864): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-06-09 02:29:10 (41864): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 02:29:13 (41864): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-06-09 02:29:13 (41864): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-06-09 02:29:14 (41864): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-06-09 02:29:15 (41864): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-06-09 02:29:16 (41864): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-06-09 02:29:16 (41864): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2025-06-09 02:29:16 (41864): Guest Log: [INFO] Environment HTTP proxy: not set 2025-06-09 02:29:16 (41864): Guest Log: [INFO] Reading volunteer information 2025-06-09 02:29:20 (41864): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 02:29:40 (41864): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 02:29:50 (41864): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 02:30:50 (41864): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 02:31:44 (41864): Guest Log: [INFO] Using weak account key. 2025-06-09 02:31:44 (41864): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-06-09 02:31:47 (41864): Guest Log: [INFO] Using weak account key. 2025-06-09 02:31:48 (41864): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-06-09 02:31:49 (41864): Guest Log: [INFO] CMS application starting. Check log files. 2025-06-09 02:32:00 (41864): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 02:32:20 (41864): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 02:32:40 (41864): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 02:33:00 (41864): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 02:33:10 (41864): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 02:33:30 (41864): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 02:34:10 (41864): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 02:34:30 (41864): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 02:35:40 (41864): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 02:36:00 (41864): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 02:36:50 (41864): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 02:37:10 (41864): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:00:11 (2292): vboxwrapper version 26210 2025-06-09 21:00:11 (2292): BOINC client version: 8.0.2 2025-06-09 21:00:16 (2292): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2025-06-09 21:00:16 (2292): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-06-09 21:00:17 (2292): Guest Log: BIOS: VirtualBox 7.0.6 2025-06-09 21:00:17 (2292): Guest Log: CPUID EDX: 0x178bfbff 2025-06-09 21:00:17 (2292): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-06-09 21:00:17 (2292): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-06-09 21:00:17 (2292): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-06-09 21:00:17 (2292): Guest Log: BIOS: Booting from Hard Disk... 2025-06-09 21:00:17 (2292): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-06-09 21:00:17 (2292): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-06-09 21:00:17 (2292): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-06-09 21:00:17 (2292): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-06-09 21:00:17 (2292): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-06-09 21:00:17 (2292): Guest Log: 00:00:00.000175 main OS Product: Linux 2025-06-09 21:00:17 (2292): Guest Log: 00:00:00.000194 main OS Release: 4.14.232-19.cernvm.x86_64 2025-06-09 21:00:17 (2292): Guest Log: 00:00:00.000210 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-06-09 21:00:17 (2292): Guest Log: 00:00:00.000225 main Executable: /usr/sbin/VBoxService 2025-06-09 21:00:17 (2292): Guest Log: 00:00:00.000226 main Process ID: 2291 2025-06-09 21:00:17 (2292): Guest Log: 00:00:00.000226 main Package type: LINUX_64BITS_GENERIC 2025-06-09 21:00:17 (2292): Guest Log: 00:00:00.000606 main 5.2.6 r120293 started. Verbose level = 0 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Mounting the shared directory 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Testing connection to cern.ch 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Testing connection to VCCS 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Testing connection to HTCondor 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Testing connection to WMAgent 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Testing connection to EOSCMS 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Testing connection to CMS-Factory 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Testing connection to Frontier 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Could not find a local HTTP proxy 2025-06-09 21:00:17 (2292): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-06-09 21:00:17 (2292): Guest Log: [INFO] This makes the application less efficient 2025-06-09 21:00:17 (2292): Guest Log: [INFO] It also puts higher load on the project servers 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Advice can be found in the project forum 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-06-09 21:00:17 (2292): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Environment HTTP proxy: not set 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Reading volunteer information 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Using weak account key. 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Using weak account key. 2025-06-09 21:00:17 (2292): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-06-09 21:00:17 (2292): Guest Log: [INFO] CMS application starting. Check log files. 2025-06-09 21:00:17 (2292): Starting VM using VBoxManage interface. (boinc_ace868c6a998deac, slot#0) 2025-06-09 21:00:27 (2292): Successfully started VM. (PID = '18044') 2025-06-09 21:00:27 (2292): Reporting VM Process ID to BOINC. 2025-06-09 21:00:27 (2292): VM state change detected. (old = 'poweredoff', new = 'running') 2025-06-09 21:00:27 (2292): Detected: Web Application Enabled (http://localhost:59738) 2025-06-09 21:00:28 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:01:22 (2292): Preference change detected 2025-06-09 21:01:22 (2292): Setting CPU throttle for VM. (100%) 2025-06-09 21:01:22 (2292): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds)) 2025-06-09 21:01:23 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:01:24 (2292): Guest Log: 00:06:50.389846 timesync vgsvcTimeSyncWorker: Radical host time change: 66 161 661 000 000ns (HostNow=1 749 474 083 377 000 000 ns HostLast=1 749 407 921 716 000 000 ns) 2025-06-09 21:01:34 (2292): Guest Log: 00:07:00.392870 timesync vgsvcTimeSyncWorker: Radical guest time change: 66 481 420 594 000ns (GuestNow=1 749 474 093 380 188 000 ns GuestLast=1 749 407 611 959 594 000 ns fSetTimeLastLoop=true ) 2025-06-09 21:01:53 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:02:13 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:05:23 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:05:43 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:06:03 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:06:23 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:07:03 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:07:23 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:07:43 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:08:03 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:08:18 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:08:54 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:09:04 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:09:24 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:09:34 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:09:54 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:10:04 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:10:24 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:10:34 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:10:54 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:11:04 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:11:24 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:11:34 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:11:54 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:12:04 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:12:24 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:12:34 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:12:54 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:13:04 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:13:24 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:13:34 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:13:54 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:14:04 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:14:24 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:14:34 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:14:54 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:15:04 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:15:44 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:15:54 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:16:34 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:16:44 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:17:04 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:17:14 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:17:34 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:17:44 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:18:04 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:18:14 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:18:34 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:18:44 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:19:04 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:19:14 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:19:34 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:19:44 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:20:04 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:20:14 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:20:38 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:20:45 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:21:05 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:21:15 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:21:35 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:21:45 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:23:05 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:23:15 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:23:35 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:23:45 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:24:05 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:24:15 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:24:35 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:24:45 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:25:05 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:25:15 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:25:35 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:25:45 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:26:05 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:26:15 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:26:35 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:26:45 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:27:05 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:27:15 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:28:15 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:28:25 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:28:45 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:28:55 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:29:15 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:29:25 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:29:45 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:29:55 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:30:15 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:30:25 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:30:45 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:30:55 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:31:15 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:31:25 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:31:45 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:31:55 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:32:15 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:32:25 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:32:45 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:32:55 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:33:15 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:33:25 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:33:45 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:33:55 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:34:15 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:34:25 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:34:45 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:34:55 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:35:15 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:35:25 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:35:45 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:35:55 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:36:15 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:36:25 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:48:06 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:48:16 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:48:36 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:48:46 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:50:26 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:50:36 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:52:17 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:52:27 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 21:55:47 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 21:55:57 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 22:00:37 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 22:00:47 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 22:52:30 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 22:52:37 (2292): Guest Log: 00:21:41.173009 timesync vgsvcTimeSyncWorker: Radical host time change: 3 112 310 000 000ns (HostNow=1 749 480 756 971 000 000 ns HostLast=1 749 477 644 661 000 000 ns) 2025-06-09 22:52:40 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 22:53:00 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 22:53:07 (2292): Guest Log: 00:21:51.176107 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 112 312 631 000ns (GuestNow=1 749 480 766 974 140 000 ns GuestLast=1 749 477 654 661 509 000 ns fSetTimeLastLoop=true ) 2025-06-09 22:53:10 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 22:53:30 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 22:53:40 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 22:54:00 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 22:54:10 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 22:54:50 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 22:55:00 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 22:55:20 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 22:55:30 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 22:55:50 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 22:56:00 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 22:59:00 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 22:59:10 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 22:59:30 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 22:59:40 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:00:00 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:00:10 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:00:50 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:01:00 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:02:00 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:02:10 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:03:30 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:03:40 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:04:00 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:04:10 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:04:50 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:05:00 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:05:40 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:05:50 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:06:10 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:06:20 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:06:40 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:06:50 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:07:10 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:07:20 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:08:40 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:08:50 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:09:10 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:09:20 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:09:40 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:09:50 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:10:10 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:10:20 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:15:00 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:15:10 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:16:30 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:16:41 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:17:00 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:17:11 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:17:31 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:17:40 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:18:01 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:18:11 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:18:30 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:18:41 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:19:01 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:19:11 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:19:31 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:19:41 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:20:01 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:20:11 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:20:31 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:20:41 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:21:01 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:21:11 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:21:31 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:21:41 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:22:01 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:22:11 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:22:31 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:22:41 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:23:01 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:23:11 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:26:31 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:26:41 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:28:22 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:28:32 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:28:52 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:29:02 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:29:22 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:29:32 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:29:52 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:30:02 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:30:22 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:30:32 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:30:52 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:31:02 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:31:22 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:31:32 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:31:52 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:32:02 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:32:22 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:32:32 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:32:52 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:33:02 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:34:22 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:34:32 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:36:32 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:36:42 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:37:02 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:37:12 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:37:32 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:37:42 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:40:02 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:40:12 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:40:32 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:40:42 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:41:02 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:41:12 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:42:12 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:42:22 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:42:42 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:42:52 (2292): VM state change detected. (old = 'running', new = 'paused') 2025-06-09 23:43:12 (2292): VM state change detected. (old = 'paused', new = 'running') 2025-06-09 23:43:21 (2292): Guest Log: [INFO] glidein exited with return value 0. 2025-06-09 23:43:21 (2292): Guest Log: [INFO] Shutting Down. 2025-06-09 23:43:21 (2292): VM Completion File Detected. 2025-06-09 23:43:21 (2292): VM Completion Message: glidein exited with return value 0. . 2025-06-09 23:43:21 (2292): Powering off VM. 2025-06-09 23:43:22 (2292): Successfully stopped VM. 2025-06-09 23:43:22 (2292): Deregistering VM. (boinc_ace868c6a998deac, slot#0) 2025-06-09 23:43:22 (2292): Removing network bandwidth throttle group from VM. 2025-06-09 23:43:22 (2292): Removing VM from VirtualBox. 2025-06-09 23:43:28 (2292): called boinc_finish(0) </stderr_txt> ]]>
©2025 CERN