time="2024-10-02T18:51:09.602Z" level=info msg="capturing logs" argo=true 2024/10/02 18:51:10 INFO : 196226268224.clapoff2: Copied (new) 2024/10/02 18:51:10 INFO : 195013170528.clapoff1: Copied (new) 2024/10/02 18:51:10 INFO : 196832475008.clapon2: Copied (new) 2024/10/02 18:51:10 INFO : 195623354112.clapon1: Copied (new) 2024/10/02 18:51:10 INFO : 197430145376.clapoff3: Copied (new) 2024/10/02 18:51:10 INFO : 198038257120.clapon3: Copied (new) 2024/10/02 18:51:10 INFO : assets.json: Copied (new) 2024/10/02 18:51:27 INFO : mkv/1727303184639000000_cam1.mkv: Multi-thread Copied (new) 2024/10/02 18:51:27 INFO : mkv/1727303184639000000_cam0.mkv: Multi-thread Copied (new) 2024/10/02 18:51:27 INFO : mkv/1727303184639000000_cam2.mkv: Multi-thread Copied (new) 2024/10/02 18:51:27 INFO : mkv/start_time.txt: Copied (new) 2024/10/02 18:51:27 INFO : Counter/2024_09_25/22_26_24_00639/(TEMP_WIP) Counter Futzer2.xlsx: Copied (new) 2024/10/02 18:51:27 INFO : Counter/2024_09_25/22_26_24_00639/Counter.ipd: Copied (new) 2024/10/02 18:51:27 INFO : Counter/2024_09_25/22_26_24_00639/Counter.original: Copied (new) 2024/10/02 18:51:30 INFO : mkv/1727303184639000000_cam3.mkv: Multi-thread Copied (new) 2024/10/02 18:51:30 INFO : Transferred: 2.528 GiB / 2.528 GiB, 100%, 125.099 MiB/s, ETA 0s Transferred: 15 / 15, 100% Elapsed time: 20.5s time="2024-10-02T18:51:30.618Z" level=info msg="sub-process exited" argo=true error="" time="2024-10-02T18:55:33.739Z" level=info msg="capturing logs" argo=true 2024/10/02 18:55:34 INFO : c3251417-6413-4596-ba0d-31501350d441/asset.json: Updated modification time in destination 2024/10/02 18:55:34 INFO : c3251417-6413-4596-ba0d-31501350d441/processing/data/cam0.tjd: Updated modification time in destination 2024/10/02 18:55:34 INFO : c3251417-6413-4596-ba0d-31501350d441/processing/data/cam2.tjd: Updated modification time in destination 2024/10/02 18:55:34 INFO : c3251417-6413-4596-ba0d-31501350d441/processing/data/cam1.tjd: Updated modification time in destination 2024/10/02 18:55:34 INFO : c3251417-6413-4596-ba0d-31501350d441/processing/data/cam3.tjd: Updated modification time in destination 2024/10/02 18:55:34 INFO : c3251417-6413-4596-ba0d-31501350d441/processing/data/payout.tjd: Updated modification time in destination 2024/10/02 18:55:38 INFO : c3251417-6413-4596-ba0d-31501350d441/c3251417-6413-4596-ba0d-31501350d441.iso: Copied (replaced existing) 2024/10/02 18:55:38 INFO : Transferred: 206.873 MiB / 206.873 MiB, 100%, 58.667 MiB/s, ETA 0s Checks: 7 / 7, 100% Transferred: 1 / 1, 100% Elapsed time: 5.0s time="2024-10-02T18:55:39.742Z" level=info msg="sub-process exited" argo=true error="" time="2024-10-02T18:56:06.723Z" level=info msg="capturing logs" argo=true fetch https://dl-cdn.alpinelinux.org/alpine/v3.20/main/x86_64/APKINDEX.tar.gz fetch https://dl-cdn.alpinelinux.org/alpine/v3.20/community/x86_64/APKINDEX.tar.gz v3.20.3-96-g1827497eea7 [https://dl-cdn.alpinelinux.org/alpine/v3.20/main] v3.20.3-104-g27788c02ed4 [https://dl-cdn.alpinelinux.org/alpine/v3.20/community] OK: 24163 distinct packages available fetch https://dl-cdn.alpinelinux.org/alpine/v3.20/main/x86_64/APKINDEX.tar.gz fetch https://dl-cdn.alpinelinux.org/alpine/v3.20/community/x86_64/APKINDEX.tar.gz (1/2) Installing oniguruma (6.9.9-r0) (2/2) Installing jq (1.7.1-r0) Executing busybox-1.36.1-r29.trigger OK: 9 MiB in 16 packages [ { "name": "P09420_US", "guid": "c3251417-6413-4596-ba0d-31501350d441", "start_m": 1.6363950781538463, "end_m": 7.3951403090989016, "start_ns": 167880001600, "end_ns": 375023001600, "direction": "upstream", "direction_cctv": "downstream" } ] time="2024-10-02T18:56:08.728Z" level=info msg="sub-process exited" argo=true error="" time="2024-10-02T18:56:08.728Z" level=info msg="/output.json -> /var/run/argo/outputs/parameters//output.json" argo=true time="2024-10-02T18:56:18.601Z" level=info msg="capturing logs" argo=true time="2024-10-02T18:56:18.684Z" level=info msg="Starting Workflow Executor" version=untagged time="2024-10-02T18:56:18.689Z" level=info msg="Using executor retry strategy" Duration=1s Factor=1.6 Jitter=0.5 Steps=5 time="2024-10-02T18:56:18.689Z" level=info msg="Executor initialized" deadline="0001-01-01 00:00:00 +0000 UTC" includeScriptOutput=false namespace=argo podName=p3d1-9rmnj-generate-volume-1669529220 templateName=generate-volume version="&Version{Version:untagged,BuildDate:2024-10-02T02:46:27Z,GitCommit:5310c396ee1e9f4e5575f62ef5340a86540d8679,GitTag:untagged,GitTreeState:clean,GoVersion:go1.23.2,Compiler:gc,Platform:linux/amd64,}" time="2024-10-02T18:56:18.704Z" level=info msg="Loading manifest to /tmp/manifest.yaml" time="2024-10-02T18:56:18.705Z" level=info msg="kubectl create -f /tmp/manifest.yaml -o json" time="2024-10-02T18:56:18.838Z" level=info msg="Resource: argo/persistentvolumeclaim./asset-volume-jnv2r. SelfLink: api/v1/namespaces/argo/persistentvolumeclaims/asset-volume-jnv2r" time="2024-10-02T18:56:18.838Z" level=info msg="Saving resource output parameters" time="2024-10-02T18:56:18.838Z" level=info msg="kubectl -n argo get persistentvolumeclaim./asset-volume-jnv2r -o jsonpath={.metadata.name}" time="2024-10-02T18:56:18.860Z" level=info msg=kubectl args="[kubectl -n argo get persistentvolumeclaim./asset-volume-jnv2r -o jsonpath={.metadata.name}]" error="" out=asset-volume-jnv2r time="2024-10-02T18:56:18.860Z" level=info msg="Saved output parameter: pvc-name, value: asset-volume-jnv2r" time="2024-10-02T18:56:19.602Z" level=info msg="sub-process exited" argo=true error="" time="2024-10-02T20:09:53.394Z" level=info msg="capturing logs" argo=true 2024/10/02 20:09:53 PostHoc 0.2.7 2024/10/02 20:09:53 asset.json found at /working/c3251417-6413-4596-ba0d-31501350d441/asset.json 2024/10/02 20:09:53 Reporting imperial units 2024/10/02 20:09:53 Inspection direction is upstream, enabling reversal 2024/10/02 20:09:53 Video frame rate is 15 2024/10/02 20:09:53 Reversing video... 1 image files updated 2024/10/02 20:10:06 Reversed video in 12.530269594s 2024/10/02 20:10:06 Subtitling video... 1 image files updated 2024/10/02 20:10:14 Subtitled video in 8.722625457s time="2024-10-02T20:10:15.406Z" level=info msg="sub-process exited" argo=true error="" time="2024-10-02T20:10:43.147Z" level=info msg="capturing logs" argo=true Uploading /working/c3251417-6413-4596-ba0d-31501350d441/deliverables/ 2024/10/02 20:10:43 INFO : counter/1970_01_01/00_06_13_00892/Counter.ipd: Copied (new) 2024/10/02 20:10:46 INFO : c3251417-6413-4596-ba0d-31501350d441_subtitled.mp4: Copied (new) 2024/10/02 20:10:47 INFO : c3251417-6413-4596-ba0d-31501350d441_reversed.mp4: Copied (new) 2024/10/02 20:10:47 INFO : c3251417-6413-4596-ba0d-31501350d441.mp4: Copied (new) 2024/10/02 20:10:47 INFO : Transferred: 122.696 MiB / 122.696 MiB, 100%, 40.899 MiB/s, ETA 0s Transferred: 4 / 4, 100% Elapsed time: 3.7s 2024/10/02 20:10:47 INFO : counter/1970_01_01/00_06_13_00892/Counter.ipd: Updated modification time in destination 2024/10/02 20:10:49 INFO : c3251417-6413-4596-ba0d-31501350d441.mp4: Copied (replaced existing) 2024/10/02 20:10:49 INFO : c3251417-6413-4596-ba0d-31501350d441_reversed.mp4: Copied (replaced existing) 2024/10/02 20:10:50 INFO : c3251417-6413-4596-ba0d-31501350d441_subtitled.mp4: Copied (replaced existing) 2024/10/02 20:10:50 INFO : Transferred: 122.693 MiB / 122.693 MiB, 100%, 61.347 MiB/s, ETA 0s Checks: 4 / 4, 100% Transferred: 3 / 3, 100% Elapsed time: 2.8s time="2024-10-02T20:10:50.152Z" level=info msg="sub-process exited" argo=true error=""