Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-06-19 17:45:32 (15560): Detected: vboxwrapper 26197
2019-06-19 17:45:32 (15560): Detected: BOINC client v7.7
2019-06-19 17:45:33 (15560): Detected: VirtualBox VboxManage Interface (Version: 5.2.12)
2019-06-19 17:45:33 (15560): Successfully copied 'init_data.xml' to the shared directory.
2019-06-19 17:45:33 (15560): Successfully copied 'cranky' to the shared directory.
2019-06-19 17:45:33 (15560): Successfully copied 'input' to the shared directory.
2019-06-19 17:45:36 (15560): Create VM. (boinc_23e89403951aede4, slot#2)
2019-06-19 17:45:37 (15560): Setting Memory Size for VM. (730MB)
2019-06-19 17:45:38 (15560): Setting CPU Count for VM. (1)
2019-06-19 17:45:38 (15560): Setting Chipset Options for VM.
2019-06-19 17:45:39 (15560): Setting Boot Options for VM.
2019-06-19 17:45:39 (15560): Setting Network Configuration for NAT.
2019-06-19 17:45:39 (15560): Enabling VM Network Access.
2019-06-19 17:45:40 (15560): Disabling USB Support for VM.
2019-06-19 17:45:40 (15560): Disabling COM Port Support for VM.
2019-06-19 17:45:41 (15560): Disabling LPT Port Support for VM.
2019-06-19 17:45:41 (15560): Disabling Audio Support for VM.
2019-06-19 17:45:42 (15560): Disabling Clipboard Support for VM.
2019-06-19 17:45:42 (15560): Disabling Drag and Drop Support for VM.
2019-06-19 17:45:43 (15560): Adding storage controller(s) to VM.
2019-06-19 17:45:43 (15560): Adding virtual disk drive to VM. (vm_image.vdi)
2019-06-19 17:45:44 (15560): Adding VirtualBox Guest Additions to VM.
2019-06-19 17:45:45 (15560): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-06-19 17:45:45 (15560): forwarding host port 54196 to guest port 80
2019-06-19 17:45:46 (15560): Enabling remote desktop for VM.
2019-06-19 17:45:46 (15560): Required extension pack not installed, remote desktop not enabled.
2019-06-19 17:45:46 (15560): Enabling shared directory for VM.
2019-06-19 17:45:46 (15560): Starting VM using VBoxManage interface. (boinc_23e89403951aede4, slot#2)
2019-06-19 17:45:53 (15560): Successfully started VM. (PID = '5412')
2019-06-19 17:45:53 (15560): Reporting VM Process ID to BOINC.
2019-06-19 17:45:53 (15560): Guest Log: BIOS: VirtualBox 5.2.12

2019-06-19 17:45:53 (15560): Guest Log: CPUID EDX: 0x078bfbff

2019-06-19 17:45:53 (15560): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-06-19 17:45:53 (15560): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-06-19 17:45:53 (15560): Detected: Web Application Enabled (http://localhost:54196)
2019-06-19 17:45:54 (15560): Preference change detected
2019-06-19 17:45:54 (15560): Setting CPU throttle for VM. (100%)
2019-06-19 17:45:54 (15560): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-06-19 17:45:56 (15560): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-06-19 17:45:56 (15560): Guest Log: BIOS: Booting from Hard Disk...

2019-06-19 17:45:58 (15560): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-06-19 17:45:58 (15560): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-06-19 17:46:30 (15560): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-06-19 17:46:30 (15560): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-06-19 17:46:37 (15560): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-06-19 17:46:37 (15560): Guest Log: 00:00:00.000197 main     Log opened 2019-06-19T22:46:51.150553000Z

2019-06-19 17:46:37 (15560): Guest Log: 00:00:00.000384 main     OS Product: Linux

2019-06-19 17:46:37 (15560): Guest Log: 00:00:00.000438 main     OS Release: 4.14.76-13.cernvm.x86_64

2019-06-19 17:46:37 (15560): Guest Log: 00:00:00.000496 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2019-06-19 17:46:37 (15560): Guest Log: 00:00:00.000552 main     Executable: /usr/sbin/VBoxService

2019-06-19 17:46:37 (15560): Guest Log: 00:00:00.000554 main     Process ID: 3209

2019-06-19 17:46:37 (15560): Guest Log: 00:00:00.000555 main     Package type: LINUX_64BITS_GENERIC

2019-06-19 17:46:37 (15560): Guest Log: 00:00:00.007702 main     5.2.6 r120293 started. Verbose level = 0

2019-06-19 17:47:05 (15560): Guest Log: 22:47:19 2019-06-19: cranky: [INFO] Detected Theory App

2019-06-19 17:47:05 (15560): Guest Log: 22:47:19 2019-06-19: cranky: [INFO] Checking CVMFS.

2019-06-19 17:47:20 (15560): Guest Log: 22:47:33 2019-06-19: cranky: [INFO] Checking runc.

2019-06-19 17:47:21 (15560): Guest Log: 22:47:34 2019-06-19: cranky: [INFO] Creating the filesystem.

2019-06-19 17:47:21 (15560): Guest Log: 22:47:34 2019-06-19: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2019-06-19 17:47:21 (15560): Guest Log: 22:47:35 2019-06-19: cranky: [INFO] Updating config.json.

2019-06-19 17:47:21 (15560): Guest Log: 22:47:35 2019-06-19: cranky: [INFO] Running Container 'runc'.

2019-06-19 19:32:59 (15560): Status Report: Job Duration: '64800.000000'
2019-06-19 19:32:59 (15560): Status Report: Elapsed Time: '6000.000000'
2019-06-19 19:32:59 (15560): Status Report: CPU Time: '6337.843750'
2019-06-19 20:12:26 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 20:39:04 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 20:39:34 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 20:39:36 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 20:43:33 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 20:43:39 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 20:44:39 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 20:44:45 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 20:45:02 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 20:45:07 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 20:45:58 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 20:46:07 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 20:47:47 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 20:47:57 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 20:50:27 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 20:50:38 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 20:51:49 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 20:52:07 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 20:52:17 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 20:52:27 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 20:55:37 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 20:55:43 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 21:05:12 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 21:05:20 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 21:12:29 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 21:12:47 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 21:55:55 (15560): Status Report: Job Duration: '64800.000000'
2019-06-19 21:55:55 (15560): Status Report: Elapsed Time: '12000.042311'
2019-06-19 21:55:55 (15560): Status Report: CPU Time: '13174.062500'
2019-06-19 23:35:46 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-19 23:46:29 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-19 23:56:43 (15560): Status Report: Job Duration: '64800.000000'
2019-06-19 23:56:43 (15560): Status Report: Elapsed Time: '18000.967407'
2019-06-19 23:56:43 (15560): Status Report: CPU Time: '19716.781250'
2019-06-20 00:45:43 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-20 00:59:01 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-20 01:13:44 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-20 02:30:53 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-20 02:30:55 (15560): Guest Log: 06:34:47.349582 timesync vgsvcTimeSyncWorker: Radical host time change: 4 637 639 000 000ns (HostNow=1 561 015 854 687 000 000 ns HostLast=1 561 011 217 048 000 000 ns)

2019-06-20 02:31:07 (15560): Guest Log: 06:34:57.350394 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 637 645 166 000ns (GuestNow=1 561 015 864 693 071 000 ns GuestLast=1 561 011 227 047 905 000 ns fSetTimeLastLoop=true )

2019-06-20 02:33:14 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-20 02:33:28 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-20 02:48:44 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-20 02:48:53 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-20 02:56:44 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-20 02:56:54 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-20 03:28:45 (15560): Status Report: Job Duration: '64800.000000'
2019-06-20 03:28:45 (15560): Status Report: Elapsed Time: '24001.602734'
2019-06-20 03:28:45 (15560): Status Report: CPU Time: '26917.515625'
2019-06-20 03:57:43 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-20 06:25:23 (15560): Guest Log: 08:01:08.974532 timesync vgsvcTimeSyncWorker: Radical host time change: 8 868 496 000 000ns (HostNow=1 561 029 921 549 000 000 ns HostLast=1 561 021 053 053 000 000 ns)

2019-06-20 06:25:23 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-20 06:25:32 (15560): Guest Log: 08:01:18.984981 timesync vgsvcTimeSyncWorker: Radical guest time change: 8 895 242 265 000ns (GuestNow=1 561 029 931 559 460 000 ns GuestLast=1 561 021 036 317 195 000 ns fSetTimeLastLoop=true )

2019-06-20 06:34:22 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-20 09:48:56 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-20 09:49:03 (15560): Guest Log: 08:10:09.115450 timesync vgsvcTimeSyncWorker: Radical host time change: 11 690 745 000 000ns (HostNow=1 561 042 142 425 000 000 ns HostLast=1 561 030 451 680 000 000 ns)

2019-06-20 09:49:13 (15560): Guest Log: 08:10:19.121354 timesync vgsvcTimeSyncWorker: Radical guest time change: 11 690 741 015 000ns (GuestNow=1 561 042 152 430 919 000 ns GuestLast=1 561 030 461 689 904 000 ns fSetTimeLastLoop=true )

2019-06-20 11:19:03 (15560): Status Report: Job Duration: '64800.000000'
2019-06-20 11:19:03 (15560): Status Report: Elapsed Time: '30001.832756'
2019-06-20 11:19:03 (15560): Status Report: CPU Time: '34550.765625'
2019-06-20 13:21:15 (15560): Status Report: Job Duration: '64800.000000'
2019-06-20 13:21:15 (15560): Status Report: Elapsed Time: '36002.689873'
2019-06-20 13:21:15 (15560): Status Report: CPU Time: '41835.734375'
2019-06-20 14:48:49 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-20 16:28:15 (15560): Guest Log: 13:09:54.998363 timesync vgsvcTimeSyncWorker: Radical host time change: 5 971 475 000 000ns (HostNow=1 561 066 089 777 000 000 ns HostLast=1 561 060 118 302 000 000 ns)

2019-06-20 16:28:15 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-20 16:28:20 (15560): Guest Log: 13:10:05.001232 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 971 471 983 000ns (GuestNow=1 561 066 099 779 881 000 ns GuestLast=1 561 060 128 307 898 000 ns fSetTimeLastLoop=true )

2019-06-20 16:31:20 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-20 16:42:34 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-20 17:09:34 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-20 18:19:28 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-20 18:19:31 (15560): Guest Log: 13:40:05.544949 timesync vgsvcTimeSyncWorker: Radical host time change: 4 203 178 000 000ns (HostNow=1 561 072 771 291 000 000 ns HostLast=1 561 068 568 113 000 000 ns)

2019-06-20 18:19:46 (15560): Guest Log: 13:40:15.553910 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 880 976 391 000ns (GuestNow=1 561 072 781 299 973 000 ns GuestLast=1 561 067 900 323 582 000 ns fSetTimeLastLoop=true )

2019-06-20 18:24:34 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-20 21:27:50 (15560): Guest Log: 13:45:05.666538 timesync vgsvcTimeSyncWorker: Radical host time change: 11 005 499 000 000ns (HostNow=1 561 084 066 908 000 000 ns HostLast=1 561 073 061 409 000 000 ns)

2019-06-20 21:27:50 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-20 21:28:02 (15560): Guest Log: 13:45:15.670536 timesync vgsvcTimeSyncWorker: Radical guest time change: 11 005 499 440 000ns (GuestNow=1 561 084 076 912 015 000 ns GuestLast=1 561 073 071 412 575 000 ns fSetTimeLastLoop=true )

2019-06-20 21:35:47 (15560): Status Report: Job Duration: '64800.000000'
2019-06-20 21:35:47 (15560): Status Report: Elapsed Time: '42003.177387'
2019-06-20 21:35:47 (15560): Status Report: CPU Time: '49635.687500'
2019-06-20 21:57:38 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-20 22:52:37 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-20 22:52:42 (15560): Guest Log: 14:14:56.296451 timesync vgsvcTimeSyncWorker: Radical host time change: 3 313 397 000 000ns (HostNow=1 561 089 160 931 000 000 ns HostLast=1 561 085 847 534 000 000 ns)

2019-06-20 22:52:51 (15560): Guest Log: 14:15:06.297377 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 313 394 041 000ns (GuestNow=1 561 089 170 931 940 000 ns GuestLast=1 561 085 857 537 899 000 ns fSetTimeLastLoop=true )

2019-06-20 22:59:39 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-20 23:41:26 (15560): Guest Log: 14:21:46.407704 timesync vgsvcTimeSyncWorker: Radical host time change: 2 521 619 000 000ns (HostNow=1 561 092 082 660 000 000 ns HostLast=1 561 089 561 041 000 000 ns)

2019-06-20 23:41:26 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-20 23:41:33 (15560): Guest Log: 14:21:56.408745 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 521 618 833 000ns (GuestNow=1 561 092 092 661 070 000 ns GuestLast=1 561 089 571 042 237 000 ns fSetTimeLastLoop=true )

2019-06-20 23:53:05 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-21 01:12:08 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-21 01:12:09 (15560): Guest Log: 14:33:36.628170 timesync vgsvcTimeSyncWorker: Radical host time change: 4 746 329 000 000ns (HostNow=1 561 097 529 207 000 000 ns HostLast=1 561 092 782 878 000 000 ns)

2019-06-21 01:12:19 (15560): Guest Log: 14:33:46.630112 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 746 328 524 000ns (GuestNow=1 561 097 539 208 972 000 ns GuestLast=1 561 092 792 880 448 000 ns fSetTimeLastLoop=true )

2019-06-21 01:16:46 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-21 01:26:08 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-21 02:16:47 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-21 02:16:58 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-21 02:44:39 (15560): Status Report: Job Duration: '64800.000000'
2019-06-21 02:44:39 (15560): Status Report: Elapsed Time: '48003.682158'
2019-06-21 02:44:39 (15560): Status Report: CPU Time: '57001.781250'
2019-06-21 04:40:51 (15560): Status Report: Job Duration: '64800.000000'
2019-06-21 04:40:51 (15560): Status Report: Elapsed Time: '54003.721512'
2019-06-21 04:40:51 (15560): Status Report: CPU Time: '63945.234375'
2019-06-21 06:42:15 (15560): Status Report: Job Duration: '64800.000000'
2019-06-21 06:42:15 (15560): Status Report: Elapsed Time: '60004.218693'
2019-06-21 06:42:15 (15560): Status Report: CPU Time: '71215.921875'
2019-06-21 06:51:19 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-21 06:51:32 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-21 06:52:10 (15560): VM state change detected. (old = 'Running', new = 'Paused')
2019-06-21 06:52:20 (15560): VM state change detected. (old = 'Paused', new = 'Running')
2019-06-21 08:19:30 (15560): Powering off VM.
2019-06-21 08:24:31 (15560): VM did not power off when requested.
2019-06-21 08:24:31 (15560): VM was successfully terminated.
2019-06-21 08:24:31 (15560): Deregistering VM. (boinc_23e89403951aede4, slot#2)
2019-06-21 08:24:32 (15560): Removing network bandwidth throttle group from VM.
2019-06-21 08:24:32 (15560): Removing VM from VirtualBox.
08:24:38 (15560): called boinc_finish(0)

</stderr_txt>
<message>
upload failure: <file_xfer_error>
  <file_name>Theory_2279-803784-70_0_r1952584465_result</file_name>
  <error_code>-240 (stat() failed)</error_code>
</file_xfer_error>
</message>
]]>

©2024 CERN