Stderr output

<core_client_version>7.16.11</core_client_version>
<![CDATA[
<stderr_txt>
2021-10-18 09:35:47 (19404): Detected: vboxwrapper 26197
2021-10-18 09:35:47 (19404): Detected: BOINC client v7.7
2021-10-18 09:35:48 (19404): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2021-10-18 09:35:48 (19404): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-10-18 09:35:48 (19404): Successfully copied 'init_data.xml' to the shared directory.
2021-10-18 09:35:49 (19404): Successfully copied 'input' to the shared directory.
2021-10-18 09:35:50 (19404): Create VM. (boinc_a7c144f0cbe298f6, slot#8)
2021-10-18 09:35:51 (19404): Setting Memory Size for VM. (630MB)
2021-10-18 09:35:52 (19404): Setting CPU Count for VM. (1)
2021-10-18 09:35:52 (19404): Setting Chipset Options for VM.
2021-10-18 09:35:53 (19404): Setting Boot Options for VM.
2021-10-18 09:35:53 (19404): Setting Network Configuration for NAT.
2021-10-18 09:35:54 (19404): Enabling VM Network Access.
2021-10-18 09:35:54 (19404): Disabling USB Support for VM.
2021-10-18 09:35:55 (19404): Disabling COM Port Support for VM.
2021-10-18 09:35:55 (19404): Disabling LPT Port Support for VM.
2021-10-18 09:35:56 (19404): Disabling Audio Support for VM.
2021-10-18 09:35:56 (19404): Disabling Clipboard Support for VM.
2021-10-18 09:35:57 (19404): Disabling Drag and Drop Support for VM.
2021-10-18 09:35:57 (19404): Adding storage controller(s) to VM.
2021-10-18 09:35:57 (19404): Adding virtual disk drive to VM. (vm_image.vdi)
2021-10-18 09:35:58 (19404): Adding VirtualBox Guest Additions to VM.
2021-10-18 09:35:59 (19404): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2021-10-18 09:35:59 (19404): forwarding host port 51858 to guest port 80
2021-10-18 09:36:00 (19404): Enabling remote desktop for VM.
2021-10-18 09:36:00 (19404): Enabling shared directory for VM.
2021-10-18 09:36:01 (19404): Starting VM using VBoxManage interface. (boinc_a7c144f0cbe298f6, slot#8)
2021-10-18 09:36:20 (19404): Successfully started VM. (PID = '5696')
2021-10-18 09:36:20 (19404): Reporting VM Process ID to BOINC.
2021-10-18 09:36:20 (19404): Guest Log: BIOS: VirtualBox 6.1.4

2021-10-18 09:36:20 (19404): Guest Log: CPUID EDX: 0x178bfbff

2021-10-18 09:36:20 (19404): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-10-18 09:36:20 (19404): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-10-18 09:36:20 (19404): Detected: Web Application Enabled (http://localhost:51858)
2021-10-18 09:36:20 (19404): Detected: Remote Desktop Enabled (localhost:51859)
2021-10-18 09:36:20 (19404): Preference change detected
2021-10-18 09:36:20 (19404): Setting CPU throttle for VM. (75%)
2021-10-18 09:36:21 (19404): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2021-10-18 09:36:22 (19404): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-10-18 09:36:22 (19404): Guest Log: BIOS: Booting from Hard Disk...

2021-10-18 09:36:25 (19404): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-10-18 09:36:25 (19404): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-10-18 09:37:12 (19404): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2021-10-18 09:37:12 (19404): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2021-10-18 09:37:18 (19404): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2021-10-18 09:37:18 (19404): Guest Log: 00:00:00.000159 main     Log opened 2021-10-18T13:37:20.466746000Z

2021-10-18 09:37:18 (19404): Guest Log: 00:00:00.000299 main     OS Product: Linux

2021-10-18 09:37:18 (19404): Guest Log: 00:00:00.000365 main     OS Release: 4.14.76-13.cernvm.x86_64

2021-10-18 09:37:18 (19404): Guest Log: 00:00:00.000448 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2021-10-18 09:37:18 (19404): Guest Log: 00:00:00.000506 main     Executable: /usr/sbin/VBoxService

2021-10-18 09:37:18 (19404): Guest Log: 00:00:00.000508 main     Process ID: 3217

2021-10-18 09:37:18 (19404): Guest Log: 00:00:00.000510 main     Package type: LINUX_64BITS_GENERIC

2021-10-18 09:37:18 (19404): Guest Log: 00:00:00.005892 main     5.2.6 r120293 started. Verbose level = 0

2021-10-18 09:38:13 (19404): Guest Log: 15:38:15 CEST +02:00 2021-10-18: cranky: [INFO] Detected Theory App

2021-10-18 09:38:13 (19404): Guest Log: 15:38:15 CEST +02:00 2021-10-18: cranky: [INFO] Checking CVMFS.

2021-10-18 09:38:18 (19404): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2021-10-18 09:38:21 (19404): 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

2021-10-18 09:38:21 (19404): Guest Log: 2.5.2.0 4084 0 28572 21279 3 1 268147 4096000 0 65024 0 0 n/a 5 1 http://s1bnl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2021-10-18 09:38:26 (19404): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2021-10-18 09:38:28 (19404): 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

2021-10-18 09:38:28 (19404): Guest Log: 2.5.2.0 4074 0 25476 16511 3 2 268147 4096000 0 65024 0 2 -100 7193 1226 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2021-10-18 09:38:33 (19404): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2021-10-18 09:38:36 (19404): 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

2021-10-18 09:38:36 (19404): Guest Log: 2.5.2.0 4117 0 25184 268 3 1 268147 4096000 0 65024 0 0 n/a 4 2 http://s1bnl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2021-10-18 09:38:40 (19404): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2021-10-18 09:38:43 (19404): 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

2021-10-18 09:38:43 (19404): Guest Log: 2.5.2.0 4146 0 31748 10582 3 1 268147 4096000 0 65024 0 0 n/a 5428 981 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2021-10-18 09:38:43 (19404): Guest Log: 15:38:45 CEST +02:00 2021-10-18: cranky: [INFO] Checking runc.

2021-10-18 09:38:43 (19404): Guest Log: 15:38:45 CEST +02:00 2021-10-18: cranky: [INFO] Creating the filesystem.

2021-10-18 09:38:43 (19404): Guest Log: 15:38:45 CEST +02:00 2021-10-18: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2021-10-18 09:38:44 (19404): Guest Log: 15:38:46 CEST +02:00 2021-10-18: cranky: [INFO] Updating config.json.

2021-10-18 09:38:45 (19404): Guest Log: 15:38:46 CEST +02:00 2021-10-18: cranky: [INFO] Running Container 'runc'.

2021-10-18 09:38:58 (19404): Guest Log: job: htmld=/shared/html/job

2021-10-18 09:38:58 (19404): Guest Log: job: unpack exitcode=0

2021-10-18 09:38:59 (19404): Guest Log: 15:39:01 CEST +02:00 2021-10-18: cranky: [INFO] ===> [runRivet] Mon Oct 18 13:39:00 UTC 2021 [boinc pp jets 13000 250,-,4760 - pythia8 8.180 default-CD 100000 214]

2021-10-18 10:56:39 (19404): VM state change detected. (old = 'Running', new = 'Paused')
2021-10-18 11:48:40 (19404): VM state change detected. (old = 'Paused', new = 'Running')
2021-10-18 11:48:46 (19404): Guest Log: 01:19:24.906884 timesync vgsvcTimeSyncWorker: Radical host time change: 3 130 366 000 000ns (HostNow=1 634 572 125 726 000 000 ns HostLast=1 634 568 995 360 000 000 ns)

2021-10-18 11:48:56 (19404): Guest Log: 01:19:34.940019 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 130 404 245 000ns (GuestNow=1 634 572 135 768 383 000 ns GuestLast=1 634 569 005 364 138 000 ns fSetTimeLastLoop=true )

2021-10-18 12:08:28 (19404): Status Report: Job Duration: '864000.000000'
2021-10-18 12:08:28 (19404): Status Report: Elapsed Time: '6000.000000'
2021-10-18 12:08:28 (19404): Status Report: CPU Time: '4639.203125'
2021-10-18 13:43:08 (19404): VM state change detected. (old = 'Running', new = 'Paused')
2021-10-18 18:29:33 (19404): VM state change detected. (old = 'Paused', new = 'Running')
2021-10-18 18:29:38 (19404): Guest Log: 03:13:52.003453 timesync vgsvcTimeSyncWorker: Radical host time change: 17 194 994 000 000ns (HostNow=1 634 596 177 825 000 000 ns HostLast=1 634 578 982 831 000 000 ns)

2021-10-18 18:29:48 (19404): Guest Log: 03:14:02.038243 timesync vgsvcTimeSyncWorker: Radical guest time change: 17 195 028 188 000ns (GuestNow=1 634 596 187 859 875 000 ns GuestLast=1 634 578 992 831 687 000 ns fSetTimeLastLoop=true )

2021-10-18 18:32:10 (19404): VM state change detected. (old = 'Running', new = 'Paused')
2021-10-19 13:26:01 (19404): VM state change detected. (old = 'Paused', new = 'Running')
2021-10-19 13:26:09 (19404): Guest Log: 03:16:32.218701 timesync vgsvcTimeSyncWorker: Radical host time change: 68 040 312 000 000ns (HostNow=1 634 664 368 349 000 000 ns HostLast=1 634 596 328 037 000 000 ns)

2021-10-19 13:26:19 (19404): Guest Log: 03:16:42.224249 timesync vgsvcTimeSyncWorker: Radical guest time change: 68 040 314 341 000ns (GuestNow=1 634 664 378 354 608 000 ns GuestLast=1 634 596 338 040 267 000 ns fSetTimeLastLoop=true )

2021-10-19 13:28:53 (19404): Status Report: Job Duration: '864000.000000'
2021-10-19 13:28:53 (19404): Status Report: Elapsed Time: '12000.124344'
2021-10-19 13:28:53 (19404): Status Report: CPU Time: '9207.703125'
2021-10-19 15:08:59 (19404): Status Report: Job Duration: '864000.000000'
2021-10-19 15:08:59 (19404): Status Report: Elapsed Time: '18000.124344'
2021-10-19 15:08:59 (19404): Status Report: CPU Time: '13725.109375'
2021-10-19 16:49:04 (19404): Status Report: Job Duration: '864000.000000'
2021-10-19 16:49:04 (19404): Status Report: Elapsed Time: '24000.124344'
2021-10-19 16:49:04 (19404): Status Report: CPU Time: '18241.859375'
2021-10-19 18:20:01 (19404): VM state change detected. (old = 'Running', new = 'Paused')
2021-10-19 18:29:09 (19404): VM state change detected. (old = 'Paused', new = 'Running')
2021-10-19 18:38:20 (19404): Status Report: Job Duration: '864000.000000'
2021-10-19 18:38:20 (19404): Status Report: Elapsed Time: '30000.124344'
2021-10-19 18:38:20 (19404): Status Report: CPU Time: '22762.609375'
2021-10-19 20:18:26 (19404): Status Report: Job Duration: '864000.000000'
2021-10-19 20:18:26 (19404): Status Report: Elapsed Time: '36000.124344'
2021-10-19 20:18:26 (19404): Status Report: CPU Time: '27284.093750'
2021-10-19 21:58:32 (19404): Status Report: Job Duration: '864000.000000'
2021-10-19 21:58:32 (19404): Status Report: Elapsed Time: '42000.124344'
2021-10-19 21:58:32 (19404): Status Report: CPU Time: '31801.468750'
2021-10-19 23:38:34 (19404): Status Report: Job Duration: '864000.000000'
2021-10-19 23:38:34 (19404): Status Report: Elapsed Time: '48000.124344'
2021-10-19 23:38:34 (19404): Status Report: CPU Time: '36309.000000'
2021-10-20 01:03:14 (19404): VM state change detected. (old = 'Running', new = 'Paused')
2021-10-20 03:04:06 (19404): VM state change detected. (old = 'Paused', new = 'Running')
2021-10-20 03:04:12 (19404): Guest Log: 14:44:47.844155 timesync vgsvcTimeSyncWorker: Radical host time change: 7 262 363 000 000ns (HostNow=1 634 713 452 076 000 000 ns HostLast=1 634 706 189 713 000 000 ns)

2021-10-20 03:04:22 (19404): Guest Log: 14:44:57.851063 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 798 108 410 000ns (GuestNow=1 634 713 462 082 895 000 ns GuestLast=1 634 705 663 974 485 000 ns fSetTimeLastLoop=true )

2021-10-20 03:19:29 (19404): Status Report: Job Duration: '864000.000000'
2021-10-20 03:19:30 (19404): Status Report: Elapsed Time: '54000.124344'
2021-10-20 03:19:30 (19404): Status Report: CPU Time: '40819.359375'
2021-10-20 04:59:31 (19404): Status Report: Job Duration: '864000.000000'
2021-10-20 04:59:31 (19404): Status Report: Elapsed Time: '60000.124344'
2021-10-20 04:59:31 (19404): Status Report: CPU Time: '45324.687500'
2021-10-20 06:39:33 (19404): Status Report: Job Duration: '864000.000000'
2021-10-20 06:39:33 (19404): Status Report: Elapsed Time: '66000.124344'
2021-10-20 06:39:33 (19404): Status Report: CPU Time: '49831.140625'
2021-10-20 07:34:54 (19404): Guest Log: job: run exitcode=0

2021-10-20 07:34:54 (19404): Guest Log: job: diskusage=1964

2021-10-20 07:34:54 (19404): Guest Log: job: logsize=72 k

2021-10-20 07:34:54 (19404): Guest Log: job: times=

2021-10-20 07:34:54 (19404): Guest Log: 0m0.038s 0m0.120s

2021-10-20 07:34:54 (19404): Guest Log: 1092m14.900s 17m44.515s

2021-10-20 07:34:54 (19404): Guest Log: job: cpuusage=66600

2021-10-20 07:34:54 (19404): Guest Log: 13:34:54 CEST +02:00 2021-10-20: cranky: [INFO] Container 'runc' finished with status code 0.

2021-10-20 07:34:55 (19404): Guest Log: 13:34:55 CEST +02:00 2021-10-20: cranky: [INFO] Preparing output.

2021-10-20 07:34:59 (19404): Guest Log: [INFO] Job Finished

2021-10-20 07:34:59 (19404): Guest Log: [INFO] Shutting Down.

2021-10-20 07:34:59 (19404): VM Completion File Detected.
2021-10-20 07:34:59 (19404): VM Completion Message: Job Finished
.
2021-10-20 07:34:59 (19404): Powering off VM.
2021-10-20 07:40:01 (19404): VM did not power off when requested.
2021-10-20 07:40:01 (19404): VM was successfully terminated.
2021-10-20 07:40:01 (19404): Deregistering VM. (boinc_a7c144f0cbe298f6, slot#8)
2021-10-20 07:40:03 (19404): Removing network bandwidth throttle group from VM.
2021-10-20 07:40:03 (19404): Removing VM from VirtualBox.
07:40:09 (19404): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN