2026-06-14 12:49:09 - uvicorn.error - INFO - Started server process [8]
2026-06-14 12:49:09 - uvicorn.error - INFO - Waiting for application startup.
2026-06-14 12:49:09 - uvicorn.error - INFO - Application startup complete.
2026-06-14 12:49:09 - uvicorn.error - INFO - Uvicorn running on http://0.0.0.0:8081 (Press CTRL+C to quit)
2026-06-14 13:03:46 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/test_person_left_20260614130341377.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T130346Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=e8762f19c8621e8675e24257ab561ed05d1feb579e60fd0c745acc2460c6df94
2026-06-14 13:03:46 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 13:03:46 - foot_measurements.api - WARNING - DEBUG job_id: 6616e20a-11e0-4d91-9339-9f997c1ec822
2026-06-14 13:04:04 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '6616e20a-11e0-4d91-9339-9f997c1ec822', 'status': 'COMPLETED', 'result': {'foot_length': 12.389082579981736, 'foot_width': 8.11631761366935, 'heel_to_big_toe_joint': 6.752657379100674, 'toe_gap_to_toe_tip': -7.668575051111091, 'toe_gap_to_medial_edge': 6.751861518572882}, 'trace': {'scanId': '6616e20a-11e0-4d91-9339-9f997c1ec822', 'status': 'COMPLETED', 'startedAt': '2026-06-14T13:03:46.951503+00:00', 'finishedAt': '2026-06-14T13:04:04.096725+00:00', 'processingTimeMs': 17145.224, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '9.5 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '19.9 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.7 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.3 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '46.0 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7921.1 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 9.467, 'convert_to_cm_ms': 0.065, 'preprocess_ms': 19.897, 'initial_landmarks_ms': 3.677, 'normalize_x_ms': 0.081, 'medial_alignment_ms': 1.308, 'zehensteg_detection_ms': 46.002, 'metrics_ms': 0.531, 'quality_validation_ms': 0.009, 'save_json_ms': 0.54, 'debug_plots_ms': 7921.052, 'total_ms': 8002.635}}}
2026-06-14 13:04:04 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 15:14:09 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/anna_mayer_right_20260614151401552.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T151409Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=6975e0577d09eccc7eda4c84d0b4a4a5344df73ddf544392f624dd9cd31d8639
2026-06-14 15:14:09 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 15:14:09 - foot_measurements.api - WARNING - DEBUG job_id: f9b1037d-8d35-4de7-b202-411ab692213a
2026-06-14 15:14:26 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': 'f9b1037d-8d35-4de7-b202-411ab692213a', 'status': 'COMPLETED', 'result': {'foot_length': 14.165854829930232, 'foot_width': 7.367553614638406, 'heel_to_big_toe_joint': 7.3515889143374675, 'toe_gap_to_toe_tip': -5.814439220348618, 'toe_gap_to_medial_edge': 5.427028236713426}, 'trace': {'scanId': 'f9b1037d-8d35-4de7-b202-411ab692213a', 'status': 'COMPLETED', 'startedAt': '2026-06-14T15:14:09.306406+00:00', 'finishedAt': '2026-06-14T15:14:26.469008+00:00', 'processingTimeMs': 17162.6, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.2 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '20.4 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.7 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.5 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '39.2 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7699.7 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.245, 'convert_to_cm_ms': 0.048, 'preprocess_ms': 20.435, 'initial_landmarks_ms': 3.726, 'normalize_x_ms': 0.096, 'medial_alignment_ms': 1.507, 'zehensteg_detection_ms': 39.229, 'metrics_ms': 0.536, 'quality_validation_ms': 0.009, 'save_json_ms': 0.47, 'debug_plots_ms': 7699.739, 'total_ms': 7772.053}}}
2026-06-14 15:14:26 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 15:25:09 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/anna2_mayer_right_20260614152500816.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T152509Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=e06a434b8ac9f1bdd314693d1388545f4a94d74c6d84175bc62372da353db1ae
2026-06-14 15:25:09 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 15:25:09 - foot_measurements.api - WARNING - DEBUG job_id: 78c88076-79ed-47dd-bd7c-cf69cf81ecdf
2026-06-14 15:25:26 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '78c88076-79ed-47dd-bd7c-cf69cf81ecdf', 'status': 'COMPLETED', 'result': {'foot_length': 12.628082395079959, 'foot_width': 6.644261668999149, 'heel_to_big_toe_joint': 10.770487561047299, 'toe_gap_to_toe_tip': -8.282757102646102, 'toe_gap_to_medial_edge': 8.52930271398835}, 'trace': {'scanId': '78c88076-79ed-47dd-bd7c-cf69cf81ecdf', 'status': 'COMPLETED', 'startedAt': '2026-06-14T15:25:09.727204+00:00', 'finishedAt': '2026-06-14T15:25:26.938327+00:00', 'processingTimeMs': 17211.123, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '7.1 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '22.1 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.9 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.4 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '37.7 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.6 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.6 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7643.4 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 7.069, 'convert_to_cm_ms': 0.077, 'preprocess_ms': 22.055, 'initial_landmarks_ms': 3.939, 'normalize_x_ms': 0.117, 'medial_alignment_ms': 1.448, 'zehensteg_detection_ms': 37.741, 'metrics_ms': 0.598, 'quality_validation_ms': 0.012, 'save_json_ms': 0.59, 'debug_plots_ms': 7643.43, 'total_ms': 7717.082}}}
2026-06-14 15:25:26 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 15:38:33 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/anna_mayer_left_20260614153826474.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T153833Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=509b6dffd46a40f51df8f821a1cd850965adc93917c7b7538feeade7bd7d328e
2026-06-14 15:38:33 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 15:38:33 - foot_measurements.api - WARNING - DEBUG job_id: f38c2b83-246f-4688-8f32-14b7f3075807
2026-06-14 15:38:50 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': 'f38c2b83-246f-4688-8f32-14b7f3075807', 'status': 'COMPLETED', 'result': {'foot_length': 11.12702937281879, 'foot_width': 6.208281698077646, 'heel_to_big_toe_joint': 9.246378313488329, 'toe_gap_to_toe_tip': -9.650784238585114, 'toe_gap_to_medial_edge': 9.865338690895932}, 'trace': {'scanId': 'f38c2b83-246f-4688-8f32-14b7f3075807', 'status': 'COMPLETED', 'startedAt': '2026-06-14T15:38:33.890509+00:00', 'finishedAt': '2026-06-14T15:38:50.736529+00:00', 'processingTimeMs': 16846.02, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.7 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '24.4 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '4.0 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.4 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '41.0 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7772.6 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.657, 'convert_to_cm_ms': 0.102, 'preprocess_ms': 24.379, 'initial_landmarks_ms': 3.958, 'normalize_x_ms': 0.122, 'medial_alignment_ms': 1.421, 'zehensteg_detection_ms': 41.044, 'metrics_ms': 0.496, 'quality_validation_ms': 0.01, 'save_json_ms': 0.538, 'debug_plots_ms': 7772.586, 'total_ms': 7851.321}}}
2026-06-14 15:38:50 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 15:49:52 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/anna2_mayer_left_20260614154945978.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T154952Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=4d95d5ddbde6ea076f292d5bdeb408ded7b4a73f2dcf4aae68e2452bc8027f9d
2026-06-14 15:49:52 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 15:49:52 - foot_measurements.api - WARNING - DEBUG job_id: a9a7a657-85c1-4acd-814f-375bf75559ec
2026-06-14 15:50:09 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': 'a9a7a657-85c1-4acd-814f-375bf75559ec', 'status': 'COMPLETED', 'result': {'foot_length': 12.241654093468266, 'foot_width': 6.867231447164356, 'heel_to_big_toe_joint': 9.966930690291854, 'toe_gap_to_toe_tip': -8.95004974967599, 'toe_gap_to_medial_edge': 10.63093220765109}, 'trace': {'scanId': 'a9a7a657-85c1-4acd-814f-375bf75559ec', 'status': 'COMPLETED', 'startedAt': '2026-06-14T15:49:52.054731+00:00', 'finishedAt': '2026-06-14T15:50:09.055474+00:00', 'processingTimeMs': 17000.743, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.2 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '23.9 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.4 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.3 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '45.6 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7639.4 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.227, 'convert_to_cm_ms': 0.041, 'preprocess_ms': 23.94, 'initial_landmarks_ms': 3.444, 'normalize_x_ms': 0.086, 'medial_alignment_ms': 1.274, 'zehensteg_detection_ms': 45.61, 'metrics_ms': 0.482, 'quality_validation_ms': 0.008, 'save_json_ms': 0.496, 'debug_plots_ms': 7639.42, 'total_ms': 7721.035}}}
2026-06-14 15:50:09 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 16:07:58 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/barbara_huber_right_20260614160756189.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T160758Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=e5e6297c186c4b032e0d92b988cffc70f0b6aaa43ef60f895872306705c90a2e
2026-06-14 16:07:58 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 16:07:58 - foot_measurements.api - WARNING - DEBUG job_id: 09336aac-a3f7-4c0d-ac6c-727f0a510697
2026-06-14 16:08:15 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '09336aac-a3f7-4c0d-ac6c-727f0a510697', 'status': 'COMPLETED', 'result': {'foot_length': 25.7998066291049, 'foot_width': 10.596390695755314, 'heel_to_big_toe_joint': 18.504657335116732, 'toe_gap_to_toe_tip': 5.284194989656566, 'toe_gap_to_medial_edge': 3.346375863036669}, 'trace': {'scanId': '09336aac-a3f7-4c0d-ac6c-727f0a510697', 'status': 'COMPLETED', 'startedAt': '2026-06-14T16:07:58.177075+00:00', 'finishedAt': '2026-06-14T16:08:15.147656+00:00', 'processingTimeMs': 16970.585, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.2 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '25.7 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.9 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.5 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '11.4 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.7 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7584.0 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.223, 'convert_to_cm_ms': 0.042, 'preprocess_ms': 25.688, 'initial_landmarks_ms': 3.908, 'normalize_x_ms': 0.087, 'medial_alignment_ms': 1.527, 'zehensteg_detection_ms': 11.426, 'metrics_ms': 0.719, 'quality_validation_ms': 0.009, 'save_json_ms': 0.503, 'debug_plots_ms': 7583.985, 'total_ms': 7634.126}}}
2026-06-14 16:08:15 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 16:17:37 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/barbara2_huber_right_20260614161735798.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T161737Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=3cfbae931fdefa20546ca8c72547cfa2535b3979069708e4ec8b02ff0a044d23
2026-06-14 16:17:37 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 16:17:37 - foot_measurements.api - WARNING - DEBUG job_id: 5a741965-d0b1-4fb1-814f-989ae5dcf58c
2026-06-14 16:17:54 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '5a741965-d0b1-4fb1-814f-989ae5dcf58c', 'status': 'COMPLETED', 'result': {'foot_length': 14.42032761050028, 'foot_width': 7.817346522522417, 'heel_to_big_toe_joint': 6.936949992798317, 'toe_gap_to_toe_tip': -6.581266146350863, 'toe_gap_to_medial_edge': 6.675593277267137}, 'trace': {'scanId': '5a741965-d0b1-4fb1-814f-989ae5dcf58c', 'status': 'COMPLETED', 'startedAt': '2026-06-14T16:17:37.612430+00:00', 'finishedAt': '2026-06-14T16:17:54.540735+00:00', 'processingTimeMs': 16928.303, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.3 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '22.7 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '4.0 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.4 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '55.1 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7584.7 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.277, 'convert_to_cm_ms': 0.071, 'preprocess_ms': 22.678, 'initial_landmarks_ms': 3.99, 'normalize_x_ms': 0.114, 'medial_alignment_ms': 1.372, 'zehensteg_detection_ms': 55.085, 'metrics_ms': 0.488, 'quality_validation_ms': 0.01, 'save_json_ms': 0.518, 'debug_plots_ms': 7584.655, 'total_ms': 7675.264}}}
2026-06-14 16:17:54 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 16:29:17 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/barbara_huber_left_20260614162915280.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T162917Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=d010e69c66cec8afc1a8aa78f939cca4e79d71a916c75877756ea789d5734142
2026-06-14 16:29:17 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 16:29:17 - foot_measurements.api - WARNING - DEBUG job_id: f7d49c9c-0992-4b8b-affb-795f65dd941d
2026-06-14 16:29:34 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': 'f7d49c9c-0992-4b8b-affb-795f65dd941d', 'status': 'COMPLETED', 'result': {'foot_length': 24.37849149089379, 'foot_width': 10.458542513254407, 'heel_to_big_toe_joint': 17.752327219114445, 'toe_gap_to_toe_tip': 4.41133334268088, 'toe_gap_to_medial_edge': 3.290958230520353}, 'trace': {'scanId': 'f7d49c9c-0992-4b8b-affb-795f65dd941d', 'status': 'COMPLETED', 'startedAt': '2026-06-14T16:29:17.723476+00:00', 'finishedAt': '2026-06-14T16:29:34.626650+00:00', 'processingTimeMs': 16903.171, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.3 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '26.8 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '4.2 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.4 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '22.6 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.6 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7514.5 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.255, 'convert_to_cm_ms': 0.074, 'preprocess_ms': 26.783, 'initial_landmarks_ms': 4.192, 'normalize_x_ms': 0.119, 'medial_alignment_ms': 1.438, 'zehensteg_detection_ms': 22.626, 'metrics_ms': 0.593, 'quality_validation_ms': 0.01, 'save_json_ms': 0.513, 'debug_plots_ms': 7514.455, 'total_ms': 7577.064}}}
2026-06-14 16:29:34 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 16:42:39 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/barbara2_huber_left_20260614164237347.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T164239Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=13c2dcf878e89965c42efbd4dfa0ff5bb75c7e542ecb9147f71ea4dd5a1efc21
2026-06-14 16:42:39 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 16:42:39 - foot_measurements.api - WARNING - DEBUG job_id: 0dd62b87-864b-4da6-a5e4-c647749cb7a1
2026-06-14 16:42:55 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '0dd62b87-864b-4da6-a5e4-c647749cb7a1', 'status': 'COMPLETED', 'result': {'foot_length': 14.630404173300239, 'foot_width': 7.208758861693503, 'heel_to_big_toe_joint': 12.524776609436298, 'toe_gap_to_toe_tip': -0.6176767343770386, 'toe_gap_to_medial_edge': 11.638918663075277}, 'trace': {'scanId': '0dd62b87-864b-4da6-a5e4-c647749cb7a1', 'status': 'COMPLETED', 'startedAt': '2026-06-14T16:42:39.087132+00:00', 'finishedAt': '2026-06-14T16:42:55.867159+00:00', 'processingTimeMs': 16780.029, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.1 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '21.7 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.3 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.2 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '49.7 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.6 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7563.8 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.076, 'convert_to_cm_ms': 0.039, 'preprocess_ms': 21.685, 'initial_landmarks_ms': 3.306, 'normalize_x_ms': 0.104, 'medial_alignment_ms': 1.247, 'zehensteg_detection_ms': 49.673, 'metrics_ms': 0.502, 'quality_validation_ms': 0.008, 'save_json_ms': 0.562, 'debug_plots_ms': 7563.801, 'total_ms': 7647.01}}}
2026-06-14 16:42:55 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 16:53:16 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/rupert_huber_right_20260614165314895.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T165316Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=3ff4aaf6792ddf0296fd9d9d28b3c28b3a1391445f372c58780e0698c5ee5ef5
2026-06-14 16:53:16 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 16:53:16 - foot_measurements.api - WARNING - DEBUG job_id: a0d90213-26f7-40d4-8787-79435bd22bf0
2026-06-14 16:53:33 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': 'a0d90213-26f7-40d4-8787-79435bd22bf0', 'status': 'COMPLETED', 'result': {'foot_length': 27.714388960058482, 'foot_width': 10.334347801766771, 'heel_to_big_toe_joint': 19.946266342228526, 'toe_gap_to_toe_tip': 4.952349650337517, 'toe_gap_to_medial_edge': 3.2061260670039706}, 'trace': {'scanId': 'a0d90213-26f7-40d4-8787-79435bd22bf0', 'status': 'COMPLETED', 'startedAt': '2026-06-14T16:53:16.683981+00:00', 'finishedAt': '2026-06-14T16:53:33.847449+00:00', 'processingTimeMs': 17163.467, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.5 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '28.9 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '4.0 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.9 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '26.4 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.8 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7691.1 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.48, 'convert_to_cm_ms': 0.064, 'preprocess_ms': 28.898, 'initial_landmarks_ms': 4.03, 'normalize_x_ms': 0.106, 'medial_alignment_ms': 1.924, 'zehensteg_detection_ms': 26.366, 'metrics_ms': 0.778, 'quality_validation_ms': 0.01, 'save_json_ms': 0.536, 'debug_plots_ms': 7691.09, 'total_ms': 7760.287}}}
2026-06-14 16:53:33 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 17:03:34 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/rupert2_huber_right_20260614170332842.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T170334Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=a44c80dfe8a31973fda9fa8e6f689094d4f01ff783dd098b73fa78ad5460b068
2026-06-14 17:03:34 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 17:03:34 - foot_measurements.api - WARNING - DEBUG job_id: b93832a4-413a-4200-9afd-8e3153109a3f
2026-06-14 17:03:51 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': 'b93832a4-413a-4200-9afd-8e3153109a3f', 'status': 'COMPLETED', 'result': {'foot_length': 27.170620035463127, 'foot_width': 10.331244508589581, 'heel_to_big_toe_joint': 21.449231658174327, 'toe_gap_to_toe_tip': 4.599187909326034, 'toe_gap_to_medial_edge': 7.255640237656866}, 'trace': {'scanId': 'b93832a4-413a-4200-9afd-8e3153109a3f', 'status': 'COMPLETED', 'startedAt': '2026-06-14T17:03:34.682195+00:00', 'finishedAt': '2026-06-14T17:03:51.716994+00:00', 'processingTimeMs': 17034.797, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.2 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '25.6 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '4.4 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.2 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.5 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '27.5 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.6 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7631.3 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.157, 'convert_to_cm_ms': 0.074, 'preprocess_ms': 25.613, 'initial_landmarks_ms': 4.409, 'normalize_x_ms': 0.152, 'medial_alignment_ms': 1.532, 'zehensteg_detection_ms': 27.499, 'metrics_ms': 0.634, 'quality_validation_ms': 0.01, 'save_json_ms': 0.487, 'debug_plots_ms': 7631.278, 'total_ms': 7697.853}}}
2026-06-14 17:03:51 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 17:16:08 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/rupert_huber_left_20260614171606960.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T171608Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=a64276e9fdde3881016f998e6e380f0600bfb5ebfadea0ac2bae4612ff0ec436
2026-06-14 17:16:08 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 17:16:08 - foot_measurements.api - WARNING - DEBUG job_id: 9ea31893-bcb3-413d-8a3c-60a8380b1f93
2026-06-14 17:16:26 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '9ea31893-bcb3-413d-8a3c-60a8380b1f93', 'status': 'COMPLETED', 'result': {'foot_length': 28.183237972262933, 'foot_width': 10.806115560971003, 'heel_to_big_toe_joint': 21.481832508920668, 'toe_gap_to_toe_tip': 5.035428557621078, 'toe_gap_to_medial_edge': 1.6390342816896375}, 'trace': {'scanId': '9ea31893-bcb3-413d-8a3c-60a8380b1f93', 'status': 'COMPLETED', 'startedAt': '2026-06-14T17:16:08.916318+00:00', 'finishedAt': '2026-06-14T17:16:26.557018+00:00', 'processingTimeMs': 17640.697, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.1 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '27.8 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '4.2 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.6 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '18.2 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.7 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7793.2 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.133, 'convert_to_cm_ms': 0.069, 'preprocess_ms': 27.813, 'initial_landmarks_ms': 4.173, 'normalize_x_ms': 0.117, 'medial_alignment_ms': 1.605, 'zehensteg_detection_ms': 18.178, 'metrics_ms': 0.681, 'quality_validation_ms': 0.011, 'save_json_ms': 0.501, 'debug_plots_ms': 7793.227, 'total_ms': 7852.516}}}
2026-06-14 17:16:26 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 17:27:00 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/rupert2_huber_left_20260614172657780.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T172700Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=c38626a71bc6a45fd457ee5c6a00562015e5d45135d759f3a115e7a2a2b081ba
2026-06-14 17:27:00 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 17:27:00 - foot_measurements.api - WARNING - DEBUG job_id: e50de295-1965-4558-9d85-1f9e29ff366c
2026-06-14 17:27:17 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': 'e50de295-1965-4558-9d85-1f9e29ff366c', 'status': 'COMPLETED', 'result': {'foot_length': 27.015139591480104, 'foot_width': 10.517559302220823, 'heel_to_big_toe_joint': 17.79056941413973, 'toe_gap_to_toe_tip': 4.8011554824941385, 'toe_gap_to_medial_edge': 9.332221084012573}, 'trace': {'scanId': 'e50de295-1965-4558-9d85-1f9e29ff366c', 'status': 'COMPLETED', 'startedAt': '2026-06-14T17:27:00.642043+00:00', 'finishedAt': '2026-06-14T17:27:17.839508+00:00', 'processingTimeMs': 17197.467, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.3 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '34.4 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.9 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.9 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '28.4 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.8 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7582.0 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.284, 'convert_to_cm_ms': 0.05, 'preprocess_ms': 34.383, 'initial_landmarks_ms': 3.895, 'normalize_x_ms': 0.107, 'medial_alignment_ms': 1.854, 'zehensteg_detection_ms': 28.392, 'metrics_ms': 0.803, 'quality_validation_ms': 0.012, 'save_json_ms': 0.544, 'debug_plots_ms': 7581.975, 'total_ms': 7658.305}}}
2026-06-14 17:27:17 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 17:50:45 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/stefan_huber_right_20260614175039240.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T175045Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=ed8d75ad63c63e3659e73ed6b746fca345bcfe446363f2149fe0611528522b5a
2026-06-14 17:50:45 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 17:50:45 - foot_measurements.api - WARNING - DEBUG job_id: 81a1ca93-c58e-4848-ba37-dfc09245cf90
2026-06-14 17:51:03 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '81a1ca93-c58e-4848-ba37-dfc09245cf90', 'status': 'COMPLETED', 'result': {'foot_length': 26.161801734789037, 'foot_width': 10.658636915410252, 'heel_to_big_toe_joint': 19.65495707603011, 'toe_gap_to_toe_tip': 5.203366776969283, 'toe_gap_to_medial_edge': 3.175674873513807}, 'trace': {'scanId': '81a1ca93-c58e-4848-ba37-dfc09245cf90', 'status': 'COMPLETED', 'startedAt': '2026-06-14T17:50:45.838833+00:00', 'finishedAt': '2026-06-14T17:51:03.106749+00:00', 'processingTimeMs': 17267.917, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.0 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '27.7 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.9 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.8 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '30.8 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.8 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7760.4 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.028, 'convert_to_cm_ms': 0.037, 'preprocess_ms': 27.696, 'initial_landmarks_ms': 3.856, 'normalize_x_ms': 0.083, 'medial_alignment_ms': 1.788, 'zehensteg_detection_ms': 30.808, 'metrics_ms': 0.834, 'quality_validation_ms': 0.008, 'save_json_ms': 0.454, 'debug_plots_ms': 7760.45, 'total_ms': 7832.049}}}
2026-06-14 17:51:03 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 18:07:49 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/stefan2_huber_right_20260614180743963.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T180749Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=3898d211453484ec57b7286a56b6c4bf48bb359f2a3602bc87ec79e698d3ec4f
2026-06-14 18:07:49 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 18:07:49 - foot_measurements.api - WARNING - DEBUG job_id: 18fda972-2a2f-4857-b33a-91e927915631
2026-06-14 18:08:06 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '18fda972-2a2f-4857-b33a-91e927915631', 'status': 'COMPLETED', 'result': {'foot_length': 25.353262991089043, 'foot_width': 11.012725051033353, 'heel_to_big_toe_joint': 18.048556650420533, 'toe_gap_to_toe_tip': 4.562151175847422, 'toe_gap_to_medial_edge': 7.873236032265483}, 'trace': {'scanId': '18fda972-2a2f-4857-b33a-91e927915631', 'status': 'COMPLETED', 'startedAt': '2026-06-14T18:07:49.401742+00:00', 'finishedAt': '2026-06-14T18:08:06.099462+00:00', 'processingTimeMs': 16697.72, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.1 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '30.9 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.9 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.8 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '1.8 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.8 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7471.0 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.141, 'convert_to_cm_ms': 0.04, 'preprocess_ms': 30.896, 'initial_landmarks_ms': 3.865, 'normalize_x_ms': 0.082, 'medial_alignment_ms': 1.83, 'zehensteg_detection_ms': 1.839, 'metrics_ms': 0.793, 'quality_validation_ms': 0.009, 'save_json_ms': 0.489, 'debug_plots_ms': 7471.037, 'total_ms': 7517.027}}}
2026-06-14 18:08:06 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 18:17:10 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/stefan_huber_left_20260614181702968.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T181710Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=73bc57bb5828b82a61afe816806f9988edfe0e0f7b7c63b25cbc90a8688ea552
2026-06-14 18:17:10 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 18:17:10 - foot_measurements.api - WARNING - DEBUG job_id: 94c261ad-2bdc-4dbb-94d1-f6b979d79259
2026-06-14 18:17:27 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '94c261ad-2bdc-4dbb-94d1-f6b979d79259', 'status': 'COMPLETED', 'result': {'foot_length': 25.258310206174563, 'foot_width': 10.705883363397856, 'heel_to_big_toe_joint': 19.92498999209249, 'toe_gap_to_toe_tip': 5.34395557053325, 'toe_gap_to_medial_edge': 7.001945709487278}, 'trace': {'scanId': '94c261ad-2bdc-4dbb-94d1-f6b979d79259', 'status': 'COMPLETED', 'startedAt': '2026-06-14T18:17:10.350986+00:00', 'finishedAt': '2026-06-14T18:17:27.378892+00:00', 'processingTimeMs': 17027.909, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.2 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '30.0 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '4.2 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.8 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '9.1 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.8 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.6 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7623.6 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.241, 'convert_to_cm_ms': 0.095, 'preprocess_ms': 30.042, 'initial_landmarks_ms': 4.16, 'normalize_x_ms': 0.107, 'medial_alignment_ms': 1.839, 'zehensteg_detection_ms': 9.13, 'metrics_ms': 0.794, 'quality_validation_ms': 0.011, 'save_json_ms': 0.568, 'debug_plots_ms': 7623.614, 'total_ms': 7676.607}}}
2026-06-14 18:17:27 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 18:32:20 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/stefan2_huber_left_20260614183217207.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260614T183220Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260614%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=b7f843dff96f6b7ced5e9d573736c462dff0648c0349e30c202a25e978848fe7
2026-06-14 18:32:20 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-14 18:32:20 - foot_measurements.api - WARNING - DEBUG job_id: 57b64918-6faf-4014-8cbf-a874e276168a
2026-06-14 18:32:37 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '57b64918-6faf-4014-8cbf-a874e276168a', 'status': 'COMPLETED', 'result': {'foot_length': 26.23743467054122, 'foot_width': 10.875798397051412, 'heel_to_big_toe_joint': 20.899588491111317, 'toe_gap_to_toe_tip': 5.451867098984387, 'toe_gap_to_medial_edge': 7.06493184602026}, 'trace': {'scanId': '57b64918-6faf-4014-8cbf-a874e276168a', 'status': 'COMPLETED', 'startedAt': '2026-06-14T18:32:20.210986+00:00', 'finishedAt': '2026-06-14T18:32:37.398195+00:00', 'processingTimeMs': 17187.211, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.2 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '30.2 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '4.1 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.9 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '2.5 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.8 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7786.5 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.151, 'convert_to_cm_ms': 0.055, 'preprocess_ms': 30.187, 'initial_landmarks_ms': 4.065, 'normalize_x_ms': 0.108, 'medial_alignment_ms': 1.856, 'zehensteg_detection_ms': 2.462, 'metrics_ms': 0.792, 'quality_validation_ms': 0.011, 'save_json_ms': 0.504, 'debug_plots_ms': 7786.473, 'total_ms': 7832.673}}}
2026-06-14 18:32:37 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-15 19:51:23 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/herbert2_reiter_right_20260615195115579.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260615T195122Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260615%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=0df2b15ab521c6bf315219fb891d210340399a8077a37b1d50cb490afff8dc11
2026-06-15 19:51:23 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-15 19:51:23 - foot_measurements.api - WARNING - DEBUG job_id: 9a11de3c-e848-44f8-9773-860a7383ec21
2026-06-15 19:51:40 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '9a11de3c-e848-44f8-9773-860a7383ec21', 'status': 'COMPLETED', 'result': {'foot_length': 26.94455252165719, 'foot_width': 10.379634149714434, 'heel_to_big_toe_joint': 18.85728579394598, 'toe_gap_to_toe_tip': 5.007121569729442, 'toe_gap_to_medial_edge': 2.9296977163936258}, 'trace': {'scanId': '9a11de3c-e848-44f8-9773-860a7383ec21', 'status': 'COMPLETED', 'startedAt': '2026-06-15T19:51:23.008263+00:00', 'finishedAt': '2026-06-15T19:51:40.169527+00:00', 'processingTimeMs': 17161.26, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.1 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '29.3 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.9 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.7 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '11.8 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.8 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7726.1 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.125, 'convert_to_cm_ms': 0.042, 'preprocess_ms': 29.322, 'initial_landmarks_ms': 3.942, 'normalize_x_ms': 0.083, 'medial_alignment_ms': 1.703, 'zehensteg_detection_ms': 11.82, 'metrics_ms': 0.754, 'quality_validation_ms': 0.009, 'save_json_ms': 0.513, 'debug_plots_ms': 7726.116, 'total_ms': 7780.436}}}
2026-06-15 19:51:40 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-15 19:58:06 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/herbert2_reiter_left_20260615195758802.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260615T195806Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260615%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=1d131d4bf0d3e16faca643747375c34cbbbd464ae1bcad14a56e846aee29d390
2026-06-15 19:58:06 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-15 19:58:06 - foot_measurements.api - WARNING - DEBUG job_id: d36bd7e7-ffea-4eee-9d0d-ebd25f276177
2026-06-15 19:58:22 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': 'd36bd7e7-ffea-4eee-9d0d-ebd25f276177', 'status': 'COMPLETED', 'result': {'foot_length': 26.77261048106412, 'foot_width': 11.12426466137508, 'heel_to_big_toe_joint': 18.770254971009884, 'toe_gap_to_toe_tip': 5.373211571459301, 'toe_gap_to_medial_edge': 10.053115231075818}, 'trace': {'scanId': 'd36bd7e7-ffea-4eee-9d0d-ebd25f276177', 'status': 'COMPLETED', 'startedAt': '2026-06-15T19:58:06.056162+00:00', 'finishedAt': '2026-06-15T19:58:22.913656+00:00', 'processingTimeMs': 16857.495, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.1 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '31.4 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '4.3 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '2.0 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '35.0 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.9 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7691.7 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.116, 'convert_to_cm_ms': 0.048, 'preprocess_ms': 31.45, 'initial_landmarks_ms': 4.319, 'normalize_x_ms': 0.108, 'medial_alignment_ms': 2.001, 'zehensteg_detection_ms': 34.952, 'metrics_ms': 0.874, 'quality_validation_ms': 0.009, 'save_json_ms': 0.477, 'debug_plots_ms': 7691.652, 'total_ms': 7772.012}}}
2026-06-15 19:58:22 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-15 20:12:08 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/luisa2_wimmer_right_20260615201201184.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260615T201208Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260615%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=a24e62e7aabec3bbf8e2bbc73766569fc8f4185f1cc8e49fa2204d604988dbef
2026-06-15 20:12:08 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-15 20:12:08 - foot_measurements.api - WARNING - DEBUG job_id: 1653b72e-b295-48ac-bd7a-5831eef74d01
2026-06-15 20:12:25 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '1653b72e-b295-48ac-bd7a-5831eef74d01', 'status': 'COMPLETED', 'result': {'foot_length': 10.393009997231268, 'foot_width': 6.868583511854463, 'heel_to_big_toe_joint': 9.00649643271582, 'toe_gap_to_toe_tip': -11.122557959311067, 'toe_gap_to_medial_edge': 0.9965115664875559}, 'trace': {'scanId': '1653b72e-b295-48ac-bd7a-5831eef74d01', 'status': 'COMPLETED', 'startedAt': '2026-06-15T20:12:08.650718+00:00', 'finishedAt': '2026-06-15T20:12:25.218040+00:00', 'processingTimeMs': 16567.323, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.2 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '20.2 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.6 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.2 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '41.4 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7359.4 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.232, 'convert_to_cm_ms': 0.046, 'preprocess_ms': 20.227, 'initial_landmarks_ms': 3.564, 'normalize_x_ms': 0.08, 'medial_alignment_ms': 1.246, 'zehensteg_detection_ms': 41.41, 'metrics_ms': 0.514, 'quality_validation_ms': 0.009, 'save_json_ms': 0.465, 'debug_plots_ms': 7359.441, 'total_ms': 7433.247}}}
2026-06-15 20:12:25 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-15 20:21:17 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/luisa2_wimmer_left_20260615202111056.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260615T202117Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260615%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=2c02f26d4b897409b622ccf5687667851072fdcad20a3faa69dbd19edb820da3
2026-06-15 20:21:17 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-15 20:21:17 - foot_measurements.api - WARNING - DEBUG job_id: 3d403c5b-50c7-44ac-97be-31bcfde98529
2026-06-15 20:21:32 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '3d403c5b-50c7-44ac-97be-31bcfde98529', 'status': 'COMPLETED', 'result': {'foot_length': 24.83913612116245, 'foot_width': 10.423396095307375, 'heel_to_big_toe_joint': 19.804791133846802, 'toe_gap_to_toe_tip': 4.838600724233977, 'toe_gap_to_medial_edge': 7.209012217683128}, 'trace': {'scanId': '3d403c5b-50c7-44ac-97be-31bcfde98529', 'status': 'COMPLETED', 'startedAt': '2026-06-15T20:21:17.091579+00:00', 'finishedAt': '2026-06-15T20:21:32.737336+00:00', 'processingTimeMs': 15645.755, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '4.5 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '25.4 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.9 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.6 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '30.2 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.7 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '6911.2 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 4.49, 'convert_to_cm_ms': 0.048, 'preprocess_ms': 25.35, 'initial_landmarks_ms': 3.857, 'normalize_x_ms': 0.123, 'medial_alignment_ms': 1.63, 'zehensteg_detection_ms': 30.231, 'metrics_ms': 0.667, 'quality_validation_ms': 0.011, 'save_json_ms': 0.479, 'debug_plots_ms': 6911.186, 'total_ms': 6978.079}}}
2026-06-15 20:21:32 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-17 15:37:06 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/flipping_imuhrzeigersinn_right_20260617153658011.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260617T153706Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260617%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=bc6a5ceee1bc702719ac9751b3d9a2c7111e5106134a82644384154d3b827f20
2026-06-17 15:37:06 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-17 15:37:06 - foot_measurements.api - WARNING - DEBUG job_id: 4581086a-8c58-4a49-8b2e-b670b0ab6a97
2026-06-17 15:37:22 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '4581086a-8c58-4a49-8b2e-b670b0ab6a97', 'status': 'COMPLETED', 'result': {'foot_length': 26.98873059651357, 'foot_width': 10.64619160437031, 'heel_to_big_toe_joint': 20.035925341556812, 'toe_gap_to_toe_tip': 4.834933828948273, 'toe_gap_to_medial_edge': 3.2134577866433305}, 'trace': {'scanId': '4581086a-8c58-4a49-8b2e-b670b0ab6a97', 'status': 'COMPLETED', 'startedAt': '2026-06-17T15:37:06.369566+00:00', 'finishedAt': '2026-06-17T15:37:22.704213+00:00', 'processingTimeMs': 16334.647, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '4.9 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '21.9 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.5 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.4 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '13.6 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.6 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7088.6 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 4.859, 'convert_to_cm_ms': 0.062, 'preprocess_ms': 21.895, 'initial_landmarks_ms': 3.457, 'normalize_x_ms': 0.1, 'medial_alignment_ms': 1.449, 'zehensteg_detection_ms': 13.559, 'metrics_ms': 0.616, 'quality_validation_ms': 0.009, 'save_json_ms': 0.48, 'debug_plots_ms': 7088.65, 'total_ms': 7135.142}}}
2026-06-17 15:37:22 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-17 15:46:10 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/flipping_gegenuhrzeigersinn_right_20260617154602102.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260617T154610Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260617%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=1e5be9e342e552b68c81405f49e2203d6d6eb593cc560580a4002a9aedfdc200
2026-06-17 15:46:10 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-17 15:46:10 - foot_measurements.api - WARNING - DEBUG job_id: 24a5cad7-352f-4f51-b4cc-3c7f379ac3d6
2026-06-17 15:46:27 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '24a5cad7-352f-4f51-b4cc-3c7f379ac3d6', 'status': 'COMPLETED', 'result': {'foot_length': 26.649849126211766, 'foot_width': 10.525665176202413, 'heel_to_big_toe_joint': 18.17162368570058, 'toe_gap_to_toe_tip': 4.71603125364096, 'toe_gap_to_medial_edge': 3.331431296152263}, 'trace': {'scanId': '24a5cad7-352f-4f51-b4cc-3c7f379ac3d6', 'status': 'COMPLETED', 'startedAt': '2026-06-17T15:46:10.291090+00:00', 'finishedAt': '2026-06-17T15:46:27.106274+00:00', 'processingTimeMs': 16815.183, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.2 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '27.9 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.8 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.7 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '4.7 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.7 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7635.8 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.183, 'convert_to_cm_ms': 0.043, 'preprocess_ms': 27.851, 'initial_landmarks_ms': 3.813, 'normalize_x_ms': 0.088, 'medial_alignment_ms': 1.707, 'zehensteg_detection_ms': 4.71, 'metrics_ms': 0.732, 'quality_validation_ms': 0.009, 'save_json_ms': 0.491, 'debug_plots_ms': 7635.78, 'total_ms': 7681.415}}}
2026-06-17 15:46:27 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-17 18:06:49 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/falsche_backendurl_left_20260617180648495.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260617T180649Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260617%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=ae6c3bf9cb564a4e9791f5a54314031fceebb227bc632144b408fcfb3ebb5803
2026-06-17 18:06:49 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-17 18:06:49 - foot_measurements.api - WARNING - DEBUG job_id: d32fd8c5-00bb-4935-b7ba-1031b6a580e3
2026-06-17 18:07:06 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': 'd32fd8c5-00bb-4935-b7ba-1031b6a580e3', 'status': 'COMPLETED', 'result': {'foot_length': 23.149641861405946, 'foot_width': 8.303980769615784, 'heel_to_big_toe_joint': 16.46393226137416, 'toe_gap_to_toe_tip': 4.624972983432134, 'toe_gap_to_medial_edge': 7.477842333871155}, 'trace': {'scanId': 'd32fd8c5-00bb-4935-b7ba-1031b6a580e3', 'status': 'COMPLETED', 'startedAt': '2026-06-17T18:06:49.776652+00:00', 'finishedAt': '2026-06-17T18:07:06.454245+00:00', 'processingTimeMs': 16677.594, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '6.3 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '30.7 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '4.1 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.9 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '21.8 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.9 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '7542.2 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 6.327, 'convert_to_cm_ms': 0.073, 'preprocess_ms': 30.704, 'initial_landmarks_ms': 4.097, 'normalize_x_ms': 0.107, 'medial_alignment_ms': 1.876, 'zehensteg_detection_ms': 21.794, 'metrics_ms': 0.892, 'quality_validation_ms': 0.011, 'save_json_ms': 0.502, 'debug_plots_ms': 7542.156, 'total_ms': 7608.547}}}
2026-06-17 18:07:06 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-17 18:12:55 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/keine_internetverbindung_left_20260617181254694.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260617T181255Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260617%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=01d2da36e930a9875a57e8578e1fc094d963fa257c7ea8abae9410a7500ffba6
2026-06-17 18:12:55 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-17 18:12:55 - foot_measurements.api - WARNING - DEBUG job_id: 4a307d97-462a-4699-95c4-212084f638ec
2026-06-17 18:13:10 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '4a307d97-462a-4699-95c4-212084f638ec', 'status': 'COMPLETED', 'result': {'foot_length': 22.483967351305385, 'foot_width': 7.891986909036866, 'heel_to_big_toe_joint': 15.022868125845081, 'toe_gap_to_toe_tip': 4.493505322316004, 'toe_gap_to_medial_edge': 4.1197563087295785}, 'trace': {'scanId': '4a307d97-462a-4699-95c4-212084f638ec', 'status': 'COMPLETED', 'startedAt': '2026-06-17T18:12:55.866433+00:00', 'finishedAt': '2026-06-17T18:13:10.850966+00:00', 'processingTimeMs': 14984.532, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '3.2 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '17.6 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.3 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.3 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '17.5 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '6051.6 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 3.16, 'convert_to_cm_ms': 0.046, 'preprocess_ms': 17.635, 'initial_landmarks_ms': 3.284, 'normalize_x_ms': 0.078, 'medial_alignment_ms': 1.322, 'zehensteg_detection_ms': 17.471, 'metrics_ms': 0.503, 'quality_validation_ms': 0.009, 'save_json_ms': 0.526, 'debug_plots_ms': 6051.568, 'total_ms': 6095.609}}}
2026-06-17 18:13:10 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-17 18:19:08 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/falsche_backenurl_left_20260617181907091.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260617T181908Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260617%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=4b22943686a3e3abe92aa33cb4ddbc5494a700f2f88e5c45f2a19e03529d9f4c
2026-06-17 18:19:08 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-17 18:19:08 - foot_measurements.api - WARNING - DEBUG job_id: c446b189-3728-4728-a9e6-93af80b92a0c
2026-06-17 18:19:23 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': 'c446b189-3728-4728-a9e6-93af80b92a0c', 'status': 'COMPLETED', 'result': {'foot_length': 21.94848889033405, 'foot_width': 9.719795064510457, 'heel_to_big_toe_joint': 16.978724044824148, 'toe_gap_to_toe_tip': 3.7861829627528287, 'toe_gap_to_medial_edge': 8.759013383937384}, 'trace': {'scanId': 'c446b189-3728-4728-a9e6-93af80b92a0c', 'status': 'COMPLETED', 'startedAt': '2026-06-17T18:19:08.385255+00:00', 'finishedAt': '2026-06-17T18:19:23.364064+00:00', 'processingTimeMs': 14978.804, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '3.0 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '16.9 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '3.0 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.3 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '19.1 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '6133.2 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 3.038, 'convert_to_cm_ms': 0.031, 'preprocess_ms': 16.928, 'initial_landmarks_ms': 3.034, 'normalize_x_ms': 0.062, 'medial_alignment_ms': 1.319, 'zehensteg_detection_ms': 19.092, 'metrics_ms': 0.51, 'quality_validation_ms': 0.008, 'save_json_ms': 0.495, 'debug_plots_ms': 6133.242, 'total_ms': 6177.766}}}
2026-06-17 18:19:23 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-17 18:26:59 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/internet_abbruch_left_20260617182658354.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260617T182659Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260617%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=2f257a0288e1162efd8f78136e8cd4031c8a28dbc6467a8ce6431f2f82daa99c
2026-06-17 18:26:59 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-17 18:26:59 - foot_measurements.api - WARNING - DEBUG job_id: 1bb62702-c82f-4d02-b360-dcdbd5268001
2026-06-17 18:27:14 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': '1bb62702-c82f-4d02-b360-dcdbd5268001', 'status': 'COMPLETED', 'result': {'foot_length': 22.658598291670074, 'foot_width': 8.120428869544893, 'heel_to_big_toe_joint': 15.342743454541521, 'toe_gap_to_toe_tip': 3.9272999915581863, 'toe_gap_to_medial_edge': 5.341928849266209}, 'trace': {'scanId': '1bb62702-c82f-4d02-b360-dcdbd5268001', 'status': 'COMPLETED', 'startedAt': '2026-06-17T18:26:59.476334+00:00', 'finishedAt': '2026-06-17T18:27:14.606097+00:00', 'processingTimeMs': 15129.765, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '3.1 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '15.9 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '2.9 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.1 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '1.3 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '17.2 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.4 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '6205.2 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 3.064, 'convert_to_cm_ms': 0.029, 'preprocess_ms': 15.921, 'initial_landmarks_ms': 2.891, 'normalize_x_ms': 0.058, 'medial_alignment_ms': 1.297, 'zehensteg_detection_ms': 17.176, 'metrics_ms': 0.524, 'quality_validation_ms': 0.008, 'save_json_ms': 0.441, 'debug_plots_ms': 6205.152, 'total_ms': 6246.567}}}
2026-06-17 18:27:14 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-18 10:38:27 - foot_measurements.api - WARNING - DEBUG full presigned source_url: https://ifootscan-312346757126-eu-central-1-an.s3.eu-central-1.amazonaws.com/raw/philipp_haas_left_20260618103826644.usdz?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Date=20260618T103827Z&X-Amz-SignedHeaders=host&X-Amz-Credential=AKIAUROKRJADN534GENL%2F20260618%2Feu-central-1%2Fs3%2Faws4_request&X-Amz-Expires=604800&X-Amz-Signature=5884c2fa4d2b4f1f160199daa5714f86cea710fa7fdd6445f91d1365cc945494
2026-06-18 10:38:27 - foot_measurements.api - WARNING - DEBUG callback_url: http://backend:8080/api/v1/scans/measurements/callback
2026-06-18 10:38:27 - foot_measurements.api - WARNING - DEBUG job_id: c5c7262c-7552-42fe-9e5f-c57c56010e39
2026-06-18 10:38:41 - foot_measurements.api - WARNING - CALLBACK payload (raw dict): {'jobId': 'c5c7262c-7552-42fe-9e5f-c57c56010e39', 'status': 'COMPLETED', 'result': {'foot_length': 21.843276787137384, 'foot_width': 14.372104248866338, 'heel_to_big_toe_joint': 16.882278159305464, 'toe_gap_to_toe_tip': 0.7686219081208705, 'toe_gap_to_medial_edge': 12.088412075562946}, 'trace': {'scanId': 'c5c7262c-7552-42fe-9e5f-c57c56010e39', 'status': 'COMPLETED', 'startedAt': '2026-06-18T10:38:27.724364+00:00', 'finishedAt': '2026-06-18T10:38:41.116548+00:00', 'processingTimeMs': 13392.186, 'error': None, 'steps': [{'source': 'WORKER', 'key': 'load_point_cloud_ms', 'label': 'Point cloud loaded', 'status': 'SUCCESS', 'detail': '1.2 ms'}, {'source': 'WORKER', 'key': 'convert_to_cm_ms', 'label': 'Point cloud converted to cm', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'preprocess_ms', 'label': 'Data cleaned', 'status': 'SUCCESS', 'detail': '6.2 ms'}, {'source': 'WORKER', 'key': 'initial_landmarks_ms', 'label': 'Initial landmarks determined', 'status': 'SUCCESS', 'detail': '2.3 ms'}, {'source': 'WORKER', 'key': 'normalize_x_ms', 'label': 'Foot normalized', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'medial_alignment_ms', 'label': 'Medial side determined', 'status': 'SUCCESS', 'detail': '0.8 ms'}, {'source': 'WORKER', 'key': 'zehensteg_detection_ms', 'label': 'Landmarks detected', 'status': 'SUCCESS', 'detail': '21.9 ms'}, {'source': 'WORKER', 'key': 'metrics_ms', 'label': 'Measurements calculated', 'status': 'SUCCESS', 'detail': '0.2 ms'}, {'source': 'WORKER', 'key': 'quality_validation_ms', 'label': 'Quality validation completed', 'status': 'SUCCESS', 'detail': '0.0 ms'}, {'source': 'WORKER', 'key': 'save_json_ms', 'label': 'Results stored', 'status': 'SUCCESS', 'detail': '0.5 ms'}, {'source': 'WORKER', 'key': 'debug_plots_ms', 'label': 'Debug plots generated', 'status': 'SUCCESS', 'detail': '5238.7 ms'}], 'workerProcessingTimesMs': {'load_point_cloud_ms': 1.168, 'convert_to_cm_ms': 0.044, 'preprocess_ms': 6.216, 'initial_landmarks_ms': 2.269, 'normalize_x_ms': 0.045, 'medial_alignment_ms': 0.838, 'zehensteg_detection_ms': 21.859, 'metrics_ms': 0.241, 'quality_validation_ms': 0.007, 'save_json_ms': 0.47, 'debug_plots_ms': 5238.666, 'total_ms': 5271.831}}}
2026-06-18 10:38:41 - foot_measurements.api - WARNING - CALLBACK url: http://backend:8080/api/v1/scans/measurements/callback