2026-04-07T03:46:38 - INFO: MSIP3D 0.2.0
2026-04-07T03:46:39 - INFO: P3D files will be output to /working/ab2687ac-f0be-7ffd-2b31-cd865ecb306f/ab2687ac-f0be-7ffd-2b31-cd865ecb306f
2026-04-07T03:46:39 - INFO: Found 10 TFD dictionary entries
2026-04-07T03:46:39 - INFO: Found TFD mission for 50001817
2026-04-07T03:46:39 - INFO: Recording will be processed as MD
2026-04-07T03:46:39 - INFO: Copying assets JSON to output directory...
2026-04-07T03:46:39 - INFO: Preparing counter data...
2026-04-07T03:46:39 - INFO: Interpreting counter as metric
2026-04-07T03:46:39 - INFO: Preparing size track from assets JSON...
2026-04-07T03:46:39 - INFO: Preparing material track from assets JSON...
2026-04-07T03:46:39 - INFO: Prepared counter data in 0.0109 s
2026-04-07T03:46:39 - INFO: Extracting sonar data...
TFD path: /working/ab2687ac-f0be-7ffd-2b31-cd865ecb306f/tfd/50001817_001.tfd
TFD path: /working/ab2687ac-f0be-7ffd-2b31-cd865ecb306f/tfd/output
Name: Dictionary, Tag: 18446744073709551615
Name: LASER, Tag: 4855215978501720652
Name: CCTV, Tag: 4855215978501720643
Name: MISSION_FILE, Tag: 5073671569755687501
Name: SONAR, Tag: 6012079240663880375
Name: SONAR_SENSOR_DATA, Tag: 6012079240663878227
Name: Index, Tag: 5291276999180902239
Name: Checkpoint, Tag: 4857243675797774090
Getting track SONAR
Number of frames: 512
21 / 512 [--->_________________________________________________________________________] 4.10% ? p/s42 / 512 [------>______________________________________________________________________] 8.20% ? p/s63 / 512 [--------->__________________________________________________________________] 12.30% ? p/s85 / 512 [------------>_____________________________________________________________] 16.60% 107 p/s107 / 512 [--------------->_________________________________________________________] 20.90% 107 p/s128 / 512 [------------------>______________________________________________________] 25.00% 107 p/s150 / 512 [--------------------->___________________________________________________] 29.30% 107 p/s171 / 512 [------------------------>________________________________________________] 33.40% 107 p/s193 / 512 [--------------------------->_____________________________________________] 37.70% 107 p/s215 / 512 [------------------------------>__________________________________________] 41.99% 107 p/s237 / 512 [--------------------------------->_______________________________________] 46.29% 107 p/s258 / 512 [------------------------------------>____________________________________] 50.39% 107 p/s279 / 512 [--------------------------------------->_________________________________] 54.49% 107 p/s301 / 512 [------------------------------------------>______________________________] 58.79% 107 p/s323 / 512 [---------------------------------------------->__________________________] 63.09% 107 p/s345 / 512 [------------------------------------------------->_______________________] 67.38% 107 p/s366 / 512 [---------------------------------------------------->____________________] 71.48% 107 p/s388 / 512 [------------------------------------------------------->_________________] 75.78% 107 p/s410 / 512 [---------------------------------------------------------->______________] 80.08% 107 p/s432 / 512 [------------------------------------------------------------->___________] 84.38% 107 p/s453 / 512 [---------------------------------------------------------------->________] 88.48% 107 p/s472 / 512 [------------------------------------------------------------------->_____] 92.19% 107 p/s493 / 512 [---------------------------------------------------------------------->__] 96.29% 107 p/s512 / 512 [------------------------------------------------------------------------] 100.00% 112 p/s2026-04-07T03:46:43 - INFO: Parsing 512 sonar sweeps from /working/ab2687ac-f0be-7ffd-2b31-cd865ecb306f/tfd/output/SONAR/all-sweeps-info.csv...

  0%|          | 0/512 [00:00<?, ?it/s]
  1%|          | 3/512 [00:00<00:19, 26.49it/s]
  1%|          | 6/512 [00:00<00:18, 27.29it/s]
  2%|▏         | 9/512 [00:00<00:18, 27.64it/s]
  2%|▏         | 12/512 [00:00<00:17, 27.80it/s]
  3%|▎         | 15/512 [00:00<00:17, 27.86it/s]
  4%|▎         | 18/512 [00:00<00:17, 27.90it/s]
  4%|▍         | 21/512 [00:00<00:17, 27.93it/s]
  5%|▍         | 24/512 [00:00<00:17, 28.12it/s]
  5%|▌         | 27/512 [00:00<00:17, 28.16it/s]
  6%|▌         | 30/512 [00:01<00:17, 28.23it/s]
  6%|▋         | 33/512 [00:01<00:16, 28.31it/s]
  7%|▋         | 36/512 [00:01<00:16, 28.32it/s]
  8%|▊         | 39/512 [00:01<00:16, 28.36it/s]
  8%|▊         | 42/512 [00:01<00:16, 27.86it/s]
  9%|▉         | 45/512 [00:01<00:17, 27.26it/s]
  9%|▉         | 48/512 [00:01<00:16, 27.57it/s]
 10%|▉         | 51/512 [00:01<00:16, 27.79it/s]
 11%|█         | 54/512 [00:01<00:16, 27.43it/s]
 11%|█         | 57/512 [00:02<00:16, 27.68it/s]
 12%|█▏        | 60/512 [00:02<00:16, 27.69it/s]
 12%|█▏        | 63/512 [00:02<00:16, 27.88it/s]
 13%|█▎        | 66/512 [00:02<00:15, 27.98it/s]
 13%|█▎        | 69/512 [00:02<00:15, 28.05it/s]
 14%|█▍        | 72/512 [00:02<00:15, 28.14it/s]
 15%|█▍        | 75/512 [00:02<00:15, 28.19it/s]
 15%|█▌        | 78/512 [00:02<00:15, 28.20it/s]
 16%|█▌        | 81/512 [00:02<00:15, 28.22it/s]
 16%|█▋        | 84/512 [00:03<00:15, 28.24it/s]
 17%|█▋        | 87/512 [00:03<00:15, 28.24it/s]
 18%|█▊        | 90/512 [00:03<00:14, 28.26it/s]
 18%|█▊        | 93/512 [00:03<00:14, 28.29it/s]
 19%|█▉        | 96/512 [00:03<00:14, 28.27it/s]
 19%|█▉        | 99/512 [00:03<00:14, 28.28it/s]
 20%|█▉        | 102/512 [00:03<00:14, 28.24it/s]
 21%|██        | 105/512 [00:03<00:14, 28.23it/s]
 21%|██        | 108/512 [00:03<00:14, 28.19it/s]
 22%|██▏       | 111/512 [00:03<00:14, 27.98it/s]
 22%|██▏       | 114/512 [00:04<00:14, 27.92it/s]
 23%|██▎       | 117/512 [00:04<00:14, 27.87it/s]
 23%|██▎       | 120/512 [00:04<00:14, 28.00it/s]
 24%|██▍       | 123/512 [00:04<00:13, 28.06it/s]
 25%|██▍       | 126/512 [00:04<00:13, 28.04it/s]
 25%|██▌       | 129/512 [00:04<00:13, 28.09it/s]
 26%|██▌       | 132/512 [00:04<00:13, 27.95it/s]
 26%|██▋       | 135/512 [00:04<00:13, 28.04it/s]
 27%|██▋       | 138/512 [00:04<00:13, 28.03it/s]
 28%|██▊       | 141/512 [00:05<00:13, 28.12it/s]
 28%|██▊       | 144/512 [00:05<00:13, 28.19it/s]
 29%|██▊       | 147/512 [00:05<00:12, 28.28it/s]
 29%|██▉       | 150/512 [00:05<00:12, 28.24it/s]
 30%|██▉       | 153/512 [00:05<00:12, 28.27it/s]
 30%|███       | 156/512 [00:05<00:12, 28.32it/s]
 31%|███       | 159/512 [00:05<00:12, 28.20it/s]
 32%|███▏      | 162/512 [00:05<00:12, 28.07it/s]
 32%|███▏      | 165/512 [00:05<00:12, 27.97it/s]
 33%|███▎      | 168/512 [00:05<00:12, 28.02it/s]
 33%|███▎      | 171/512 [00:06<00:12, 28.10it/s]
 34%|███▍      | 174/512 [00:06<00:12, 28.15it/s]
 35%|███▍      | 177/512 [00:06<00:11, 28.22it/s]
 35%|███▌      | 180/512 [00:06<00:11, 28.21it/s]
 36%|███▌      | 183/512 [00:06<00:11, 28.29it/s]
 36%|███▋      | 186/512 [00:06<00:11, 27.30it/s]
 37%|███▋      | 189/512 [00:06<00:11, 27.45it/s]
 38%|███▊      | 192/512 [00:06<00:11, 27.63it/s]
 38%|███▊      | 195/512 [00:06<00:11, 27.86it/s]
 39%|███▊      | 198/512 [00:07<00:11, 28.00it/s]
 39%|███▉      | 201/512 [00:07<00:11, 28.11it/s]
 40%|███▉      | 204/512 [00:07<00:10, 28.05it/s]
 40%|████      | 207/512 [00:07<00:10, 28.00it/s]
 41%|████      | 210/512 [00:07<00:10, 27.90it/s]
 42%|████▏     | 213/512 [00:07<00:10, 27.84it/s]
 42%|████▏     | 216/512 [00:07<00:10, 27.82it/s]
 43%|████▎     | 219/512 [00:07<00:10, 27.83it/s]
 43%|████▎     | 222/512 [00:07<00:10, 27.99it/s]
 44%|████▍     | 225/512 [00:08<00:10, 28.14it/s]
 45%|████▍     | 228/512 [00:08<00:10, 28.25it/s]
 45%|████▌     | 231/512 [00:08<00:09, 28.32it/s]
 46%|████▌     | 234/512 [00:08<00:09, 28.33it/s]
 46%|████▋     | 237/512 [00:08<00:09, 28.35it/s]
 47%|████▋     | 240/512 [00:08<00:09, 28.37it/s]
 47%|████▋     | 243/512 [00:08<00:09, 28.38it/s]
 48%|████▊     | 246/512 [00:08<00:09, 28.34it/s]
 49%|████▊     | 249/512 [00:08<00:09, 28.36it/s]
 49%|████▉     | 252/512 [00:08<00:09, 28.32it/s]
 50%|████▉     | 255/512 [00:09<00:09, 28.27it/s]
 50%|█████     | 258/512 [00:09<00:09, 28.20it/s]
 51%|█████     | 261/512 [00:09<00:08, 28.20it/s]
 52%|█████▏    | 264/512 [00:09<00:08, 28.19it/s]
 52%|█████▏    | 267/512 [00:09<00:08, 28.20it/s]
 53%|█████▎    | 270/512 [00:09<00:08, 28.22it/s]
 53%|█████▎    | 273/512 [00:09<00:08, 28.28it/s]
 54%|█████▍    | 276/512 [00:09<00:08, 28.35it/s]
 54%|█████▍    | 279/512 [00:09<00:08, 28.37it/s]
 55%|█████▌    | 282/512 [00:10<00:08, 28.35it/s]
 56%|█████▌    | 285/512 [00:10<00:07, 28.39it/s]
 56%|█████▋    | 288/512 [00:10<00:07, 28.40it/s]
 57%|█████▋    | 291/512 [00:10<00:07, 28.24it/s]
 57%|█████▋    | 294/512 [00:10<00:07, 28.20it/s]
 58%|█████▊    | 297/512 [00:10<00:07, 28.24it/s]
 59%|█████▊    | 300/512 [00:10<00:07, 28.29it/s]
 59%|█████▉    | 303/512 [00:10<00:07, 28.31it/s]
 60%|█████▉    | 306/512 [00:10<00:07, 28.29it/s]
 60%|██████    | 309/512 [00:10<00:07, 28.31it/s]
 61%|██████    | 312/512 [00:11<00:07, 28.35it/s]
 62%|██████▏   | 315/512 [00:11<00:06, 28.39it/s]
 62%|██████▏   | 318/512 [00:11<00:07, 27.05it/s]
 63%|██████▎   | 321/512 [00:11<00:06, 27.30it/s]
 63%|██████▎   | 324/512 [00:11<00:06, 27.43it/s]
 64%|██████▍   | 327/512 [00:11<00:06, 27.59it/s]
 64%|██████▍   | 330/512 [00:11<00:06, 27.72it/s]
 65%|██████▌   | 333/512 [00:11<00:06, 27.88it/s]
 66%|██████▌   | 336/512 [00:11<00:06, 27.95it/s]
 66%|██████▌   | 339/512 [00:12<00:06, 27.89it/s]
 67%|██████▋   | 342/512 [00:12<00:06, 28.04it/s]
 67%|██████▋   | 345/512 [00:12<00:05, 28.09it/s]
 68%|██████▊   | 348/512 [00:12<00:05, 28.16it/s]
 69%|██████▊   | 351/512 [00:12<00:05, 28.07it/s]
 69%|██████▉   | 354/512 [00:12<00:05, 27.95it/s]
 70%|██████▉   | 357/512 [00:12<00:05, 27.85it/s]
 70%|███████   | 360/512 [00:12<00:05, 27.92it/s]
 71%|███████   | 363/512 [00:12<00:05, 27.98it/s]
 71%|███████▏  | 366/512 [00:13<00:05, 27.93it/s]
 72%|███████▏  | 369/512 [00:13<00:05, 27.96it/s]
 73%|███████▎  | 372/512 [00:13<00:05, 27.98it/s]
 73%|███████▎  | 375/512 [00:13<00:04, 28.05it/s]
 74%|███████▍  | 378/512 [00:13<00:04, 28.10it/s]
 74%|███████▍  | 381/512 [00:13<00:04, 28.10it/s]
 75%|███████▌  | 384/512 [00:13<00:04, 28.10it/s]
 76%|███████▌  | 387/512 [00:13<00:04, 28.14it/s]
 76%|███████▌  | 390/512 [00:13<00:04, 28.18it/s]
 77%|███████▋  | 393/512 [00:14<00:04, 28.20it/s]
 77%|███████▋  | 396/512 [00:14<00:04, 28.19it/s]
 78%|███████▊  | 399/512 [00:14<00:04, 28.13it/s]
 79%|███████▊  | 402/512 [00:14<00:03, 28.07it/s]
 79%|███████▉  | 405/512 [00:14<00:03, 28.04it/s]
 80%|███████▉  | 408/512 [00:14<00:03, 28.06it/s]
 80%|████████  | 411/512 [00:14<00:03, 28.05it/s]
 81%|████████  | 414/512 [00:14<00:03, 28.00it/s]
 81%|████████▏ | 417/512 [00:14<00:03, 28.05it/s]
 82%|████████▏ | 420/512 [00:14<00:03, 28.08it/s]
 83%|████████▎ | 423/512 [00:15<00:03, 28.13it/s]
 83%|████████▎ | 426/512 [00:15<00:03, 28.14it/s]
 84%|████████▍ | 429/512 [00:15<00:02, 28.18it/s]
 84%|████████▍ | 432/512 [00:15<00:02, 28.14it/s]
 85%|████████▍ | 435/512 [00:15<00:02, 28.12it/s]
 86%|████████▌ | 438/512 [00:15<00:02, 28.14it/s]
 86%|████████▌ | 441/512 [00:15<00:02, 28.16it/s]
 87%|████████▋ | 444/512 [00:15<00:02, 28.20it/s]
 87%|████████▋ | 447/512 [00:15<00:02, 28.20it/s]
 88%|████████▊ | 450/512 [00:16<00:02, 28.15it/s]
 88%|████████▊ | 453/512 [00:16<00:02, 26.47it/s]
 89%|████████▉ | 456/512 [00:16<00:02, 27.04it/s]
 90%|████████▉ | 459/512 [00:16<00:01, 27.29it/s]
 90%|█████████ | 462/512 [00:16<00:01, 27.52it/s]
 91%|█████████ | 465/512 [00:16<00:01, 27.61it/s]
 91%|█████████▏| 468/512 [00:16<00:01, 27.74it/s]
 92%|█████████▏| 471/512 [00:16<00:01, 27.87it/s]
 93%|█████████▎| 474/512 [00:16<00:01, 28.01it/s]
 93%|█████████▎| 477/512 [00:17<00:01, 28.12it/s]
 94%|█████████▍| 480/512 [00:17<00:01, 28.25it/s]
 94%|█████████▍| 483/512 [00:17<00:01, 28.36it/s]
 95%|█████████▍| 486/512 [00:17<00:00, 28.41it/s]
 96%|█████████▌| 489/512 [00:17<00:00, 28.44it/s]
 96%|█████████▌| 492/512 [00:17<00:00, 28.25it/s]
 97%|█████████▋| 495/512 [00:17<00:00, 28.22it/s]
 97%|█████████▋| 498/512 [00:17<00:00, 28.03it/s]
 98%|█████████▊| 501/512 [00:17<00:00, 28.10it/s]
 98%|█████████▊| 504/512 [00:17<00:00, 28.18it/s]
 99%|█████████▉| 508/512 [00:18<00:00, 30.31it/s]
100%|██████████| 512/512 [00:18<00:00, 29.63it/s]
100%|██████████| 512/512 [00:18<00:00, 28.09it/s]
2026-04-07T03:47:02 - INFO: Running DataPrep with "python -m dataprep --force --no-counter --no-video --no-laser --sonar --sonar-format cbor --temperature 17.8 --salinity 0 --pressure 0 --sonar-cluster-size 15 --sonar-mask-angle 120 --sonar-despike --sonar-flip /working/ab2687ac-f0be-7ffd-2b31-cd865ecb306f/ab2687ac-f0be-7ffd-2b31-cd865ecb306f"
2026-04-07T03:47:03 - INFO: DataPrep 0.14.0
2026-04-07T03:47:03 - INFO: Prepared data will be output to /working/ab2687ac-f0be-7ffd-2b31-cd865ecb306f/ab2687ac-f0be-7ffd-2b31-cd865ecb306f/processing/data
2026-04-07T03:47:03 - deployment - WARNING: No CCTV directory, defaulting to P3D 1.0
2026-04-07T03:47:03 - deployment - INFO: Detected P3D version: P3D_1
2026-04-07T03:47:03 - INFO: Recording GUID: ab2687ac-f0be-7ffd-2b31-cd865ecb306f
2026-04-07T03:47:03 - INFO: Preparing sonar data...
2026-04-07T03:47:03 - INFO: /working/ab2687ac-f0be-7ffd-2b31-cd865ecb306f/ab2687ac-f0be-7ffd-2b31-cd865ecb306f/sonar/sonar.dat has metadata version 0.1 (0x00000001)
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/dataprep/__main__.py", line 171, in main
    dataprep.prepare.prepare(
  File "/usr/local/lib/python3.11/site-packages/dataprep/prepare.py", line 320, in prepare
    sonar = dataprep.sonar.Sonar.from_file(sonars[0])
            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/dataprep/sonar.py", line 88, in from_file
    sweeps=[
           ^
  File "/usr/local/lib/python3.11/site-packages/dataprep/sonar.py", line 89, in <listcomp>
    Sweep.from_bin(b, steps, samples)
  File "/usr/local/lib/python3.11/site-packages/dataprep/sonar.py", line 118, in from_bin
    beams=[Beam.from_bin(data[i : i + sample_len]) for i in range(4, len(data), sample_len)],
          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/dataprep/sonar.py", line 118, in <listcomp>
    beams=[Beam.from_bin(data[i : i + sample_len]) for i in range(4, len(data), sample_len)],
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/dataprep/sonar.py", line 142, in from_bin
    raise ValueError("Invalid beam signature")
ValueError: Invalid beam signature
Traceback (most recent call last):
  File "/app/src/msip3d/__main__.py", line 259, in main
    msip3d.sonar.extract_sonar(
  File "/app/src/msip3d/sonar.py", line 115, in extract_sonar
    subprocess.run(cmd, check=True)
  File "/usr/local/lib/python3.11/subprocess.py", line 571, in run
    raise CalledProcessError(retcode, process.args,
subprocess.CalledProcessError: Command '['python', '-m', 'dataprep', '--force', '--no-counter', '--no-video', '--no-laser', '--sonar', '--sonar-format', 'cbor', '--temperature', '17.8', '--salinity', '0', '--pressure', '0', '--sonar-cluster-size', '15', '--sonar-mask-angle', '120', '--sonar-despike', '--sonar-flip', '/working/ab2687ac-f0be-7ffd-2b31-cd865ecb306f/ab2687ac-f0be-7ffd-2b31-cd865ecb306f']' returned non-zero exit status 255.