Stderr output

<core_client_version>7.16.20</core_client_version>
<![CDATA[
<stderr_txt>
2022-04-25 12:38:24 (63776): Detected: vboxwrapper 26197
2022-04-25 12:38:24 (63776): Detected: BOINC client v7.7
2022-04-25 12:38:26 (63776): Detected: VirtualBox VboxManage Interface (Version: 6.1.30)
2022-04-25 12:38:27 (63776): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-04-25 12:38:27 (63776): Successfully copied 'init_data.xml' to the shared directory.
2022-04-25 12:38:27 (63776): Successfully copied 'input' to the shared directory.
2022-04-25 12:38:28 (63776): Create VM. (boinc_07a8055d6fb3ccb4, slot#11)
2022-04-25 12:38:28 (63776): Setting Memory Size for VM. (630MB)
2022-04-25 12:38:29 (63776): Setting CPU Count for VM. (1)
2022-04-25 12:38:29 (63776): Setting Chipset Options for VM.
2022-04-25 12:38:29 (63776): Setting Boot Options for VM.
2022-04-25 12:38:29 (63776): Setting Network Configuration for NAT.
2022-04-25 12:38:30 (63776): Enabling VM Network Access.
2022-04-25 12:38:30 (63776): Disabling USB Support for VM.
2022-04-25 12:38:30 (63776): Disabling COM Port Support for VM.
2022-04-25 12:38:30 (63776): Disabling LPT Port Support for VM.
2022-04-25 12:38:31 (63776): Disabling Audio Support for VM.
2022-04-25 12:38:31 (63776): Disabling Clipboard Support for VM.
2022-04-25 12:38:31 (63776): Disabling Drag and Drop Support for VM.
2022-04-25 12:38:32 (63776): Adding storage controller(s) to VM.
2022-04-25 12:38:32 (63776): Adding virtual disk drive to VM. (vm_image.vdi)
2022-04-25 12:38:32 (63776): Adding VirtualBox Guest Additions to VM.
2022-04-25 12:38:32 (63776): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2022-04-25 12:38:33 (63776): forwarding host port 64369 to guest port 80
2022-04-25 12:38:33 (63776): Enabling remote desktop for VM.
2022-04-25 12:38:33 (63776): Enabling shared directory for VM.
2022-04-25 12:38:34 (63776): Starting VM using VBoxManage interface. (boinc_07a8055d6fb3ccb4, slot#11)
2022-04-25 12:38:48 (63776): Successfully started VM. (PID = '49220')
2022-04-25 12:38:48 (63776): Reporting VM Process ID to BOINC.
2022-04-25 12:38:48 (63776): Guest Log: BIOS: VirtualBox 6.1.30

2022-04-25 12:38:48 (63776): Guest Log: CPUID EDX: 0x178bfbff

2022-04-25 12:38:48 (63776): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2022-04-25 12:38:48 (63776): VM state change detected. (old = 'PoweredOff', new = 'Running')
2022-04-25 12:38:48 (63776): Detected: Web Application Enabled (http://localhost:64369)
2022-04-25 12:38:48 (63776): Detected: Remote Desktop Enabled (localhost:64371)
2022-04-25 12:38:48 (63776): Preference change detected
2022-04-25 12:38:48 (63776): Setting CPU throttle for VM. (75%)
2022-04-25 12:38:48 (63776): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-04-25 12:38:50 (63776): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2022-04-25 12:38:50 (63776): Guest Log: BIOS: Booting from Hard Disk...

2022-04-25 12:38:53 (63776): Guest Log: BIOS: KBD: unsupported int 16h function 03

2022-04-25 12:38:53 (63776): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2022-04-25 12:39:22 (63776): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2022-04-25 12:39:22 (63776): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2022-04-25 12:39:30 (63776): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2022-04-25 12:39:30 (63776): Guest Log: 00:00:00.000187 main     Log opened 2022-04-25T16:39:28.450607000Z

2022-04-25 12:39:30 (63776): Guest Log: 00:00:00.000453 main     OS Product: Linux

2022-04-25 12:39:30 (63776): Guest Log: 00:00:00.000566 main     OS Release: 4.14.76-13.cernvm.x86_64

2022-04-25 12:39:30 (63776): Guest Log: 00:00:00.000678 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2022-04-25 12:39:30 (63776): Guest Log: 00:00:00.000776 main     Executable: /usr/sbin/VBoxService

2022-04-25 12:39:30 (63776): Guest Log: 00:00:00.000778 main     Process ID: 3213

2022-04-25 12:39:30 (63776): Guest Log: 00:00:00.000780 main     Package type: LINUX_64BITS_GENERIC

2022-04-25 12:39:30 (63776): Guest Log: 00:00:00.019286 main     5.2.6 r120293 started. Verbose level = 0

2022-04-25 12:40:13 (63776): Guest Log: 18:40:11 CEST +02:00 2022-04-25: cranky: [INFO] Detected Theory App

2022-04-25 12:40:13 (63776): Guest Log: 18:40:11 CEST +02:00 2022-04-25: cranky: [INFO] Checking CVMFS.

2022-04-25 12:40:15 (63776): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2022-04-25 12:40:16 (63776): 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

2022-04-25 12:40:16 (63776): Guest Log: 2.5.2.0 4112 0 26192 22720 3 1 291200 4096000 0 65024 0 0 n/a 5 3 http://s1bnl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2022-04-25 12:40:19 (63776): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2022-04-25 12:40:21 (63776): 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

2022-04-25 12:40:21 (63776): Guest Log: 2.5.2.0 4087 0 29564 18065 3 2 291200 4096000 0 65024 0 2 -100 7755 1265 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2022-04-25 12:40:23 (63776): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2022-04-25 12:40:24 (63776): 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

2022-04-25 12:40:24 (63776): Guest Log: 2.5.2.0 4061 0 25204 268 3 1 291200 4096000 0 65024 0 0 n/a 4 3 http://s1fnal-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2022-04-25 12:40:27 (63776): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2022-04-25 12:40:28 (63776): 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

2022-04-25 12:40:28 (63776): Guest Log: 2.5.2.0 4137 0 34464 12197 3 1 291200 4096000 0 65024 0 0 n/a 16129 1469 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2022-04-25 12:40:28 (63776): Guest Log: 18:40:26 CEST +02:00 2022-04-25: cranky: [INFO] Checking runc.

2022-04-25 12:40:28 (63776): Guest Log: 18:40:26 CEST +02:00 2022-04-25: cranky: [INFO] Creating the filesystem.

2022-04-25 12:40:28 (63776): Guest Log: 18:40:27 CEST +02:00 2022-04-25: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2022-04-25 12:40:28 (63776): Guest Log: 18:40:27 CEST +02:00 2022-04-25: cranky: [INFO] Updating config.json.

2022-04-25 12:40:29 (63776): Guest Log: 18:40:27 CEST +02:00 2022-04-25: cranky: [INFO] Running Container 'runc'.

2022-04-25 12:40:42 (63776): Guest Log: job: htmld=/shared/html/job

2022-04-25 12:40:42 (63776): Guest Log: job: unpack exitcode=0

2022-04-25 12:40:43 (63776): Guest Log: 18:40:41 CEST +02:00 2022-04-25: cranky: [INFO] ===> [runRivet] Mon Apr 25 16:40:41 UTC 2022 [boinc pp jets 13000 180,-,3560 - pythia8 8.235 tune-A14-CTEQL1 100000 250]

2022-04-25 14:18:50 (63776): Status Report: Job Duration: '864000.000000'
2022-04-25 14:18:50 (63776): Status Report: Elapsed Time: '6000.000000'
2022-04-25 14:18:50 (63776): Status Report: CPU Time: '4272.140625'
2022-04-25 14:36:22 (63776): VM state change detected. (old = 'Running', new = 'Paused')
2022-04-25 15:31:17 (63776): VM state change detected. (old = 'Paused', new = 'Running')
2022-04-25 15:31:26 (63776): Guest Log: 01:57:02.680787 timesync vgsvcTimeSyncWorker: Radical host time change: 3 305 010 000 000ns (HostNow=1 650 915 086 049 000 000 ns HostLast=1 650 911 781 039 000 000 ns)

2022-04-25 15:31:36 (63776): Guest Log: 01:57:12.712732 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 305 045 749 000ns (GuestNow=1 650 915 096 099 606 000 ns GuestLast=1 650 911 791 053 857 000 ns fSetTimeLastLoop=true )

2022-04-25 16:53:48 (63776): Status Report: Job Duration: '864000.000000'
2022-04-25 16:53:48 (63776): Status Report: Elapsed Time: '12000.000000'
2022-04-25 16:53:48 (63776): Status Report: CPU Time: '8843.000000'
2022-04-25 18:33:52 (63776): Status Report: Job Duration: '864000.000000'
2022-04-25 18:33:52 (63776): Status Report: Elapsed Time: '18000.000000'
2022-04-25 18:33:52 (63776): Status Report: CPU Time: '13409.921875'
2022-04-25 19:59:40 (63776): VM state change detected. (old = 'Running', new = 'Paused')
2022-04-25 21:46:12 (63776): VM state change detected. (old = 'Paused', new = 'Running')
2022-04-25 21:46:21 (63776): Guest Log: 06:25:25.721515 timesync vgsvcTimeSyncWorker: Radical host time change: 6 401 319 000 000ns (HostNow=1 650 937 580 475 000 000 ns HostLast=1 650 931 179 156 000 000 ns)

2022-04-25 21:46:31 (63776): Guest Log: 06:25:35.724560 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 401 369 689 000ns (GuestNow=1 650 937 590 478 030 000 ns GuestLast=1 650 931 189 108 341 000 ns fSetTimeLastLoop=true )

2022-04-25 22:00:27 (63776): Status Report: Job Duration: '864000.000000'
2022-04-25 22:00:27 (63776): Status Report: Elapsed Time: '24000.000000'
2022-04-25 22:00:27 (63776): Status Report: CPU Time: '17927.187500'
2022-04-25 23:40:30 (63776): Status Report: Job Duration: '864000.000000'
2022-04-25 23:40:30 (63776): Status Report: Elapsed Time: '30000.000000'
2022-04-25 23:40:30 (63776): Status Report: CPU Time: '22461.234375'
2022-04-26 00:39:29 (63776): VM state change detected. (old = 'Running', new = 'Paused')
2022-04-26 00:52:09 (63776): VM state change detected. (old = 'Paused', new = 'Running')
2022-04-26 01:08:58 (63776): VM state change detected. (old = 'Running', new = 'Paused')
2022-04-26 02:09:13 (63776): VM state change detected. (old = 'Paused', new = 'Running')
2022-04-26 02:09:16 (63776): Guest Log: 09:35:27.155027 timesync vgsvcTimeSyncWorker: Radical host time change: 3 624 427 000 000ns (HostNow=1 650 953 355 612 000 000 ns HostLast=1 650 949 731 185 000 000 ns)

2022-04-26 02:09:26 (63776): Guest Log: 09:35:37.178363 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 383 726 905 000ns (GuestNow=1 650 953 365 635 386 000 ns GuestLast=1 650 948 981 908 481 000 ns fSetTimeLastLoop=true )

2022-04-26 02:33:31 (63776): Status Report: Job Duration: '864000.000000'
2022-04-26 02:33:31 (63776): Status Report: Elapsed Time: '36000.000000'
2022-04-26 02:33:31 (63776): Status Report: CPU Time: '26959.046875'
2022-04-26 04:13:34 (63776): Status Report: Job Duration: '864000.000000'
2022-04-26 04:13:34 (63776): Status Report: Elapsed Time: '42000.000000'
2022-04-26 04:13:34 (63776): Status Report: CPU Time: '31505.296875'
2022-04-26 04:55:17 (63776): VM state change detected. (old = 'Running', new = 'Paused')
2022-04-26 05:42:31 (63776): VM state change detected. (old = 'Paused', new = 'Running')
2022-04-26 05:42:38 (63776): Guest Log: 12:21:35.755734 timesync vgsvcTimeSyncWorker: Radical host time change: 2 843 742 000 000ns (HostNow=1 650 966 157 949 000 000 ns HostLast=1 650 963 314 207 000 000 ns)

2022-04-26 05:42:48 (63776): Guest Log: 12:21:45.764700 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 843 745 267 000ns (GuestNow=1 650 966 167 957 988 000 ns GuestLast=1 650 963 324 212 721 000 ns fSetTimeLastLoop=true )

2022-04-26 06:40:52 (63776): Status Report: Job Duration: '864000.000000'
2022-04-26 06:40:52 (63776): Status Report: Elapsed Time: '48000.000000'
2022-04-26 06:40:52 (63776): Status Report: CPU Time: '36081.328125'
2022-04-26 08:20:57 (63776): Status Report: Job Duration: '864000.000000'
2022-04-26 08:20:57 (63776): Status Report: Elapsed Time: '54000.000000'
2022-04-26 08:20:57 (63776): Status Report: CPU Time: '40626.375000'
2022-04-26 10:01:08 (63776): Status Report: Job Duration: '864000.000000'
2022-04-26 10:01:08 (63776): Status Report: Elapsed Time: '60000.000000'
2022-04-26 10:01:08 (63776): Status Report: CPU Time: '44986.421875'
2022-04-26 11:41:10 (63776): Status Report: Job Duration: '864000.000000'
2022-04-26 11:41:10 (63776): Status Report: Elapsed Time: '66000.000000'
2022-04-26 11:41:10 (63776): Status Report: CPU Time: '49539.984375'
2022-04-26 13:21:10 (63776): Status Report: Job Duration: '864000.000000'
2022-04-26 13:21:10 (63776): Status Report: Elapsed Time: '72000.000000'
2022-04-26 13:21:10 (63776): Status Report: CPU Time: '54093.031250'
2022-04-26 15:01:13 (63776): Status Report: Job Duration: '864000.000000'
2022-04-26 15:01:13 (63776): Status Report: Elapsed Time: '78000.000000'
2022-04-26 15:01:13 (63776): Status Report: CPU Time: '58643.203125'
2022-04-26 16:41:14 (63776): Status Report: Job Duration: '864000.000000'
2022-04-26 16:41:14 (63776): Status Report: Elapsed Time: '84000.000000'
2022-04-26 16:41:14 (63776): Status Report: CPU Time: '63186.437500'
2022-04-26 18:21:14 (63776): Status Report: Job Duration: '864000.000000'
2022-04-26 18:21:14 (63776): Status Report: Elapsed Time: '90000.000000'
2022-04-26 18:21:14 (63776): Status Report: CPU Time: '67716.906250'
2022-04-26 20:01:14 (63776): Status Report: Job Duration: '864000.000000'
2022-04-26 20:01:14 (63776): Status Report: Elapsed Time: '96000.000000'
2022-04-26 20:01:14 (63776): Status Report: CPU Time: '72280.375000'
2022-04-26 21:41:16 (63776): Status Report: Job Duration: '864000.000000'
2022-04-26 21:41:16 (63776): Status Report: Elapsed Time: '102000.000000'
2022-04-26 21:41:16 (63776): Status Report: CPU Time: '76860.171875'
2022-04-26 23:21:17 (63776): Status Report: Job Duration: '864000.000000'
2022-04-26 23:21:17 (63776): Status Report: Elapsed Time: '108000.000000'
2022-04-26 23:21:17 (63776): Status Report: CPU Time: '81441.359375'
2022-04-26 23:39:50 (63776): Guest Log: job: run exitcode=0

2022-04-26 23:39:50 (63776): Guest Log: job: diskusage=2068

2022-04-26 23:39:50 (63776): Guest Log: job: logsize=72 k

2022-04-26 23:39:50 (63776): Guest Log: job: times=

2022-04-26 23:39:50 (63776): Guest Log: 0m0.025s 0m0.086s

2022-04-26 23:39:50 (63776): Guest Log: 1763m34.068s 13m40.469s

2022-04-26 23:39:50 (63776): Guest Log: job: cpuusage=106635

2022-04-26 23:39:51 (63776): Guest Log: 05:39:51 CEST +02:00 2022-04-27: cranky: [INFO] Container 'runc' finished with status code 0.

2022-04-26 23:39:51 (63776): Guest Log: 05:39:51 CEST +02:00 2022-04-27: cranky: [INFO] Preparing output.

2022-04-26 23:39:55 (63776): Guest Log: [INFO] Job Finished

2022-04-26 23:39:55 (63776): Guest Log: [INFO] Shutting Down.

2022-04-26 23:39:55 (63776): VM Completion File Detected.
2022-04-26 23:39:55 (63776): VM Completion Message: Job Finished
.
2022-04-26 23:39:55 (63776): Powering off VM.
2022-04-26 23:44:56 (63776): VM did not power off when requested.
2022-04-26 23:44:56 (63776): VM was successfully terminated.
2022-04-26 23:44:56 (63776): Deregistering VM. (boinc_07a8055d6fb3ccb4, slot#11)
2022-04-26 23:44:58 (63776): Removing network bandwidth throttle group from VM.
2022-04-26 23:44:58 (63776): Removing VM from VirtualBox.
23:45:04 (63776): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN