Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2020-02-20 17:16:51 (1904): Detected: vboxwrapper 26197
2020-02-20 17:16:51 (1904): Detected: BOINC client v7.7
2020-02-20 17:16:54 (1904): Detected: VirtualBox VboxManage Interface (Version: 5.2.34)
2020-02-20 17:16:54 (1904): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-02-20 17:16:54 (1904): Successfully copied 'init_data.xml' to the shared directory.
2020-02-20 17:16:54 (1904): Successfully copied 'input' to the shared directory.
2020-02-20 17:16:58 (1904): Create VM. (boinc_8807085c9bdcc240, slot#4)
2020-02-20 17:16:59 (1904): Setting Memory Size for VM. (630MB)
2020-02-20 17:17:00 (1904): Setting CPU Count for VM. (1)
2020-02-20 17:17:00 (1904): Setting Chipset Options for VM.
2020-02-20 17:17:01 (1904): Setting Boot Options for VM.
2020-02-20 17:17:02 (1904): Setting Network Configuration for NAT.
2020-02-20 17:17:02 (1904): Enabling VM Network Access.
2020-02-20 17:17:02 (1904): Disabling USB Support for VM.
2020-02-20 17:17:03 (1904): Disabling COM Port Support for VM.
2020-02-20 17:17:04 (1904): Disabling LPT Port Support for VM.
2020-02-20 17:17:04 (1904): Disabling Audio Support for VM.
2020-02-20 17:17:05 (1904): Disabling Clipboard Support for VM.
2020-02-20 17:17:05 (1904): Disabling Drag and Drop Support for VM.
2020-02-20 17:17:05 (1904): Adding storage controller(s) to VM.
2020-02-20 17:17:06 (1904): Adding virtual disk drive to VM. (vm_image.vdi)
2020-02-20 17:17:06 (1904): Adding VirtualBox Guest Additions to VM.
2020-02-20 17:17:07 (1904): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2020-02-20 17:17:07 (1904): forwarding host port 53376 to guest port 80
2020-02-20 17:17:08 (1904): Enabling remote desktop for VM.
2020-02-20 17:17:08 (1904): Enabling shared directory for VM.
2020-02-20 17:17:09 (1904): Starting VM using VBoxManage interface. (boinc_8807085c9bdcc240, slot#4)
2020-02-20 17:17:19 (1904): Successfully started VM. (PID = '11424')
2020-02-20 17:17:19 (1904): Reporting VM Process ID to BOINC.
2020-02-20 17:17:19 (1904): Guest Log: BIOS: VirtualBox 5.2.34

2020-02-20 17:17:19 (1904): Guest Log: CPUID EDX: 0x078bfbff

2020-02-20 17:17:19 (1904): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-02-20 17:17:19 (1904): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-02-20 17:17:19 (1904): Detected: Web Application Enabled (http://localhost:53376)
2020-02-20 17:17:19 (1904): Detected: Remote Desktop Enabled (localhost:53377)
2020-02-20 17:17:19 (1904): Preference change detected
2020-02-20 17:17:19 (1904): Setting CPU throttle for VM. (100%)
2020-02-20 17:17:19 (1904): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-02-20 17:17:21 (1904): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-02-20 17:17:21 (1904): Guest Log: BIOS: Booting from Hard Disk...

2020-02-20 17:17:24 (1904): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-02-20 17:17:24 (1904): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-02-20 17:18:02 (1904): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-02-20 17:18:02 (1904): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-02-20 17:18:11 (1904): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-02-20 17:18:11 (1904): Guest Log: 00:00:00.000711 main     Log opened 2020-02-20T16:18:11.731827000Z

2020-02-20 17:18:11 (1904): Guest Log: 00:00:00.001982 main     OS Product: Linux

2020-02-20 17:18:11 (1904): Guest Log: 00:00:00.002105 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-02-20 17:18:11 (1904): Guest Log: 00:00:00.002196 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-02-20 17:18:11 (1904): Guest Log: 00:00:00.002324 main     Executable: /usr/sbin/VBoxService

2020-02-20 17:18:11 (1904): Guest Log: 00:00:00.002328 main     Process ID: 3208

2020-02-20 17:18:11 (1904): Guest Log: 00:00:00.002331 main     Package type: LINUX_64BITS_GENERIC

2020-02-20 17:18:11 (1904): Guest Log: 00:00:00.012072 main     5.2.6 r120293 started. Verbose level = 0

2020-02-20 17:19:06 (1904): Guest Log: 17:19:07 CET +01:00 2020-02-20: cranky: [INFO] Detected Theory App

2020-02-20 17:19:06 (1904): Guest Log: 17:19:07 CET +01:00 2020-02-20: cranky: [INFO] Checking CVMFS.

2020-02-20 17:19:32 (1904): Guest Log: 17:19:33 CET +01:00 2020-02-20: cranky: [INFO] Checking runc.

2020-02-20 17:19:32 (1904): Guest Log: 17:19:33 CET +01:00 2020-02-20: cranky: [INFO] Creating the filesystem.

2020-02-20 17:19:32 (1904): Guest Log: 17:19:33 CET +01:00 2020-02-20: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-02-20 17:19:33 (1904): Guest Log: 17:19:34 CET +01:00 2020-02-20: cranky: [INFO] Updating config.json.

2020-02-20 17:19:33 (1904): Guest Log: 17:19:34 CET +01:00 2020-02-20: cranky: [INFO] Running Container 'runc'.

2020-02-20 17:19:34 (1904): Guest Log: job: htmld=/shared/html/job

2020-02-20 17:19:34 (1904): Guest Log: job: unpack exitcode=0

2020-02-20 17:19:35 (1904): Guest Log: 17:19:36 CET +01:00 2020-02-20: cranky: [INFO] ===> [runRivet] Thu Feb 20 16:19:35 UTC 2020 [boinc pp jets 7000 100 - pythia8 8.212 tune-A14-MSTW2008LO 100000 40]

2020-02-20 18:58:13 (1904): Status Report: Job Duration: '864000.000000'
2020-02-20 18:58:13 (1904): Status Report: Elapsed Time: '6000.000000'
2020-02-20 18:58:13 (1904): Status Report: CPU Time: '5969.625000'
2020-02-20 20:39:11 (1904): Status Report: Job Duration: '864000.000000'
2020-02-20 20:39:11 (1904): Status Report: Elapsed Time: '12000.000000'
2020-02-20 20:39:11 (1904): Status Report: CPU Time: '12008.640625'
2020-02-20 22:20:09 (1904): Status Report: Job Duration: '864000.000000'
2020-02-20 22:20:09 (1904): Status Report: Elapsed Time: '18000.000000'
2020-02-20 22:20:09 (1904): Status Report: CPU Time: '18045.812500'
2020-02-20 22:56:37 (1904): VM state change detected. (old = 'Running', new = 'Paused')
2020-02-21 17:58:43 (1904): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_8807085c9bdcc240" 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-02-21 17:58:43 (1904): Guest Log: 05:38:30.004423 timesync vgsvcTimeSyncWorker: Radical host time change: 68 522 773 000 000ns (HostNow=1 582 304 314 330 000 000 ns HostLast=1 582 235 791 557 000 000 ns)

2020-02-21 17:58:43 (1904): VM state change detected. (old = 'Paused', new = 'Running')
2020-02-21 17:58:45 (1904): Guest Log: 05:38:40.041526 timesync vgsvcTimeSyncWorker: Radical guest time change: 68 522 842 826 000ns (GuestNow=1 582 304 324 411 195 000 ns GuestLast=1 582 235 801 568 369 000 ns fSetTimeLastLoop=true )

2020-02-21 19:03:28 (1904): Status Report: Job Duration: '864000.000000'
2020-02-21 19:03:28 (1904): Status Report: Elapsed Time: '24000.000000'
2020-02-21 19:03:28 (1904): Status Report: CPU Time: '24100.468750'
2020-02-21 20:44:30 (1904): Status Report: Job Duration: '864000.000000'
2020-02-21 20:44:30 (1904): Status Report: Elapsed Time: '30000.000000'
2020-02-21 20:44:30 (1904): Status Report: CPU Time: '30130.328125'
2020-02-21 21:54:10 (1904): VM state change detected. (old = 'Running', new = 'Paused')
2020-02-22 15:18:28 (1904): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_8807085c9bdcc240" 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-02-22 15:18:29 (1904): Guest Log: 09:34:10.644982 timesync vgsvcTimeSyncWorker: Radical host time change: 62 662 333 000 000ns (HostNow=1 582 381 103 701 000 000 ns HostLast=1 582 318 441 368 000 000 ns)

2020-02-22 15:18:29 (1904): VM state change detected. (old = 'Paused', new = 'Running')
2020-02-22 15:18:34 (1904): Guest Log: 09:34:20.649171 timesync vgsvcTimeSyncWorker: Radical guest time change: 62 658 690 576 000ns (GuestNow=1 582 381 113 705 210 000 ns GuestLast=1 582 318 455 014 634 000 ns fSetTimeLastLoop=true )

2020-02-22 15:50:04 (1904): Status Report: Job Duration: '864000.000000'
2020-02-22 15:50:04 (1904): Status Report: Elapsed Time: '36000.000000'
2020-02-22 15:50:04 (1904): Status Report: CPU Time: '36175.218750'
2020-02-22 16:51:00 (1904): Stopping VM.
2020-02-22 16:51:01 (1904): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_8807085c9bdcc240" savestate
Output:

2020-02-22 16:51:01 (1904): VM did not stop when requested.
2020-02-22 16:51:01 (1904): VM was successfully terminated.
2020-02-22 16:55:56 (9176): Detected: vboxwrapper 26197
2020-02-22 16:55:56 (9176): Detected: BOINC client v7.7
2020-02-22 16:56:03 (9176): Detected: VirtualBox VboxManage Interface (Version: 5.2.34)
2020-02-22 16:56:04 (9176): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-02-22 16:56:05 (9176): Starting VM using VBoxManage interface. (boinc_8807085c9bdcc240, slot#4)
2020-02-22 16:56:22 (9176): Successfully started VM. (PID = '8716')
2020-02-22 16:56:22 (9176): Reporting VM Process ID to BOINC.
2020-02-22 16:56:22 (9176): Guest Log: BIOS: VirtualBox 5.2.34

2020-02-22 16:56:22 (9176): Guest Log: CPUID EDX: 0x078bfbff

2020-02-22 16:56:22 (9176): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-02-22 16:56:22 (9176): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-02-22 16:56:22 (9176): Detected: Web Application Enabled (http://localhost:53376)
2020-02-22 16:56:22 (9176): Detected: Remote Desktop Enabled (localhost:53377)
2020-02-22 16:56:22 (9176): Status Report: Job Duration: '864000.000000'
2020-02-22 16:56:22 (9176): Status Report: Elapsed Time: '39615.000000'
2020-02-22 16:56:22 (9176): Status Report: CPU Time: '39806.453125'
2020-02-22 16:56:22 (9176): Preference change detected
2020-02-22 16:56:22 (9176): Setting CPU throttle for VM. (100%)
2020-02-22 16:56:23 (9176): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-02-22 16:56:24 (9176): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-02-22 16:56:24 (9176): Guest Log: BIOS: Booting from Hard Disk...

2020-02-22 16:56:29 (9176): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-02-22 16:56:29 (9176): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-02-22 16:57:06 (9176): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-02-22 16:57:06 (9176): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-02-22 16:57:14 (9176): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-02-22 16:57:14 (9176): Guest Log: 00:00:00.000282 main     Log opened 2020-02-22T15:57:15.530302000Z

2020-02-22 16:57:14 (9176): Guest Log: 00:00:00.001263 main     OS Product: Linux

2020-02-22 16:57:14 (9176): Guest Log: 00:00:00.010225 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-02-22 16:57:14 (9176): Guest Log: 00:00:00.013208 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-02-22 16:57:14 (9176): Guest Log: 00:00:00.015690 main     Executable: /usr/sbin/VBoxService

2020-02-22 16:57:14 (9176): Guest Log: 00:00:00.015696 main     Process ID: 3169

2020-02-22 16:57:14 (9176): Guest Log: 00:00:00.015699 main     Package type: LINUX_64BITS_GENERIC

2020-02-22 16:57:14 (9176): Guest Log: 00:00:00.021930 main     5.2.6 r120293 started. Verbose level = 0

2020-02-22 16:58:04 (9176): Guest Log: 16:58:05 CET +01:00 2020-02-22: cranky: [INFO] Detected Theory App

2020-02-22 16:58:04 (9176): Guest Log: 16:58:05 CET +01:00 2020-02-22: cranky: [INFO] Checking CVMFS.

2020-02-22 16:58:28 (9176): Guest Log: 16:58:30 CET +01:00 2020-02-22: cranky: [INFO] Checking runc.

2020-02-22 16:58:29 (9176): Guest Log: 16:58:31 CET +01:00 2020-02-22: cranky: [INFO] Creating the filesystem.

2020-02-22 16:58:30 (9176): Guest Log: 16:58:31 CET +01:00 2020-02-22: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-02-22 16:58:30 (9176): Guest Log: 16:58:31 CET +01:00 2020-02-22: cranky: [INFO] Updating config.json.

2020-02-22 16:58:30 (9176): Guest Log: 16:58:32 CET +01:00 2020-02-22: cranky: [INFO] Running Container 'runc'.

2020-02-22 16:58:33 (9176): Guest Log: job: htmld=/shared/html/job

2020-02-22 16:58:33 (9176): Guest Log: job: unpack exitcode=0

2020-02-22 16:58:35 (9176): Guest Log: 16:58:36 CET +01:00 2020-02-22: cranky: [INFO] ===> [runRivet] Sat Feb 22 15:58:35 UTC 2020 [boinc pp jets 7000 100 - pythia8 8.212 tune-A14-MSTW2008LO 100000 40]

2020-02-22 18:37:14 (9176): Status Report: Job Duration: '864000.000000'
2020-02-22 18:37:14 (9176): Status Report: Elapsed Time: '45615.000000'
2020-02-22 18:37:14 (9176): Status Report: CPU Time: '45755.906250'
2020-02-22 20:18:01 (9176): Status Report: Job Duration: '864000.000000'
2020-02-22 20:18:01 (9176): Status Report: Elapsed Time: '51615.000000'
2020-02-22 20:18:01 (9176): Status Report: CPU Time: '51759.062500'
2020-02-22 21:58:46 (9176): Status Report: Job Duration: '864000.000000'
2020-02-22 21:58:46 (9176): Status Report: Elapsed Time: '57615.000000'
2020-02-22 21:58:46 (9176): Status Report: CPU Time: '57761.578125'
2020-02-22 23:16:56 (9176): VM state change detected. (old = 'Running', new = 'Paused')
2020-02-23 12:23:19 (9176): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_8807085c9bdcc240" 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-02-23 12:23:20 (9176): Guest Log: 06:19:41.666556 timesync vgsvcTimeSyncWorker: Radical host time change: 47 184 835 000 000ns (HostNow=1 582 456 991 956 000 000 ns HostLast=1 582 409 807 121 000 000 ns)

2020-02-23 12:23:20 (9176): VM state change detected. (old = 'Paused', new = 'Running')
2020-02-23 12:23:22 (9176): Guest Log: 06:19:51.673530 timesync vgsvcTimeSyncWorker: Radical guest time change: 47 184 858 836 000ns (GuestNow=1 582 457 001 981 194 000 ns GuestLast=1 582 409 817 122 358 000 ns fSetTimeLastLoop=true )

2020-02-23 12:46:14 (9176): Status Report: Job Duration: '864000.000000'
2020-02-23 12:46:14 (9176): Status Report: Elapsed Time: '63615.000000'
2020-02-23 12:46:14 (9176): Status Report: CPU Time: '63790.000000'
2020-02-23 14:27:07 (9176): Status Report: Job Duration: '864000.000000'
2020-02-23 14:27:07 (9176): Status Report: Elapsed Time: '69615.000000'
2020-02-23 14:27:07 (9176): Status Report: CPU Time: '69804.421875'
2020-02-23 16:07:55 (9176): Status Report: Job Duration: '864000.000000'
2020-02-23 16:07:55 (9176): Status Report: Elapsed Time: '75615.000000'
2020-02-23 16:07:55 (9176): Status Report: CPU Time: '75823.546875'
2020-02-23 17:48:47 (9176): Status Report: Job Duration: '864000.000000'
2020-02-23 17:48:47 (9176): Status Report: Elapsed Time: '81615.000000'
2020-02-23 17:48:47 (9176): Status Report: CPU Time: '81842.625000'
2020-02-23 19:20:49 (9176): Guest Log: job: run exitcode=0

2020-02-23 19:20:49 (9176): Guest Log: job: diskusage=5604

2020-02-23 19:20:49 (9176): Guest Log: job: logsize=92 k

2020-02-23 19:20:49 (9176): Guest Log: job: times=

2020-02-23 19:20:49 (9176): Guest Log: 0m0.025s 0m0.030s

2020-02-23 19:20:49 (9176): Guest Log: 772m49.265s 7m44.429s

2020-02-23 19:20:49 (9176): Guest Log: job: cpuusage=46834

2020-02-23 19:20:49 (9176): Guest Log: 19:20:49 CET +01:00 2020-02-23: cranky: [INFO] Container 'runc' finished with status code 0.

2020-02-23 19:20:49 (9176): Guest Log: 19:20:49 CET +01:00 2020-02-23: cranky: [INFO] Preparing output.

2020-02-23 19:20:50 (9176): Guest Log: [INFO] Job Finished

2020-02-23 19:20:50 (9176): Guest Log: [INFO] Shutting Down.

2020-02-23 19:20:50 (9176): VM Completion File Detected.
2020-02-23 19:20:50 (9176): VM Completion Message: Job Finished
.
2020-02-23 19:20:50 (9176): Powering off VM.
2020-02-23 19:25:52 (9176): VM did not power off when requested.
2020-02-23 19:25:52 (9176): VM was successfully terminated.
2020-02-23 19:25:52 (9176): Deregistering VM. (boinc_8807085c9bdcc240, slot#4)
2020-02-23 19:25:52 (9176): Removing network bandwidth throttle group from VM.
2020-02-23 19:25:53 (9176): Removing VM from VirtualBox.
19:25:59 (9176): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN