Name Theory_2743-2719119-403_1
Workunit 225154719
Created 30 Aug 2024, 17:36:20 UTC
Sent 31 Aug 2024, 11:20:57 UTC
Report deadline 11 Sep 2024, 11:20:57 UTC
Received 13 Sep 2024, 3:37:45 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 10840962
Run time 2 days 6 hours 39 min 51 sec
CPU time 1 days 17 hours 25 min 1 sec
Validate state Valid
Credit 1,724.16
Device peak FLOPS 3.78 GFLOPS
Application version Theory Simulation v300.30 (vbox64_theory)
windows_x86_64
Peak working set size 87.34 MB
Peak swap size 77.93 MB
Peak disk usage 1.64 GB

Stderr output

<core_client_version>8.0.2</core_client_version>
<![CDATA[
<stderr_txt>
2024-09-03 10:28:14 (5584): vboxwrapper version 26207
2024-09-03 10:28:14 (5584): BOINC client version: 8.0.2
2024-09-03 10:28:14 (5584): Detected: VirtualBox VboxManage Interface (Version: 7.0.20)
2024-09-03 10:28:15 (5584): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-09-03 10:28:15 (5584): Successfully copied 'init_data.xml' to the shared directory.
2024-09-03 10:28:15 (5584): Successfully copied 'input' to the shared directory.
2024-09-03 10:28:15 (5584): Create VM. (boinc_870f26651c5dc9ad, slot#6)
2024-09-03 10:28:16 (5584): Setting Memory Size for VM. (630MB)
2024-09-03 10:28:16 (5584): Setting CPU Count for VM. (1)
2024-09-03 10:28:16 (5584): Setting Chipset Options for VM.
2024-09-03 10:28:16 (5584): Setting Graphics Controller Options for VM.
2024-09-03 10:28:17 (5584): Setting Boot Options for VM.
2024-09-03 10:28:17 (5584): Setting Network Configuration for NAT.
2024-09-03 10:28:17 (5584): Enabling VM Network Access.
2024-09-03 10:28:17 (5584): Disabling USB Support for VM.
2024-09-03 10:28:18 (5584): Disabling COM Port Support for VM.
2024-09-03 10:28:18 (5584): Disabling LPT Port Support for VM.
2024-09-03 10:28:18 (5584): Disabling Audio Support for VM.
2024-09-03 10:28:18 (5584): Disabling Clipboard Support for VM.
2024-09-03 10:28:19 (5584): Disabling Drag and Drop Support for VM.
2024-09-03 10:28:19 (5584): Adding storage controller(s) to VM.
2024-09-03 10:28:19 (5584): Adding virtual disk drive to VM. (Theory_2024_04_30_prod.vdi)
2024-09-03 10:28:20 (5584): Adding VirtualBox Guest Additions to VM.
2024-09-03 10:28:20 (5584): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2024-09-03 10:28:20 (5584): forwarding host port 53641 to guest port 80
2024-09-03 10:28:21 (5584): Enabling remote desktop for VM.
2024-09-03 10:28:21 (5584): Required extension pack not installed, remote desktop not enabled.
2024-09-03 10:28:21 (5584): Enabling shared directory for VM.
2024-09-03 10:28:21 (5584): Starting VM using VBoxManage interface. (boinc_870f26651c5dc9ad, slot#6)
2024-09-03 10:28:30 (5584): Successfully started VM. (PID = '31188')
2024-09-03 10:28:30 (5584): Reporting VM Process ID to BOINC.
2024-09-03 10:28:30 (5584): Guest Log: BIOS: VirtualBox 7.0.20
2024-09-03 10:28:30 (5584): Guest Log: CPUID EDX: 0x178bfbff
2024-09-03 10:28:30 (5584): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-09-03 10:28:30 (5584): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-09-03 10:28:30 (5584): VM state change detected. (old = 'poweredoff', new = 'running')
2024-09-03 10:28:30 (5584): Detected: Web Application Enabled (http://localhost:53641)
2024-09-03 10:28:30 (5584): Preference change detected
2024-09-03 10:28:30 (5584): Setting CPU throttle for VM. (70%)
2024-09-03 10:28:30 (5584): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-09-03 10:28:32 (5584): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-09-03 10:28:32 (5584): Guest Log: BIOS: Booting from Hard Disk...
2024-09-03 10:28:35 (5584): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-09-03 10:28:35 (5584): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-09-03 10:28:54 (5584): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-09-03 10:28:54 (5584): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-09-03 10:28:59 (5584): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-09-03 10:28:59 (5584): Guest Log: 00:00:00.000145 main     Log opened 2024-09-03T15:28:59.140296000Z
2024-09-03 10:28:59 (5584): Guest Log: 00:00:00.000342 main     OS Product: Linux
2024-09-03 10:28:59 (5584): Guest Log: 00:00:00.000387 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-09-03 10:28:59 (5584): Guest Log: 00:00:00.000434 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-09-03 10:28:59 (5584): Guest Log: 00:00:00.000463 main     Executable: /usr/sbin/VBoxService
2024-09-03 10:28:59 (5584): Guest Log: 00:00:00.000464 main     Process ID: 3222
2024-09-03 10:28:59 (5584): Guest Log: 00:00:00.000465 main     Package type: LINUX_64BITS_GENERIC
2024-09-03 10:28:59 (5584): Guest Log: 00:00:00.003504 main     5.2.6 r120293 started. Verbose level = 0
2024-09-03 10:29:24 (5584): Guest Log: 17:29:24 CEST +02:00 2024-09-03: cranky: [INFO] Detected Theory App
2024-09-03 10:29:24 (5584): Guest Log: 17:29:24 CEST +02:00 2024-09-03: cranky: [INFO] Checking CVMFS.
2024-09-03 10:29:26 (5584): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-09-03 10:29:26 (5584): 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-03 10:29:26 (5584): Guest Log: 2.5.2.0 4129 0 26556 30376 3 1 273025 4096000 0 65024 0 0 n/a 5 10 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-09-03 10:29:27 (5584): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-09-03 10:29:28 (5584): 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-03 10:29:28 (5584): Guest Log: 2.5.2.0 4107 0 27532 25133 3 2 273025 4096000 0 65024 0 2 -100 7969 4850 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-09-03 10:29:29 (5584): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-09-03 10:29:30 (5584): 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-03 10:29:30 (5584): Guest Log: 2.5.2.0 4081 0 27224 272 3 1 273025 4096000 0 65024 0 0 n/a 4 3 http://s1bnl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-09-03 10:29:31 (5584): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-09-03 10:29:32 (5584): 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-03 10:29:32 (5584): Guest Log: 2.5.2.0 4060 0 36592 19568 3 1 273025 4096000 0 65024 0 0 n/a 5799 4639 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-09-03 10:29:32 (5584): Guest Log: 17:29:32 CEST +02:00 2024-09-03: cranky: [INFO] Checking runc.
2024-09-03 10:29:32 (5584): Guest Log: 17:29:32 CEST +02:00 2024-09-03: cranky: [INFO] Creating the filesystem.
2024-09-03 10:29:32 (5584): Guest Log: 17:29:32 CEST +02:00 2024-09-03: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-09-03 10:29:32 (5584): Guest Log: 17:29:32 CEST +02:00 2024-09-03: cranky: [INFO] Updating config.json.
2024-09-03 10:29:32 (5584): Guest Log: 17:29:32 CEST +02:00 2024-09-03: cranky: [INFO] Running Container 'runc'.
2024-09-03 10:29:34 (5584): Guest Log: job: htmld=/shared/html/job
2024-09-03 10:29:35 (5584): Guest Log: job: unpack exitcode=0
2024-09-03 10:29:35 (5584): Guest Log: INFO: activated the work-around for ld:
2024-09-03 10:29:35 (5584): Guest Log: lrwxrwxrwx 1 0 0 15 Sep  3 15:29 /tmp/tmp.C2AG3KYmWS/ld -> /usr/bin/ld.bfd
2024-09-03 10:29:37 (5584): Guest Log: 17:29:36 CEST +02:00 2024-09-03: cranky: [INFO] ===> [runRivet] Tue Sep  3 15:29:35 UTC 2024 [boinc pp jets 13000 150 - pythia8 8.308 CP1-CR1 100000 403]
2024-09-03 10:43:45 (5584): VM state change detected. (old = 'running', new = 'paused')
2024-09-03 10:44:06 (5584): VM state change detected. (old = 'paused', new = 'running')
2024-09-03 11:22:02 (5584): VM state change detected. (old = 'running', new = 'paused')
2024-09-03 11:22:22 (5584): VM state change detected. (old = 'paused', new = 'running')
2024-09-03 11:42:26 (5584): VM state change detected. (old = 'running', new = 'paused')
2024-09-03 11:42:46 (5584): VM state change detected. (old = 'paused', new = 'running')
2024-09-03 11:45:59 (5584): VM state change detected. (old = 'running', new = 'paused')
2024-09-03 11:46:20 (5584): VM state change detected. (old = 'paused', new = 'running')
2024-09-03 12:10:01 (5584): Status Report: Job Duration: '864000.000000'
2024-09-03 12:10:01 (5584): Status Report: Elapsed Time: '6000.000000'
2024-09-03 12:10:01 (5584): Status Report: CPU Time: '4446.625000'
2024-09-03 12:15:19 (5584): VM state change detected. (old = 'running', new = 'paused')
2024-09-03 12:15:39 (5584): VM state change detected. (old = 'paused', new = 'running')
2024-09-03 13:20:06 (5584): VM state change detected. (old = 'running', new = 'paused')
2024-09-03 13:20:26 (5584): VM state change detected. (old = 'paused', new = 'running')
2024-09-03 13:50:48 (5584): Status Report: Job Duration: '864000.000000'
2024-09-03 13:50:48 (5584): Status Report: Elapsed Time: '12000.000000'
2024-09-03 13:50:48 (5584): Status Report: CPU Time: '9011.609375'
2024-09-03 14:22:14 (5584): VM state change detected. (old = 'running', new = 'paused')
2024-09-06 13:50:30 (5584): Stopping VM.
2024-09-06 13:50:45 (5584): Successfully stopped VM.
2024-09-10 17:36:43 (22436): vboxwrapper version 26207
2024-09-10 17:36:43 (22436): BOINC client version: 8.0.2
2024-09-10 17:36:44 (22436): Detected: VirtualBox VboxManage Interface (Version: 7.0.20)
2024-09-10 17:36:45 (22436): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-09-10 17:36:45 (22436): Guest Log: BIOS: VirtualBox 7.0.20
2024-09-10 17:36:45 (22436): Guest Log: CPUID EDX: 0x178bfbff
2024-09-10 17:36:45 (22436): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-09-10 17:36:45 (22436): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-09-10 17:36:45 (22436): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-09-10 17:36:45 (22436): Guest Log: BIOS: Booting from Hard Disk...
2024-09-10 17:36:45 (22436): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-09-10 17:36:45 (22436): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-09-10 17:36:45 (22436): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-09-10 17:36:45 (22436): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-09-10 17:36:45 (22436): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-09-10 17:36:45 (22436): Guest Log: 00:00:00.000145 main     Log opened 2024-09-03T15:28:59.140296000Z
2024-09-10 17:36:45 (22436): Guest Log: 00:00:00.000342 main     OS Product: Linux
2024-09-10 17:36:45 (22436): Guest Log: 00:00:00.000387 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-09-10 17:36:45 (22436): Guest Log: 00:00:00.000434 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-09-10 17:36:45 (22436): Guest Log: 00:00:00.000463 main     Executable: /usr/sbin/VBoxService
2024-09-10 17:36:45 (22436): Guest Log: 00:00:00.000464 main     Process ID: 3222
2024-09-10 17:36:45 (22436): Guest Log: 00:00:00.000465 main     Package type: LINUX_64BITS_GENERIC
2024-09-10 17:36:45 (22436): Guest Log: 00:00:00.003504 main     5.2.6 r120293 started. Verbose level = 0
2024-09-10 17:36:45 (22436): Guest Log: 17:29:24 CEST +02:00 2024-09-03: cranky: [INFO] Detected Theory App
2024-09-10 17:36:45 (22436): Guest Log: 17:29:24 CEST +02:00 2024-09-03: cranky: [INFO] Checking CVMFS.
2024-09-10 17:36:45 (22436): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-09-10 17:36:45 (22436): 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-10 17:36:45 (22436): Guest Log: 2.5.2.0 4129 0 26556 30376 3 1 273025 4096000 0 65024 0 0 n/a 5 10 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-09-10 17:36:45 (22436): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-09-10 17:36:45 (22436): 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-10 17:36:45 (22436): Guest Log: 2.5.2.0 4107 0 27532 25133 3 2 273025 4096000 0 65024 0 2 -100 7969 4850 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-09-10 17:36:45 (22436): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-09-10 17:36:45 (22436): 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-10 17:36:45 (22436): Guest Log: 2.5.2.0 4081 0 27224 272 3 1 273025 4096000 0 65024 0 0 n/a 4 3 http://s1bnl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-09-10 17:36:45 (22436): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-09-10 17:36:45 (22436): 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-10 17:36:45 (22436): Guest Log: 2.5.2.0 4060 0 36592 19568 3 1 273025 4096000 0 65024 0 0 n/a 5799 4639 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-09-10 17:36:45 (22436): Guest Log: 17:29:32 CEST +02:00 2024-09-03: cranky: [INFO] Checking runc.
2024-09-10 17:36:45 (22436): Guest Log: 17:29:32 CEST +02:00 2024-09-03: cranky: [INFO] Creating the filesystem.
2024-09-10 17:36:45 (22436): Guest Log: 17:29:32 CEST +02:00 2024-09-03: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-09-10 17:36:45 (22436): Guest Log: 17:29:32 CEST +02:00 2024-09-03: cranky: [INFO] Updating config.json.
2024-09-10 17:36:45 (22436): Guest Log: 17:29:32 CEST +02:00 2024-09-03: cranky: [INFO] Running Container 'runc'.
2024-09-10 17:36:45 (22436): Guest Log: job: htmld=/shared/html/job
2024-09-10 17:36:45 (22436): Guest Log: job: unpack exitcode=0
2024-09-10 17:36:45 (22436): Guest Log: INFO: activated the work-around for ld:
2024-09-10 17:36:45 (22436): Guest Log: lrwxrwxrwx 1 0 0 15 Sep  3 15:29 /tmp/tmp.C2AG3KYmWS/ld -> /usr/bin/ld.bfd
2024-09-10 17:36:45 (22436): Guest Log: 17:29:36 CEST +02:00 2024-09-03: cranky: [INFO] ===> [runRivet] Tue Sep  3 15:29:35 UTC 2024 [boinc pp jets 13000 150 - pythia8 8.308 CP1-CR1 100000 403]
2024-09-10 17:36:45 (22436): Starting VM using VBoxManage interface. (boinc_870f26651c5dc9ad, slot#6)
2024-09-10 17:36:59 (22436): Successfully started VM. (PID = '30392')
2024-09-10 17:36:59 (22436): Reporting VM Process ID to BOINC.
2024-09-10 17:36:59 (22436): VM state change detected. (old = 'poweredoff', new = 'running')
2024-09-10 17:36:59 (22436): Detected: Web Application Enabled (http://localhost:53641)
2024-09-10 17:37:00 (22436): VM state change detected. (old = 'running', new = 'paused')
2024-09-10 17:37:08 (22436): Status Report: Job Duration: '864000.000000'
2024-09-10 17:37:08 (22436): Status Report: Elapsed Time: '13882.000000'
2024-09-10 17:37:08 (22436): Status Report: CPU Time: '10448.546875'
2024-09-10 17:37:08 (22436): Preference change detected
2024-09-10 17:37:08 (22436): Setting CPU throttle for VM. (70%)
2024-09-10 17:37:08 (22436): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-09-10 17:37:08 (22436): VM state change detected. (old = 'paused', new = 'running')
2024-09-10 17:37:14 (22436): Guest Log: 03:51:27.399440 timesync vgsvcTimeSyncWorker: Radical host time change: 616 503 284 000 000ns (HostNow=1 726 007 834 749 000 000 ns HostLast=1 725 391 331 465 000 000 ns)
2024-09-10 17:37:24 (22436): Guest Log: 03:51:37.426508 timesync vgsvcTimeSyncWorker: Radical guest time change: 616 618 347 137 000ns (GuestNow=1 726 007 844 809 825 000 ns GuestLast=1 725 391 226 462 688 000 ns fSetTimeLastLoop=true )
2024-09-10 18:14:07 (22436): VM state change detected. (old = 'running', new = 'paused')
2024-09-10 18:14:28 (22436): VM state change detected. (old = 'paused', new = 'running')
2024-09-10 18:26:27 (22436): VM state change detected. (old = 'running', new = 'paused')
2024-09-10 18:26:47 (22436): VM state change detected. (old = 'paused', new = 'running')
2024-09-10 18:30:30 (22436): VM state change detected. (old = 'running', new = 'paused')
2024-09-10 18:30:50 (22436): VM state change detected. (old = 'paused', new = 'running')
2024-09-10 18:52:38 (22436): VM state change detected. (old = 'running', new = 'paused')
2024-09-10 18:52:58 (22436): VM state change detected. (old = 'paused', new = 'running')
2024-09-10 19:18:37 (22436): Status Report: Job Duration: '864000.000000'
2024-09-10 19:18:37 (22436): Status Report: Elapsed Time: '19882.000000'
2024-09-10 19:18:37 (22436): Status Report: CPU Time: '15020.937500'
2024-09-10 20:32:20 (22436): VM state change detected. (old = 'running', new = 'paused')
2024-09-10 20:32:40 (22436): VM state change detected. (old = 'paused', new = 'running')
2024-09-10 20:42:06 (22436): VM state change detected. (old = 'running', new = 'paused')
2024-09-10 20:42:26 (22436): VM state change detected. (old = 'paused', new = 'running')
2024-09-10 20:59:24 (22436): Status Report: Job Duration: '864000.000000'
2024-09-10 20:59:24 (22436): Status Report: Elapsed Time: '25882.000000'
2024-09-10 20:59:24 (22436): Status Report: CPU Time: '19584.703125'
2024-09-10 21:17:37 (22436): VM state change detected. (old = 'running', new = 'paused')
2024-09-10 21:17:57 (22436): VM state change detected. (old = 'paused', new = 'running')
2024-09-10 21:53:11 (22436): VM state change detected. (old = 'running', new = 'paused')
2024-09-10 21:53:31 (22436): VM state change detected. (old = 'paused', new = 'running')
2024-09-10 22:40:11 (22436): Status Report: Job Duration: '864000.000000'
2024-09-10 22:40:11 (22436): Status Report: Elapsed Time: '31882.000000'
2024-09-10 22:40:11 (22436): Status Report: CPU Time: '24145.968750'
2024-09-11 00:20:16 (22436): Status Report: Job Duration: '864000.000000'
2024-09-11 00:20:16 (22436): Status Report: Elapsed Time: '37882.000000'
2024-09-11 00:20:16 (22436): Status Report: CPU Time: '28706.593750'
2024-09-11 02:00:21 (22436): Status Report: Job Duration: '864000.000000'
2024-09-11 02:00:21 (22436): Status Report: Elapsed Time: '43882.000000'
2024-09-11 02:00:21 (22436): Status Report: CPU Time: '33267.859375'
2024-09-11 02:47:01 (22436): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 02:47:22 (22436): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 03:40:47 (22436): Status Report: Job Duration: '864000.000000'
2024-09-11 03:40:47 (22436): Status Report: Elapsed Time: '49882.000000'
2024-09-11 03:40:47 (22436): Status Report: CPU Time: '37833.593750'
2024-09-11 05:24:54 (22436): Status Report: Job Duration: '864000.000000'
2024-09-11 05:24:54 (22436): Status Report: Elapsed Time: '55882.085007'
2024-09-11 05:24:54 (22436): Status Report: CPU Time: '42433.593750'
2024-09-11 07:04:59 (22436): Status Report: Job Duration: '864000.000000'
2024-09-11 07:04:59 (22436): Status Report: Elapsed Time: '61882.085007'
2024-09-11 07:04:59 (22436): Status Report: CPU Time: '46994.734375'
2024-09-11 08:45:03 (22436): Status Report: Job Duration: '864000.000000'
2024-09-11 08:45:03 (22436): Status Report: Elapsed Time: '67882.085007'
2024-09-11 08:45:03 (22436): Status Report: CPU Time: '51557.578125'
2024-09-11 10:25:08 (22436): Status Report: Job Duration: '864000.000000'
2024-09-11 10:25:08 (22436): Status Report: Elapsed Time: '73882.085007'
2024-09-11 10:25:08 (22436): Status Report: CPU Time: '56119.406250'
2024-09-11 11:01:50 (22436): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 11:02:11 (22436): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 11:03:42 (22436): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 11:04:02 (22436): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 11:49:56 (22436): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 11:50:16 (22436): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 12:06:20 (22436): Status Report: Job Duration: '864000.000000'
2024-09-11 12:06:20 (22436): Status Report: Elapsed Time: '79882.085007'
2024-09-11 12:06:20 (22436): Status Report: CPU Time: '60678.953125'
2024-09-11 14:06:27 (19552): vboxwrapper version 26207
2024-09-11 14:06:27 (19552): BOINC client version: 8.0.2
2024-09-11 14:06:28 (19552): Detected: VirtualBox VboxManage Interface (Version: 7.0.20)
2024-09-11 14:06:29 (19552): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-09-11 14:06:30 (19552): Guest Log: 03:51:27.399440 timesync vgsvcTimeSyncWorker: Radical host time change: 616 503 284 000 000ns (HostNow=1 726 007 834 749 000 000 ns HostLast=1 725 391 331 465 000 000 ns)
2024-09-11 14:06:30 (19552): Guest Log: 03:51:37.426508 timesync vgsvcTimeSyncWorker: Radical guest time change: 616 618 347 137 000ns (GuestNow=1 726 007 844 809 825 000 ns GuestLast=1 725 391 226 462 688 000 ns fSetTimeLastLoop=true )
2024-09-11 14:06:30 (19552): Starting VM using VBoxManage interface. (boinc_870f26651c5dc9ad, slot#6)
2024-09-11 14:06:41 (19552): Successfully started VM. (PID = '26376')
2024-09-11 14:06:41 (19552): Reporting VM Process ID to BOINC.
2024-09-11 14:06:41 (19552): Guest Log: BIOS: VirtualBox 7.0.20
2024-09-11 14:06:41 (19552): Guest Log: CPUID EDX: 0x178bfbff
2024-09-11 14:06:41 (19552): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-09-11 14:06:41 (19552): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-09-11 14:06:41 (19552): VM state change detected. (old = 'poweredoff', new = 'running')
2024-09-11 14:06:41 (19552): Detected: Web Application Enabled (http://localhost:53641)
2024-09-11 14:06:41 (19552): Status Report: Job Duration: '864000.000000'
2024-09-11 14:06:41 (19552): Status Report: Elapsed Time: '82401.085007'
2024-09-11 14:06:41 (19552): Status Report: CPU Time: '62595.437500'
2024-09-11 14:06:41 (19552): Preference change detected
2024-09-11 14:06:41 (19552): Setting CPU throttle for VM. (70%)
2024-09-11 14:06:41 (19552): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-09-11 14:06:43 (19552): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-09-11 14:06:43 (19552): Guest Log: BIOS: Booting from Hard Disk...
2024-09-11 14:06:46 (19552): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-09-11 14:06:46 (19552): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-09-11 14:07:07 (19552): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-09-11 14:07:07 (19552): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-09-11 14:07:13 (19552): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-09-11 14:07:13 (19552): Guest Log: 00:00:00.000241 main     Log opened 2024-09-11T19:07:12.778075000Z
2024-09-11 14:07:13 (19552): Guest Log: 00:00:00.000358 main     OS Product: Linux
2024-09-11 14:07:13 (19552): Guest Log: 00:00:00.000404 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-09-11 14:07:13 (19552): Guest Log: 00:00:00.000456 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-09-11 14:07:13 (19552): Guest Log: 00:00:00.000487 main     Executable: /usr/sbin/VBoxService
2024-09-11 14:07:13 (19552): Guest Log: 00:00:00.000488 main     Process ID: 3220
2024-09-11 14:07:13 (19552): Guest Log: 00:00:00.000489 main     Package type: LINUX_64BITS_GENERIC
2024-09-11 14:07:13 (19552): Guest Log: 00:00:00.003448 main     5.2.6 r120293 started. Verbose level = 0
2024-09-11 14:07:41 (19552): Guest Log: 21:07:41 CEST +02:00 2024-09-11: cranky: [INFO] Detected Theory App
2024-09-11 14:07:41 (19552): Guest Log: 21:07:41 CEST +02:00 2024-09-11: cranky: [INFO] Checking CVMFS.
2024-09-11 14:07:42 (19552): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-09-11 14:07:43 (19552): 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-11 14:07:43 (19552): Guest Log: 2.5.2.0 4129 0 28088 30437 3 1 1305046 4096001 0 65024 0 0 n/a 5 5 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-09-11 14:07:45 (19552): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-09-11 14:07:45 (19552): 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-11 14:07:45 (19552): Guest Log: 2.5.2.0 4107 0 27524 25209 3 2 1305046 4096001 0 65024 0 2 0 7926 5901 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-09-11 14:07:47 (19552): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-09-11 14:07:47 (19552): 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-11 14:07:47 (19552): Guest Log: 2.5.2.0 4082 0 25160 272 3 1 1305046 4096001 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-09-11 14:07:49 (19552): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-09-11 14:07:50 (19552): 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-11 14:07:50 (19552): Guest Log: 2.5.2.0 4057 0 38628 19679 3 1 1305046 4096001 0 65024 0 0 n/a 5865 4373 http://s1ihep-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-09-11 14:07:50 (19552): Guest Log: 21:07:49 CEST +02:00 2024-09-11: cranky: [INFO] Checking runc.
2024-09-11 14:07:50 (19552): Guest Log: 21:07:49 CEST +02:00 2024-09-11: cranky: [INFO] Creating the filesystem.
2024-09-11 14:07:50 (19552): Guest Log: 21:07:50 CEST +02:00 2024-09-11: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-09-11 14:07:50 (19552): Guest Log: 21:07:50 CEST +02:00 2024-09-11: cranky: [INFO] Updating config.json.
2024-09-11 14:07:50 (19552): Guest Log: 21:07:50 CEST +02:00 2024-09-11: cranky: [INFO] Running Container 'runc'.
2024-09-11 14:07:51 (19552): Guest Log: job: htmld=/shared/html/job
2024-09-11 14:07:51 (19552): Guest Log: job: unpack exitcode=0
2024-09-11 14:07:51 (19552): Guest Log: INFO: activated the work-around for ld:
2024-09-11 14:07:51 (19552): Guest Log: lrwxrwxrwx 1 0 0 15 Sep 11 19:07 /tmp/tmp.FoE0wbfUxa/ld -> /usr/bin/ld.bfd
2024-09-11 14:07:52 (19552): Guest Log: 21:07:52 CEST +02:00 2024-09-11: cranky: [INFO] ===> [runRivet] Wed Sep 11 19:07:51 UTC 2024 [boinc pp jets 13000 150 - pythia8 8.308 CP1-CR1 100000 403]
2024-09-11 14:10:05 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 14:10:10 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 14:11:01 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 14:11:21 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 14:13:23 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 14:13:43 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 14:15:34 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 14:15:54 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 14:17:45 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 14:18:05 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 14:27:12 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 14:27:32 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 14:35:39 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 14:35:59 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 14:45:36 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 14:45:56 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 14:54:22 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 14:54:42 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 14:58:26 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 14:58:46 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 15:01:08 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 15:01:28 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 15:10:26 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 15:10:46 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 15:20:54 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 15:21:14 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 15:23:16 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 15:23:36 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 15:31:02 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 15:31:22 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 15:50:37 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 15:50:57 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 15:52:22 (19552): Status Report: Job Duration: '864000.000000'
2024-09-11 15:52:22 (19552): Status Report: Elapsed Time: '88401.085007'
2024-09-11 15:52:22 (19552): Status Report: CPU Time: '67002.796875'
2024-09-11 15:53:41 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 15:54:01 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 15:56:42 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 15:57:02 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 16:05:08 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 16:05:29 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 16:08:01 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 16:08:21 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 16:21:32 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 16:21:53 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 16:23:34 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 16:23:54 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 16:26:46 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 16:27:06 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 16:30:29 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 16:30:49 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 16:32:51 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 16:33:12 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 16:34:12 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 16:34:32 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 16:47:12 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 16:47:32 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 16:47:52 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 16:48:12 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 16:57:10 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 16:57:30 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 17:18:27 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 17:18:47 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 17:22:40 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 17:23:00 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 17:29:04 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 17:29:24 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 17:38:06 (19552): Status Report: Job Duration: '864000.000000'
2024-09-11 17:38:06 (19552): Status Report: Elapsed Time: '94401.085007'
2024-09-11 17:38:06 (19552): Status Report: CPU Time: '71575.000000'
2024-09-11 18:54:42 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 18:55:02 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 18:59:25 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 18:59:45 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 19:02:27 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 19:02:47 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 19:03:58 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 19:04:18 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 19:19:35 (19552): Status Report: Job Duration: '864000.000000'
2024-09-11 19:19:35 (19552): Status Report: Elapsed Time: '100401.085007'
2024-09-11 19:19:35 (19552): Status Report: CPU Time: '76137.203125'
2024-09-11 19:20:51 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 19:21:11 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 19:47:12 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 19:47:32 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 19:49:54 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 19:50:14 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 20:03:24 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 20:03:44 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 20:13:31 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 20:13:51 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 20:14:21 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 20:14:41 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 20:18:13 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 20:18:33 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 20:19:35 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 20:19:56 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 20:21:58 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 20:22:18 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 20:27:42 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 20:28:02 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 20:41:01 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 20:41:21 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 20:42:03 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 20:42:23 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 20:55:02 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 20:55:23 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 20:59:15 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 20:59:35 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 21:04:35 (19552): Status Report: Job Duration: '864000.000000'
2024-09-11 21:04:35 (19552): Status Report: Elapsed Time: '106401.085007'
2024-09-11 21:04:35 (19552): Status Report: CPU Time: '80710.671875'
2024-09-11 21:23:15 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 21:23:35 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 21:29:59 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 21:30:19 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 21:32:01 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 21:32:21 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 21:38:05 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 21:38:25 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 21:40:28 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 21:40:48 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 21:44:11 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 21:44:31 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 21:48:14 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 21:48:34 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 21:49:15 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 21:49:35 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 22:14:14 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 22:14:34 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 22:25:25 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 22:25:45 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 22:48:10 (19552): Status Report: Job Duration: '864000.000000'
2024-09-11 22:48:10 (19552): Status Report: Elapsed Time: '112401.085007'
2024-09-11 22:48:10 (19552): Status Report: CPU Time: '85287.937500'
2024-09-11 22:57:19 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 22:57:40 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 23:01:14 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 23:01:35 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 23:12:34 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 23:12:54 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 23:47:41 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 23:48:02 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 23:51:34 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 23:51:54 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-11 23:57:20 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-11 23:57:40 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 00:00:12 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 00:00:32 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 00:22:20 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 00:22:40 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 00:30:18 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 00:30:38 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 00:31:27 (19552): Status Report: Job Duration: '864000.000000'
2024-09-12 00:31:27 (19552): Status Report: Elapsed Time: '118401.085007'
2024-09-12 00:31:27 (19552): Status Report: CPU Time: '89868.968750'
2024-09-12 00:49:37 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 00:49:58 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 02:11:55 (19552): Status Report: Job Duration: '864000.000000'
2024-09-12 02:11:55 (19552): Status Report: Elapsed Time: '124401.085007'
2024-09-12 02:11:55 (19552): Status Report: CPU Time: '94436.359375'
2024-09-12 02:21:56 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 02:22:16 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 02:48:08 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 02:48:28 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 03:52:42 (19552): Status Report: Job Duration: '864000.000000'
2024-09-12 03:52:42 (19552): Status Report: Elapsed Time: '130401.085007'
2024-09-12 03:52:42 (19552): Status Report: CPU Time: '99004.468750'
2024-09-12 04:06:39 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 04:06:59 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 04:08:30 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 04:08:50 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 04:35:05 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 04:35:25 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 04:50:38 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 04:50:58 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 05:01:20 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 05:01:40 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 05:09:59 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 05:10:20 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 05:22:31 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 05:22:51 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 05:33:32 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 05:33:52 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 05:37:29 (19552): Status Report: Job Duration: '864000.000000'
2024-09-12 05:37:29 (19552): Status Report: Elapsed Time: '136401.085007'
2024-09-12 05:37:29 (19552): Status Report: CPU Time: '103591.187500'
2024-09-12 05:39:58 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 05:40:18 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 05:40:58 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 05:41:18 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 05:55:32 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 05:55:53 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 06:24:52 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 06:25:12 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 06:44:42 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 06:45:02 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 07:19:21 (19552): Status Report: Job Duration: '864000.000000'
2024-09-12 07:19:21 (19552): Status Report: Elapsed Time: '142401.085007'
2024-09-12 07:19:21 (19552): Status Report: CPU Time: '108163.828125'
2024-09-12 07:39:57 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 07:40:17 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 07:40:58 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 07:41:18 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 07:43:21 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 07:43:41 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 07:50:18 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 07:50:38 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 07:51:19 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 07:51:39 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 08:23:08 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 08:23:28 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 08:29:13 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 08:29:33 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 08:38:53 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 08:39:13 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 08:55:30 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 08:55:50 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 09:02:35 (19552): Status Report: Job Duration: '864000.000000'
2024-09-12 09:02:35 (19552): Status Report: Elapsed Time: '148401.085007'
2024-09-12 09:02:35 (19552): Status Report: CPU Time: '112728.453125'
2024-09-12 09:03:18 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 09:03:38 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 09:27:21 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 09:27:41 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 09:36:41 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 09:37:01 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 09:44:39 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 09:44:59 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 10:09:13 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 10:09:33 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 10:14:18 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 10:14:38 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 10:35:18 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 10:35:38 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 10:37:50 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 10:38:11 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 10:45:28 (19552): Status Report: Job Duration: '864000.000000'
2024-09-12 10:45:28 (19552): Status Report: Elapsed Time: '154401.085007'
2024-09-12 10:45:28 (19552): Status Report: CPU Time: '117303.312500'
2024-09-12 10:56:50 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 10:57:10 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 11:06:11 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 11:06:31 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 11:10:55 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 11:11:15 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 11:12:16 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 11:12:36 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 11:21:48 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 11:22:08 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 11:26:33 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 11:26:53 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 11:40:07 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 11:40:27 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 11:43:42 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 11:44:02 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 11:47:05 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 11:47:25 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 11:59:18 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 11:59:38 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 12:02:51 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 12:03:11 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 12:07:57 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 12:08:18 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 12:11:01 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 12:11:21 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 12:13:13 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 12:13:33 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 12:18:59 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 12:19:19 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 12:19:59 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 12:20:20 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 12:29:01 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 12:29:21 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 12:29:41 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 12:30:02 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 12:31:54 (19552): Status Report: Job Duration: '864000.000000'
2024-09-12 12:31:54 (19552): Status Report: Elapsed Time: '160401.085007'
2024-09-12 12:31:54 (19552): Status Report: CPU Time: '121883.640625'
2024-09-12 12:47:42 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 12:48:02 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 12:48:32 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 12:48:52 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 12:49:33 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 12:49:53 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 13:06:10 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 13:06:30 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 13:41:49 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 13:42:09 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 13:48:05 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 13:48:25 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 13:56:54 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 13:57:14 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 14:10:17 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 14:10:37 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 14:14:47 (19552): Status Report: Job Duration: '864000.000000'
2024-09-12 14:14:47 (19552): Status Report: Elapsed Time: '166401.085007'
2024-09-12 14:14:47 (19552): Status Report: CPU Time: '126460.906250'
2024-09-12 14:17:45 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 14:18:05 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 14:22:30 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 14:22:50 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 14:23:51 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 14:24:11 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 14:50:50 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 14:51:10 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 15:56:15 (19552): Status Report: Job Duration: '864000.000000'
2024-09-12 15:56:15 (19552): Status Report: Elapsed Time: '172401.085007'
2024-09-12 15:56:15 (19552): Status Report: CPU Time: '131040.609375'
2024-09-12 16:16:21 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 16:16:41 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 16:52:59 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 16:53:19 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 17:37:02 (19552): Status Report: Job Duration: '864000.000000'
2024-09-12 17:37:02 (19552): Status Report: Elapsed Time: '178401.085007'
2024-09-12 17:37:02 (19552): Status Report: CPU Time: '135608.718750'
2024-09-12 17:53:44 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 17:54:04 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 18:30:23 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 18:30:43 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 18:34:47 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 18:35:07 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 18:51:02 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 18:51:22 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 19:18:31 (19552): Status Report: Job Duration: '864000.000000'
2024-09-12 19:18:31 (19552): Status Report: Elapsed Time: '184401.085007'
2024-09-12 19:18:31 (19552): Status Report: CPU Time: '140176.156250'
2024-09-12 20:34:35 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 20:34:55 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 20:38:20 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 20:38:40 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 20:39:51 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 20:40:11 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 20:49:51 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 20:50:11 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 20:50:42 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 20:51:02 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 20:53:14 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 20:53:34 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 21:00:42 (19552): Status Report: Job Duration: '864000.000000'
2024-09-12 21:00:42 (19552): Status Report: Elapsed Time: '190401.085007'
2024-09-12 21:00:42 (19552): Status Report: CPU Time: '144751.515625'
2024-09-12 21:22:58 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 21:23:18 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 21:29:15 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 21:29:35 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 21:31:38 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 21:31:58 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 22:33:06 (19552): VM state change detected. (old = 'running', new = 'paused')
2024-09-12 22:33:26 (19552): VM state change detected. (old = 'paused', new = 'running')
2024-09-12 22:37:25 (19552): Guest Log: job: run exitcode=0
2024-09-12 22:37:25 (19552): Guest Log: job: diskusage=11036
2024-09-12 22:37:25 (19552): Guest Log: job: logsize=124 k
2024-09-12 22:37:25 (19552): Guest Log: job: times=
2024-09-12 22:37:25 (19552): Guest Log: 0m0.013s 0m0.028s
2024-09-12 22:37:25 (19552): Guest Log: 1867m56.032s 8m42.061s
2024-09-12 22:37:25 (19552): Guest Log: job: cpuusage=112598
2024-09-12 22:37:26 (19552): Guest Log: 05:29:22 CEST +02:00 2024-09-13: cranky: [INFO] Container 'runc' finished with status code 0.
2024-09-12 22:37:26 (19552): Guest Log: 05:29:22 CEST +02:00 2024-09-13: cranky: [INFO] Preparing output.
2024-09-12 22:37:26 (19552): Guest Log: [INFO] Job Finished
2024-09-12 22:37:26 (19552): Guest Log: [INFO] Shutting Down.
2024-09-12 22:37:26 (19552): VM Completion File Detected.
2024-09-12 22:37:26 (19552): VM Completion Message: Job Finished
.
2024-09-12 22:37:26 (19552): Powering off VM.
2024-09-12 22:37:27 (19552): Successfully stopped VM.
2024-09-12 22:37:27 (19552): Deregistering VM. (boinc_870f26651c5dc9ad, slot#6)
2024-09-12 22:37:27 (19552): Removing network bandwidth throttle group from VM.
2024-09-12 22:37:27 (19552): Removing VM from VirtualBox.
2024-09-12 22:37:32 (19552): called boinc_finish(0)

</stderr_txt>
]]>


©2025 CERN