Name | Theory_2743-2736713-248_1 |
Workunit | 223760401 |
Created | 27 Jun 2024, 2:17:10 UTC |
Sent | 27 Jun 2024, 6:07:45 UTC |
Report deadline | 8 Jul 2024, 6:07:45 UTC |
Received | 27 Jun 2024, 17:07:57 UTC |
Server state | Over |
Outcome | Success |
Client state | Done |
Exit status | 0 (0x00000000) |
Computer ID | 10835034 |
Run time | 4 hours 33 min 4 sec |
CPU time | 3 hours 12 min 40 sec |
Validate state | Valid |
Credit | 254.01 |
Device peak FLOPS | 6.70 GFLOPS |
Application version | Theory Simulation v300.30 (vbox64_theory) windows_x86_64 |
Peak working set size | 85.72 MB |
Peak swap size | 99.61 MB |
Peak disk usage | 1.38 GB |
<core_client_version>8.0.2</core_client_version> <![CDATA[ <stderr_txt> 2024-06-27 13:10:48 (24232): vboxwrapper version 26207 2024-06-27 13:10:48 (24232): BOINC client version: 8.0.2 2024-06-27 13:10:49 (24232): Detected: VirtualBox VboxManage Interface (Version: 7.0.14) 2024-06-27 13:10:49 (24232): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-06-27 13:10:49 (24232): Successfully copied 'init_data.xml' to the shared directory. 2024-06-27 13:10:49 (24232): Successfully copied 'input' to the shared directory. 2024-06-27 13:10:50 (24232): Create VM. (boinc_e3a90e8a9878428d, slot#0) 2024-06-27 13:10:50 (24232): Setting Memory Size for VM. (630MB) 2024-06-27 13:10:50 (24232): Setting CPU Count for VM. (1) 2024-06-27 13:10:51 (24232): Setting Chipset Options for VM. 2024-06-27 13:10:51 (24232): Setting Graphics Controller Options for VM. 2024-06-27 13:10:51 (24232): Setting Boot Options for VM. 2024-06-27 13:10:51 (24232): Setting Network Configuration for NAT. 2024-06-27 13:10:52 (24232): Enabling VM Network Access. 2024-06-27 13:10:52 (24232): Disabling USB Support for VM. 2024-06-27 13:10:52 (24232): Disabling COM Port Support for VM. 2024-06-27 13:10:52 (24232): Disabling LPT Port Support for VM. 2024-06-27 13:10:53 (24232): Disabling Audio Support for VM. 2024-06-27 13:10:53 (24232): Disabling Clipboard Support for VM. 2024-06-27 13:10:53 (24232): Disabling Drag and Drop Support for VM. 2024-06-27 13:10:53 (24232): Adding storage controller(s) to VM. 2024-06-27 13:10:54 (24232): Adding virtual disk drive to VM. (Theory_2024_04_30_prod.vdi) 2024-06-27 13:10:54 (24232): Adding VirtualBox Guest Additions to VM. 2024-06-27 13:10:54 (24232): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2024-06-27 13:10:55 (24232): forwarding host port 50473 to guest port 80 2024-06-27 13:10:55 (24232): Enabling remote desktop for VM. 2024-06-27 13:10:55 (24232): Enabling shared directory for VM. 2024-06-27 13:10:56 (24232): Starting VM using VBoxManage interface. (boinc_e3a90e8a9878428d, slot#0) 2024-06-27 13:11:02 (24232): Successfully started VM. (PID = '23640') 2024-06-27 13:11:02 (24232): Reporting VM Process ID to BOINC. 2024-06-27 13:11:02 (24232): Guest Log: BIOS: VirtualBox 7.0.14 2024-06-27 13:11:02 (24232): Guest Log: CPUID EDX: 0x178bfbff 2024-06-27 13:11:02 (24232): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-27 13:11:02 (24232): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-27 13:11:02 (24232): VM state change detected. (old = 'poweredoff', new = 'running') 2024-06-27 13:11:02 (24232): Detected: Web Application Enabled (http://localhost:50473) 2024-06-27 13:11:02 (24232): Detected: Remote Desktop Enabled (localhost:50474) 2024-06-27 13:11:02 (24232): Preference change detected 2024-06-27 13:11:02 (24232): Setting CPU throttle for VM. (70%) 2024-06-27 13:11:02 (24232): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-27 13:11:04 (24232): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-27 13:11:04 (24232): Guest Log: BIOS: Booting from Hard Disk... 2024-06-27 13:11:06 (24232): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-27 13:11:06 (24232): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-27 13:11:22 (24232): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-27 13:11:22 (24232): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-27 13:11:24 (24232): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-27 13:11:24 (24232): Guest Log: 00:00:00.000064 main Log opened 2024-06-27T10:11:23.553014000Z 2024-06-27 13:11:24 (24232): Guest Log: 00:00:00.000137 main OS Product: Linux 2024-06-27 13:11:24 (24232): Guest Log: 00:00:00.000171 main OS Release: 4.14.76-13.cernvm.x86_64 2024-06-27 13:11:24 (24232): Guest Log: 00:00:00.000193 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-06-27 13:11:24 (24232): Guest Log: 00:00:00.000213 main Executable: /usr/sbin/VBoxService 2024-06-27 13:11:24 (24232): Guest Log: 00:00:00.000214 main Process ID: 3221 2024-06-27 13:11:24 (24232): Guest Log: 00:00:00.000214 main Package type: LINUX_64BITS_GENERIC 2024-06-27 13:11:24 (24232): Guest Log: 00:00:00.014911 main 5.2.6 r120293 started. Verbose level = 0 2024-06-27 13:12:17 (24232): Guest Log: [INFO] Detected local proxy http://proxy:3128 in init_data.xml 2024-06-27 13:12:17 (24232): Guest Log: [INFO] Testing connection to proxy on port 3128 2024-06-27 13:12:18 (24232): Guest Log: [INFO] Ncat: Version 7.50 ( https://nmap.org/ncat ) 2024-06-27 13:12:18 (24232): Guest Log: Ncat: Connected to 192.168.123.108:3128. 2024-06-27 13:12:18 (24232): Guest Log: Ncat: 0 bytes sent, 0 bytes received in 0.02 seconds. 2024-06-27 13:12:18 (24232): Guest Log: [INFO] 0 2024-06-27 13:12:59 (24232): Guest Log: 12:12:59 CEST +02:00 2024-06-27: cranky: [INFO] Detected Theory App 2024-06-27 13:12:59 (24232): Guest Log: 12:12:59 CEST +02:00 2024-06-27: cranky: [INFO] Checking CVMFS. 2024-06-27 13:12:59 (24232): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-06-27 13:13:00 (24232): 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 2024-06-27 13:13:00 (24232): Guest Log: 2.5.2.0 4047 1 28112 29780 3 1 272414 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-06-27 13:13:00 (24232): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-06-27 13:13:00 (24232): 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 2024-06-27 13:13:00 (24232): Guest Log: 2.5.2.0 4072 1 29948 24575 3 1 272414 4096000 0 65024 0 2 0 2139 72 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-06-27 13:13:00 (24232): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-06-27 13:13:01 (24232): 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 2024-06-27 13:13:01 (24232): Guest Log: 2.5.2.0 4114 1 26192 272 3 1 272414 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-06-27 13:13:01 (24232): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-06-27 13:13:01 (24232): 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 2024-06-27 13:13:01 (24232): Guest Log: 2.5.2.0 4147 1 44840 19011 3 1 272414 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-06-27 13:13:01 (24232): Guest Log: 12:13:01 CEST +02:00 2024-06-27: cranky: [INFO] Checking runc. 2024-06-27 13:13:01 (24232): Guest Log: 12:13:01 CEST +02:00 2024-06-27: cranky: [INFO] Creating the filesystem. 2024-06-27 13:13:01 (24232): Guest Log: 12:13:01 CEST +02:00 2024-06-27: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-06-27 13:13:01 (24232): Guest Log: 12:13:01 CEST +02:00 2024-06-27: cranky: [INFO] Updating config.json. 2024-06-27 13:13:01 (24232): Guest Log: 12:13:01 CEST +02:00 2024-06-27: cranky: [INFO] Running Container 'runc'. 2024-06-27 13:13:04 (24232): Guest Log: job: htmld=/shared/html/job 2024-06-27 13:13:05 (24232): Guest Log: job: unpack exitcode=0 2024-06-27 13:13:05 (24232): Guest Log: INFO: activated the work-around for ld: 2024-06-27 13:13:05 (24232): Guest Log: lrwxrwxrwx 1 0 0 15 Jun 27 10:13 /tmp/tmp.zRpUHkFw0P/ld -> /usr/bin/ld.bfd 2024-06-27 13:13:06 (24232): Guest Log: 12:13:05 CEST +02:00 2024-06-27: cranky: [INFO] ===> [runRivet] Thu Jun 27 10:13:05 UTC 2024 [boinc pp jets 13000 370 - pythia8 8.243 CP5-CR1 100000 248] 2024-06-27 13:17:16 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:17:36 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:18:27 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:18:49 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:19:09 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:19:10 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:37:05 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:37:25 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:37:46 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:38:06 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:39:08 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:39:27 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:39:58 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:40:18 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:42:09 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:42:29 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:42:40 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:42:59 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:44:30 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:44:50 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:45:41 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:46:00 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:48:24 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:48:43 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:49:03 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:49:23 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:50:25 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:50:44 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:51:05 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:51:25 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:52:16 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:52:36 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:53:17 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:53:36 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:53:46 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:54:06 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:54:26 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:54:46 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:55:07 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:55:27 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:55:39 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:55:58 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:56:08 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:56:27 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:56:38 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:56:58 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:57:19 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:57:38 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:57:49 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:58:08 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 13:58:28 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 13:58:48 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:00:00 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:00:20 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:00:50 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:01:10 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:01:21 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:01:40 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:01:50 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:02:10 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:02:20 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:02:41 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:02:51 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:03:11 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:03:21 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:03:41 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:03:52 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:04:11 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:04:23 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:04:42 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:04:52 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:05:11 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:05:22 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:05:42 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:05:52 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:06:12 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:06:23 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:06:42 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:06:54 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:07:13 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:07:23 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:07:43 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:07:53 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:08:13 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:08:25 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:08:45 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:08:54 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:09:14 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:09:25 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:09:45 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:09:55 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:10:14 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:10:24 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:10:45 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:10:54 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:11:14 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:11:26 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:11:45 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:11:56 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:12:15 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:12:26 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:12:46 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:13:07 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:13:26 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:13:37 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:13:57 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:14:17 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:14:37 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:14:58 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:15:17 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:16:18 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:16:38 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:16:58 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:17:19 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:17:40 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:17:58 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:18:09 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:18:28 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:18:41 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:18:59 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:19:10 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:19:30 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:19:40 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:19:59 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:20:09 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:20:29 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:20:40 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:21:00 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:21:09 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:21:29 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:21:41 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:22:00 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:22:10 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:22:30 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:22:41 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:23:00 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:23:11 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:23:30 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:23:40 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:24:00 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:24:11 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:24:31 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:24:42 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:25:01 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:25:12 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:25:31 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:25:43 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:26:01 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:26:12 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:26:32 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:26:43 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:27:02 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:27:12 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:27:32 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:27:42 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:28:02 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:28:13 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:28:32 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:28:44 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:29:02 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:29:13 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:29:33 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:29:44 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:30:04 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:30:24 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:30:44 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:30:55 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:31:14 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:31:24 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:31:44 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:31:55 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:32:14 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:32:25 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:32:44 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:32:56 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:33:15 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:33:25 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:33:44 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:33:54 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:34:14 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:34:25 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:34:46 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:34:58 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:35:16 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:35:36 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:35:56 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:37:40 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:37:57 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:38:19 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:38:38 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:39:30 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:39:48 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:39:58 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:40:18 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:40:31 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:40:49 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:44:34 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:44:53 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:45:03 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:45:23 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:45:47 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:46:03 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:46:29 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:46:44 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:47:07 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:47:24 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:50:41 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:50:57 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 14:54:42 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 14:55:02 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:07:54 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:08:11 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:08:35 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:08:51 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:09:04 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:09:22 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:09:45 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:10:02 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:10:24 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:10:42 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:10:54 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:11:13 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:11:24 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:11:42 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:11:55 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:12:13 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:12:23 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:12:43 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:13:04 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:13:23 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:13:34 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:13:54 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:14:05 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:14:24 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:14:35 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:14:54 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:15:16 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:15:35 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:15:56 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:16:15 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:16:25 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:16:45 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:16:55 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:17:15 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:17:26 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:17:46 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:17:58 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:18:17 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:18:28 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:18:46 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:18:58 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:19:17 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:19:27 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:19:47 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:19:57 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:20:17 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:20:27 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:20:28 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:35:42 (24232): Status Report: Job Duration: '864000.000000' 2024-06-27 15:35:42 (24232): Status Report: Elapsed Time: '6000.025272' 2024-06-27 15:35:42 (24232): Status Report: CPU Time: '3949.125000' 2024-06-27 15:40:06 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:40:26 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:40:48 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:41:07 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:42:03 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:42:38 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:45:21 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:45:41 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:53:40 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:53:58 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:54:08 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:54:28 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:54:50 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:55:10 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:55:29 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:55:48 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:56:51 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:57:11 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 15:57:40 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 15:58:00 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 16:07:00 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 16:07:19 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 16:10:02 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 16:10:21 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 16:10:31 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 16:10:51 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 16:17:24 (24232): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 16:17:44 (24232): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 16:53:42 (24232): Stopping VM. 2024-06-27 16:53:44 (24232): Successfully stopped VM. 2024-06-27 17:26:38 (26504): vboxwrapper version 26207 2024-06-27 17:26:38 (26504): BOINC client version: 8.0.2 2024-06-27 17:26:39 (26504): Detected: VirtualBox VboxManage Interface (Version: 7.0.14) 2024-06-27 17:26:39 (26504): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-06-27 17:26:40 (26504): Guest Log: BIOS: VirtualBox 7.0.14 2024-06-27 17:26:40 (26504): Guest Log: CPUID EDX: 0x178bfbff 2024-06-27 17:26:40 (26504): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-27 17:26:40 (26504): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-27 17:26:40 (26504): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-27 17:26:40 (26504): Guest Log: BIOS: Booting from Hard Disk... 2024-06-27 17:26:40 (26504): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-27 17:26:40 (26504): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-27 17:26:40 (26504): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-27 17:26:40 (26504): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-27 17:26:40 (26504): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-27 17:26:40 (26504): Guest Log: 00:00:00.000064 main Log opened 2024-06-27T10:11:23.553014000Z 2024-06-27 17:26:40 (26504): Guest Log: 00:00:00.000137 main OS Product: Linux 2024-06-27 17:26:40 (26504): Guest Log: 00:00:00.000171 main OS Release: 4.14.76-13.cernvm.x86_64 2024-06-27 17:26:40 (26504): Guest Log: 00:00:00.000193 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-06-27 17:26:40 (26504): Guest Log: 00:00:00.000213 main Executable: /usr/sbin/VBoxService 2024-06-27 17:26:40 (26504): Guest Log: 00:00:00.000214 main Process ID: 3221 2024-06-27 17:26:40 (26504): Guest Log: 00:00:00.000214 main Package type: LINUX_64BITS_GENERIC 2024-06-27 17:26:40 (26504): Guest Log: 00:00:00.014911 main 5.2.6 r120293 started. Verbose level = 0 2024-06-27 17:26:40 (26504): Guest Log: [INFO] Detected local proxy http://proxy:3128 in init_data.xml 2024-06-27 17:26:40 (26504): Guest Log: [INFO] Testing connection to proxy on port 3128 2024-06-27 17:26:40 (26504): Guest Log: [INFO] Ncat: Version 7.50 ( https://nmap.org/ncat ) 2024-06-27 17:26:40 (26504): Guest Log: Ncat: Connected to 192.168.123.108:3128. 2024-06-27 17:26:40 (26504): Guest Log: Ncat: 0 bytes sent, 0 bytes received in 0.02 seconds. 2024-06-27 17:26:40 (26504): Guest Log: [INFO] 0 2024-06-27 17:26:40 (26504): Guest Log: 12:12:59 CEST +02:00 2024-06-27: cranky: [INFO] Detected Theory App 2024-06-27 17:26:40 (26504): Guest Log: 12:12:59 CEST +02:00 2024-06-27: cranky: [INFO] Checking CVMFS. 2024-06-27 17:26:40 (26504): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-06-27 17:26:40 (26504): 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 2024-06-27 17:26:40 (26504): Guest Log: 2.5.2.0 4047 1 28112 29780 3 1 272414 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-06-27 17:26:40 (26504): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2024-06-27 17:26:40 (26504): 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 2024-06-27 17:26:40 (26504): Guest Log: 2.5.2.0 4072 1 29948 24575 3 1 272414 4096000 0 65024 0 2 0 2139 72 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2024-06-27 17:26:40 (26504): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2024-06-27 17:26:40 (26504): 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 2024-06-27 17:26:40 (26504): Guest Log: 2.5.2.0 4114 1 26192 272 3 1 272414 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2024-06-27 17:26:40 (26504): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2024-06-27 17:26:40 (26504): 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 2024-06-27 17:26:40 (26504): Guest Log: 2.5.2.0 4147 1 44840 19011 3 1 272414 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2024-06-27 17:26:40 (26504): Guest Log: 12:13:01 CEST +02:00 2024-06-27: cranky: [INFO] Checking runc. 2024-06-27 17:26:40 (26504): Guest Log: 12:13:01 CEST +02:00 2024-06-27: cranky: [INFO] Creating the filesystem. 2024-06-27 17:26:40 (26504): Guest Log: 12:13:01 CEST +02:00 2024-06-27: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2024-06-27 17:26:40 (26504): Guest Log: 12:13:01 CEST +02:00 2024-06-27: cranky: [INFO] Updating config.json. 2024-06-27 17:26:40 (26504): Guest Log: 12:13:01 CEST +02:00 2024-06-27: cranky: [INFO] Running Container 'runc'. 2024-06-27 17:26:40 (26504): Guest Log: job: htmld=/shared/html/job 2024-06-27 17:26:40 (26504): Guest Log: job: unpack exitcode=0 2024-06-27 17:26:40 (26504): Guest Log: INFO: activated the work-around for ld: 2024-06-27 17:26:40 (26504): Guest Log: lrwxrwxrwx 1 0 0 15 Jun 27 10:13 /tmp/tmp.zRpUHkFw0P/ld -> /usr/bin/ld.bfd 2024-06-27 17:26:40 (26504): Guest Log: 12:13:05 CEST +02:00 2024-06-27: cranky: [INFO] ===> [runRivet] Thu Jun 27 10:13:05 UTC 2024 [boinc pp jets 13000 370 - pythia8 8.243 CP5-CR1 100000 248] 2024-06-27 17:26:40 (26504): Starting VM using VBoxManage interface. (boinc_e3a90e8a9878428d, slot#0) 2024-06-27 17:26:46 (26504): Successfully started VM. (PID = '5480') 2024-06-27 17:26:46 (26504): Reporting VM Process ID to BOINC. 2024-06-27 17:26:46 (26504): VM state change detected. (old = 'poweredoff', new = 'running') 2024-06-27 17:26:46 (26504): Detected: Web Application Enabled (http://localhost:50473) 2024-06-27 17:26:46 (26504): Detected: Remote Desktop Enabled (localhost:50474) 2024-06-27 17:26:46 (26504): Status Report: Job Duration: '864000.000000' 2024-06-27 17:26:46 (26504): Status Report: Elapsed Time: '10361.038460' 2024-06-27 17:26:46 (26504): Status Report: CPU Time: '6484.187500' 2024-06-27 17:26:46 (26504): Preference change detected 2024-06-27 17:26:46 (26504): Setting CPU throttle for VM. (70%) 2024-06-27 17:26:46 (26504): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-27 17:26:48 (26504): Guest Log: 02:57:46.482911 timesync vgsvcTimeSyncWorker: Radical host time change: 1 992 952 000 000ns (HostNow=1 719 498 408 241 000 000 ns HostLast=1 719 496 415 289 000 000 ns) 2024-06-27 17:26:58 (26504): Guest Log: 02:57:56.507695 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 234 413 236 000ns (GuestNow=1 719 498 418 297 537 000 ns GuestLast=1 719 496 183 884 301 000 ns fSetTimeLastLoop=true ) 2024-06-27 17:27:07 (26504): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 17:27:28 (26504): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 17:35:13 (26504): Stopping VM. 2024-06-27 17:35:16 (26504): Successfully stopped VM. 2024-06-27 17:37:09 (6980): vboxwrapper version 26207 2024-06-27 17:37:09 (6980): BOINC client version: 8.0.2 2024-06-27 17:37:09 (6980): Detected: VirtualBox VboxManage Interface (Version: 7.0.14) 2024-06-27 17:37:10 (6980): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-06-27 17:37:11 (6980): Guest Log: 02:57:46.482911 timesync vgsvcTimeSyncWorker: Radical host time change: 1 992 952 000 000ns (HostNow=1 719 498 408 241 000 000 ns HostLast=1 719 496 415 289 000 000 ns) 2024-06-27 17:37:11 (6980): Guest Log: 02:57:56.507695 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 234 413 236 000ns (GuestNow=1 719 498 418 297 537 000 ns GuestLast=1 719 496 183 884 301 000 ns fSetTimeLastLoop=true ) 2024-06-27 17:37:11 (6980): Starting VM using VBoxManage interface. (boinc_e3a90e8a9878428d, slot#0) 2024-06-27 17:37:17 (6980): Successfully started VM. (PID = '19952') 2024-06-27 17:37:17 (6980): Reporting VM Process ID to BOINC. 2024-06-27 17:37:17 (6980): VM state change detected. (old = 'poweredoff', new = 'running') 2024-06-27 17:37:17 (6980): Detected: Web Application Enabled (http://localhost:50473) 2024-06-27 17:37:17 (6980): Detected: Remote Desktop Enabled (localhost:50474) 2024-06-27 17:37:17 (6980): Status Report: Job Duration: '864000.000000' 2024-06-27 17:37:17 (6980): Status Report: Elapsed Time: '10846.038460' 2024-06-27 17:37:17 (6980): Status Report: CPU Time: '6706.296875' 2024-06-27 17:37:17 (6980): Preference change detected 2024-06-27 17:37:17 (6980): Setting CPU throttle for VM. (70%) 2024-06-27 17:37:17 (6980): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-27 17:40:42 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 17:41:02 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 17:44:34 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 17:44:54 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 17:47:26 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 17:47:46 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:02:27 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:02:47 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:03:29 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:03:48 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:05:42 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:06:01 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:06:21 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:06:40 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:07:03 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:07:21 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:07:33 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:07:52 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:08:12 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:08:31 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:08:52 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:09:12 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:09:33 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:09:52 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:10:02 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:10:22 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:10:34 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:10:52 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:11:02 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:11:22 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:11:43 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:12:03 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:22:22 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:22:42 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:23:04 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:23:22 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:42:10 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:42:30 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:42:51 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:43:11 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:43:31 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:43:51 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 18:44:11 (6980): VM state change detected. (old = 'running', new = 'paused') 2024-06-27 18:44:32 (6980): VM state change detected. (old = 'paused', new = 'running') 2024-06-27 19:25:03 (6980): Status Report: Job Duration: '864000.000000' 2024-06-27 19:25:03 (6980): Status Report: Elapsed Time: '16846.049586' 2024-06-27 19:25:03 (6980): Status Report: CPU Time: '10608.031250' 2024-06-27 19:51:35 (6980): Guest Log: job: run exitcode=0 2024-06-27 19:51:35 (6980): Guest Log: job: diskusage=3408 2024-06-27 19:51:35 (6980): Guest Log: job: logsize=76 k 2024-06-27 19:51:35 (6980): Guest Log: job: times= 2024-06-27 19:51:35 (6980): Guest Log: 0m0.008s 0m0.004s 2024-06-27 19:51:35 (6980): Guest Log: 305m26.155s 0m43.350s 2024-06-27 19:51:35 (6980): Guest Log: job: cpuusage=18370 2024-06-27 19:51:36 (6980): Guest Log: 18:44:36 CEST +02:00 2024-06-27: cranky: [INFO] Container 'runc' finished with status code 0. 2024-06-27 19:51:36 (6980): Guest Log: 18:44:36 CEST +02:00 2024-06-27: cranky: [INFO] Preparing output. 2024-06-27 19:51:37 (6980): Guest Log: [INFO] Job Finished 2024-06-27 19:51:37 (6980): Guest Log: [INFO] Shutting Down. 2024-06-27 19:51:37 (6980): VM Completion File Detected. 2024-06-27 19:51:37 (6980): VM Completion Message: Job Finished . 2024-06-27 19:51:37 (6980): Powering off VM. 2024-06-27 19:51:37 (6980): Successfully stopped VM. 2024-06-27 19:51:37 (6980): Deregistering VM. (boinc_e3a90e8a9878428d, slot#0) 2024-06-27 19:51:37 (6980): Removing network bandwidth throttle group from VM. 2024-06-27 19:51:38 (6980): Removing VM from VirtualBox. 2024-06-27 19:51:43 (6980): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN