Stderr output

<core_client_version>7.24.1</core_client_version>
<![CDATA[
<stderr_txt>
2025-01-22 18:03:56 (990292): vboxwrapper version 26208
2025-01-22 18:03:56 (990292): BOINC client version: 7.24.1
2025-01-22 18:03:57 (990292): Detected: VirtualBox VboxManage Interface (Version: 6.1.36)
2025-01-22 18:03:59 (990292): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-01-22 18:03:59 (990292): Successfully copied 'init_data.xml' to the shared directory.
2025-01-22 18:03:59 (990292): Successfully copied 'input' to the shared directory.
2025-01-22 18:04:00 (990292): Create VM. (boinc_5f0ea01489ca9190, slot#3)
2025-01-22 18:04:02 (990292): Setting Memory Size for VM. (630MB)
2025-01-22 18:04:03 (990292): Setting CPU Count for VM. (1)
2025-01-22 18:04:03 (990292): Setting Chipset Options for VM.
2025-01-22 18:04:05 (990292): Setting Graphics Controller Options for VM.
2025-01-22 18:04:06 (990292): Setting Boot Options for VM.
2025-01-22 18:04:07 (990292): Setting Network Configuration for NAT.
2025-01-22 18:04:08 (990292): Enabling VM Network Access.
2025-01-22 18:04:09 (990292): Disabling USB Support for VM.
2025-01-22 18:04:10 (990292): Disabling COM Port Support for VM.
2025-01-22 18:04:11 (990292): Disabling LPT Port Support for VM.
2025-01-22 18:04:11 (990292): Disabling Audio Support for VM.
2025-01-22 18:04:12 (990292): Disabling Clipboard Support for VM.
2025-01-22 18:04:13 (990292): Disabling Drag and Drop Support for VM.
2025-01-22 18:04:15 (990292): Adding storage controller(s) to VM.
2025-01-22 18:04:16 (990292): Adding virtual disk drive to VM. (Theory_2025_01_16_prod.vdi)
2025-01-22 18:04:17 (990292): Adding VirtualBox Guest Additions to VM.
2025-01-22 18:04:19 (990292): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2025-01-22 18:04:20 (990292): forwarding host port 63175 to guest port 80
2025-01-22 18:04:21 (990292): Enabling remote desktop for VM.
2025-01-22 18:04:23 (990292): Enabling shared directory for VM.
2025-01-22 18:04:25 (990292): Starting VM using VBoxManage interface. (boinc_5f0ea01489ca9190, slot#3)
2025-01-22 18:04:37 (990292): Successfully started VM. (PID = '989668')
2025-01-22 18:04:37 (990292): Reporting VM Process ID to BOINC.
2025-01-22 18:04:37 (990292): Guest Log: BIOS: VirtualBox 6.1.36
2025-01-22 18:04:37 (990292): Guest Log: CPUID EDX: 0x178bfbff
2025-01-22 18:04:37 (990292): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-22 18:04:37 (990292): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-22 18:04:37 (990292): VM state change detected. (old = 'poweredoff', new = 'running')
2025-01-22 18:04:37 (990292): Detected: Web Application Enabled (http://localhost:63175)
2025-01-22 18:04:37 (990292): Detected: Remote Desktop Enabled (localhost:63176)
2025-01-22 18:04:37 (990292): Preference change detected
2025-01-22 18:04:37 (990292): Setting CPU throttle for VM. (100%)
2025-01-22 18:04:38 (990292): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2025-01-22 18:04:39 (990292): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-22 18:04:39 (990292): Guest Log: BIOS: Booting from Hard Disk...
2025-01-22 18:04:41 (990292): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-22 18:04:41 (990292): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-01-22 18:05:08 (990292): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-22 18:05:08 (990292): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-22 18:05:13 (990292): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-22 18:05:13 (990292): Guest Log: 00:00:00.000200 main     Log opened 2025-01-23T02:05:13.714789000Z
2025-01-22 18:05:13 (990292): Guest Log: 00:00:00.000324 main     OS Product: Linux
2025-01-22 18:05:13 (990292): Guest Log: 00:00:00.000409 main     OS Release: 4.14.76-13.cernvm.x86_64
2025-01-22 18:05:13 (990292): Guest Log: 00:00:00.000455 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2025-01-22 18:05:13 (990292): Guest Log: 00:00:00.000498 main     Executable: /usr/sbin/VBoxService
2025-01-22 18:05:13 (990292): Guest Log: 00:00:00.000500 main     Process ID: 3223
2025-01-22 18:05:13 (990292): Guest Log: 00:00:00.000501 main     Package type: LINUX_64BITS_GENERIC
2025-01-22 18:05:13 (990292): Guest Log: 00:00:00.004934 main     5.2.6 r120293 started. Verbose level = 0
2025-01-22 18:05:43 (990292): Guest Log: 21:05:44 EST -05:00 2025-01-22: cranky: [INFO] Detected Theory App
2025-01-22 18:05:43 (990292): Guest Log: 21:05:44 EST -05:00 2025-01-22: cranky: [INFO] Checking CVMFS.
2025-01-22 18:05:44 (990292): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2025-01-22 18:05:45 (990292): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2025-01-22 18:05:45 (990292): Guest Log: 2.5.2.0 4082 0 27180 31544 3 1 278943 4096000 0 65024 0 0 n/a 5 2 http://s1ihep-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2025-01-22 18:05:46 (990292): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2025-01-22 18:05:47 (990292): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2025-01-22 18:05:47 (990292): Guest Log: 2.5.2.0 4130 0 27548 26293 3 2 278943 4096000 0 65024 0 2 -100 10083 2789 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2025-01-22 18:05:49 (990292): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2025-01-22 18:05:50 (990292): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2025-01-22 18:05:50 (990292): Guest Log: 2.5.2.0 4103 0 27220 272 3 1 278943 4096000 0 65024 0 0 n/a 4 3 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2025-01-22 18:05:51 (990292): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2025-01-22 18:05:52 (990292): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2025-01-22 18:05:52 (990292): Guest Log: 2.5.2.0 4055 0 42248 20944 3 1 278943 4096000 0 65024 0 0 n/a 6593 3552 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2025-01-22 18:05:52 (990292): Guest Log: 21:05:52 EST -05:00 2025-01-22: cranky: [INFO] Checking runc.
2025-01-22 18:05:52 (990292): Guest Log: 21:05:52 EST -05:00 2025-01-22: cranky: [INFO] Creating the filesystem.
2025-01-22 18:05:52 (990292): Guest Log: 21:05:52 EST -05:00 2025-01-22: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2025-01-22 18:05:52 (990292): Guest Log: 21:05:52 EST -05:00 2025-01-22: cranky: [INFO] Updating config.json.
2025-01-22 18:05:53 (990292): Guest Log: 21:05:53 EST -05:00 2025-01-22: cranky: [INFO] Running Container 'runc'.
2025-01-22 18:05:56 (990292): Guest Log: job: htmld=/shared/html/job
2025-01-22 18:05:57 (990292): Guest Log: job: unpack exitcode=0
2025-01-22 18:05:57 (990292): Guest Log: INFO: activated the work-around for ld:
2025-01-22 18:05:57 (990292): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 23 02:05 /tmp/tmp.2r03lCQ5yy/ld -> /usr/bin/ld.bfd
2025-01-22 18:05:59 (990292): Guest Log: 21:05:59 EST -05:00 2025-01-22: cranky: [INFO] ===> [runRivet] Thu Jan 23 02:05:57 UTC 2025 [boinc ppbar jets 1960 17 - powheg-box r3744 pthard2 100000 326]
2025-01-22 18:24:40 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-23 02:30:44 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-23 02:30:49 (990292): Guest Log: 00:19:31.078618 timesync vgsvcTimeSyncWorker: Radical host time change: 29 173 625 000 000ns (HostNow=1 737 628 247 941 000 000 ns HostLast=1 737 599 074 316 000 000 ns)
2025-01-23 02:30:58 (990292): Guest Log: 00:19:41.107590 timesync vgsvcTimeSyncWorker: Radical guest time change: 29 173 359 332 000ns (GuestNow=1 737 628 257 991 503 000 ns GuestLast=1 737 599 084 632 171 000 ns fSetTimeLastLoop=true )
2025-01-23 03:31:32 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-23 04:31:57 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-23 04:32:04 (990292): Guest Log: 01:20:22.131147 timesync vgsvcTimeSyncWorker: Radical host time change: 3 634 756 000 000ns (HostNow=1 737 635 523 770 000 000 ns HostLast=1 737 631 889 014 000 000 ns)
2025-01-23 04:32:14 (990292): Guest Log: 01:20:32.169670 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 634 793 489 000ns (GuestNow=1 737 635 533 808 532 000 ns GuestLast=1 737 631 899 015 043 000 ns fSetTimeLastLoop=true )
2025-01-23 04:54:34 (990292): Status Report: Job Duration: '864000.000000'
2025-01-23 04:54:34 (990292): Status Report: Elapsed Time: '6000.152962'
2025-01-23 04:54:34 (990292): Status Report: CPU Time: '5270.421875'
2025-01-23 06:25:45 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-23 17:21:34 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-23 17:21:35 (990292): Guest Log: 03:14:04.033034 timesync vgsvcTimeSyncWorker: Radical host time change: 39 358 488 000 000ns (HostNow=1 737 681 694 156 000 000 ns HostLast=1 737 642 335 668 000 000 ns)
2025-01-23 17:21:45 (990292): Guest Log: 03:14:14.043319 timesync vgsvcTimeSyncWorker: Radical guest time change: 39 358 494 431 000ns (GuestNow=1 737 681 704 166 301 000 ns GuestLast=1 737 642 345 671 870 000 ns fSetTimeLastLoop=true )
2025-01-23 17:32:46 (990292): Status Report: Job Duration: '864000.000000'
2025-01-23 17:32:46 (990292): Status Report: Elapsed Time: '12001.132406'
2025-01-23 17:32:46 (990292): Status Report: CPU Time: '11257.390625'
2025-01-23 17:45:46 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-23 18:45:17 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-23 18:45:25 (990292): Guest Log: 03:38:24.484295 timesync vgsvcTimeSyncWorker: Radical host time change: 3 580 558 000 000ns (HostNow=1 737 686 725 155 000 000 ns HostLast=1 737 683 144 597 000 000 ns)
2025-01-23 18:45:35 (990292): Guest Log: 03:38:34.485778 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 580 549 217 000ns (GuestNow=1 737 686 735 156 471 000 ns GuestLast=1 737 683 154 607 254 000 ns fSetTimeLastLoop=true )
2025-01-23 20:14:14 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-23 20:15:15 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-23 20:16:12 (990292): Status Report: Job Duration: '864000.000000'
2025-01-23 20:16:12 (990292): Status Report: Elapsed Time: '18001.316944'
2025-01-23 20:16:12 (990292): Status Report: CPU Time: '17257.359375'
2025-01-23 20:57:27 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-23 21:14:54 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-23 22:03:19 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-23 22:36:26 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-23 22:36:29 (990292): Guest Log: 06:37:57.644215 timesync vgsvcTimeSyncWorker: Radical host time change: 1 996 031 000 000ns (HostNow=1 737 700 588 966 000 000 ns HostLast=1 737 698 592 935 000 000 ns)
2025-01-23 22:36:39 (990292): Guest Log: 06:38:07.644755 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 100 651 657 000ns (GuestNow=1 737 700 598 966 563 000 ns GuestLast=1 737 697 498 314 906 000 ns fSetTimeLastLoop=true )
2025-01-23 22:50:13 (990292): Status Report: Job Duration: '864000.000000'
2025-01-23 22:50:13 (990292): Status Report: Elapsed Time: '24002.093449'
2025-01-23 22:50:13 (990292): Status Report: CPU Time: '23185.015625'
2025-01-23 23:56:07 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 00:03:37 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 00:41:29 (990292): Status Report: Job Duration: '864000.000000'
2025-01-24 00:41:29 (990292): Status Report: Elapsed Time: '30002.402636'
2025-01-24 00:41:29 (990292): Status Report: CPU Time: '29077.937500'
2025-01-24 01:04:31 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 01:46:46 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 01:46:54 (990292): Guest Log: 08:58:40.325039 timesync vgsvcTimeSyncWorker: Radical host time change: 2 543 720 000 000ns (HostNow=1 737 712 013 624 000 000 ns HostLast=1 737 709 469 904 000 000 ns)
2025-01-24 01:47:04 (990292): Guest Log: 08:58:50.326583 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 991 978 732 000ns (GuestNow=1 737 712 023 625 556 000 ns GuestLast=1 737 709 031 646 824 000 ns fSetTimeLastLoop=true )
2025-01-24 02:41:40 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 03:41:23 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 03:41:28 (990292): Guest Log: 09:53:31.266951 timesync vgsvcTimeSyncWorker: Radical host time change: 3 592 589 000 000ns (HostNow=1 737 718 887 152 000 000 ns HostLast=1 737 715 294 563 000 000 ns)
2025-01-24 03:41:37 (990292): Guest Log: 09:53:41.273294 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 592 592 458 000ns (GuestNow=1 737 718 897 158 356 000 ns GuestLast=1 737 715 304 565 898 000 ns fSetTimeLastLoop=true )
2025-01-24 04:06:40 (990292): Status Report: Job Duration: '864000.000000'
2025-01-24 04:06:40 (990292): Status Report: Elapsed Time: '36002.461918'
2025-01-24 04:06:40 (990292): Status Report: CPU Time: '34663.375000'
2025-01-24 04:08:57 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 05:08:29 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 05:08:30 (990292): Guest Log: 10:21:01.762453 timesync vgsvcTimeSyncWorker: Radical host time change: 3 582 670 000 000ns (HostNow=1 737 724 110 316 000 000 ns HostLast=1 737 720 527 646 000 000 ns)
2025-01-24 05:08:42 (990292): Guest Log: 10:21:11.762987 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 582 669 054 000ns (GuestNow=1 737 724 120 316 546 000 ns GuestLast=1 737 720 537 647 492 000 ns fSetTimeLastLoop=true )
2025-01-24 06:09:03 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 07:09:47 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 07:09:56 (990292): Guest Log: 11:21:42.956825 timesync vgsvcTimeSyncWorker: Radical host time change: 3 653 941 000 000ns (HostNow=1 737 731 395 444 000 000 ns HostLast=1 737 727 741 503 000 000 ns)
2025-01-24 07:10:06 (990292): Guest Log: 11:21:52.958307 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 653 935 184 000ns (GuestNow=1 737 731 405 445 518 000 ns GuestLast=1 737 727 751 510 334 000 ns fSetTimeLastLoop=true )
2025-01-24 07:58:07 (990292): Status Report: Job Duration: '864000.000000'
2025-01-24 07:58:07 (990292): Status Report: Elapsed Time: '42003.195457'
2025-01-24 07:58:07 (990292): Status Report: CPU Time: '40795.984375'
2025-01-24 08:10:24 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 08:33:45 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 09:34:22 (990292): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 09:54:44 (990292): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 10:42:05 (990292): Status Report: Job Duration: '864000.000000'
2025-01-24 10:42:05 (990292): Status Report: Elapsed Time: '48003.704193'
2025-01-24 10:42:05 (990292): Status Report: CPU Time: '47132.656250'
2025-01-24 12:54:03 (990292): Status Report: Job Duration: '864000.000000'
2025-01-24 12:54:03 (990292): Status Report: Elapsed Time: '54005.862667'
2025-01-24 12:54:03 (990292): Status Report: CPU Time: '53448.437500'
2025-01-24 15:05:12 (990292): Status Report: Job Duration: '864000.000000'
2025-01-24 15:05:12 (990292): Status Report: Elapsed Time: '60013.604668'
2025-01-24 15:05:12 (990292): Status Report: CPU Time: '59534.750000'
2025-01-24 18:23:11 (990292): Status Report: Job Duration: '864000.000000'
2025-01-24 18:23:11 (990292): Status Report: Elapsed Time: '66014.525822'
2025-01-24 18:23:11 (990292): Status Report: CPU Time: '68939.828125'
2025-01-24 23:24:45 (990292): Status Report: Job Duration: '864000.000000'
2025-01-24 23:24:45 (990292): Status Report: Elapsed Time: '72047.154467'
2025-01-24 23:24:45 (990292): Status Report: CPU Time: '83399.359375'
2025-01-25 03:39:03 (990292): Status Report: Job Duration: '864000.000000'
2025-01-25 03:39:03 (990292): Status Report: Elapsed Time: '78057.324718'
2025-01-25 03:39:03 (990292): Status Report: CPU Time: '95504.234375'
2025-01-25 05:39:15 (990292): Guest Log: job: run exitcode=0
2025-01-25 05:39:15 (990292): Guest Log: job: diskusage=100196
2025-01-25 05:39:15 (990292): Guest Log: job: logsize=80 k
2025-01-25 05:39:15 (990292): Guest Log: job: times=
2025-01-25 05:39:15 (990292): Guest Log: 0m0.017s 0m0.025s
2025-01-25 05:39:15 (990292): Guest Log: 1936m5.004s 11m43.658s
2025-01-25 05:39:15 (990292): Guest Log: job: cpuusage=116869
2025-01-25 05:39:16 (990292): Guest Log: 08:39:15 EST -05:00 2025-01-25: cranky: [INFO] Container 'runc' finished with status code 0.
2025-01-25 05:39:16 (990292): Guest Log: 08:39:15 EST -05:00 2025-01-25: cranky: [INFO] Preparing output.
2025-01-25 05:39:25 (990292): Guest Log: [INFO] Job Finished
2025-01-25 05:39:25 (990292): Guest Log: [INFO] Shutting Down.
2025-01-25 05:39:25 (990292): VM Completion File Detected.
2025-01-25 05:39:25 (990292): VM Completion Message: Job Finished
.
2025-01-25 05:39:25 (990292): Powering off VM.
2025-01-25 05:40:04 (990292): Successfully stopped VM.
2025-01-25 05:40:04 (990292): Deregistering VM. (boinc_5f0ea01489ca9190, slot#3)
2025-01-25 05:40:11 (990292): Removing network bandwidth throttle group from VM.
2025-01-25 05:40:20 (990292): Removing VM from VirtualBox.
2025-01-25 05:40:39 (990292): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN