Stderr output

<core_client_version>7.24.1</core_client_version>
<![CDATA[
<message>
Process still present 5 min after writing finish file; aborting</message>
<stderr_txt>
2024-01-05 12:25:03 (9740): Detected: vboxwrapper 26206
2024-01-05 12:25:03 (9740): Detected: BOINC client v7.24.1
2024-01-05 12:25:04 (9740): Detected: VirtualBox VboxManage Interface (Version: 7.0.12)
2024-01-05 12:25:06 (9740): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-01-05 12:25:06 (9740): Successfully copied 'init_data.xml' to the shared directory.
2024-01-05 12:25:07 (9740): Create VM. (boinc_fac48e2104beca95, slot#5)
2024-01-05 12:25:08 (9740): Setting Memory Size for VM. (1896MB)
2024-01-05 12:25:10 (9740): Setting CPU Count for VM. (1)
2024-01-05 12:25:11 (9740): Setting Chipset Options for VM.
2024-01-05 12:25:11 (9740): Setting Graphics Controller Options for VM.
2024-01-05 12:25:12 (9740): Setting Boot Options for VM.
2024-01-05 12:25:12 (9740): Setting Network Configuration for NAT.
2024-01-05 12:25:13 (9740): Enabling VM Network Access.
2024-01-05 12:25:15 (9740): Disabling USB Support for VM.
2024-01-05 12:25:15 (9740): Disabling COM Port Support for VM.
2024-01-05 12:25:18 (9740): Disabling LPT Port Support for VM.
2024-01-05 12:25:18 (9740): Disabling Audio Support for VM.
2024-01-05 12:25:18 (9740): Disabling Clipboard Support for VM.
2024-01-05 12:25:19 (9740): Disabling Drag and Drop Support for VM.
2024-01-05 12:25:19 (9740): Adding storage controller(s) to VM.
2024-01-05 12:25:20 (9740): Adding virtual disk drive to VM. (CMS_2022_09_07.vdi)
2024-01-05 12:25:28 (9740): Adding VirtualBox Guest Additions to VM.
2024-01-05 12:25:28 (9740): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2024-01-05 12:25:29 (9740): forwarding host port 59522 to guest port 80
2024-01-05 12:25:29 (9740): Enabling remote desktop for VM.
2024-01-05 12:25:32 (9740): Enabling shared directory for VM.
2024-01-05 12:25:33 (9740): Starting VM using VBoxManage interface. (boinc_fac48e2104beca95, slot#5)
2024-01-05 12:25:40 (9740): Successfully started VM. (PID = '1124')
2024-01-05 12:25:40 (9740): Reporting VM Process ID to BOINC.
2024-01-05 12:25:40 (9740): Guest Log: BIOS: VirtualBox 7.0.12
2024-01-05 12:25:40 (9740): Guest Log: CPUID EDX: 0x178bfbff
2024-01-05 12:25:40 (9740): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-01-05 12:25:40 (9740): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-01-05 12:25:40 (9740): VM state change detected. (old = 'poweredoff', new = 'running')
2024-01-05 12:25:40 (9740): Detected: Web Application Enabled (http://localhost:59522)
2024-01-05 12:25:40 (9740): Detected: Remote Desktop Enabled (localhost:59527)
2024-01-05 12:25:40 (9740): Preference change detected
2024-01-05 12:25:40 (9740): Setting CPU throttle for VM. (95%)
2024-01-05 12:25:41 (9740): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-01-05 12:25:42 (9740): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-01-05 12:25:42 (9740): Guest Log: BIOS: Booting from Hard Disk...
2024-01-05 12:25:45 (9740): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-01-05 12:25:45 (9740): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-01-05 12:26:22 (9740): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-01-05 12:26:22 (9740): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-01-05 12:26:24 (9740): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-01-05 12:26:24 (9740): Guest Log: 00:00:00.000071 main     Log opened 2024-01-05T11:26:23.735331000Z
2024-01-05 12:26:24 (9740): Guest Log: 00:00:00.000181 main     OS Product: Linux
2024-01-05 12:26:24 (9740): Guest Log: 00:00:00.000237 main     OS Release: 4.14.232-19.cernvm.x86_64
2024-01-05 12:26:24 (9740): Guest Log: 00:00:00.000281 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2024-01-05 12:26:24 (9740): Guest Log: 00:00:00.000307 main     Executable: /usr/sbin/VBoxService
2024-01-05 12:26:24 (9740): Guest Log: 00:00:00.000308 main     Process ID: 2170
2024-01-05 12:26:24 (9740): Guest Log: 00:00:00.000308 main     Package type: LINUX_64BITS_GENERIC
2024-01-05 12:26:24 (9740): Guest Log: 00:00:00.003833 main     5.2.6 r120293 started. Verbose level = 0
2024-01-05 12:26:42 (9740): Guest Log: [INFO] Mounting the shared directory
2024-01-05 12:26:42 (9740): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2024-01-05 12:26:42 (9740): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2024-01-05 12:26:42 (9740): Guest Log: [INFO] Testing connection to cern.ch
2024-01-05 12:26:42 (9740): Guest Log: [INFO] Testing connection to VCCS
2024-01-05 12:26:43 (9740): Guest Log: [INFO] Testing connection to HTCondor
2024-01-05 12:26:43 (9740): Guest Log: [INFO] Testing connection to WMAgent
2024-01-05 12:26:43 (9740): Guest Log: [INFO] Testing connection to EOSCMS
2024-01-05 12:26:43 (9740): Guest Log: [INFO] Testing connection to CMS-Factory
2024-01-05 12:26:43 (9740): Guest Log: [INFO] Testing connection to CMS-Frontier
2024-01-05 12:26:43 (9740): Guest Log: [INFO] Testing connection to Frontier
2024-01-05 12:26:44 (9740): Guest Log: [INFO] Could not find a local HTTP proxy
2024-01-05 12:26:44 (9740): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2024-01-05 12:26:44 (9740): Guest Log: [INFO] This makes the application less efficient
2024-01-05 12:26:44 (9740): Guest Log: [INFO] It also puts higher load on the project servers
2024-01-05 12:26:44 (9740): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2024-01-05 12:26:44 (9740): Guest Log: [INFO] Advice can be found in the project forum
2024-01-05 12:26:45 (9740): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2024-01-05 12:26:53 (9740): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2024-01-05 12:26:57 (9740): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2024-01-05 12:26:57 (9740): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2024-01-05 12:26:57 (9740): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2024-01-05 12:26:58 (9740): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2024-01-05 12:26:59 (9740): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2024-01-05 12:26:59 (9740): Guest Log: [INFO] 2.7.2.0 http://s1swinburne-cvmfs.openhtc.io:8080 DIRECT
2024-01-05 12:26:59 (9740): Guest Log: [INFO] Environment HTTP proxy: not set
2024-01-05 12:26:59 (9740): Guest Log: [INFO] Reading volunteer information
2024-01-05 12:27:40 (9740): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2024-01-05 12:27:44 (9740): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2024-01-05 12:27:45 (9740): Guest Log: [INFO] CMS application starting. Check log files.
2024-01-05 12:27:45 (9740): Guest Log: [INFO] Requesting an idtoken from LHC@home
2024-01-05 12:27:46 (9740): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2024-01-05 14:05:45 (9740): Status Report: Job Duration: '64800.000000'
2024-01-05 14:05:45 (9740): Status Report: Elapsed Time: '6000.000000'
2024-01-05 14:05:45 (9740): Status Report: CPU Time: '4429.375000'
2024-01-05 15:45:50 (9740): Status Report: Job Duration: '64800.000000'
2024-01-05 15:45:50 (9740): Status Report: Elapsed Time: '12000.134607'
2024-01-05 15:45:50 (9740): Status Report: CPU Time: '8969.484375'
2024-01-05 17:25:55 (9740): Status Report: Job Duration: '64800.000000'
2024-01-05 17:25:55 (9740): Status Report: Elapsed Time: '18000.370754'
2024-01-05 17:25:55 (9740): Status Report: CPU Time: '13549.796875'
2024-01-05 19:06:00 (9740): Status Report: Job Duration: '64800.000000'
2024-01-05 19:06:00 (9740): Status Report: Elapsed Time: '24000.370754'
2024-01-05 19:06:00 (9740): Status Report: CPU Time: '18101.078125'
19:32:34 (6680): Can't acquire lockfile (32) - waiting 35s
19:33:09 (6680): Can't acquire lockfile (32) - exiting
19:33:09 (6680): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
19:44:48 (2768): Can't acquire lockfile (32) - waiting 35s
19:45:23 (2768): Can't acquire lockfile (32) - exiting
19:45:23 (2768): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
19:56:09 (3184): Can't acquire lockfile (32) - waiting 35s
19:56:44 (3184): Can't acquire lockfile (32) - exiting
19:56:44 (3184): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
20:07:20 (4004): Can't acquire lockfile (32) - waiting 35s
20:07:55 (4004): Can't acquire lockfile (32) - exiting
20:07:55 (4004): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
20:18:34 (10480): Can't acquire lockfile (32) - waiting 35s
20:19:09 (10480): Can't acquire lockfile (32) - exiting
20:19:09 (10480): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
20:29:56 (10328): Can't acquire lockfile (32) - waiting 35s
20:30:31 (10328): Can't acquire lockfile (32) - exiting
20:30:31 (10328): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
20:41:31 (13012): Can't acquire lockfile (32) - waiting 35s
20:42:06 (13012): Can't acquire lockfile (32) - exiting
20:42:06 (13012): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
2024-01-05 20:46:05 (9740): Status Report: Job Duration: '64800.000000'
2024-01-05 20:46:05 (9740): Status Report: Elapsed Time: '30001.288315'
2024-01-05 20:46:05 (9740): Status Report: CPU Time: '22785.500000'
20:52:14 (10772): Can't acquire lockfile (32) - waiting 35s
20:52:49 (10772): Can't acquire lockfile (32) - exiting
20:52:49 (10772): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
21:03:17 (11280): Can't acquire lockfile (32) - waiting 35s
21:03:52 (11280): Can't acquire lockfile (32) - exiting
21:03:52 (11280): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
21:14:10 (2012): Can't acquire lockfile (32) - waiting 35s
21:14:45 (2012): Can't acquire lockfile (32) - exiting
21:14:45 (2012): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
21:26:49 (2836): Can't acquire lockfile (32) - waiting 35s
21:27:24 (2836): Can't acquire lockfile (32) - exiting
21:27:24 (2836): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
21:48:40 (3548): Can't acquire lockfile (32) - waiting 35s
21:49:15 (3548): Can't acquire lockfile (32) - exiting
21:49:15 (3548): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
21:59:46 (9900): Can't acquire lockfile (32) - waiting 35s
22:00:21 (9900): Can't acquire lockfile (32) - exiting
22:00:21 (9900): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
22:10:49 (10664): Can't acquire lockfile (32) - waiting 35s
22:11:24 (10664): Can't acquire lockfile (32) - exiting
22:11:24 (10664): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
2024-01-05 22:26:11 (9740): Status Report: Job Duration: '64800.000000'
2024-01-05 22:26:11 (9740): Status Report: Elapsed Time: '36002.285400'
2024-01-05 22:26:11 (9740): Status Report: CPU Time: '27447.750000'
22:26:52 (4480): Can't acquire lockfile (32) - waiting 35s
22:27:27 (4480): Can't acquire lockfile (32) - exiting
22:27:27 (4480): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
22:42:41 (12616): Can't acquire lockfile (32) - waiting 35s
22:43:16 (12616): Can't acquire lockfile (32) - exiting
22:43:16 (12616): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
22:55:12 (12788): Can't acquire lockfile (32) - waiting 35s
22:55:47 (12788): Can't acquire lockfile (32) - exiting
22:55:47 (12788): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
23:06:25 (2128): Can't acquire lockfile (32) - waiting 35s
23:07:00 (2128): Can't acquire lockfile (32) - exiting
23:07:00 (2128): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
23:17:14 (4560): Can't acquire lockfile (32) - waiting 35s
23:17:49 (4560): Can't acquire lockfile (32) - exiting
23:17:49 (4560): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
23:39:54 (9264): Can't acquire lockfile (32) - waiting 35s
23:40:29 (9264): Can't acquire lockfile (32) - exiting
23:40:29 (9264): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
00:01:20 (1668): Can't acquire lockfile (32) - waiting 35s
00:02:08 (1668): Can't acquire lockfile (32) - exiting
00:02:08 (1668): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
2024-01-06 00:08:55 (9740): Status Report: Job Duration: '64800.000000'
2024-01-06 00:08:55 (9740): Status Report: Elapsed Time: '42003.053810'
2024-01-06 00:08:55 (9740): Status Report: CPU Time: '32392.234375'
00:12:53 (5920): Can't acquire lockfile (32) - waiting 35s
00:13:36 (5920): Can't acquire lockfile (32) - exiting
00:13:36 (5920): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
00:24:30 (5920): Can't acquire lockfile (32) - waiting 35s
00:25:12 (5920): Can't acquire lockfile (32) - exiting
00:25:12 (5920): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
00:36:33 (1992): Can't acquire lockfile (32) - waiting 35s
00:37:08 (1992): Can't acquire lockfile (32) - exiting
00:37:08 (1992): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
00:58:31 (10640): Can't acquire lockfile (32) - waiting 35s
00:59:12 (10640): Can't acquire lockfile (32) - exiting
00:59:12 (10640): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
01:00:24 (5880): Can't acquire lockfile (32) - waiting 35s
01:01:05 (5880): Can't acquire lockfile (32) - exiting
01:01:05 (5880): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
01:13:44 (5652): Can't acquire lockfile (32) - waiting 35s
01:14:20 (5652): Can't acquire lockfile (32) - exiting
01:14:20 (5652): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
01:24:29 (12372): Can't acquire lockfile (32) - waiting 35s
01:25:09 (12372): Can't acquire lockfile (32) - exiting
01:25:09 (12372): Error: Der Prozess kann nicht auf die Datei zugreifen, da sie von einem anderen Prozess verwendet wird.

 (0x20)
2024-01-06 01:31:13 (9740): Guest Log: [INFO] glidein exited with return value 0.
2024-01-06 01:31:13 (9740): Guest Log: [INFO] Shutting Down.
2024-01-06 01:31:13 (9740): VM Completion File Detected.
2024-01-06 01:31:13 (9740): VM Completion Message: glidein exited with return value 0.
.
2024-01-06 01:31:13 (9740): Powering off VM.
2024-01-06 01:31:19 (9740): Successfully stopped VM.
2024-01-06 01:31:19 (9740): Deregistering VM. (boinc_fac48e2104beca95, slot#5)
2024-01-06 01:31:19 (9740): Removing network bandwidth throttle group from VM.
2024-01-06 01:31:25 (9740): Removing VM from VirtualBox.
01:31:37 (9740): called boinc_finish(0)
2024-01-06 01:35:33 (9840): Detected: vboxwrapper 26206
2024-01-06 01:35:33 (9840): Detected: BOINC client v7.24.1
2024-01-06 01:35:39 (9840): Detected: VirtualBox VboxManage Interface (Version: 7.0.12)
2024-01-06 01:35:58 (9840): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-01-06 01:36:11 (9840): Create VM. (boinc_fac48e2104beca95, slot#5)
2024-01-06 01:36:22 (9840): Setting Memory Size for VM. (1896MB)
2024-01-06 01:36:24 (9840): Setting CPU Count for VM. (1)
2024-01-06 01:36:32 (9840): Setting Chipset Options for VM.
2024-01-06 01:36:38 (9840): Setting Graphics Controller Options for VM.
2024-01-06 01:36:39 (9840): Setting Boot Options for VM.
2024-01-06 01:36:46 (9840): Setting Network Configuration for NAT.
2024-01-06 01:36:52 (9840): Enabling VM Network Access.
2024-01-06 01:36:58 (9840): Disabling USB Support for VM.
2024-01-06 01:37:03 (9840): Disabling COM Port Support for VM.
2024-01-06 01:37:15 (9840): Disabling LPT Port Support for VM.
2024-01-06 01:37:21 (9840): Disabling Audio Support for VM.
2024-01-06 01:37:27 (9840): Disabling Clipboard Support for VM.
2024-01-06 01:37:32 (9840): Disabling Drag and Drop Support for VM.
2024-01-06 01:37:39 (9840): Adding storage controller(s) to VM.
2024-01-06 01:37:43 (9840): Adding virtual disk drive to VM. (CMS_2022_09_07.vdi)
2024-01-06 01:38:38 (9840): Adding VirtualBox Guest Additions to VM.
2024-01-06 01:38:44 (9840): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2024-01-06 01:38:51 (9840): forwarding host port 59522 to guest port 80
2024-01-06 01:39:01 (9840): Enabling remote desktop for VM.
2024-01-06 01:39:23 (9840): Enabling shared directory for VM.
2024-01-06 01:39:40 (9840): Starting VM using VBoxManage interface. (boinc_fac48e2104beca95, slot#5)
2024-01-06 01:40:02 (9840): Successfully started VM. (PID = '8644')
2024-01-06 01:40:02 (9840): Reporting VM Process ID to BOINC.
2024-01-06 01:40:02 (9840): Guest Log: BIOS: VirtualBox 7.0.12
2024-01-06 01:40:02 (9840): Guest Log: CPUID EDX: 0x178bfbff
2024-01-06 01:40:02 (9840): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-01-06 01:40:02 (9840): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-01-06 01:40:02 (9840): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-01-06 01:40:02 (9840): Guest Log: BIOS: Booting from Hard Disk...
2024-01-06 01:40:02 (9840): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-01-06 01:40:02 (9840): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-01-06 01:40:02 (9840): VM state change detected. (old = 'poweredoff', new = 'running')
2024-01-06 01:40:02 (9840): Detected: Web Application Enabled (http://localhost:59522)
2024-01-06 01:40:02 (9840): Detected: Remote Desktop Enabled (localhost:49547)
2024-01-06 01:40:02 (9840): VM Completion File Detected.
2024-01-06 01:40:02 (9840): VM Completion Message: glidein exited with return value 0.
.
2024-01-06 01:40:02 (9840): Powering off VM.
2024-01-06 01:40:07 (9840): Successfully stopped VM.
2024-01-06 01:40:07 (9840): Deregistering VM. (boinc_fac48e2104beca95, slot#5)
2024-01-06 01:40:22 (9840): Removing network bandwidth throttle group from VM.
2024-01-06 01:40:25 (9840): Removing VM from VirtualBox.
01:40:46 (9840): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN