Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2020-02-20 18:54:41 (8016): Detected: vboxwrapper 26197
2020-02-20 18:54:41 (8016): Detected: BOINC client v7.7
2020-02-20 18:54:43 (8016): Detected: VirtualBox VboxManage Interface (Version: 5.2.34)
2020-02-20 18:54:45 (8016): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-02-20 18:54:45 (8016): Successfully copied 'init_data.xml' to the shared directory.
2020-02-20 18:54:45 (8016): Successfully copied 'input' to the shared directory.
2020-02-20 18:54:50 (8016): Create VM. (boinc_c9e44ab9845474fc, slot#1)
2020-02-20 18:54:57 (8016): Setting Memory Size for VM. (630MB)
2020-02-20 18:55:01 (8016): Setting CPU Count for VM. (1)
2020-02-20 18:55:02 (8016): Setting Chipset Options for VM.
2020-02-20 18:55:04 (8016): Setting Boot Options for VM.
2020-02-20 18:55:06 (8016): Setting Network Configuration for NAT.
2020-02-20 18:55:08 (8016): Enabling VM Network Access.
2020-02-20 18:55:10 (8016): Disabling USB Support for VM.
2020-02-20 18:55:11 (8016): Disabling COM Port Support for VM.
2020-02-20 18:55:14 (8016): Disabling LPT Port Support for VM.
2020-02-20 18:55:15 (8016): Disabling Audio Support for VM.
2020-02-20 18:55:19 (8016): Disabling Clipboard Support for VM.
2020-02-20 18:55:21 (8016): Disabling Drag and Drop Support for VM.
2020-02-20 18:55:23 (8016): Adding storage controller(s) to VM.
2020-02-20 18:55:25 (8016): Adding virtual disk drive to VM. (vm_image.vdi)
2020-02-20 18:55:28 (8016): Adding VirtualBox Guest Additions to VM.
2020-02-20 18:55:30 (8016): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2020-02-20 18:55:31 (8016): forwarding host port 53537 to guest port 80
2020-02-20 18:55:33 (8016): Enabling remote desktop for VM.
2020-02-20 18:55:36 (8016): Enabling shared directory for VM.
2020-02-20 18:55:37 (8016): Starting VM using VBoxManage interface. (boinc_c9e44ab9845474fc, slot#1)
2020-02-20 18:55:50 (8016): Successfully started VM. (PID = '1980')
2020-02-20 18:55:50 (8016): Reporting VM Process ID to BOINC.
2020-02-20 18:55:50 (8016): Guest Log: BIOS: VirtualBox 5.2.34

2020-02-20 18:55:50 (8016): Guest Log: CPUID EDX: 0x078bfbff

2020-02-20 18:55:50 (8016): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-02-20 18:55:50 (8016): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-02-20 18:55:50 (8016): Detected: Web Application Enabled (http://localhost:53537)
2020-02-20 18:55:50 (8016): Detected: Remote Desktop Enabled (localhost:53538)
2020-02-20 18:55:50 (8016): Preference change detected
2020-02-20 18:55:50 (8016): Setting CPU throttle for VM. (100%)
2020-02-20 18:55:51 (8016): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-02-20 18:55:52 (8016): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-02-20 18:55:52 (8016): Guest Log: BIOS: Booting from Hard Disk...

2020-02-20 18:55:55 (8016): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-02-20 18:55:55 (8016): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-02-20 18:56:52 (8016): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

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

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

2020-02-20 18:56:59 (8016): Guest Log: 00:00:00.000405 main     Log opened 2020-02-20T17:57:09.796243000Z

2020-02-20 18:56:59 (8016): Guest Log: 00:00:00.001328 main     OS Product: Linux

2020-02-20 18:56:59 (8016): Guest Log: 00:00:00.003469 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-02-20 18:56:59 (8016): Guest Log: 00:00:00.003628 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-02-20 18:56:59 (8016): Guest Log: 00:00:00.003738 main     Executable: /usr/sbin/VBoxService

2020-02-20 18:56:59 (8016): Guest Log: 00:00:00.003742 main     Process ID: 3205

2020-02-20 18:56:59 (8016): Guest Log: 00:00:00.003746 main     Package type: LINUX_64BITS_GENERIC

2020-02-20 18:56:59 (8016): Guest Log: 00:00:00.012515 main     5.2.6 r120293 started. Verbose level = 0

2020-02-20 18:57:45 (8016): Guest Log: 18:57:56 CET +01:00 2020-02-20: cranky: [INFO] Detected Theory App

2020-02-20 18:57:45 (8016): Guest Log: 18:57:56 CET +01:00 2020-02-20: cranky: [INFO] Checking CVMFS.

2020-02-20 18:58:11 (8016): Guest Log: 18:58:23 CET +01:00 2020-02-20: cranky: [INFO] Checking runc.

2020-02-20 18:58:11 (8016): Guest Log: 18:58:23 CET +01:00 2020-02-20: cranky: [INFO] Creating the filesystem.

2020-02-20 18:58:11 (8016): Guest Log: 18:58:23 CET +01:00 2020-02-20: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-02-20 18:58:12 (8016): Guest Log: 18:58:23 CET +01:00 2020-02-20: cranky: [INFO] Updating config.json.

2020-02-20 18:58:12 (8016): Guest Log: 18:58:23 CET +01:00 2020-02-20: cranky: [INFO] Running Container 'runc'.

2020-02-20 18:58:13 (8016): Guest Log: job: htmld=/shared/html/job

2020-02-20 18:58:13 (8016): Guest Log: job: unpack exitcode=0

2020-02-20 18:58:14 (8016): Guest Log: 18:58:26 CET +01:00 2020-02-20: cranky: [INFO] ===> [runRivet] Thu Feb 20 17:58:24 UTC 2020 [boinc pp jets 7000 150,-,2160 - pythia8 8.230 tune-AU2loxx 100000 40]

2020-02-20 20:36:47 (8016): Status Report: Job Duration: '864000.000000'
2020-02-20 20:36:47 (8016): Status Report: Elapsed Time: '6000.000000'
2020-02-20 20:36:47 (8016): Status Report: CPU Time: '5977.031250'
2020-02-20 22:17:45 (8016): Status Report: Job Duration: '864000.000000'
2020-02-20 22:17:45 (8016): Status Report: Elapsed Time: '12000.001671'
2020-02-20 22:17:45 (8016): Status Report: CPU Time: '12014.171875'
2020-02-20 22:56:37 (8016): VM state change detected. (old = 'Running', new = 'Paused')
2020-02-21 17:58:42 (8016): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_c9e44ab9845474fc" 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:42 (8016): Guest Log: 03:59:36.645457 timesync vgsvcTimeSyncWorker: Radical host time change: 68 522 492 000 000ns (HostNow=1 582 304 314 370 000 000 ns HostLast=1 582 235 791 878 000 000 ns)

2020-02-21 17:58:42 (8016): VM state change detected. (old = 'Paused', new = 'Running')
2020-02-21 17:58:43 (8016): VM state change detected. (old = 'Running', new = 'Paused')
2020-02-21 18:10:52 (8016): VM state change detected. (old = 'Paused', new = 'Running')
2020-02-21 18:10:53 (8016): Guest Log: 03:59:46.667479 timesync vgsvcTimeSyncWorker: Radical guest time change: 68 518 056 108 000ns (GuestNow=1 582 304 324 425 206 000 ns GuestLast=1 582 235 806 369 098 000 ns fSetTimeLastLoop=true )

2020-02-21 19:13:00 (8016): Status Report: Job Duration: '864000.000000'
2020-02-21 19:13:00 (8016): Status Report: Elapsed Time: '18000.001671'
2020-02-21 19:13:00 (8016): Status Report: CPU Time: '18061.000000'
2020-02-21 20:54:03 (8016): Status Report: Job Duration: '864000.000000'
2020-02-21 20:54:03 (8016): Status Report: Elapsed Time: '24000.001671'
2020-02-21 20:54:03 (8016): Status Report: CPU Time: '24091.718750'
2020-02-21 21:54:11 (8016): VM state change detected. (old = 'Running', new = 'Paused')
2020-02-22 15:18:35 (8016): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_c9e44ab9845474fc" 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:35 (8016): Guest Log: 07:43:15.345892 timesync vgsvcTimeSyncWorker: Radical host time change: 62 661 202 000 000ns (HostNow=1 582 381 103 692 000 000 ns HostLast=1 582 318 442 490 000 000 ns)

2020-02-22 15:18:35 (8016): Guest Log: 07:43:25.354691 timesync vgsvcTimeSyncWorker: Radical guest time change: 63 380 659 358 000ns (GuestNow=1 582 381 113 731 895 000 ns GuestLast=1 582 317 733 072 537 000 ns fSetTimeLastLoop=true )

2020-02-22 15:18:35 (8016): VM state change detected. (old = 'Paused', new = 'Running')
2020-02-22 15:59:41 (8016): Status Report: Job Duration: '864000.000000'
2020-02-22 15:59:41 (8016): Status Report: Elapsed Time: '30000.001671'
2020-02-22 15:59:41 (8016): Status Report: CPU Time: '30142.015625'
2020-02-22 16:51:00 (8016): Stopping VM.
2020-02-22 16:51:01 (8016): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_c9e44ab9845474fc" savestate
Output:

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

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

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

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

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

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

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

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

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

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

2020-02-22 16:57:11 (4348): Guest Log: 00:00:00.000269 main     Log opened 2020-02-22T15:57:13.428782000Z

2020-02-22 16:57:11 (4348): Guest Log: 00:00:00.000481 main     OS Product: Linux

2020-02-22 16:57:11 (4348): Guest Log: 00:00:00.000551 main     OS Release: 4.14.76-13.cernvm.x86_64

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

2020-02-22 16:57:11 (4348): Guest Log: 00:00:00.000695 main     Executable: /usr/sbin/VBoxService

2020-02-22 16:57:11 (4348): Guest Log: 00:00:00.000698 main     Process ID: 3208

2020-02-22 16:57:11 (4348): Guest Log: 00:00:00.000700 main     Package type: LINUX_64BITS_GENERIC

2020-02-22 16:57:11 (4348): Guest Log: 00:00:00.009756 main     5.2.6 r120293 started. Verbose level = 0

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

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

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

2020-02-22 16:58:27 (4348): Guest Log: 16:58:28 CET +01:00 2020-02-22: cranky: [INFO] Creating the filesystem.

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

2020-02-22 16:58:27 (4348): Guest Log: 16:58:29 CET +01:00 2020-02-22: cranky: [INFO] Updating config.json.

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

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

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

2020-02-22 16:58:33 (4348): Guest Log: 16:58:34 CET +01:00 2020-02-22: cranky: [INFO] ===> [runRivet] Sat Feb 22 15:58:32 UTC 2020 [boinc pp jets 7000 150,-,2160 - pythia8 8.230 tune-AU2loxx 100000 40]

2020-02-22 18:37:14 (4348): Status Report: Job Duration: '864000.000000'
2020-02-22 18:37:14 (4348): Status Report: Elapsed Time: '39043.001671'
2020-02-22 18:37:14 (4348): Status Report: CPU Time: '39147.296875'
2020-02-22 20:18:01 (4348): Status Report: Job Duration: '864000.000000'
2020-02-22 20:18:01 (4348): Status Report: Elapsed Time: '45043.001671'
2020-02-22 20:18:01 (4348): Status Report: CPU Time: '45153.031250'
2020-02-22 21:58:45 (4348): Status Report: Job Duration: '864000.000000'
2020-02-22 21:58:45 (4348): Status Report: Elapsed Time: '51043.001671'
2020-02-22 21:58:45 (4348): Status Report: CPU Time: '51154.875000'
2020-02-22 23:16:56 (4348): VM state change detected. (old = 'Running', new = 'Paused')
2020-02-23 12:23:25 (4348): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_c9e44ab9845474fc" 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:25 (4348): Guest Log: 06:19:51.592075 timesync vgsvcTimeSyncWorker: Radical host time change: 47 184 662 000 000ns (HostNow=1 582 456 999 597 000 000 ns HostLast=1 582 409 814 935 000 000 ns)

2020-02-23 12:23:25 (4348): VM state change detected. (old = 'Paused', new = 'Running')
2020-02-23 12:23:30 (4348): Guest Log: 06:20:01.640149 timesync vgsvcTimeSyncWorker: Radical guest time change: 47 184 724 979 000ns (GuestNow=1 582 457 009 660 739 000 ns GuestLast=1 582 409 824 935 760 000 ns fSetTimeLastLoop=true )

2020-02-23 12:46:16 (4348): Status Report: Job Duration: '864000.000000'
2020-02-23 12:46:16 (4348): Status Report: Elapsed Time: '57043.001671'
2020-02-23 12:46:16 (4348): Status Report: CPU Time: '57190.453125'
2020-02-23 14:27:08 (4348): Status Report: Job Duration: '864000.000000'
2020-02-23 14:27:08 (4348): Status Report: Elapsed Time: '63043.001671'
2020-02-23 14:27:08 (4348): Status Report: CPU Time: '63203.296875'
2020-02-23 16:07:55 (4348): Status Report: Job Duration: '864000.000000'
2020-02-23 16:07:55 (4348): Status Report: Elapsed Time: '69043.001671'
2020-02-23 16:07:55 (4348): Status Report: CPU Time: '69220.468750'
2020-02-23 17:48:48 (4348): Status Report: Job Duration: '864000.000000'
2020-02-23 17:48:48 (4348): Status Report: Elapsed Time: '75043.001671'
2020-02-23 17:48:48 (4348): Status Report: CPU Time: '75237.109375'
2020-02-23 19:29:37 (4348): Status Report: Job Duration: '864000.000000'
2020-02-23 19:29:37 (4348): Status Report: Elapsed Time: '81043.001671'
2020-02-23 19:29:37 (4348): Status Report: CPU Time: '81243.296875'
2020-02-23 21:11:29 (4348): Status Report: Job Duration: '864000.000000'
2020-02-23 21:11:29 (4348): Status Report: Elapsed Time: '87043.001671'
2020-02-23 21:11:29 (4348): Status Report: CPU Time: '87307.890625'
2020-02-23 22:11:39 (4348): VM state change detected. (old = 'Running', new = 'Paused')
2020-02-24 17:50:13 (4348): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_c9e44ab9845474fc" 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-24 17:50:14 (4348): Guest Log: 16:08:11.728300 timesync vgsvcTimeSyncWorker: Radical host time change: 70 712 560 000 000ns (HostNow=1 582 563 002 372 000 000 ns HostLast=1 582 492 289 812 000 000 ns)

2020-02-24 17:50:14 (4348): Guest Log: 16:08:21.732371 timesync vgsvcTimeSyncWorker: Radical guest time change: 70 712 627 230 000ns (GuestNow=1 582 563 012 376 088 000 ns GuestLast=1 582 492 299 748 858 000 ns fSetTimeLastLoop=true )

2020-02-24 17:50:14 (4348): VM state change detected. (old = 'Paused', new = 'Running')
2020-02-24 18:31:10 (4348): Status Report: Job Duration: '864000.000000'
2020-02-24 18:31:10 (4348): Status Report: Elapsed Time: '93043.001671'
2020-02-24 18:31:10 (4348): Status Report: CPU Time: '93355.906250'
2020-02-24 20:07:03 (4348): Guest Log: job: run exitcode=0

2020-02-24 20:07:03 (4348): Guest Log: job: diskusage=2032

2020-02-24 20:07:03 (4348): Guest Log: job: logsize=76 k

2020-02-24 20:07:03 (4348): Guest Log: job: times=

2020-02-24 20:07:03 (4348): Guest Log: 0m0.025s 0m0.030s

2020-02-24 20:07:03 (4348): Guest Log: 1071m58.086s 10m11.373s

2020-02-24 20:07:03 (4348): Guest Log: job: cpuusage=64930

2020-02-24 20:07:04 (4348): Guest Log: 20:07:03 CET +01:00 2020-02-24: cranky: [INFO] Container 'runc' finished with status code 0.

2020-02-24 20:07:04 (4348): Guest Log: 20:07:03 CET +01:00 2020-02-24: cranky: [INFO] Preparing output.

2020-02-24 20:07:06 (4348): Guest Log: [INFO] Job Finished

2020-02-24 20:07:06 (4348): Guest Log: [INFO] Shutting Down.

2020-02-24 20:07:06 (4348): VM Completion File Detected.
2020-02-24 20:07:06 (4348): VM Completion Message: Job Finished
.
2020-02-24 20:07:06 (4348): Powering off VM.
2020-02-24 20:12:07 (4348): VM did not power off when requested.
2020-02-24 20:12:07 (4348): VM was successfully terminated.
2020-02-24 20:12:07 (4348): Deregistering VM. (boinc_c9e44ab9845474fc, slot#1)
2020-02-24 20:12:08 (4348): Removing network bandwidth throttle group from VM.
2020-02-24 20:12:08 (4348): Removing VM from VirtualBox.
20:12:14 (4348): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN