| Name | Theory_2773-2945257-356_1 |
| Workunit | 229866221 |
| Created | 20 Jan 2025, 20:38:12 UTC |
| Sent | 21 Jan 2025, 2:32:51 UTC |
| Report deadline | 1 Feb 2025, 2:32:51 UTC |
| Received | 21 Jan 2025, 10:19:55 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10835034 |
| Run time | 3 hours 51 min 41 sec |
| CPU time | 2 hours 11 min 24 sec |
| Validate state | Valid |
| Credit | 32.18 |
| Device peak FLOPS | 1.00 GFLOPS |
| Application version | Theory Simulation v300.60 (vbox64_theory) windows_x86_64 |
| Peak working set size | 84.10 MB |
| Peak swap size | 99.05 MB |
| Peak disk usage | 2.09 GB |
Stderr output
<core_client_version>8.0.2</core_client_version> <![CDATA[ <stderr_txt> 2025-01-21 06:08:13 (27540): vboxwrapper version 26208 2025-01-21 06:08:13 (27540): BOINC client version: 8.0.2 2025-01-21 06:08:14 (27540): Detected: VirtualBox VboxManage Interface (Version: 7.0.22) 2025-01-21 06:08:14 (27540): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-21 06:08:14 (27540): Successfully copied 'init_data.xml' to the shared directory. 2025-01-21 06:08:14 (27540): Successfully copied 'input' to the shared directory. 2025-01-21 06:08:14 (27540): Create VM. (boinc_88895359aba7745d, slot#9) 2025-01-21 06:08:15 (27540): Setting Memory Size for VM. (630MB) 2025-01-21 06:08:15 (27540): Setting CPU Count for VM. (1) 2025-01-21 06:08:16 (27540): Setting Chipset Options for VM. 2025-01-21 06:08:16 (27540): Setting Graphics Controller Options for VM. 2025-01-21 06:08:16 (27540): Setting Boot Options for VM. 2025-01-21 06:08:16 (27540): Setting Network Configuration for NAT. 2025-01-21 06:08:17 (27540): Enabling VM Network Access. 2025-01-21 06:08:17 (27540): Disabling USB Support for VM. 2025-01-21 06:08:17 (27540): Disabling COM Port Support for VM. 2025-01-21 06:08:17 (27540): Disabling LPT Port Support for VM. 2025-01-21 06:08:18 (27540): Disabling Audio Support for VM. 2025-01-21 06:08:18 (27540): Disabling Clipboard Support for VM. 2025-01-21 06:08:18 (27540): Disabling Drag and Drop Support for VM. 2025-01-21 06:08:18 (27540): Adding storage controller(s) to VM. 2025-01-21 06:08:19 (27540): Adding virtual disk drive to VM. (Theory_2025_01_16_prod.vdi) 2025-01-21 06:08:19 (27540): Adding VirtualBox Guest Additions to VM. 2025-01-21 06:08:19 (27540): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2025-01-21 06:08:20 (27540): forwarding host port 51684 to guest port 80 2025-01-21 06:08:20 (27540): Enabling remote desktop for VM. 2025-01-21 06:08:20 (27540): Enabling shared directory for VM. 2025-01-21 06:08:21 (27540): Starting VM using VBoxManage interface. (boinc_88895359aba7745d, slot#9) 2025-01-21 06:08:28 (27540): Successfully started VM. (PID = '27412') 2025-01-21 06:08:28 (27540): Reporting VM Process ID to BOINC. 2025-01-21 06:08:28 (27540): Guest Log: BIOS: VirtualBox 7.0.22 2025-01-21 06:08:28 (27540): Guest Log: CPUID EDX: 0x178bfbff 2025-01-21 06:08:28 (27540): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-01-21 06:08:28 (27540): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-01-21 06:08:28 (27540): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-21 06:08:28 (27540): Detected: Web Application Enabled (http://localhost:51684) 2025-01-21 06:08:28 (27540): Detected: Remote Desktop Enabled (localhost:51685) 2025-01-21 06:08:28 (27540): Preference change detected 2025-01-21 06:08:28 (27540): Setting CPU throttle for VM. (90%) 2025-01-21 06:08:28 (27540): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-21 06:08:30 (27540): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-01-21 06:08:30 (27540): Guest Log: BIOS: Booting from Hard Disk... 2025-01-21 06:08:32 (27540): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-01-21 06:08:32 (27540): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-01-21 06:08:35 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:08:55 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:09:23 (27540): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-01-21 06:09:23 (27540): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-01-21 06:09:25 (27540): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-01-21 06:09:25 (27540): Guest Log: 00:00:00.000084 main Log opened 2025-01-21T04:09:24.799486000Z 2025-01-21 06:09:25 (27540): Guest Log: 00:00:00.000162 main OS Product: Linux 2025-01-21 06:09:25 (27540): Guest Log: 00:00:00.000197 main OS Release: 4.14.76-13.cernvm.x86_64 2025-01-21 06:09:25 (27540): Guest Log: 00:00:00.000219 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2025-01-21 06:09:25 (27540): Guest Log: 00:00:00.000250 main Executable: /usr/sbin/VBoxService 2025-01-21 06:09:25 (27540): Guest Log: 00:00:00.000250 main Process ID: 3225 2025-01-21 06:09:25 (27540): Guest Log: 00:00:00.000250 main Package type: LINUX_64BITS_GENERIC 2025-01-21 06:09:25 (27540): Guest Log: 00:00:00.003235 main 5.2.6 r120293 started. Verbose level = 0 2025-01-21 06:09:36 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:09:56 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:10:13 (27540): Guest Log: [INFO] Detected local proxy http://proxy:3128 in init_data.xml 2025-01-21 06:10:13 (27540): Guest Log: [INFO] Testing connection to proxy on port 3128 2025-01-21 06:10:15 (27540): Guest Log: [INFO] Ncat: Version 7.50 ( https://nmap.org/ncat ) 2025-01-21 06:10:15 (27540): Guest Log: Ncat: Connected to 192.168.123.108:3128. 2025-01-21 06:10:15 (27540): Guest Log: Ncat: 0 bytes sent, 0 bytes received in 2.10 seconds. 2025-01-21 06:10:15 (27540): Guest Log: [INFO] 0 2025-01-21 06:10:24 (27540): Guest Log: 05:10:04 CET +01:00 2025-01-21: cranky: [INFO] Detected Theory App 2025-01-21 06:10:24 (27540): Guest Log: 05:10:04 CET +01:00 2025-01-21: cranky: [INFO] Checking CVMFS. 2025-01-21 06:10:25 (27540): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2025-01-21 06:10:25 (27540): 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 2025-01-21 06:10:25 (27540): Guest Log: 2.5.2.0 4057 0 28076 31527 3 1 278914 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch http://192.168.123.108:3128 1 2025-01-21 06:10:25 (27540): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2025-01-21 06:10:25 (27540): 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 2025-01-21 06:10:25 (27540): Guest Log: 2.5.2.0 4101 0 26476 26277 3 1 278914 4096000 0 65024 0 2 0 2138 2131 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch http://192.168.123.108:3128 1 2025-01-21 06:10:25 (27540): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2025-01-21 06:10:26 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:10:46 (27540): 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 2025-01-21 06:10:46 (27540): Guest Log: 2.5.2.0 4127 0 29556 272 3 1 278914 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch http://192.168.123.108:3128 1 2025-01-21 06:10:46 (27540): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2025-01-21 06:10:46 (27540): 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 2025-01-21 06:10:46 (27540): Guest Log: 2.5.2.0 4079 0 44536 20917 3 1 278914 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch http://192.168.123.108:3128 1 2025-01-21 06:10:46 (27540): Guest Log: 05:10:06 CET +01:00 2025-01-21: cranky: [INFO] Checking runc. 2025-01-21 06:10:46 (27540): Guest Log: 05:10:06 CET +01:00 2025-01-21: cranky: [INFO] Creating the filesystem. 2025-01-21 06:10:46 (27540): Guest Log: 05:10:06 CET +01:00 2025-01-21: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2025-01-21 06:10:46 (27540): Guest Log: 05:10:06 CET +01:00 2025-01-21: cranky: [INFO] Updating config.json. 2025-01-21 06:10:46 (27540): Guest Log: 05:10:06 CET +01:00 2025-01-21: cranky: [INFO] Running Container 'runc'. 2025-01-21 06:10:46 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:10:49 (27540): Guest Log: job: htmld=/shared/html/job 2025-01-21 06:10:49 (27540): Guest Log: job: unpack exitcode=0 2025-01-21 06:10:50 (27540): Guest Log: INFO: activated the work-around for ld: 2025-01-21 06:10:50 (27540): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 21 04:10 /tmp/tmp.8vpw0AcSXR/ld -> /usr/bin/ld.bfd 2025-01-21 06:10:51 (27540): Guest Log: 05:10:10 CET +01:00 2025-01-21: cranky: [INFO] ===> [runRivet] Tue Jan 21 04:10:09 UTC 2025 [boinc pp jets 13000 520 - powheg-box r3744 pthard0 100000 356] 2025-01-21 06:24:19 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:24:39 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:25:00 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:25:20 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:25:30 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:25:50 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:26:00 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:26:20 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:26:40 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:27:00 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:27:10 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:27:30 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:27:40 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:28:01 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:28:11 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:28:31 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:28:41 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:29:00 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:29:10 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:29:31 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:29:41 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:30:01 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:30:12 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:30:31 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:30:42 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:31:02 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:31:22 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:31:42 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:32:02 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:32:22 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:32:33 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:32:52 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:33:03 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:33:22 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:33:33 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:33:52 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:34:03 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:34:23 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:34:33 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:34:53 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:35:03 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:35:23 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:35:33 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:35:53 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:36:03 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:36:23 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:36:34 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:36:54 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:37:14 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:37:34 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:37:45 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:38:05 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:38:15 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:38:35 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:39:26 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:39:45 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:40:16 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:40:36 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:40:46 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:41:06 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:46:20 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:46:40 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:47:01 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:47:21 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:47:31 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:47:51 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:48:11 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:48:31 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:48:42 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:49:02 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:49:22 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:49:41 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:50:03 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:50:22 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 06:56:28 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 06:56:48 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:04:25 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:04:45 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:04:56 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:05:16 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:05:26 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:05:46 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:05:56 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:06:16 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:06:27 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:06:47 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:06:57 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:07:17 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:07:27 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:07:47 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:07:58 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:08:18 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:08:38 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:08:58 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:09:08 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:09:28 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:09:38 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:09:58 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:10:09 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:10:29 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:10:39 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:10:58 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:11:09 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:11:29 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:11:39 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:11:59 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:12:19 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:12:39 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:12:49 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:13:09 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:13:19 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:13:39 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:13:49 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:14:09 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:14:20 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:14:40 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:15:00 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:15:20 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:15:30 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:15:50 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:16:00 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:16:20 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:16:31 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:16:50 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:17:11 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:17:31 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:17:41 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:18:01 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:18:11 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:18:31 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:18:42 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:19:02 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:19:23 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:19:42 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:19:54 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:20:13 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:20:23 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:20:43 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:20:53 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:21:13 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:21:24 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:21:44 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:22:04 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:22:24 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:22:34 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:22:54 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:23:14 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:23:34 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:23:44 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:24:04 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:24:14 (27540): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 07:24:34 (27540): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 07:24:34 (27540): Stopping VM. 2025-01-21 07:24:36 (27540): Successfully stopped VM. 2025-01-21 07:40:38 (26460): vboxwrapper version 26208 2025-01-21 07:40:38 (26460): BOINC client version: 8.0.2 2025-01-21 07:40:39 (26460): Detected: VirtualBox VboxManage Interface (Version: 7.0.22) 2025-01-21 07:40:39 (26460): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-21 07:40:40 (26460): Guest Log: BIOS: VirtualBox 7.0.22 2025-01-21 07:40:40 (26460): Guest Log: CPUID EDX: 0x178bfbff 2025-01-21 07:40:40 (26460): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-01-21 07:40:40 (26460): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-01-21 07:40:40 (26460): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-01-21 07:40:40 (26460): Guest Log: BIOS: Booting from Hard Disk... 2025-01-21 07:40:40 (26460): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-01-21 07:40:40 (26460): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-01-21 07:40:40 (26460): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-01-21 07:40:40 (26460): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-01-21 07:40:40 (26460): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-01-21 07:40:40 (26460): Guest Log: 00:00:00.000084 main Log opened 2025-01-21T04:09:24.799486000Z 2025-01-21 07:40:40 (26460): Guest Log: 00:00:00.000162 main OS Product: Linux 2025-01-21 07:40:40 (26460): Guest Log: 00:00:00.000197 main OS Release: 4.14.76-13.cernvm.x86_64 2025-01-21 07:40:40 (26460): Guest Log: 00:00:00.000219 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2025-01-21 07:40:40 (26460): Guest Log: 00:00:00.000250 main Executable: /usr/sbin/VBoxService 2025-01-21 07:40:40 (26460): Guest Log: 00:00:00.000250 main Process ID: 3225 2025-01-21 07:40:40 (26460): Guest Log: 00:00:00.000250 main Package type: LINUX_64BITS_GENERIC 2025-01-21 07:40:40 (26460): Guest Log: 00:00:00.003235 main 5.2.6 r120293 started. Verbose level = 0 2025-01-21 07:40:40 (26460): Guest Log: [INFO] Detected local proxy http://proxy:3128 in init_data.xml 2025-01-21 07:40:40 (26460): Guest Log: [INFO] Testing connection to proxy on port 3128 2025-01-21 07:40:40 (26460): Guest Log: [INFO] Ncat: Version 7.50 ( https://nmap.org/ncat ) 2025-01-21 07:40:40 (26460): Guest Log: Ncat: Connected to 192.168.123.108:3128. 2025-01-21 07:40:40 (26460): Guest Log: Ncat: 0 bytes sent, 0 bytes received in 2.10 seconds. 2025-01-21 07:40:40 (26460): Guest Log: [INFO] 0 2025-01-21 07:40:40 (26460): Guest Log: 05:10:04 CET +01:00 2025-01-21: cranky: [INFO] Detected Theory App 2025-01-21 07:40:40 (26460): Guest Log: 05:10:04 CET +01:00 2025-01-21: cranky: [INFO] Checking CVMFS. 2025-01-21 07:40:40 (26460): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2025-01-21 07:40:40 (26460): 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 2025-01-21 07:40:40 (26460): Guest Log: 2.5.2.0 4057 0 28076 31527 3 1 278914 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch http://192.168.123.108:3128 1 2025-01-21 07:40:40 (26460): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2025-01-21 07:40:40 (26460): 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 2025-01-21 07:40:40 (26460): Guest Log: 2.5.2.0 4101 0 26476 26277 3 1 278914 4096000 0 65024 0 2 0 2138 2131 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch http://192.168.123.108:3128 1 2025-01-21 07:40:40 (26460): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2025-01-21 07:40:40 (26460): 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 2025-01-21 07:40:40 (26460): Guest Log: 2.5.2.0 4127 0 29556 272 3 1 278914 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch http://192.168.123.108:3128 1 2025-01-21 07:40:40 (26460): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2025-01-21 07:40:40 (26460): 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 2025-01-21 07:40:40 (26460): Guest Log: 2.5.2.0 4079 0 44536 20917 3 1 278914 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch http://192.168.123.108:3128 1 2025-01-21 07:40:40 (26460): Guest Log: 05:10:06 CET +01:00 2025-01-21: cranky: [INFO] Checking runc. 2025-01-21 07:40:40 (26460): Guest Log: 05:10:06 CET +01:00 2025-01-21: cranky: [INFO] Creating the filesystem. 2025-01-21 07:40:40 (26460): Guest Log: 05:10:06 CET +01:00 2025-01-21: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2025-01-21 07:40:40 (26460): Guest Log: 05:10:06 CET +01:00 2025-01-21: cranky: [INFO] Updating config.json. 2025-01-21 07:40:40 (26460): Guest Log: 05:10:06 CET +01:00 2025-01-21: cranky: [INFO] Running Container 'runc'. 2025-01-21 07:40:40 (26460): Guest Log: job: htmld=/shared/html/job 2025-01-21 07:40:40 (26460): Guest Log: job: unpack exitcode=0 2025-01-21 07:40:40 (26460): Guest Log: INFO: activated the work-around for ld: 2025-01-21 07:40:40 (26460): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 21 04:10 /tmp/tmp.8vpw0AcSXR/ld -> /usr/bin/ld.bfd 2025-01-21 07:40:40 (26460): Guest Log: 05:10:10 CET +01:00 2025-01-21: cranky: [INFO] ===> [runRivet] Tue Jan 21 04:10:09 UTC 2025 [boinc pp jets 13000 520 - powheg-box r3744 pthard0 100000 356] 2025-01-21 07:40:40 (26460): Starting VM using VBoxManage interface. (boinc_88895359aba7745d, slot#9) 2025-01-21 07:40:45 (26460): Successfully started VM. (PID = '14668') 2025-01-21 07:40:45 (26460): Reporting VM Process ID to BOINC. 2025-01-21 07:40:45 (26460): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-21 07:40:45 (26460): Detected: Web Application Enabled (http://localhost:51684) 2025-01-21 07:40:45 (26460): Detected: Remote Desktop Enabled (localhost:51685) 2025-01-21 07:40:45 (26460): Preference change detected 2025-01-21 07:40:45 (26460): Setting CPU throttle for VM. (90%) 2025-01-21 07:40:46 (26460): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-21 08:03:08 (26460): Stopping VM. 2025-01-21 08:03:11 (26460): Successfully stopped VM. 2025-01-21 09:29:12 (19972): vboxwrapper version 26208 2025-01-21 09:29:12 (19972): BOINC client version: 8.0.2 2025-01-21 09:29:12 (19972): Detected: VirtualBox VboxManage Interface (Version: 7.0.22) 2025-01-21 09:29:13 (19972): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-21 09:29:14 (19972): Starting VM using VBoxManage interface. (boinc_88895359aba7745d, slot#9) 2025-01-21 09:29:19 (19972): Successfully started VM. (PID = '4472') 2025-01-21 09:29:19 (19972): Reporting VM Process ID to BOINC. 2025-01-21 09:29:19 (19972): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-21 09:29:19 (19972): Detected: Web Application Enabled (http://localhost:51684) 2025-01-21 09:29:19 (19972): Detected: Remote Desktop Enabled (localhost:51685) 2025-01-21 09:29:19 (19972): Preference change detected 2025-01-21 09:29:19 (19972): Setting CPU throttle for VM. (90%) 2025-01-21 09:29:19 (19972): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-21 09:29:23 (19972): Guest Log: 01:12:07.263063 timesync vgsvcTimeSyncWorker: Radical host time change: 5 179 269 000 000ns (HostNow=1 737 444 562 649 000 000 ns HostLast=1 737 439 383 380 000 000 ns) 2025-01-21 09:29:24 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:29:44 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:29:53 (19972): Guest Log: 01:12:17.340733 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 179 368 882 000ns (GuestNow=1 737 444 572 742 950 000 ns GuestLast=1 737 439 393 374 068 000 ns fSetTimeLastLoop=true ) 2025-01-21 09:29:54 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:30:14 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:30:24 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:30:44 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:32:35 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:32:55 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:37:30 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:37:50 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:38:10 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:38:30 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:38:40 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:39:00 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:39:11 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:39:31 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:40:31 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:40:51 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:41:32 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:41:52 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:42:23 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:42:43 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:42:53 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:43:13 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:44:14 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:44:34 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:45:25 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:45:45 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:46:05 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:46:25 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:55:54 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:56:14 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:57:26 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:57:45 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:00:18 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:00:38 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:01:19 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:01:39 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:01:59 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:02:19 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:03:20 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:03:40 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:04:21 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:04:41 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:05:42 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:06:02 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:06:35 (19972): Status Report: Job Duration: '864000.000000' 2025-01-21 10:06:35 (19972): Status Report: Elapsed Time: '6000.002993' 2025-01-21 10:06:35 (19972): Status Report: CPU Time: '2958.703125' 2025-01-21 10:35:39 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:35:59 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:37:40 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:38:01 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:38:21 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:38:41 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:39:43 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:40:02 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:48:11 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:48:30 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:51:32 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:51:52 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:52:22 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:52:42 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:53:43 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:54:03 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 11:49:28 (19972): Status Report: Job Duration: '864000.000000' 2025-01-21 11:49:28 (19972): Status Report: Elapsed Time: '12000.054755' 2025-01-21 11:49:28 (19972): Status Report: CPU Time: '6596.531250' 2025-01-21 12:09:25 (19972): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 12:09:45 (19972): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 12:19:39 (19972): Guest Log: job: run exitcode=0 2025-01-21 12:19:39 (19972): Guest Log: job: diskusage=102180 2025-01-21 12:19:39 (19972): Guest Log: job: logsize=80 k 2025-01-21 12:19:39 (19972): Guest Log: job: times= 2025-01-21 12:19:39 (19972): Guest Log: 0m0.008s 0m0.001s 2025-01-21 12:19:39 (19972): Guest Log: 216m42.803s 0m33.159s 2025-01-21 12:19:39 (19972): Guest Log: job: cpuusage=13036 2025-01-21 12:19:39 (19972): Guest Log: 11:09:13 CET +01:00 2025-01-21: cranky: [INFO] Container 'runc' finished with status code 0. 2025-01-21 12:19:39 (19972): Guest Log: 11:09:13 CET +01:00 2025-01-21: cranky: [INFO] Preparing output. 2025-01-21 12:19:39 (19972): Guest Log: [INFO] Job Finished 2025-01-21 12:19:39 (19972): Guest Log: [INFO] Shutting Down. 2025-01-21 12:19:39 (19972): VM Completion File Detected. 2025-01-21 12:19:39 (19972): VM Completion Message: Job Finished . 2025-01-21 12:19:39 (19972): Powering off VM. 2025-01-21 12:19:39 (19972): Successfully stopped VM. 2025-01-21 12:19:39 (19972): Deregistering VM. (boinc_88895359aba7745d, slot#9) 2025-01-21 12:19:39 (19972): Removing network bandwidth throttle group from VM. 2025-01-21 12:19:40 (19972): Removing VM from VirtualBox. 2025-01-21 12:19:45 (19972): called boinc_finish(0) </stderr_txt> ]]>
©2025 CERN