| Name | Theory_2773-2996852-417_0 |
| Workunit | 229908872 |
| Created | 23 Jan 2025, 8:42:31 UTC |
| Sent | 23 Jan 2025, 9:45:31 UTC |
| Report deadline | 3 Feb 2025, 9:45:31 UTC |
| Received | 29 Jan 2025, 9:23:17 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10853402 |
| Run time | 10 hours 28 min 10 sec |
| CPU time | 10 hours 22 min 45 sec |
| Validate state | Valid |
| Credit | 181.74 |
| Device peak FLOPS | 2.08 GFLOPS |
| Application version | Theory Simulation v300.60 (vbox64_theory) x86_64-pc-linux-gnu |
| Peak working set size | 642.66 MB |
| Peak swap size | 2.10 GB |
| Peak disk usage | 2.06 GB |
Stderr output
<core_client_version>8.0.4</core_client_version> <![CDATA[ <stderr_txt> 2025-01-23 08:42:04 (69095): vboxwrapper version 26208 2025-01-23 08:42:04 (69095): BOINC client version: 8.0.4 2025-01-23 08:42:04 (69095): Detected: VirtualBox VboxManage Interface (Version: 7.0.18) 2025-01-23 08:42:04 (69095): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-23 08:42:04 (69095): Successfully copied 'init_data.xml' to the shared directory. 2025-01-23 08:42:04 (69095): Successfully copied 'input' to the shared directory. 2025-01-23 08:42:05 (69095): Create VM. (boinc_7b0c788f457c0007, slot#4) 2025-01-23 08:42:05 (69095): Setting Memory Size for VM. (630MB) 2025-01-23 08:42:05 (69095): Setting CPU Count for VM. (1) 2025-01-23 08:42:05 (69095): Setting Chipset Options for VM. 2025-01-23 08:42:05 (69095): Setting Graphics Controller Options for VM. 2025-01-23 08:42:05 (69095): Setting Boot Options for VM. 2025-01-23 08:42:05 (69095): Setting Network Configuration for NAT. 2025-01-23 08:42:05 (69095): Enabling VM Network Access. 2025-01-23 08:42:05 (69095): Disabling USB Support for VM. 2025-01-23 08:42:05 (69095): Disabling COM Port Support for VM. 2025-01-23 08:42:05 (69095): Disabling LPT Port Support for VM. 2025-01-23 08:42:06 (69095): Disabling Audio Support for VM. 2025-01-23 08:42:06 (69095): Disabling Clipboard Support for VM. 2025-01-23 08:42:06 (69095): Disabling Drag and Drop Support for VM. 2025-01-23 08:42:06 (69095): Adding storage controller(s) to VM. 2025-01-23 08:42:06 (69095): Adding virtual disk drive to VM. (Theory_2025_01_16_prod.vdi) 2025-01-23 08:42:06 (69095): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2025-01-23 08:42:06 (69095): forwarding host port 59157 to guest port 80 2025-01-23 08:42:06 (69095): Enabling remote desktop for VM. 2025-01-23 08:42:06 (69095): Required extension pack not installed, remote desktop not enabled. 2025-01-23 08:42:06 (69095): Enabling shared directory for VM. 2025-01-23 08:42:07 (69095): Starting VM using VBoxManage interface. (boinc_7b0c788f457c0007, slot#4) 2025-01-23 08:42:08 (69095): Successfully started VM. (PID = '69461') 2025-01-23 08:42:08 (69095): Reporting VM Process ID to BOINC. 2025-01-23 08:42:08 (69095): Guest Log: BIOS: VirtualBox 7.0.18 2025-01-23 08:42:08 (69095): Guest Log: CPUID EDX: 0x178bfbff 2025-01-23 08:42:08 (69095): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-01-23 08:42:08 (69095): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-01-23 08:42:08 (69095): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-23 08:42:08 (69095): Detected: Web Application Enabled (http://localhost:59157) 2025-01-23 08:42:08 (69095): Preference change detected 2025-01-23 08:42:08 (69095): Setting CPU throttle for VM. (100%) 2025-01-23 08:42:08 (69095): Setting network throttle for VM. (40KB) 2025-01-23 08:42:08 (69095): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-23 08:42:10 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-23 08:42:30 (69095): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-01-23 08:42:30 (69095): Guest Log: BIOS: Booting from Hard Disk... 2025-01-23 08:42:30 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-23 08:42:33 (69095): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-01-23 08:42:33 (69095): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-01-23 08:42:52 (69095): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-01-23 08:42:52 (69095): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-01-23 08:42:56 (69095): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-01-23 08:42:56 (69095): Guest Log: 00:00:00.000123 main Log opened 2025-01-23T15:42:55.909369000Z 2025-01-23 08:42:56 (69095): Guest Log: 00:00:00.000272 main OS Product: Linux 2025-01-23 08:42:56 (69095): Guest Log: 00:00:00.000340 main OS Release: 4.14.76-13.cernvm.x86_64 2025-01-23 08:42:56 (69095): Guest Log: 00:00:00.000390 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2025-01-23 08:42:56 (69095): Guest Log: 00:00:00.000450 main Executable: /usr/sbin/VBoxService 2025-01-23 08:42:56 (69095): Guest Log: 00:00:00.000451 main Process ID: 3160 2025-01-23 08:42:56 (69095): Guest Log: 00:00:00.000452 main Package type: LINUX_64BITS_GENERIC 2025-01-23 08:42:56 (69095): Guest Log: 00:00:00.003531 main 5.2.6 r120293 started. Verbose level = 0 2025-01-23 08:43:19 (69095): Guest Log: 09:43:19 CST -06:00 2025-01-23: cranky: [INFO] Detected Theory App 2025-01-23 08:43:19 (69095): Guest Log: 09:43:19 CST -06:00 2025-01-23: cranky: [INFO] Checking CVMFS. 2025-01-23 08:43:20 (69095): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2025-01-23 08:43:21 (69095): 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-23 08:43:21 (69095): Guest Log: 2.5.2.0 4067 0 26480 31546 3 1 278956 4096000 0 65024 0 0 n/a 5 3 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2025-01-23 08:43:22 (69095): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2025-01-23 08:43:22 (69095): 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-23 08:43:22 (69095): Guest Log: 2.5.2.0 3996 0 25480 26297 3 2 278956 4096000 0 65024 0 2 -100 10085 3484 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2025-01-23 08:43:23 (69095): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2025-01-23 08:43:24 (69095): 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-23 08:43:24 (69095): Guest Log: 2.5.2.0 4021 0 25220 272 3 1 278956 4096000 0 65024 0 0 n/a 4 3 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2025-01-23 08:43:25 (69095): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2025-01-23 08:43:25 (69095): 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-23 08:43:25 (69095): Guest Log: 2.5.2.0 4042 0 39704 20949 3 1 278956 4096000 0 65024 0 0 n/a 6595 3266 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2025-01-23 08:43:25 (69095): Guest Log: 09:43:25 CST -06:00 2025-01-23: cranky: [INFO] Checking runc. 2025-01-23 08:43:25 (69095): Guest Log: 09:43:25 CST -06:00 2025-01-23: cranky: [INFO] Creating the filesystem. 2025-01-23 08:43:25 (69095): Guest Log: 09:43:25 CST -06:00 2025-01-23: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4 2025-01-23 08:43:26 (69095): Guest Log: 09:43:25 CST -06:00 2025-01-23: cranky: [INFO] Updating config.json. 2025-01-23 08:43:26 (69095): Guest Log: 09:43:25 CST -06:00 2025-01-23: cranky: [INFO] Running Container 'runc'. 2025-01-23 08:43:31 (69095): Guest Log: job: htmld=/shared/html/job 2025-01-23 08:43:31 (69095): Guest Log: job: unpack exitcode=0 2025-01-23 08:43:32 (69095): Guest Log: INFO: activated the work-around for ld: 2025-01-23 08:43:32 (69095): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 23 15:43 /tmp/tmp.iizkgRyKYv/ld -> /usr/bin/ld.bfd 2025-01-23 08:43:32 (69095): Guest Log: 09:43:31 CST -06:00 2025-01-23: cranky: [INFO] 2025-01-23 09:08:35 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-23 09:08:56 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-23 09:15:17 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-23 09:15:37 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-23 09:17:17 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-23 09:17:36 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-23 09:22:58 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-23 09:23:18 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-23 09:37:31 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-23 09:37:51 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-23 09:50:34 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-23 09:50:54 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-23 09:50:55 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 02:03:01 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-24 02:03:02 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 02:03:22 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-24 02:03:26 (69095): Guest Log: 01:06:03.468099 timesync vgsvcTimeSyncWorker: Radical host time change: 58 376 587 000 000ns (HostNow=1 737 709 405 244 000 000 ns HostLast=1 737 651 028 657 000 000 ns) 2025-01-24 02:03:36 (69095): Guest Log: 01:06:13.528935 timesync vgsvcTimeSyncWorker: Radical guest time change: 58 476 108 982 000ns (GuestNow=1 737 709 415 337 061 000 ns GuestLast=1 737 650 939 228 079 000 ns fSetTimeLastLoop=true ) 2025-01-24 02:04:13 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 02:04:33 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-24 02:05:39 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 07:26:20 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-24 07:26:26 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 07:26:45 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-24 07:26:46 (69095): Guest Log: 01:08:03.720042 timesync vgsvcTimeSyncWorker: Radical host time change: 19 270 510 000 000ns (HostNow=1 737 728 806 006 000 000 ns HostLast=1 737 709 535 496 000 000 ns) 2025-01-24 07:26:56 (69095): Guest Log: 01:08:13.736122 timesync vgsvcTimeSyncWorker: Radical guest time change: 19 290 493 936 000ns (GuestNow=1 737 728 816 022 099 000 ns GuestLast=1 737 709 525 528 163 000 ns fSetTimeLastLoop=true ) 2025-01-24 07:27:27 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 18:04:56 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-24 18:05:03 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 18:05:23 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-24 18:05:25 (69095): Guest Log: 01:08:53.786347 timesync vgsvcTimeSyncWorker: Radical host time change: 38 278 707 000 000ns (HostNow=1 737 767 124 737 000 000 ns HostLast=1 737 728 846 030 000 000 ns) 2025-01-24 18:05:35 (69095): Guest Log: 01:09:03.791568 timesync vgsvcTimeSyncWorker: Radical guest time change: 38 278 669 944 000ns (GuestNow=1 737 767 134 742 240 000 ns GuestLast=1 737 728 856 072 296 000 ns fSetTimeLastLoop=true ) 2025-01-24 18:09:33 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 18:09:53 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-24 18:11:34 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-25 04:24:59 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-25 04:25:00 (69095): Guest Log: 01:14:44.031732 timesync vgsvcTimeSyncWorker: Radical host time change: 36 814 615 000 000ns (HostNow=1 737 804 299 160 000 000 ns HostLast=1 737 767 484 545 000 000 ns) 2025-01-25 04:25:10 (69095): Guest Log: 01:14:54.042363 timesync vgsvcTimeSyncWorker: Radical guest time change: 36 834 188 260 000ns (GuestNow=1 737 804 309 170 640 000 ns GuestLast=1 737 767 474 982 380 000 ns fSetTimeLastLoop=true ) 2025-01-25 04:49:05 (69095): Status Report: Job Duration: '864000.000000' 2025-01-25 04:49:05 (69095): Status Report: Elapsed Time: '6000.000000' 2025-01-25 04:49:05 (69095): Status Report: CPU Time: '5653.030000' 2025-01-25 04:54:54 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-25 04:55:13 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-25 04:55:14 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-25 09:41:49 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-25 09:41:56 (69095): Guest Log: 01:44:46.284518 timesync vgsvcTimeSyncWorker: Radical host time change: 17 224 685 000 000ns (HostNow=1 737 823 315 842 000 000 ns HostLast=1 737 806 091 157 000 000 ns) 2025-01-25 09:41:58 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-25 09:42:18 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-25 09:42:26 (69095): Guest Log: 01:44:56.297172 timesync vgsvcTimeSyncWorker: Radical guest time change: 17 224 441 883 000ns (GuestNow=1 737 823 325 854 666 000 ns GuestLast=1 737 806 101 412 783 000 ns fSetTimeLastLoop=true ) 2025-01-25 10:09:04 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-25 10:09:25 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-25 11:15:45 (69095): Status Report: Job Duration: '864000.000000' 2025-01-25 11:15:45 (69095): Status Report: Elapsed Time: '12000.000000' 2025-01-25 11:15:45 (69095): Status Report: CPU Time: '11598.480000' 2025-01-25 11:20:18 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-25 11:20:38 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-25 11:37:12 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-25 11:37:32 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-25 11:56:45 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-25 11:57:05 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-25 11:57:15 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-25 11:57:36 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-25 12:23:31 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-25 12:23:52 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-25 12:34:14 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-25 12:34:34 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-25 12:34:37 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-26 01:53:59 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-26 01:54:00 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-26 01:54:20 (69095): Guest Log: 04:34:51.995814 timesync vgsvcTimeSyncWorker: Radical host time change: 48 011 965 000 000ns (HostNow=1 737 881 659 724 000 000 ns HostLast=1 737 833 647 759 000 000 ns) 2025-01-26 01:54:20 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-26 01:54:31 (69095): Guest Log: 04:35:02.000373 timesync vgsvcTimeSyncWorker: Radical guest time change: 48 148 175 272 000ns (GuestNow=1 737 881 669 728 566 000 ns GuestLast=1 737 833 521 553 294 000 ns fSetTimeLastLoop=true ) 2025-01-26 02:03:52 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-26 02:11:48 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-26 02:11:55 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-26 02:12:14 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-26 02:19:45 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-26 03:16:37 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-26 03:16:38 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-26 03:16:58 (69095): Guest Log: 04:52:02.513335 timesync vgsvcTimeSyncWorker: Radical host time change: 3 442 179 000 000ns (HostNow=1 737 886 617 248 000 000 ns HostLast=1 737 883 175 069 000 000 ns) 2025-01-26 03:16:58 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-26 03:17:07 (69095): Guest Log: 04:52:12.519388 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 937 012 527 000ns (GuestNow=1 737 886 627 254 056 000 ns GuestLast=1 737 882 690 241 529 000 ns fSetTimeLastLoop=true ) 2025-01-26 03:22:07 (69095): Status Report: Job Duration: '864000.000000' 2025-01-26 03:22:07 (69095): Status Report: Elapsed Time: '18000.000000' 2025-01-26 03:22:07 (69095): Status Report: CPU Time: '17553.530000' 2025-01-26 03:23:49 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-26 03:24:09 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-26 03:24:50 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-26 03:25:10 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-26 03:25:11 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-26 04:29:08 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-26 04:29:13 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-26 04:29:33 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-26 04:29:35 (69095): Guest Log: 04:59:43.335993 timesync vgsvcTimeSyncWorker: Radical host time change: 3 887 238 000 000ns (HostNow=1 737 890 974 931 000 000 ns HostLast=1 737 887 087 693 000 000 ns) 2025-01-26 04:29:45 (69095): Guest Log: 04:59:53.351666 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 906 876 036 000ns (GuestNow=1 737 890 984 946 688 000 ns GuestLast=1 737 887 078 070 652 000 ns fSetTimeLastLoop=true ) 2025-01-26 04:41:46 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-26 04:42:06 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-26 04:42:07 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 00:37:19 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 00:37:23 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 00:37:43 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 00:37:49 (69095): Guest Log: 05:12:04.513049 timesync vgsvcTimeSyncWorker: Radical host time change: 71 742 229 000 000ns (HostNow=1 737 963 468 270 000 000 ns HostLast=1 737 891 726 041 000 000 ns) 2025-01-27 00:37:59 (69095): Guest Log: 05:12:14.536154 timesync vgsvcTimeSyncWorker: Radical guest time change: 71 762 185 079 000ns (GuestNow=1 737 963 478 293 125 000 ns GuestLast=1 737 891 716 108 046 000 ns fSetTimeLastLoop=true ) 2025-01-27 00:39:14 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 00:39:35 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 00:39:36 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 00:48:30 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 00:49:58 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 00:50:18 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 00:50:19 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 07:53:52 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 07:53:55 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 07:54:15 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 07:54:16 (69095): Guest Log: 05:15:04.645026 timesync vgsvcTimeSyncWorker: Radical host time change: 25 463 575 000 000ns (HostNow=1 737 989 656 291 000 000 ns HostLast=1 737 964 192 716 000 000 ns) 2025-01-27 07:54:27 (69095): Guest Log: 05:15:14.652350 timesync vgsvcTimeSyncWorker: Radical guest time change: 26 017 896 363 000ns (GuestNow=1 737 989 666 298 337 000 ns GuestLast=1 737 963 648 401 974 000 ns fSetTimeLastLoop=true ) 2025-01-27 08:02:47 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 08:03:07 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 08:05:07 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 08:05:27 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 08:11:00 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 08:11:20 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 08:27:03 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 08:27:23 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 08:29:13 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 08:29:34 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 08:36:15 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 08:36:35 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 08:49:18 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 08:49:38 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 08:51:49 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 08:52:08 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 08:53:39 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 08:53:59 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 08:55:39 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 08:56:00 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 09:06:42 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 09:07:01 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 09:07:13 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 09:07:33 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 09:17:15 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 09:17:35 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 09:18:26 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 09:18:45 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 09:20:30 (69095): Status Report: Job Duration: '864000.000000' 2025-01-27 09:20:30 (69095): Status Report: Elapsed Time: '24000.000000' 2025-01-27 09:20:30 (69095): Status Report: CPU Time: '23539.760000' 2025-01-27 09:25:26 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 09:25:47 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 10:20:08 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 10:20:27 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 10:41:43 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 10:42:03 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 10:48:03 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 10:48:24 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 10:48:25 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 18:39:53 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 18:39:57 (69095): Guest Log: 08:03:22.316410 timesync vgsvcTimeSyncWorker: Radical host time change: 28 318 707 000 000ns (HostNow=1 738 028 397 310 000 000 ns HostLast=1 738 000 078 603 000 000 ns) 2025-01-27 18:39:59 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 18:40:19 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 18:40:27 (69095): Guest Log: 08:03:32.320358 timesync vgsvcTimeSyncWorker: Radical guest time change: 28 653 351 580 000ns (GuestNow=1 738 028 407 313 961 000 ns GuestLast=1 737 999 753 962 381 000 ns fSetTimeLastLoop=true ) 2025-01-27 18:40:52 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 18:40:53 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 18:46:02 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 18:46:22 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 18:46:23 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 19:10:55 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 19:11:02 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 19:11:23 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 19:12:23 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 08:37:15 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 08:37:16 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 08:37:36 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 08:37:43 (69095): Guest Log: 08:10:22.520438 timesync vgsvcTimeSyncWorker: Radical host time change: 48 322 036 000 000ns (HostNow=1 738 078 662 604 000 000 ns HostLast=1 738 030 340 568 000 000 ns) 2025-01-28 08:37:53 (69095): Guest Log: 08:10:32.530378 timesync vgsvcTimeSyncWorker: Radical guest time change: 48 342 615 606 000ns (GuestNow=1 738 078 672 613 961 000 ns GuestLast=1 738 030 329 998 355 000 ns fSetTimeLastLoop=true ) 2025-01-28 08:42:08 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 08:42:28 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 08:42:30 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 20:23:22 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 20:23:23 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 20:23:43 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 20:23:45 (69095): Guest Log: 08:14:52.920147 timesync vgsvcTimeSyncWorker: Radical host time change: 42 101 771 000 000ns (HostNow=1 738 121 024 822 000 000 ns HostLast=1 738 078 923 051 000 000 ns) 2025-01-28 20:23:55 (69095): Guest Log: 08:15:02.949938 timesync vgsvcTimeSyncWorker: Radical guest time change: 42 101 848 098 000ns (GuestNow=1 738 121 034 851 805 000 ns GuestLast=1 738 078 933 003 707 000 ns fSetTimeLastLoop=true ) 2025-01-28 20:24:52 (69095): Status Report: Job Duration: '864000.000000' 2025-01-28 20:24:52 (69095): Status Report: Elapsed Time: '30000.000000' 2025-01-28 20:24:52 (69095): Status Report: CPU Time: '29519.640000' 2025-01-28 20:25:24 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 20:25:44 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 20:27:45 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 20:28:05 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 20:31:36 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 20:31:56 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 20:43:16 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 23:12:31 (69095): Guest Log: 08:33:24.151395 timesync vgsvcTimeSyncWorker: Radical host time change: 8 965 261 000 000ns (HostNow=1 738 131 151 078 000 000 ns HostLast=1 738 122 185 817 000 000 ns) 2025-01-28 23:12:31 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 23:12:32 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 23:19:52 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 23:20:01 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 23:20:21 (69095): Guest Log: 08:33:34.178359 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 025 051 735 000ns (GuestNow=1 738 131 161 104 974 000 ns GuestLast=1 738 122 136 053 239 000 ns fSetTimeLastLoop=true ) 2025-01-28 23:20:21 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 23:24:59 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 23:25:19 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 23:27:50 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 23:28:10 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 23:31:01 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 23:31:21 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 23:47:18 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 23:47:37 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 23:47:57 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 23:48:18 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 23:50:08 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 23:50:29 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-28 23:51:59 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-28 23:52:19 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 00:02:32 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 00:02:53 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 00:13:07 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 00:13:27 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 00:13:37 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 00:13:58 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 00:15:18 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 00:15:38 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 00:16:18 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 00:16:38 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 00:20:59 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 00:21:20 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 00:26:52 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 00:27:12 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 00:37:56 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 00:38:16 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 00:43:28 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 00:43:49 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 00:47:32 (69095): Status Report: Job Duration: '864000.000000' 2025-01-29 00:47:32 (69095): Status Report: Elapsed Time: '36000.000000' 2025-01-29 00:47:32 (69095): Status Report: CPU Time: '35440.460000' 2025-01-29 00:50:40 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 00:51:01 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 00:52:51 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 00:53:12 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 00:58:14 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 00:58:34 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 01:03:15 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 01:03:36 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 01:06:17 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 01:06:37 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 01:07:37 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 01:07:57 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 01:10:38 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 01:10:58 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 01:21:12 (69095): VM state change detected. (old = 'running', new = 'paused') 2025-01-29 01:21:33 (69095): VM state change detected. (old = 'paused', new = 'running') 2025-01-29 01:22:43 (69095): Guest Log: job: run exitcode=0 2025-01-29 01:22:43 (69095): Guest Log: job: diskusage=99704 2025-01-29 01:22:43 (69095): Guest Log: job: logsize=76 k 2025-01-29 01:22:43 (69095): Guest Log: job: times= 2025-01-29 01:22:43 (69095): Guest Log: 0m0.006s 0m0.020s 2025-01-29 01:22:43 (69095): Guest Log: 610m29.806s 2m0.170s 2025-01-29 01:22:43 (69095): Guest Log: job: cpuusage=36750 2025-01-29 01:22:43 (69095): Guest Log: 02:07:02 CST -06:00 2025-01-29: cranky: [INFO] Container 'runc' finished with status code 0. 2025-01-29 01:22:43 (69095): Guest Log: 02:07:02 CST -06:00 2025-01-29: cranky: [INFO] Preparing output. 2025-01-29 01:22:44 (69095): Guest Log: [INFO] Job Finished 2025-01-29 01:22:44 (69095): Guest Log: [INFO] Shutting Down. 2025-01-29 01:22:44 (69095): VM Completion File Detected. 2025-01-29 01:22:44 (69095): VM Completion Message: Job Finished . 2025-01-29 01:22:44 (69095): Powering off VM. 2025-01-29 01:22:45 (69095): Successfully stopped VM. 2025-01-29 01:22:45 (69095): Deregistering VM. (boinc_7b0c788f457c0007, slot#4) 2025-01-29 01:22:45 (69095): Removing network bandwidth throttle group from VM. 2025-01-29 01:22:45 (69095): Removing VM from VirtualBox. 2025-01-29 01:22:50 (69095): called boinc_finish(0) </stderr_txt> ]]>
©2025 CERN