Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2020-05-03 21:38:46 (15652): Detected: vboxwrapper 26197
2020-05-03 21:38:46 (15652): Detected: BOINC client v7.7
2020-05-03 21:38:46 (15652): Status Report: Detected vboxsvc.exe. (PID = '19824')
2020-05-03 21:38:47 (15652): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2020-05-03 21:38:47 (15652): Detected: Sandbox Configuration Enabled
2020-05-03 21:38:47 (15652): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-05-03 21:38:47 (15652): Successfully copied 'init_data.xml' to the shared directory.
2020-05-03 21:38:47 (15652): Successfully copied 'input' to the shared directory.
2020-05-03 21:38:48 (15652): Create VM. (boinc_27fec29e8f7a0957, slot#4)
2020-05-03 21:38:49 (15652): Setting Memory Size for VM. (630MB)
2020-05-03 21:38:49 (15652): Setting CPU Count for VM. (1)
2020-05-03 21:38:49 (15652): Setting Chipset Options for VM.
2020-05-03 21:38:50 (15652): Setting Boot Options for VM.
2020-05-03 21:38:50 (15652): Setting Network Configuration for NAT.
2020-05-03 21:38:50 (15652): Enabling VM Network Access.
2020-05-03 21:38:51 (15652): Disabling USB Support for VM.
2020-05-03 21:38:51 (15652): Disabling COM Port Support for VM.
2020-05-03 21:38:51 (15652): Disabling LPT Port Support for VM.
2020-05-03 21:38:51 (15652): Disabling Audio Support for VM.
2020-05-03 21:38:52 (15652): Disabling Clipboard Support for VM.
2020-05-03 21:38:52 (15652): Disabling Drag and Drop Support for VM.
2020-05-03 21:38:52 (15652): Adding storage controller(s) to VM.
2020-05-03 21:38:52 (15652): Adding virtual disk drive to VM. (vm_image.vdi)
2020-05-03 21:38:53 (15652): Adding VirtualBox Guest Additions to VM.
2020-05-03 21:38:53 (15652): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2020-05-03 21:38:53 (15652): forwarding host port 58358 to guest port 80
2020-05-03 21:38:53 (15652): Enabling remote desktop for VM.
2020-05-03 21:38:54 (15652): Required extension pack not installed, remote desktop not enabled.
2020-05-03 21:38:54 (15652): Enabling shared directory for VM.
2020-05-03 21:38:54 (15652): Starting VM using VBoxManage interface. (boinc_27fec29e8f7a0957, slot#4)
2020-05-03 21:38:58 (15652): Successfully started VM. (PID = '25472')
2020-05-03 21:38:58 (15652): Reporting VM Process ID to BOINC.
2020-05-03 21:38:58 (15652): Guest Log: BIOS: VirtualBox 5.2.8

2020-05-03 21:38:58 (15652): Guest Log: CPUID EDX: 0x078bfbff

2020-05-03 21:38:58 (15652): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-05-03 21:38:58 (15652): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-05-03 21:38:58 (15652): Detected: Web Application Enabled (http://localhost:58358)
2020-05-03 21:38:58 (15652): Preference change detected
2020-05-03 21:38:58 (15652): Setting CPU throttle for VM. (60%)
2020-05-03 21:38:58 (15652): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-03 21:39:00 (15652): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-05-03 21:39:00 (15652): Guest Log: BIOS: Booting from Hard Disk...

2020-05-03 21:39:03 (15652): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-05-03 21:39:03 (15652): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-05-03 21:39:30 (15652): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-05-03 21:39:30 (15652): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-05-03 21:39:33 (15652): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-05-03 21:39:33 (15652): Guest Log: 00:00:00.000079 main     Log opened 2020-05-03T19:39:32.374679000Z

2020-05-03 21:39:33 (15652): Guest Log: 00:00:00.000150 main     OS Product: Linux

2020-05-03 21:39:33 (15652): Guest Log: 00:00:00.000187 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-05-03 21:39:33 (15652): Guest Log: 00:00:00.000211 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-05-03 21:39:33 (15652): Guest Log: 00:00:00.000233 main     Executable: /usr/sbin/VBoxService

2020-05-03 21:39:33 (15652): Guest Log: 00:00:00.000234 main     Process ID: 3186

2020-05-03 21:39:33 (15652): Guest Log: 00:00:00.000235 main     Package type: LINUX_64BITS_GENERIC

2020-05-03 21:39:33 (15652): Guest Log: 00:00:00.006981 main     5.2.6 r120293 started. Verbose level = 0

2020-05-03 21:39:50 (15652): Guest Log: 21:39:49 CEST +02:00 2020-05-03: cranky: [INFO] Detected Theory App

2020-05-03 21:39:50 (15652): Guest Log: 21:39:49 CEST +02:00 2020-05-03: cranky: [INFO] Checking CVMFS.

2020-05-03 21:39:57 (15652): Guest Log: 21:39:56 CEST +02:00 2020-05-03: cranky: [INFO] Checking runc.

2020-05-03 21:39:57 (15652): Guest Log: 21:39:56 CEST +02:00 2020-05-03: cranky: [INFO] Creating the filesystem.

2020-05-03 21:39:57 (15652): Guest Log: 21:39:56 CEST +02:00 2020-05-03: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-05-03 21:39:57 (15652): Guest Log: 21:39:56 CEST +02:00 2020-05-03: cranky: [INFO] Updating config.json.

2020-05-03 21:39:57 (15652): Guest Log: 21:39:56 CEST +02:00 2020-05-03: cranky: [INFO] Running Container 'runc'.

2020-05-03 21:39:57 (15652): Guest Log: job: htmld=/shared/html/job

2020-05-03 21:39:57 (15652): Guest Log: job: unpack exitcode=0

2020-05-03 21:39:59 (15652): Guest Log: 21:39:58 CEST +02:00 2020-05-03: cranky: [INFO] ===> [runRivet] Sun May  3 19:39:57 UTC 2020 [boinc pp jets 7000 28 - pythia8 8.243 default 100000 0]

2020-05-03 22:04:01 (15652): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-03 22:04:07 (15652): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_27fec29e8f7a0957" resume
Output:
VBoxManage.exe: error: VM is paused due to host power management
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2020-05-04 21:46:34 (15652): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-04 21:46:39 (15652): Guest Log: 00:24:32.483669 timesync vgsvcTimeSyncWorker: Radical host time change: 85 364 200 000 000ns (HostNow=1 588 621 598 347 000 000 ns HostLast=1 588 536 234 147 000 000 ns)

2020-05-04 21:46:49 (15652): Guest Log: 00:24:42.590238 timesync vgsvcTimeSyncWorker: Radical guest time change: 85 364 545 962 000ns (GuestNow=1 588 621 608 928 845 000 ns GuestLast=1 588 536 244 382 883 000 ns fSetTimeLastLoop=true )

2020-05-04 23:01:43 (15652): Status Report: Job Duration: '360000.000000'
2020-05-04 23:01:43 (15652): Status Report: Elapsed Time: '6000.000000'
2020-05-04 23:01:43 (15652): Status Report: CPU Time: '3523.265625'
2020-05-05 00:18:14 (15652): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-05 19:57:46 (15652): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_27fec29e8f7a0957" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2020-05-05 19:57:46 (15652): Guest Log: 02:56:16.216176 timesync vgsvcTimeSyncWorker: Radical host time change: 70 772 990 000 000ns (HostNow=1 588 701 465 553 000 000 ns HostLast=1 588 630 692 563 000 000 ns)

2020-05-05 19:57:46 (15652): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-05 19:57:56 (15652): Guest Log: 02:56:26.251398 timesync vgsvcTimeSyncWorker: Radical guest time change: 70 773 033 463 000ns (GuestNow=1 588 701 475 588 230 000 ns GuestLast=1 588 630 702 554 767 000 ns fSetTimeLastLoop=true )

2020-05-05 20:21:32 (15652): Status Report: Job Duration: '360000.000000'
2020-05-05 20:21:32 (15652): Status Report: Elapsed Time: '12000.000000'
2020-05-05 20:21:32 (15652): Status Report: CPU Time: '7134.578125'
2020-05-05 21:06:39 (15652): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-10 18:01:23 (15652): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_27fec29e8f7a0957" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2020-05-10 18:01:24 (15652): Guest Log: 04:05:16.088558 timesync vgsvcTimeSyncWorker: Radical host time change: 420 886 305 000 000ns (HostNow=1 589 126 478 301 000 000 ns HostLast=1 588 705 591 996 000 000 ns)

2020-05-10 18:01:24 (15652): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-10 18:01:31 (15652): Guest Log: 04:05:26.095099 timesync vgsvcTimeSyncWorker: Radical guest time change: 420 888 219 192 000ns (GuestNow=1 589 126 490 976 057 000 ns GuestLast=1 588 705 602 756 865 000 ns fSetTimeLastLoop=true )

2020-05-10 18:29:16 (15652): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-12 06:34:55 (15652): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_27fec29e8f7a0957" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2020-05-12 06:34:55 (15652): Guest Log: 04:33:20.990861 timesync vgsvcTimeSyncWorker: Radical host time change: 129 937 595 000 000ns (HostNow=1 589 258 093 463 000 000 ns HostLast=1 589 128 155 868 000 000 ns)

2020-05-12 06:34:55 (15652): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-12 06:35:03 (15652): Guest Log: 04:33:31.023249 timesync vgsvcTimeSyncWorker: Radical guest time change: 129 937 623 594 000ns (GuestNow=1 589 258 103 495 397 000 ns GuestLast=1 589 128 165 871 803 000 ns fSetTimeLastLoop=true )

2020-05-12 07:03:12 (15652): Status Report: Job Duration: '360000.000000'
2020-05-12 07:03:12 (15652): Status Report: Elapsed Time: '18000.124401'
2020-05-12 07:03:12 (15652): Status Report: CPU Time: '10742.968750'
2020-05-12 07:06:30 (15652): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-13 19:47:50 (15652): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_27fec29e8f7a0957" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2020-05-13 19:47:50 (15652): Guest Log: 05:05:04.140219 timesync vgsvcTimeSyncWorker: Radical host time change: 132 079 121 000 000ns (HostNow=1 589 392 065 714 000 000 ns HostLast=1 589 259 986 593 000 000 ns)

2020-05-13 19:47:50 (15652): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-13 19:47:56 (15652): Guest Log: 05:05:14.161616 timesync vgsvcTimeSyncWorker: Radical guest time change: 132 079 123 048 000ns (GuestNow=1 589 392 075 735 400 000 ns GuestLast=1 589 259 996 612 352 000 ns fSetTimeLastLoop=true )

2020-05-13 21:25:05 (15652): Status Report: Job Duration: '360000.000000'
2020-05-13 21:25:05 (15652): Status Report: Elapsed Time: '24000.691908'
2020-05-13 21:25:05 (15652): Status Report: CPU Time: '14350.000000'
2020-05-13 22:00:45 (15652): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-15 06:51:29 (15652): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_27fec29e8f7a0957" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2020-05-15 06:51:29 (15652): Guest Log: 07:18:06.105399 timesync vgsvcTimeSyncWorker: Radical host time change: 118 244 405 000 000ns (HostNow=1 589 518 282 407 000 000 ns HostLast=1 589 400 038 002 000 000 ns)

2020-05-15 06:51:29 (15652): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-15 06:51:33 (15652): Guest Log: 07:18:16.109715 timesync vgsvcTimeSyncWorker: Radical guest time change: 118 244 732 154 000ns (GuestNow=1 589 518 292 411 327 000 ns GuestLast=1 589 400 047 679 173 000 ns fSetTimeLastLoop=true )

2020-05-15 06:52:46 (15652): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-15 16:19:49 (15652): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_27fec29e8f7a0957" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2020-05-15 16:19:51 (15652): Guest Log: 07:19:36.216360 timesync vgsvcTimeSyncWorker: Radical host time change: 34 021 022 000 000ns (HostNow=1 589 552 383 535 000 000 ns HostLast=1 589 518 362 513 000 000 ns)

2020-05-15 16:19:51 (15652): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-15 16:19:54 (15652): Guest Log: 07:19:46.237862 timesync vgsvcTimeSyncWorker: Radical guest time change: 34 021 038 553 000ns (GuestNow=1 589 552 393 556 507 000 ns GuestLast=1 589 518 372 517 954 000 ns fSetTimeLastLoop=true )

2020-05-15 17:24:51 (15652): Status Report: Job Duration: '360000.000000'
2020-05-15 17:24:51 (15652): Status Report: Elapsed Time: '30000.779169'
2020-05-15 17:24:51 (15652): Status Report: CPU Time: '17966.468750'
2020-05-15 18:02:16 (15652): Guest Log: job: run exitcode=0

2020-05-15 18:02:16 (15652): Guest Log: job: diskusage=4728

2020-05-15 18:02:16 (15652): Guest Log: job: logsize=80 k

2020-05-15 18:02:16 (15652): Guest Log: job: times=

2020-05-15 18:02:16 (15652): Guest Log: 0m0.006s 0m0.006s

2020-05-15 18:02:16 (15652): Guest Log: 533m34.835s 1m42.176s

2020-05-15 18:02:16 (15652): Guest Log: job: cpuusage=32117

2020-05-15 18:02:16 (15652): Guest Log: 18:02:15 CEST +02:00 2020-05-15: cranky: [INFO] Container 'runc' finished with status code 0.

2020-05-15 18:02:16 (15652): Guest Log: 18:02:15 CEST +02:00 2020-05-15: cranky: [INFO] Preparing output.

2020-05-15 18:02:17 (15652): Guest Log: [INFO] Job Finished

2020-05-15 18:02:18 (15652): Guest Log: [INFO] Shutting Down.

2020-05-15 18:02:18 (15652): VM Completion File Detected.
2020-05-15 18:02:18 (15652): VM Completion Message: Job Finished
.
2020-05-15 18:02:18 (15652): Powering off VM.
2020-05-15 18:07:18 (15652): VM did not power off when requested.
2020-05-15 18:07:18 (15652): VM was successfully terminated.
2020-05-15 18:07:18 (15652): Deregistering VM. (boinc_27fec29e8f7a0957, slot#4)
2020-05-15 18:07:18 (15652): Removing network bandwidth throttle group from VM.
2020-05-15 18:07:19 (15652): Removing VM from VirtualBox.
18:07:24 (15652): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN