Stderr output

<core_client_version>7.9.3</core_client_version>
<![CDATA[
<stderr_txt>
2020-08-14 15:52:37 (13867): vboxwrapper (7.7.26196): starting
2020-08-14 15:52:37 (13867): Feature: Checkpoint interval offset (143 seconds)
2020-08-14 15:52:37 (13867): Detected: VirtualBox VboxManage Interface (Version: 5.2.42)
2020-08-14 15:52:37 (13867): VBOX_JOB::parse(): unexpected text enable_screenshots_on_error/
2020-08-14 15:52:37 (13867): Detected: Minimum checkpoint interval (600.000000 seconds)
2020-08-14 15:52:37 (13867): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-08-14 15:52:37 (13867): Successfully copied 'init_data.xml' to the shared directory.
2020-08-14 15:52:37 (13867): Successfully copied 'input' to the shared directory.
2020-08-14 15:52:37 (13867): Create VM. (boinc_5116443a239e4e2c, slot#6)
2020-08-14 15:52:37 (13867): Setting Memory Size for VM. (630MB)
2020-08-14 15:52:38 (13867): Setting CPU Count for VM. (1)
2020-08-14 15:52:38 (13867): Setting Chipset Options for VM.
2020-08-14 15:52:38 (13867): Setting Boot Options for VM.
2020-08-14 15:52:38 (13867): Setting Network Configuration for NAT.
2020-08-14 15:52:38 (13867): Enabling VM Network Access.
2020-08-14 15:52:38 (13867): Disabling USB Support for VM.
2020-08-14 15:52:38 (13867): Disabling COM Port Support for VM.
2020-08-14 15:52:38 (13867): Disabling LPT Port Support for VM.
2020-08-14 15:52:38 (13867): Disabling Audio Support for VM.
2020-08-14 15:52:38 (13867): Disabling Clipboard Support for VM.
2020-08-14 15:52:38 (13867): Disabling Drag and Drop Support for VM.
2020-08-14 15:52:38 (13867): Adding storage controller(s) to VM.
2020-08-14 15:52:38 (13867): Adding virtual disk drive to VM. (vm_image.vdi)
2020-08-14 15:52:38 (13867): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2020-08-14 15:52:39 (13867): forwarding host port 47007 to guest port 80
2020-08-14 15:52:39 (13867): Enabling remote desktop for VM.
2020-08-14 15:52:39 (13867): Required extension pack not installed, remote desktop not enabled.
2020-08-14 15:52:39 (13867): Enabling shared directory for VM.
2020-08-14 15:52:39 (13867): Starting VM. (boinc_5116443a239e4e2c, slot#6)
2020-08-14 15:52:40 (13867): Successfully started VM. (PID = '14486')
2020-08-14 15:52:40 (13867): Reporting VM Process ID to BOINC.
2020-08-14 15:52:40 (13867): Guest Log: BIOS: VirtualBox 5.2.41
2020-08-14 15:52:40 (13867): Guest Log: CPUID EDX: 0x078bfbff
2020-08-14 15:52:40 (13867): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2020-08-14 15:52:40 (13867): VM state change detected. (old = 'poweroff', new = 'running')
2020-08-14 15:52:40 (13867): Detected: Web Application Enabled (http://localhost:47007)
2020-08-14 15:52:41 (13867): Preference change detected
2020-08-14 15:52:41 (13867): Setting CPU throttle for VM. (60%)
2020-08-14 15:52:41 (13867): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-08-14 15:52:43 (13867): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2020-08-14 15:52:43 (13867): Guest Log: BIOS: Booting from Hard Disk...
2020-08-14 15:52:46 (13867): Guest Log: BIOS: KBD: unsupported int 16h function 03
2020-08-14 15:52:46 (13867): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2020-08-14 15:53:10 (13867): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2020-08-14 15:53:10 (13867): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2020-08-14 15:53:16 (13867): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2020-08-14 15:53:16 (13867): Guest Log: 00:00:00.000255 main     Log opened 2020-08-14T13:53:16.233213000Z
2020-08-14 15:53:16 (13867): Guest Log: 00:00:00.000472 main     OS Product: Linux
2020-08-14 15:53:16 (13867): Guest Log: 00:00:00.000567 main     OS Release: 4.14.76-13.cernvm.x86_64
2020-08-14 15:53:16 (13867): Guest Log: 00:00:00.000659 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2020-08-14 15:53:16 (13867): Guest Log: 00:00:00.000749 main     Executable: /usr/sbin/VBoxService
2020-08-14 15:53:16 (13867): Guest Log: 00:00:00.000751 main     Process ID: 3133
2020-08-14 15:53:16 (13867): Guest Log: 00:00:00.000752 main     Package type: LINUX_64BITS_GENERIC
2020-08-14 15:53:16 (13867): Guest Log: 00:00:00.011200 main     5.2.6 r120293 started. Verbose level = 0
2020-08-14 15:54:03 (13867): Guest Log: 15:54:02 CEST +02:00 2020-08-14: cranky: [INFO] Detected Theory App
2020-08-14 15:54:03 (13867): Guest Log: 15:54:02 CEST +02:00 2020-08-14: cranky: [INFO] Checking CVMFS.
2020-08-14 15:54:07 (13867): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2020-08-14 15:54:08 (13867): 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
2020-08-14 15:54:08 (13867): Guest Log: 2.5.2.0 4036 0 25160 18616 3 1 263916 4096000 0 65024 0 0 n/a 5 16 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2020-08-14 15:54:12 (13867): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2020-08-14 15:54:14 (13867): 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
2020-08-14 15:54:14 (13867): Guest Log: 2.5.2.0 3989 0 27412 12975 3 2 263916 4096000 0 65024 0 2 -100 6410 2257 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2020-08-14 15:54:17 (13867): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2020-08-14 15:54:20 (13867): 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
2020-08-14 15:54:20 (13867): Guest Log: 2.5.2.0 4013 0 27232 255 3 1 263916 4096000 0 65024 0 0 n/a 4 10 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2020-08-14 15:54:24 (13867): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2020-08-14 15:54:25 (13867): 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
2020-08-14 15:54:25 (13867): Guest Log: 2.5.2.0 4060 0 30024 7234 3 1 263916 4096000 0 65024 0 0 n/a 4253 1449 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/alice.cern.ch DIRECT 1
2020-08-14 15:54:25 (13867): Guest Log: 15:54:24 CEST +02:00 2020-08-14: cranky: [INFO] Checking runc.
2020-08-14 15:54:25 (13867): Guest Log: 15:54:24 CEST +02:00 2020-08-14: cranky: [INFO] Creating the filesystem.
2020-08-14 15:54:25 (13867): Guest Log: 15:54:25 CEST +02:00 2020-08-14: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3
2020-08-14 15:54:26 (13867): Guest Log: 15:54:25 CEST +02:00 2020-08-14: cranky: [INFO] Updating config.json.
2020-08-14 15:54:26 (13867): Guest Log: 15:54:25 CEST +02:00 2020-08-14: cranky: [INFO] Running Container 'runc'.
2020-08-14 15:54:27 (13867): Guest Log: job: htmld=/shared/html/job
2020-08-14 15:54:27 (13867): Guest Log: job: unpack exitcode=0
2020-08-14 15:54:28 (13867): Guest Log: 15:54:27 CEST +02:00 2020-08-14: cranky: [INFO] ===> [runRivet] Fri Aug 14 13:54:26 UTC 2020 [boinc pp z1j 7000 125 - pythia8 8.226 tune-A2 100000 36]
2020-08-14 16:37:15 (13867): Stopping VM.
2020-08-14 16:37:25 (13867): Successfully stopped VM.
2020-08-29 13:41:41 (35728): vboxwrapper (7.7.26196): starting
2020-08-29 13:41:44 (35728): Feature: Checkpoint interval offset (544 seconds)
2020-08-29 13:41:44 (35728): Detected: VirtualBox VboxManage Interface (Version: 5.2.42)
2020-08-29 13:41:44 (35728): VBOX_JOB::parse(): unexpected text enable_screenshots_on_error/
2020-08-29 13:41:44 (35728): Detected: Minimum checkpoint interval (600.000000 seconds)
2020-08-29 13:41:44 (35728): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-08-29 13:41:47 (35728): Starting VM. (boinc_5116443a239e4e2c, slot#6)
2020-08-29 13:42:00 (35728): Successfully started VM. (PID = '36277')
2020-08-29 13:42:00 (35728): Reporting VM Process ID to BOINC.
2020-08-29 13:42:01 (35728): VM state change detected. (old = 'poweroff', new = 'running')
2020-08-29 13:42:01 (35728): Detected: Web Application Enabled (http://localhost:47007)
2020-08-29 13:42:01 (35728): Preference change detected
2020-08-29 13:42:01 (35728): Setting CPU throttle for VM. (60%)
2020-08-29 13:42:01 (35728): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-08-29 13:42:06 (35728): Guest Log: 00:44:04.673874 timesync vgsvcTimeSyncWorker: Radical host time change: 1 285 494 423 000 000ns (HostNow=1 598 701 325 305 000 000 ns HostLast=1 597 415 830 882 000 000 ns)
2020-08-29 13:42:16 (35728): Guest Log: 00:44:14.741880 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 285 494 515 750 000ns (GuestNow=1 598 701 335 397 761 000 ns GuestLast=1 597 415 840 882 011 000 ns fSetTimeLastLoop=true )
2020-08-29 14:36:27 (35728): Status Report: Job Duration: '864000.000000'
2020-08-29 14:36:27 (35728): Status Report: Elapsed Time: '6000.000000'
2020-08-29 14:36:27 (35728): Status Report: CPU Time: '3746.450000'
2020-08-29 16:18:01 (35728): Status Report: Job Duration: '864000.000000'
2020-08-29 16:18:01 (35728): Status Report: Elapsed Time: '12000.108658'
2020-08-29 16:18:01 (35728): Status Report: CPU Time: '7508.360000'
2020-08-29 16:33:16 (35728): Stopping VM.
2020-08-29 16:33:28 (35728): Successfully stopped VM.
2020-08-29 19:59:48 (42515): vboxwrapper (7.7.26196): starting
2020-08-29 19:59:51 (42515): Feature: Checkpoint interval offset (559 seconds)
2020-08-29 19:59:51 (42515): Detected: VirtualBox VboxManage Interface (Version: 5.2.42)
2020-08-29 19:59:51 (42515): VBOX_JOB::parse(): unexpected text enable_screenshots_on_error/
2020-08-29 19:59:51 (42515): Detected: Minimum checkpoint interval (600.000000 seconds)
2020-08-29 19:59:51 (42515): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-08-29 19:59:51 (42515): Starting VM. (boinc_5116443a239e4e2c, slot#6)
2020-08-29 20:00:04 (42515): Successfully started VM. (PID = '43218')
2020-08-29 20:00:04 (42515): Reporting VM Process ID to BOINC.
2020-08-29 20:00:04 (42515): Guest Log: 03:35:16.980785 timesync vgsvcTimeSyncWorker: Radical host time change: 12 416 320 000 000ns (HostNow=1 598 724 004 065 000 000 ns HostLast=1 598 711 587 745 000 000 ns)
2020-08-29 20:00:04 (42515): VM state change detected. (old = 'poweroff', new = 'running')
2020-08-29 20:00:04 (42515): Detected: Web Application Enabled (http://localhost:47007)
2020-08-29 20:00:05 (42515): Status Report: Job Duration: '864000.000000'
2020-08-29 20:00:05 (42515): Status Report: Elapsed Time: '12925.108658'
2020-08-29 20:00:05 (42515): Status Report: CPU Time: '8084.280000'
2020-08-29 20:00:05 (42515): Preference change detected
2020-08-29 20:00:05 (42515): Setting CPU throttle for VM. (60%)
2020-08-29 20:00:05 (42515): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-08-29 20:00:14 (42515): Guest Log: 03:35:26.989691 timesync vgsvcTimeSyncWorker: Radical guest time change: 12 416 472 658 000ns (GuestNow=1 598 724 014 091 613 000 ns GuestLast=1 598 711 597 618 955 000 ns fSetTimeLastLoop=true )
2020-08-29 21:39:06 (42515): Status Report: Job Duration: '864000.000000'
2020-08-29 21:39:06 (42515): Status Report: Elapsed Time: '18926.108658'
2020-08-29 21:39:06 (42515): Status Report: CPU Time: '11862.810000'
2020-08-29 22:36:20 (42515): Stopping VM.
2020-08-29 22:36:29 (42515): Successfully stopped VM.
2020-08-29 22:42:09 (51540): vboxwrapper (7.7.26196): starting
2020-08-29 22:42:09 (51540): Feature: Checkpoint interval offset (254 seconds)
2020-08-29 22:42:09 (51540): Detected: VirtualBox VboxManage Interface (Version: 5.2.42)
2020-08-29 22:42:10 (51540): VBOX_JOB::parse(): unexpected text enable_screenshots_on_error/
2020-08-29 22:42:10 (51540): Detected: Minimum checkpoint interval (600.000000 seconds)
2020-08-29 22:42:10 (51540): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-08-29 22:42:10 (51540): Starting VM. (boinc_5116443a239e4e2c, slot#6)
2020-08-29 22:42:21 (51540): Successfully started VM. (PID = '52304')
2020-08-29 22:42:21 (51540): Reporting VM Process ID to BOINC.
2020-08-29 22:42:21 (51540): VM state change detected. (old = 'poweroff', new = 'running')
2020-08-29 22:42:21 (51540): Detected: Web Application Enabled (http://localhost:47007)
2020-08-29 22:42:22 (51540): Status Report: Job Duration: '864000.000000'
2020-08-29 22:42:22 (51540): Status Report: Elapsed Time: '22394.337414'
2020-08-29 22:42:22 (51540): Status Report: CPU Time: '14040.940000'
2020-08-29 22:42:22 (51540): Preference change detected
2020-08-29 22:42:22 (51540): Setting CPU throttle for VM. (60%)
2020-08-29 22:42:22 (51540): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-08-29 22:54:53 (51540): VM state change detected. (old = 'running', new = 'paused')
2020-08-29 22:55:03 (51540): VM state change detected. (old = 'paused', new = 'running')
2020-08-29 22:55:18 (51540): Guest Log: job: run exitcode=0
2020-08-29 22:55:18 (51540): Guest Log: job: diskusage=2680
2020-08-29 22:55:18 (51540): Guest Log: job: logsize=84 k
2020-08-29 22:55:18 (51540): Guest Log: job: times=
2020-08-29 22:55:18 (51540): Guest Log: 0m0.006s 0m0.019s
2020-08-29 22:55:18 (51540): Guest Log: 365m32.959s 6m18.487s
2020-08-29 22:55:18 (51540): Guest Log: job: cpuusage=22311
2020-08-29 22:55:19 (51540): Guest Log: 22:55:08 CEST +02:00 2020-08-29: cranky: [INFO] Container 'runc' finished with status code 0.
2020-08-29 22:55:19 (51540): Guest Log: 22:55:08 CEST +02:00 2020-08-29: cranky: [INFO] Preparing output.
2020-08-29 22:55:21 (51540): Guest Log: [INFO] Job Finished
2020-08-29 22:55:21 (51540): Guest Log: [INFO] Shutting Down.
2020-08-29 22:55:21 (51540): VM Completion File Detected.
2020-08-29 22:55:21 (51540): VM Completion Message: Job Finished
.
2020-08-29 22:55:21 (51540): Powering off VM.
2020-08-29 22:55:21 (51540): Successfully stopped VM.
2020-08-29 22:55:21 (51540): Deregistering VM. (boinc_5116443a239e4e2c, slot#6)
2020-08-29 22:55:21 (51540): Removing network bandwidth throttle group from VM.
2020-08-29 22:55:22 (51540): Removing storage controller(s) from VM.
2020-08-29 22:55:22 (51540): Removing VM from VirtualBox.
2020-08-29 22:55:22 (51540): Removing virtual disk drive from VirtualBox.
22:55:27 (51540): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN