| Name | Theory_2743-2858383-450_0 |
| Workunit | 225601253 |
| Created | 24 Sep 2024, 16:22:45 UTC |
| Sent | 24 Sep 2024, 21:36:32 UTC |
| Report deadline | 5 Oct 2024, 21:36:32 UTC |
| Received | 1 Oct 2024, 21:44:27 UTC |
| Server state | Over |
| Outcome | Computation error |
| Client state | Compute error |
| Exit status | 1 (0x00000001) Unknown error code |
| Computer ID | 10850076 |
| Run time | 4 days 11 hours 2 min 25 sec |
| CPU time | 2 days 6 hours 5 min 18 sec |
| Validate state | Invalid |
| Credit | 0.00 |
| Device peak FLOPS | 3.49 GFLOPS |
| Application version | Theory Simulation v300.50 (vbox64_theory) x86_64-apple-darwin |
| Peak working set size | 1.32 GB |
| Peak swap size | 80.28 GB |
| Peak disk usage | 228.70 MB |
Stderr output
<core_client_version>8.0.2</core_client_version> <![CDATA[ <message> process exited with code 1 (0x1, -255)</message> <stderr_txt> 2024-09-25 07:36:45 (1981): vboxwrapper version 26207 2024-09-25 07:36:45 (1981): BOINC client version: 8.0.2 2024-09-25 07:36:51 (1981): Detected: VirtualBox VboxManage Interface (Version: 7.0.20) 2024-09-25 07:36:51 (1981): Detected: Sandbox Configuration Enabled 2024-09-25 07:36:54 (1981): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-09-25 07:36:54 (1981): Successfully copied 'init_data.xml' to the shared directory. 2024-09-25 07:36:54 (1981): Successfully copied 'input' to the shared directory. 2024-09-25 07:36:57 (1981): Create VM. (boinc_b1392f3cb160ff62, slot#0) 2024-09-25 07:37:02 (1981): Setting Memory Size for VM. (630MB) 2024-09-25 07:37:05 (1981): Setting CPU Count for VM. (1) 2024-09-25 07:37:07 (1981): Setting Chipset Options for VM. 2024-09-25 07:37:10 (1981): Setting Graphics Controller Options for VM. 2024-09-25 07:37:14 (1981): Setting Boot Options for VM. 2024-09-25 07:37:17 (1981): Setting Network Configuration for NAT. 2024-09-25 07:37:20 (1981): Enabling VM Network Access. 2024-09-25 07:37:23 (1981): Disabling USB Support for VM. 2024-09-25 07:37:27 (1981): Disabling COM Port Support for VM. 2024-09-25 07:37:29 (1981): Disabling Audio Support for VM. 2024-09-25 07:37:31 (1981): Disabling Clipboard Support for VM. 2024-09-25 07:37:35 (1981): Disabling Drag and Drop Support for VM. 2024-09-25 07:37:39 (1981): Adding storage controller(s) to VM. 2024-09-25 07:37:41 (1981): Adding virtual disk drive to VM. (Theory_2024_05_03_prod.vdi) 2024-09-25 07:37:47 (1981): Adding VirtualBox Guest Additions to VM. 2024-09-25 07:37:50 (1981): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2024-09-25 07:37:53 (1981): forwarding host port 57784 to guest port 80 2024-09-25 07:37:55 (1981): Enabling remote desktop for VM. 2024-09-25 07:37:58 (1981): Required extension pack not installed, remote desktop not enabled. 2024-09-25 07:37:58 (1981): Enabling shared directory for VM. 2024-09-25 07:38:06 (1981): Starting VM using VBoxManage interface. (boinc_b1392f3cb160ff62, slot#0) 2024-09-25 07:38:19 (1981): Successfully started VM. (PID = '2120') 2024-09-25 07:38:19 (1981): Reporting VM Process ID to BOINC. 2024-09-25 07:38:19 (1981): Guest Log: BIOS: VirtualBox 7.0.20 2024-09-25 07:38:19 (1981): Guest Log: CPUID EDX: 0x178bfbff 2024-09-25 07:38:19 (1981): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-09-25 07:38:19 (1981): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-09-25 07:38:19 (1981): VM state change detected. (old = 'poweredoff', new = 'running') 2024-09-25 07:38:19 (1981): Detected: Web Application Enabled (http://localhost:57784) 2024-09-25 07:38:19 (1981): Preference change detected 2024-09-25 07:38:19 (1981): Setting CPU throttle for VM. (100%) 2024-09-25 07:38:22 (1981): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-09-25 07:38:22 (1981): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-09-25 07:38:22 (1981): Guest Log: BIOS: Booting from Hard Disk... 2024-09-25 07:38:37 (1981): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-09-25 07:38:37 (1981): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-09-25 07:39:58 (1981): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-09-25 07:39:58 (1981): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-09-25 07:40:23 (1981): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-09-25 07:40:23 (1981): Guest Log: 00:00:00.000464 main Log opened 2024-09-24T21:40:20.480176000Z 2024-09-25 07:40:23 (1981): Guest Log: 00:00:00.003157 main OS Product: Linux 2024-09-25 07:40:23 (1981): Guest Log: 00:00:00.003371 main OS Release: 4.14.76-13.cernvm.x86_64 2024-09-25 07:40:23 (1981): Guest Log: 00:00:00.003581 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-09-25 07:40:23 (1981): Guest Log: 00:00:00.003847 main Executable: /usr/sbin/VBoxService 2024-09-25 07:40:23 (1981): Guest Log: 00:00:00.003851 main Process ID: 3269 2024-09-25 07:40:23 (1981): Guest Log: 00:00:00.003853 main Package type: LINUX_64BITS_GENERIC 2024-09-25 07:40:23 (1981): Guest Log: 00:00:00.077350 main 5.2.6 r120293 started. Verbose level = 0 2024-09-25 07:42:15 (1981): Guest Log: 07:42:14 AEST +10:00 2024-09-25: cranky: [INFO] Detected Theory App 2024-09-25 07:42:15 (1981): Guest Log: 07:42:14 AEST +10:00 2024-09-25: cranky: [INFO] Checking CVMFS. 2024-09-25 07:42:21 (1981): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-09-25 07:42:23 (1981): 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-09-25 07:42:23 (1981): Guest Log: 2.5.2.0 4150 1 28348 30547 3 1 260205 4096000 0 65024 0 0 n/a 5 1 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-09-25 07:42:28 (1981): Guest Log: Probing /cvmfs/grid.cern.ch... Failed! 2024-09-25 07:42:28 (1981): Guest Log: 07:42:27 AEST +10:00 2024-09-25: cranky: [ERROR] 'cvmfs_config probe grid.cern.ch' failed. 2024-09-25 09:18:41 (1981): Status Report: Job Duration: '864000.000000' 2024-09-25 09:18:41 (1981): Status Report: Elapsed Time: '6000.447515' 2024-09-25 09:18:41 (1981): Status Report: CPU Time: '2820.910000' 2024-09-25 10:59:00 (1981): Status Report: Job Duration: '864000.000000' 2024-09-25 10:59:00 (1981): Status Report: Elapsed Time: '12000.951657' 2024-09-25 10:59:00 (1981): Status Report: CPU Time: '5795.300000' 2024-09-25 12:39:23 (1981): Status Report: Job Duration: '864000.000000' 2024-09-25 12:39:23 (1981): Status Report: Elapsed Time: '18002.463784' 2024-09-25 12:39:23 (1981): Status Report: CPU Time: '8915.130000' 2024-09-25 14:19:43 (1981): Status Report: Job Duration: '864000.000000' 2024-09-25 14:19:43 (1981): Status Report: Elapsed Time: '24002.892121' 2024-09-25 14:19:43 (1981): Status Report: CPU Time: '12018.610000' 2024-09-25 16:00:07 (1981): Status Report: Job Duration: '864000.000000' 2024-09-25 16:00:07 (1981): Status Report: Elapsed Time: '30003.164366' 2024-09-25 16:00:07 (1981): Status Report: CPU Time: '14871.020000' 2024-09-25 17:40:25 (1981): Status Report: Job Duration: '864000.000000' 2024-09-25 17:40:25 (1981): Status Report: Elapsed Time: '36004.121675' 2024-09-25 17:40:25 (1981): Status Report: CPU Time: '17681.490000' 2024-09-25 19:20:45 (1981): Status Report: Job Duration: '864000.000000' 2024-09-25 19:20:45 (1981): Status Report: Elapsed Time: '42004.487197' 2024-09-25 19:20:45 (1981): Status Report: CPU Time: '20611.510000' 2024-09-25 20:02:20 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 20:22:12 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 20:22:35 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 20:28:40 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 20:34:54 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 20:44:17 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 20:47:36 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 20:55:27 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 20:56:32 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 21:11:47 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 21:12:03 (1981): Guest Log: 12:33:11.717373 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 000 773 350 000ns (GuestNow=1 727 262 715 771 412 000 ns GuestLast=1 727 260 714 998 062 000 ns fSetTimeLastLoop=true ) 2024-09-25 21:12:08 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 21:32:12 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 21:33:01 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 21:36:47 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 21:38:27 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 21:45:12 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 22:02:39 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 22:07:12 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 22:18:29 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 22:29:19 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 22:34:33 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 22:51:58 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 22:52:03 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 23:01:10 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 23:02:39 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-25 23:11:00 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-25 23:20:56 (1981): Status Report: Job Duration: '864000.000000' 2024-09-25 23:20:56 (1981): Status Report: Elapsed Time: '48005.250731' 2024-09-25 23:20:56 (1981): Status Report: CPU Time: '24303.160000' 2024-09-26 01:01:14 (1981): Status Report: Job Duration: '864000.000000' 2024-09-26 01:01:14 (1981): Status Report: Elapsed Time: '54005.417200' 2024-09-26 01:01:14 (1981): Status Report: CPU Time: '27210.540000' 2024-09-26 02:41:35 (1981): Status Report: Job Duration: '864000.000000' 2024-09-26 02:41:35 (1981): Status Report: Elapsed Time: '60007.296978' 2024-09-26 02:41:35 (1981): Status Report: CPU Time: '30131.050000' 2024-09-26 04:21:55 (1981): Status Report: Job Duration: '864000.000000' 2024-09-26 04:21:55 (1981): Status Report: Elapsed Time: '66008.169766' 2024-09-26 04:21:55 (1981): Status Report: CPU Time: '32972.830000' 2024-09-26 06:02:15 (1981): Status Report: Job Duration: '864000.000000' 2024-09-26 06:02:15 (1981): Status Report: Elapsed Time: '72008.306147' 2024-09-26 06:02:15 (1981): Status Report: CPU Time: '35864.950000' 2024-09-26 06:18:10 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 06:26:43 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 07:51:19 (1981): Status Report: Job Duration: '864000.000000' 2024-09-26 07:51:19 (1981): Status Report: Elapsed Time: '78008.970594' 2024-09-26 07:51:19 (1981): Status Report: CPU Time: '39234.260000' 2024-09-26 09:31:39 (1981): Status Report: Job Duration: '864000.000000' 2024-09-26 09:31:39 (1981): Status Report: Elapsed Time: '84009.199547' 2024-09-26 09:31:39 (1981): Status Report: CPU Time: '42231.480000' 2024-09-26 11:12:01 (1981): Status Report: Job Duration: '864000.000000' 2024-09-26 11:12:01 (1981): Status Report: Elapsed Time: '90010.108707' 2024-09-26 11:12:01 (1981): Status Report: CPU Time: '45178.590000' 2024-09-26 12:52:22 (1981): Status Report: Job Duration: '864000.000000' 2024-09-26 12:52:22 (1981): Status Report: Elapsed Time: '96010.623619' 2024-09-26 12:52:22 (1981): Status Report: CPU Time: '48088.870000' 2024-09-26 14:32:42 (1981): Status Report: Job Duration: '864000.000000' 2024-09-26 14:32:42 (1981): Status Report: Elapsed Time: '102011.246780' 2024-09-26 14:32:42 (1981): Status Report: CPU Time: '50980.320000' 2024-09-26 16:13:02 (1981): Status Report: Job Duration: '864000.000000' 2024-09-26 16:13:02 (1981): Status Report: Elapsed Time: '108011.297976' 2024-09-26 16:13:02 (1981): Status Report: CPU Time: '53934.250000' 2024-09-26 17:07:25 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 17:10:23 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 17:24:30 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 17:30:31 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 17:40:48 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 17:46:31 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 17:48:34 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 17:55:03 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 18:14:50 (1981): Status Report: Job Duration: '864000.000000' 2024-09-26 18:14:50 (1981): Status Report: Elapsed Time: '114011.472798' 2024-09-26 18:14:50 (1981): Status Report: CPU Time: '57222.280000' 2024-09-26 19:55:12 (1981): Status Report: Job Duration: '864000.000000' 2024-09-26 19:55:12 (1981): Status Report: Elapsed Time: '120011.988439' 2024-09-26 19:55:12 (1981): Status Report: CPU Time: '60135.540000' 2024-09-26 21:34:24 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 21:37:41 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 21:38:55 (1981): Status Report: Job Duration: '864000.000000' 2024-09-26 21:38:55 (1981): Status Report: Elapsed Time: '126012.519480' 2024-09-26 21:38:55 (1981): Status Report: CPU Time: '63132.530000' 2024-09-26 21:39:34 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 21:42:35 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 21:46:30 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 21:50:42 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 21:52:22 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 21:57:19 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 21:59:30 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 22:09:18 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 22:11:47 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 22:16:10 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 22:17:28 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 22:24:43 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 22:43:28 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 23:05:47 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 23:06:10 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-26 23:39:02 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-26 23:39:08 (1981): Guest Log: 35:38:50.609050 timesync vgsvcTimeSyncWorker: Radical host time change: 1 982 455 000 000ns (HostNow=1 727 357 931 856 000 000 ns HostLast=1 727 355 949 401 000 000 ns) 2024-09-26 23:39:18 (1981): Guest Log: 35:39:00.620788 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 982 905 098 000ns (GuestNow=1 727 357 941 872 368 000 ns GuestLast=1 727 355 958 967 270 000 ns fSetTimeLastLoop=true ) 2024-09-27 00:48:29 (1981): Status Report: Job Duration: '864000.000000' 2024-09-27 00:48:29 (1981): Status Report: Elapsed Time: '132012.726670' 2024-09-27 00:48:29 (1981): Status Report: CPU Time: '66834.070000' 2024-09-27 02:28:55 (1981): Status Report: Job Duration: '864000.000000' 2024-09-27 02:28:55 (1981): Status Report: Elapsed Time: '138013.240883' 2024-09-27 02:28:55 (1981): Status Report: CPU Time: '69804.380000' 2024-09-27 04:09:12 (1981): Status Report: Job Duration: '864000.000000' 2024-09-27 04:09:12 (1981): Status Report: Elapsed Time: '144013.850494' 2024-09-27 04:09:12 (1981): Status Report: CPU Time: '72817.870000' 2024-09-27 05:47:41 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 05:50:38 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 05:52:35 (1981): Status Report: Job Duration: '864000.000000' 2024-09-27 05:52:35 (1981): Status Report: Elapsed Time: '150015.898049' 2024-09-27 05:52:35 (1981): Status Report: CPU Time: '75855.300000' 2024-09-27 07:32:58 (1981): Status Report: Job Duration: '864000.000000' 2024-09-27 07:32:58 (1981): Status Report: Elapsed Time: '156018.353546' 2024-09-27 07:32:58 (1981): Status Report: CPU Time: '78783.570000' 2024-09-27 09:13:17 (1981): Status Report: Job Duration: '864000.000000' 2024-09-27 09:13:17 (1981): Status Report: Elapsed Time: '162019.293845' 2024-09-27 09:13:17 (1981): Status Report: CPU Time: '81746.950000' 2024-09-27 10:53:39 (1981): Status Report: Job Duration: '864000.000000' 2024-09-27 10:53:39 (1981): Status Report: Elapsed Time: '168020.283853' 2024-09-27 10:53:39 (1981): Status Report: CPU Time: '84780.540000' 2024-09-27 12:34:02 (1981): Status Report: Job Duration: '864000.000000' 2024-09-27 12:34:02 (1981): Status Report: Elapsed Time: '174021.025144' 2024-09-27 12:34:02 (1981): Status Report: CPU Time: '87792.560000' 2024-09-27 14:14:28 (1981): Status Report: Job Duration: '864000.000000' 2024-09-27 14:14:28 (1981): Status Report: Elapsed Time: '180021.577925' 2024-09-27 14:14:28 (1981): Status Report: CPU Time: '90796.720000' 2024-09-27 15:54:48 (1981): Status Report: Job Duration: '864000.000000' 2024-09-27 15:54:48 (1981): Status Report: Elapsed Time: '186022.924050' 2024-09-27 15:54:48 (1981): Status Report: CPU Time: '93859.120000' 2024-09-27 17:17:46 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 17:37:58 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 17:45:12 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 17:48:44 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 17:58:58 (1981): Status Report: Job Duration: '864000.000000' 2024-09-27 17:58:58 (1981): Status Report: Elapsed Time: '192023.787919' 2024-09-27 17:58:58 (1981): Status Report: CPU Time: '97128.720000' 2024-09-27 18:49:02 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 18:54:17 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 18:54:44 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 18:57:53 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 19:47:49 (1981): Status Report: Job Duration: '864000.000000' 2024-09-27 19:47:49 (1981): Status Report: Elapsed Time: '198024.746375' 2024-09-27 19:47:49 (1981): Status Report: CPU Time: '101061.430000' 2024-09-27 20:03:17 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 20:07:39 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 20:12:11 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 20:17:03 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 20:20:26 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 20:24:56 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 20:57:12 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 21:08:34 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 21:15:59 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 21:20:42 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 21:22:12 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 21:29:51 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 21:36:22 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 21:48:02 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 21:49:06 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 22:03:25 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 22:03:41 (1981): Guest Log: 56:25:37.900526 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 027 065 065 000ns (GuestNow=1 727 438 621 126 380 000 ns GuestLast=1 727 436 594 061 315 000 ns fSetTimeLastLoop=true ) 2024-09-27 22:10:58 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 22:13:59 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 22:20:12 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 22:27:12 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 22:41:51 (1981): Status Report: Job Duration: '864000.000000' 2024-09-27 22:41:51 (1981): Status Report: Elapsed Time: '204025.447088' 2024-09-27 22:41:51 (1981): Status Report: CPU Time: '105255.670000' 2024-09-27 23:07:51 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 23:17:14 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 23:22:26 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 23:29:16 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-27 23:40:59 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-27 23:47:27 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 00:44:51 (1981): Status Report: Job Duration: '864000.000000' 2024-09-28 00:44:51 (1981): Status Report: Elapsed Time: '210026.105633' 2024-09-28 00:44:51 (1981): Status Report: CPU Time: '109018.030000' 2024-09-28 02:25:11 (1981): Status Report: Job Duration: '864000.000000' 2024-09-28 02:25:11 (1981): Status Report: Elapsed Time: '216026.170719' 2024-09-28 02:25:11 (1981): Status Report: CPU Time: '112312.630000' 2024-09-28 04:05:27 (1981): Status Report: Job Duration: '864000.000000' 2024-09-28 04:05:27 (1981): Status Report: Elapsed Time: '222026.410990' 2024-09-28 04:05:27 (1981): Status Report: CPU Time: '115567.030000' 2024-09-28 05:45:45 (1981): Status Report: Job Duration: '864000.000000' 2024-09-28 05:45:45 (1981): Status Report: Elapsed Time: '228026.431457' 2024-09-28 05:45:45 (1981): Status Report: CPU Time: '118862.650000' 2024-09-28 07:26:05 (1981): Status Report: Job Duration: '864000.000000' 2024-09-28 07:26:05 (1981): Status Report: Elapsed Time: '234026.493631' 2024-09-28 07:26:05 (1981): Status Report: CPU Time: '122171.810000' 2024-09-28 09:06:23 (1981): Status Report: Job Duration: '864000.000000' 2024-09-28 09:06:23 (1981): Status Report: Elapsed Time: '240027.493508' 2024-09-28 09:06:23 (1981): Status Report: CPU Time: '125390.290000' 2024-09-28 10:46:43 (1981): Status Report: Job Duration: '864000.000000' 2024-09-28 10:46:43 (1981): Status Report: Elapsed Time: '246027.887120' 2024-09-28 10:46:43 (1981): Status Report: CPU Time: '128570.920000' 2024-09-28 10:58:51 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 11:03:31 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 12:31:47 (1981): Status Report: Job Duration: '864000.000000' 2024-09-28 12:31:47 (1981): Status Report: Elapsed Time: '252028.476933' 2024-09-28 12:31:47 (1981): Status Report: CPU Time: '132079.550000' 2024-09-28 13:13:13 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 13:26:48 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 14:25:47 (1981): Status Report: Job Duration: '864000.000000' 2024-09-28 14:25:47 (1981): Status Report: Elapsed Time: '258028.570307' 2024-09-28 14:25:47 (1981): Status Report: CPU Time: '135611.360000' 2024-09-28 15:52:22 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 16:24:29 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 16:24:35 (1981): Guest Log: 73:24:17.654240 timesync vgsvcTimeSyncWorker: Radical host time change: 1 936 139 000 000ns (HostNow=1 727 504 625 831 000 000 ns HostLast=1 727 502 689 692 000 000 ns) 2024-09-28 16:24:45 (1981): Guest Log: 73:24:27.655002 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 934 833 968 000ns (GuestNow=1 727 504 635 831 805 000 ns GuestLast=1 727 502 700 997 837 000 ns fSetTimeLastLoop=true ) 2024-09-28 16:38:18 (1981): Status Report: Job Duration: '864000.000000' 2024-09-28 16:38:18 (1981): Status Report: Elapsed Time: '264029.316592' 2024-09-28 16:38:18 (1981): Status Report: CPU Time: '139195.090000' 2024-09-28 18:18:37 (1981): Status Report: Job Duration: '864000.000000' 2024-09-28 18:18:37 (1981): Status Report: Elapsed Time: '270030.148264' 2024-09-28 18:18:37 (1981): Status Report: CPU Time: '142494.380000' 2024-09-28 19:58:51 (1981): Status Report: Job Duration: '864000.000000' 2024-09-28 19:58:51 (1981): Status Report: Elapsed Time: '276030.366532' 2024-09-28 19:58:51 (1981): Status Report: CPU Time: '145553.010000' 2024-09-28 20:08:15 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 20:12:21 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 20:13:11 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 20:27:33 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 20:28:01 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 20:33:03 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 20:33:20 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 20:41:03 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 20:44:09 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 20:47:13 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 20:48:48 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 20:59:29 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 21:13:56 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 21:26:19 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 21:27:09 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 21:39:44 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 21:40:11 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 21:44:39 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 22:06:40 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 22:09:53 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 22:15:10 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 22:18:11 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 22:20:37 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 22:27:49 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 22:29:40 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 22:36:09 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 22:43:45 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 23:03:15 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 23:30:11 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-28 23:33:30 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-28 23:37:08 (1981): Status Report: Job Duration: '864000.000000' 2024-09-28 23:37:08 (1981): Status Report: Elapsed Time: '282030.825112' 2024-09-28 23:37:08 (1981): Status Report: CPU Time: '149677.770000' 2024-09-29 01:17:28 (1981): Status Report: Job Duration: '864000.000000' 2024-09-29 01:17:28 (1981): Status Report: Elapsed Time: '288031.226644' 2024-09-29 01:17:28 (1981): Status Report: CPU Time: '153115.080000' 2024-09-29 02:57:45 (1981): Status Report: Job Duration: '864000.000000' 2024-09-29 02:57:45 (1981): Status Report: Elapsed Time: '294031.373986' 2024-09-29 02:57:45 (1981): Status Report: CPU Time: '156436.970000' 2024-09-29 04:38:04 (1981): Status Report: Job Duration: '864000.000000' 2024-09-29 04:38:04 (1981): Status Report: Elapsed Time: '300031.898754' 2024-09-29 04:38:04 (1981): Status Report: CPU Time: '159621.150000' 2024-09-29 05:46:17 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 05:53:12 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 06:25:24 (1981): Status Report: Job Duration: '864000.000000' 2024-09-29 06:25:24 (1981): Status Report: Elapsed Time: '306032.936223' 2024-09-29 06:25:24 (1981): Status Report: CPU Time: '163139.240000' 2024-09-29 06:56:44 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 07:12:11 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 08:21:16 (1981): Status Report: Job Duration: '864000.000000' 2024-09-29 08:21:16 (1981): Status Report: Elapsed Time: '312033.067225' 2024-09-29 08:21:16 (1981): Status Report: CPU Time: '166869.330000' 2024-09-29 10:01:36 (1981): Status Report: Job Duration: '864000.000000' 2024-09-29 10:01:36 (1981): Status Report: Elapsed Time: '318033.906183' 2024-09-29 10:01:36 (1981): Status Report: CPU Time: '169224.230000' 2024-09-29 11:41:57 (1981): Status Report: Job Duration: '864000.000000' 2024-09-29 11:41:57 (1981): Status Report: Elapsed Time: '324034.787589' 2024-09-29 11:41:57 (1981): Status Report: CPU Time: '171222.670000' 2024-09-29 13:22:17 (1981): Status Report: Job Duration: '864000.000000' 2024-09-29 13:22:17 (1981): Status Report: Elapsed Time: '330035.030237' 2024-09-29 13:22:17 (1981): Status Report: CPU Time: '173241.530000' 2024-09-29 13:30:24 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 13:43:30 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 15:15:52 (1981): Status Report: Job Duration: '864000.000000' 2024-09-29 15:15:52 (1981): Status Report: Elapsed Time: '336036.149263' 2024-09-29 15:15:52 (1981): Status Report: CPU Time: '175773.170000' 2024-09-29 16:56:15 (1981): Status Report: Job Duration: '864000.000000' 2024-09-29 16:56:15 (1981): Status Report: Elapsed Time: '342039.370281' 2024-09-29 16:56:15 (1981): Status Report: CPU Time: '177725.380000' 2024-09-29 18:36:38 (1981): Status Report: Job Duration: '864000.000000' 2024-09-29 18:36:38 (1981): Status Report: Elapsed Time: '348039.618716' 2024-09-29 18:36:38 (1981): Status Report: CPU Time: '179744.780000' 2024-09-29 19:49:47 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 19:57:40 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 19:59:22 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 20:03:49 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 20:04:23 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 20:14:52 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 20:28:29 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 20:34:42 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 20:36:03 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 20:39:02 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 20:39:15 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 20:46:54 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 20:51:03 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 21:03:47 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 21:04:59 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 21:14:54 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 21:17:48 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 21:23:38 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 21:25:42 (1981): Status Report: Job Duration: '864000.000000' 2024-09-29 21:25:42 (1981): Status Report: Elapsed Time: '354039.690657' 2024-09-29 21:25:42 (1981): Status Report: CPU Time: '182365.690000' 2024-09-29 21:25:57 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 21:28:57 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 21:30:11 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 21:33:15 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 21:34:16 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 21:38:44 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 21:38:47 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 21:42:33 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 21:44:06 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 21:47:52 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 22:02:22 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 22:05:30 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 22:08:55 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 22:11:56 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 22:12:09 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 22:15:14 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 22:21:01 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 22:31:25 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 22:31:45 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 22:34:48 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 22:37:39 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 23:06:25 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 23:06:42 (1981): Guest Log: 99:17:15.416881 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 541 641 840 000ns (GuestNow=1 727 615 201 045 043 000 ns GuestLast=1 727 612 659 403 203 000 ns fSetTimeLastLoop=true ) 2024-09-29 23:19:55 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 23:27:46 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 23:36:38 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-29 23:48:00 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-29 23:57:00 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 00:00:07 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 00:38:33 (1981): Status Report: Job Duration: '864000.000000' 2024-09-30 00:38:33 (1981): Status Report: Elapsed Time: '360040.687576' 2024-09-30 00:38:33 (1981): Status Report: CPU Time: '186175.820000' 2024-09-30 01:35:23 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-09-30 01:38:21 (1981): VM state change detected. (old = 'paused', new = 'running') 2024-09-30 02:21:56 (1981): Status Report: Job Duration: '864000.000000' 2024-09-30 02:21:56 (1981): Status Report: Elapsed Time: '366041.186229' 2024-09-30 02:21:56 (1981): Status Report: CPU Time: '188293.910000' 2024-09-30 04:02:18 (1981): Status Report: Job Duration: '864000.000000' 2024-09-30 04:02:18 (1981): Status Report: Elapsed Time: '372041.471252' 2024-09-30 04:02:18 (1981): Status Report: CPU Time: '190400.130000' 2024-09-30 05:42:43 (1981): Status Report: Job Duration: '864000.000000' 2024-09-30 05:42:43 (1981): Status Report: Elapsed Time: '378042.448691' 2024-09-30 05:42:43 (1981): Status Report: CPU Time: '192519.670000' 2024-09-30 07:23:02 (1981): VM state change detected. (old = 'running', new = 'paused') 2024-10-02 06:29:57 (4437): vboxwrapper version 26207 2024-10-02 06:29:57 (4437): BOINC client version: 8.0.2 2024-10-02 06:30:06 (4437): Detected: VirtualBox VboxManage Interface (Version: 7.0.20) 2024-10-02 06:30:06 (4437): Detected: Sandbox Configuration Enabled 2024-10-02 06:30:09 (4437): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-10-02 06:30:11 (4437): Register VM. (boinc_b1392f3cb160ff62, slot#0) 2024-10-02 06:30:14 (4437): Guest Log: BIOS: VirtualBox 7.0.20 2024-10-02 06:30:14 (4437): Guest Log: CPUID EDX: 0x178bfbff 2024-10-02 06:30:14 (4437): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-10-02 06:30:14 (4437): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-10-02 06:30:14 (4437): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-10-02 06:30:14 (4437): Guest Log: BIOS: Booting from Hard Disk... 2024-10-02 06:30:14 (4437): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-10-02 06:30:14 (4437): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-10-02 06:30:14 (4437): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-10-02 06:30:14 (4437): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-10-02 06:30:14 (4437): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-10-02 06:30:14 (4437): Guest Log: 00:00:00.000464 main Log opened 2024-09-24T21:40:20.480176000Z 2024-10-02 06:30:14 (4437): Guest Log: 00:00:00.003157 main OS Product: Linux 2024-10-02 06:30:14 (4437): Guest Log: 00:00:00.003371 main OS Release: 4.14.76-13.cernvm.x86_64 2024-10-02 06:30:14 (4437): Guest Log: 00:00:00.003581 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-10-02 06:30:14 (4437): Guest Log: 00:00:00.003847 main Executable: /usr/sbin/VBoxService 2024-10-02 06:30:14 (4437): Guest Log: 00:00:00.003851 main Process ID: 3269 2024-10-02 06:30:14 (4437): Guest Log: 00:00:00.003853 main Package type: LINUX_64BITS_GENERIC 2024-10-02 06:30:14 (4437): Guest Log: 00:00:00.077350 main 5.2.6 r120293 started. Verbose level = 0 2024-10-02 06:30:14 (4437): Guest Log: 07:42:14 AEST +10:00 2024-09-25: cranky: [INFO] Detected Theory App 2024-10-02 06:30:14 (4437): Guest Log: 07:42:14 AEST +10:00 2024-09-25: cranky: [INFO] Checking CVMFS. 2024-10-02 06:30:14 (4437): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2024-10-02 06:30:14 (4437): 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-10-02 06:30:14 (4437): Guest Log: 2.5.2.0 4150 1 28348 30547 3 1 260205 4096000 0 65024 0 0 n/a 5 1 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2024-10-02 06:30:14 (4437): Guest Log: Probing /cvmfs/grid.cern.ch... Failed! 2024-10-02 06:30:14 (4437): Guest Log: 07:42:27 AEST +10:00 2024-09-25: cranky: [ERROR] 'cvmfs_config probe grid.cern.ch' failed. 2024-10-02 06:30:14 (4437): Guest Log: 12:33:11.717373 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 000 773 350 000ns (GuestNow=1 727 262 715 771 412 000 ns GuestLast=1 727 260 714 998 062 000 ns fSetTimeLastLoop=true ) 2024-10-02 06:30:14 (4437): Guest Log: 35:38:50.609050 timesync vgsvcTimeSyncWorker: Radical host time change: 1 982 455 000 000ns (HostNow=1 727 357 931 856 000 000 ns HostLast=1 727 355 949 401 000 000 ns) 2024-10-02 06:30:14 (4437): Guest Log: 35:39:00.620788 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 982 905 098 000ns (GuestNow=1 727 357 941 872 368 000 ns GuestLast=1 727 355 958 967 270 000 ns fSetTimeLastLoop=true ) 2024-10-02 06:30:14 (4437): Guest Log: 56:25:37.900526 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 027 065 065 000ns (GuestNow=1 727 438 621 126 380 000 ns GuestLast=1 727 436 594 061 315 000 ns fSetTimeLastLoop=true ) 2024-10-02 06:30:14 (4437): Guest Log: 73:24:17.654240 timesync vgsvcTimeSyncWorker: Radical host time change: 1 936 139 000 000ns (HostNow=1 727 504 625 831 000 000 ns HostLast=1 727 502 689 692 000 000 ns) 2024-10-02 06:30:14 (4437): Guest Log: 73:24:27.655002 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 934 833 968 000ns (GuestNow=1 727 504 635 831 805 000 ns GuestLast=1 727 502 700 997 837 000 ns fSetTimeLastLoop=true ) 2024-10-02 06:30:14 (4437): Guest Log: 99:17:15.416881 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 541 641 840 000ns (GuestNow=1 727 615 201 045 043 000 ns GuestLast=1 727 612 659 403 203 000 ns fSetTimeLastLoop=true ) 2024-10-02 06:30:14 (4437): Starting VM using VBoxManage interface. (boinc_b1392f3cb160ff62, slot#0) 2024-10-02 06:30:21 (4437): Successfully started VM. (PID = '4587') 2024-10-02 06:30:21 (4437): Reporting VM Process ID to BOINC. 2024-10-02 06:30:21 (4437): Guest Log: BIOS: VirtualBox 7.0.20 2024-10-02 06:30:21 (4437): Guest Log: CPUID EDX: 0x178bfbff 2024-10-02 06:30:21 (4437): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-10-02 06:30:21 (4437): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-10-02 06:30:21 (4437): VM state change detected. (old = 'poweredoff', new = 'running') 2024-10-02 06:30:21 (4437): Detected: Web Application Enabled (http://localhost:57784) 2024-10-02 06:30:21 (4437): Status Report: Job Duration: '864000.000000' 2024-10-02 06:30:21 (4437): Status Report: Elapsed Time: '383981.919002' 2024-10-02 06:30:21 (4437): Status Report: CPU Time: '194638.870000' 2024-10-02 06:30:21 (4437): Preference change detected 2024-10-02 06:30:21 (4437): Setting CPU throttle for VM. (100%) 2024-10-02 06:30:22 (4437): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-10-02 06:30:22 (4437): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-10-02 06:30:22 (4437): Guest Log: BIOS: Booting from Hard Disk... 2024-10-02 06:30:26 (4437): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-10-02 06:30:26 (4437): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-10-02 06:31:21 (4437): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-10-02 06:31:21 (4437): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-10-02 06:31:33 (4437): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-10-02 06:31:33 (4437): Guest Log: 00:00:00.000307 main Log opened 2024-10-01T20:31:33.329728000Z 2024-10-02 06:31:33 (4437): Guest Log: 00:00:00.001938 main OS Product: Linux 2024-10-02 06:31:33 (4437): Guest Log: 00:00:00.002619 main OS Release: 4.14.76-13.cernvm.x86_64 2024-10-02 06:31:33 (4437): Guest Log: 00:00:00.002750 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2024-10-02 06:31:33 (4437): Guest Log: 00:00:00.002862 main Executable: /usr/sbin/VBoxService 2024-10-02 06:31:33 (4437): Guest Log: 00:00:00.002865 main Process ID: 3243 2024-10-02 06:31:33 (4437): Guest Log: 00:00:00.002867 main Package type: LINUX_64BITS_GENERIC 2024-10-02 06:31:33 (4437): Guest Log: 00:00:00.011259 main 5.2.6 r120293 started. Verbose level = 0 2024-10-02 06:32:21 (4437): Guest Log: -bash: /home/boinc/cranky: No such file or directory 2024-10-02 06:32:21 (4437): Guest Log: [ERROR] Job Failed 2024-10-02 06:32:22 (4437): Guest Log: [INFO] Shutting Down. 2024-10-02 06:32:22 (4437): VM Completion File Detected. 2024-10-02 06:32:22 (4437): VM Completion Message: Job Failed . 2024-10-02 06:32:22 (4437): Powering off VM. 2024-10-02 06:32:23 (4437): Successfully stopped VM. 2024-10-02 06:32:23 (4437): Deregistering VM. (boinc_b1392f3cb160ff62, slot#0) 2024-10-02 06:32:24 (4437): Removing network bandwidth throttle group from VM. 2024-10-02 06:32:26 (4437): Attempts: 2 2024-10-02 06:32:26 (4437): Removing VM from VirtualBox. 2024-10-02 06:32:32 (4437): called boinc_finish(1) </stderr_txt> ]]>
©2024 CERN