fix(app): Fix status codes of IDS responses #134

schoenenberg · 2025-04-09T04:37:35Z

What this PR changes/adds

Briefly describe WHAT your pr changes, which features it adds/modifies.

Adds status codes to IDS responses.

Why it does that

Briefly state why the change was necessary.

Further notes

List other areas of code that have changed but are not necessarily linked to the main feature. This could be method signature changes, package declarations, bugs that were encountered and were fixed inline, etc.

Linked Issue(s)

Closes # <-- insert Issue number if one exists

github-actions · 2025-04-09T04:40:49Z

Clearinghouse App Build report

Formatter report ("cargo fmt --check"):

Exit code: 0

No formatter warnings

Linter report ("cargo clippy"):

Exit code: 0

No linter warnings

Test log

Exit code: 0

Test log:

 Downloading crates ...
  Downloaded autocfg v0.1.8
  Downloaded groupable v0.2.0
  Downloaded chunked_transfer v0.3.1
warning: spurious network error (3 tries remaining): failed to get successful HTTP response from `https://static.crates.io/crates/unsafe-any/0.4.2/download` (146.75.30.137), got 502
body:
I/O error
warning: spurious network error (3 tries remaining): failed to get successful HTTP response from `https://static.crates.io/crates/tiny_http/0.6.4/download` (146.75.30.137), got 502
body:
I/O error
  Downloaded modifier v0.1.0
  Downloaded mime v0.2.6
warning: spurious network error (3 tries remaining): failed to get successful HTTP response from `https://static.crates.io/crates/indexmap/1.9.3/download` (146.75.30.137), got 502
body:
I/O error
  Downloaded serial_test_derive v3.2.0
  Downloaded unicase v1.4.2
  Downloaded traitobject v0.1.1
  Downloaded log v0.3.9
  Downloaded ascii v0.8.7
  Downloaded version_check v0.1.5
  Downloaded rand_hc v0.1.0
  Downloaded xattr v1.5.0
  Downloaded siphasher v0.2.3
  Downloaded twoway v0.1.8
  Downloaded url v1.7.2
  Downloaded nickel v0.11.0
  Downloaded testcontainers v0.23.3
  Downloaded hashbrown v0.12.3
  Downloaded testcontainers-modules v0.11.6
  Downloaded scc v2.3.3
  Downloaded hyper v0.10.16
  Downloaded serde_with v3.12.0
  Downloaded rand v0.6.5
  Downloaded bollard v0.18.1
  Downloaded multipart v0.18.0
  Downloaded idna v0.1.5
  Downloaded bollard-stubs v1.47.1-rc.27.3.1
  Downloaded tokio-tar v0.3.1
  Downloaded structmeta v0.3.0
  Downloaded rustls-native-certs v0.8.1
  Downloaded futures v0.3.31
  Downloaded time v0.1.45
  Downloaded serde_with_macros v3.12.0
  Downloaded sdd v3.0.8
  Downloaded buf_redux v0.8.4
  Downloaded rand_pcg v0.1.2
  Downloaded rand_os v0.1.3
  Downloaded rand_core v0.4.2
  Downloaded rand_core v0.3.1
  Downloaded mustache v0.9.0
  Downloaded iron v0.6.1
  Downloaded base64 v0.9.3
  Downloaded structmeta-derive v0.3.0
  Downloaded serial_test v3.2.0
  Downloaded serde_repr v0.1.20
  Downloaded safemem v0.3.3
  Downloaded rand_jitter v0.1.4
  Downloaded rand_isaac v0.1.1
  Downloaded rand_chacha v0.1.1
  Downloaded quick-error v1.2.3
  Downloaded mime_guess v1.8.8
  Downloaded filetime v0.2.25
  Downloaded percent-encoding v1.0.1
  Downloaded parse-display-derive v0.9.1
  Downloaded parse-display v0.9.1
  Downloaded num_cpus v1.16.0
  Downloaded language-tags v0.2.2
  Downloaded typemap v0.3.3
  Downloaded typeable v0.1.2
  Downloaded rand_xorshift v0.1.1
  Downloaded phf_generator v0.7.24
  Downloaded matches v0.1.10
  Downloaded hyperlocal v0.9.1
  Downloaded etcetera v0.8.0
  Downloaded docker_credential v1.3.1
  Downloaded phf_codegen v0.7.24
  Downloaded phf v0.7.24
  Downloaded plugin v0.2.6
  Downloaded phf_shared v0.7.24
  Downloaded indexmap v1.9.3
  Downloaded tiny_http v0.6.4
  Downloaded unsafe-any v0.4.2
   Compiling memchr v2.7.4
   Compiling futures-macro v0.3.31
   Compiling bitflags v2.9.0
   Compiling autocfg v0.1.8
   Compiling rand_core v0.4.2
   Compiling version_check v0.1.5
   Compiling unicase v1.4.2
   Compiling serde_json v1.0.140
   Compiling regex-syntax v0.8.5
   Compiling rustix v1.0.2
   Compiling rand_core v0.3.1
   Compiling linux-raw-sys v0.9.3
   Compiling futures-util v0.3.31
   Compiling url v2.5.4
   Compiling aho-corasick v1.1.3
   Compiling chrono v0.4.40
   Compiling hyper v1.6.0
   Compiling rand_chacha v0.1.1
   Compiling rand_pcg v0.1.2
   Compiling regex-automata v0.4.9
   Compiling hyper-util v0.1.10
   Compiling rand v0.6.5
   Compiling siphasher v0.2.3
   Compiling phf_shared v0.7.24
   Compiling openssl v0.10.71
   Compiling rand_isaac v0.1.1
   Compiling rand_xorshift v0.1.1
   Compiling rand_hc v0.1.0
   Compiling rand_jitter v0.1.4
   Compiling regex v1.11.1
   Compiling rand_os v0.1.3
   Compiling unicase v2.8.1
   Compiling mime_guess v2.0.5
   Compiling log v0.3.9
   Compiling traitobject v0.1.1
   Compiling matches v0.1.10
   Compiling idna v0.1.5
   Compiling native-tls v0.2.14
   Compiling phf_generator v0.7.24
   Compiling tower v0.5.2
   Compiling tempfile v3.19.0
   Compiling rand v0.8.5
   Compiling percent-encoding v1.0.1
   Compiling safemem v0.3.3
   Compiling url v1.7.2
   Compiling sqlx-core v0.8.3
   Compiling phf_codegen v0.7.24
   Compiling tokio-native-tls v0.3.1
   Compiling unsafe-any v0.4.2
   Compiling mime v0.2.6
   Compiling phf v0.7.24
   Compiling serde_with_macros v3.12.0
   Compiling structmeta-derive v0.3.0
   Compiling sqlx-postgres v0.8.3
   Compiling multer v3.1.0
   Compiling biscuit v0.7.0
   Compiling structmeta v0.3.0
   Compiling serde_with v3.12.0
   Compiling mime_guess v1.8.8
   Compiling typemap v0.3.3
   Compiling axum-core v0.5.0
   Compiling hyper-tls v0.6.0
   Compiling base64 v0.9.3
   Compiling sqlx-macros-core v0.8.3
   Compiling futures-executor v0.3.31
   Compiling tokio-rustls v0.26.2
   Compiling serde_repr v0.1.20
   Compiling time v0.1.45
   Compiling num_cpus v1.16.0
   Compiling language-tags v0.2.2
   Compiling typeable v0.1.2
   Compiling reqwest v0.12.14
   Compiling sqlx-macros v0.8.3
   Compiling hyper v0.10.16
   Compiling axum v0.8.1
   Compiling bollard-stubs v1.47.1-rc.27.3.1
   Compiling jsonwebtoken v9.3.1
   Compiling hyper-rustls v0.27.5
   Compiling futures v0.3.31
   Compiling ids-daps-cert v0.1.0 (https://github.com/ids-basecamp/ids-rs#2d9754b3)
   Compiling plugin v0.2.6
   Compiling parse-display-derive v0.9.1
   Compiling hyperlocal v0.9.1
   Compiling xattr v1.5.0
   Compiling rustls-native-certs v0.8.1
   Compiling filetime v0.2.25
   Compiling modifier v0.1.0
   Compiling tokio-tar v0.3.1
   Compiling parse-display v0.9.1
   Compiling tracing-subscriber v0.3.19
   Compiling sqlx v0.8.3
   Compiling ids-daps-client v0.3.0 (https://github.com/ids-basecamp/ids-rs#2d9754b3)
   Compiling axum-extra v0.10.0
   Compiling mustache v0.9.0
   Compiling docker_credential v1.3.1
   Compiling etcetera v0.8.0
   Compiling sdd v3.0.8
   Compiling groupable v0.2.0
   Compiling ascii v0.8.7
   Compiling chunked_transfer v0.3.1
   Compiling scc v2.3.3
   Compiling tiny_http v0.6.4
   Compiling bollard v0.18.1
   Compiling nickel v0.11.0
   Compiling iron v0.6.1
   Compiling buf_redux v0.8.4
   Compiling twoway v0.1.8
   Compiling serial_test_derive v3.2.0
   Compiling quick-error v1.2.3
   Compiling multipart v0.18.0
   Compiling clearing-house-app v2.0.0 (/home/runner/work/clearinghouse/clearinghouse/clearing-house-app)
   Compiling serial_test v3.2.0
   Compiling testcontainers v0.23.3
   Compiling testcontainers-modules v0.11.6
    Finished `test` profile [unoptimized + debuginfo] target(s) in 53.67s
warning: the following packages contain code that will be rejected by a future version of Rust: buf_redux v0.8.4, multipart v0.18.0, typemap v0.3.3
note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 1`
     Running unittests src/lib.rs (target/debug/deps/clearing_house_app-6f17520c274974b3)

running 5 tests
test model::test::parse_date ... ok
test model::test::validate_and_sanitize_dates ... ok
test util::test::test_new_uuid ... ok
test config::test::test_read_config_from_env ... ok
test config::test::test_read_config_from_toml ... ok

test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s

     Running unittests src/main.rs (target/debug/deps/clearing_house_app-f8ee443d6c3c6d8a)

running 0 tests

test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s

     Running tests/create_process.rs (target/debug/deps/create_process-1c4b6aad5a513430)

running 1 test
2025-04-09T04:40:05.228621Z  INFO clearing_house_app: Config read successfully! Initializing application ...
2025-04-09T04:40:05.231032Z  INFO clearing_house_app: Connecting to database
2025-04-09T04:40:05.237919Z  INFO clearing_house_app: Migrating database
2025-04-09T04:40:05.239025Z DEBUG sqlx::query: summary="SELECT current_database()" db.statement="" rows_affected=0 rows_returned=1 elapsed=824.626µs elapsed_secs=0.000824626
2025-04-09T04:40:05.239559Z DEBUG sqlx::query: summary="SELECT pg_advisory_lock($1)" db.statement="" rows_affected=1 rows_returned=1 elapsed=457.801µs elapsed_secs=0.000457801
2025-04-09T04:40:05.244553Z DEBUG sqlx::query: summary="CREATE TABLE IF NOT …" db.statement="\n\n\nCREATE TABLE IF NOT EXISTS _sqlx_migrations (\n    version BIGINT PRIMARY KEY,\n    description TEXT NOT NULL,\n    installed_on TIMESTAMPTZ NOT NULL DEFAULT now(),\n    success BOOLEAN NOT NULL,\n    checksum BYTEA NOT NULL,\n    execution_time BIGINT NOT NULL\n);\n                \n" rows_affected=0 rows_returned=0 elapsed=4.910082ms elapsed_secs=0.004910082
2025-04-09T04:40:05.245656Z DEBUG sqlx::query: summary="SELECT version FROM _sqlx_migrations …" db.statement="\n\nSELECT version FROM _sqlx_migrations WHERE success = false ORDER BY version LIMIT 1\n" rows_affected=0 rows_returned=0 elapsed=1.033322ms elapsed_secs=0.001033322
2025-04-09T04:40:05.246195Z DEBUG sqlx::query: summary="SELECT version, checksum FROM …" db.statement="\n\nSELECT version, checksum FROM _sqlx_migrations ORDER BY version\n" rows_affected=0 rows_returned=0 elapsed=475.555µs elapsed_secs=0.000475555
2025-04-09T04:40:05.260996Z DEBUG sqlx::query: summary="-- Add up migration …" db.statement="\n\n-- Add up migration script here\nCREATE TABLE processes\n(\n    id         SERIAL PRIMARY KEY,\n    process_id VARCHAR UNIQUE NOT NULL,\n    created_at TIMESTAMPTZ DEFAULT NOW()\n);\n\nCREATE INDEX idx_processes_process_id ON processes (process_id);\n\nCREATE TABLE clients\n(\n    id         SERIAL PRIMARY KEY,\n    client_id  VARCHAR UNIQUE NOT NULL,\n    created_at TIMESTAMPTZ DEFAULT NOW()\n);\n\nCREATE INDEX idx_clients_client_id ON clients (client_id);\n\nCREATE TABLE process_owners\n(\n    process_id INTEGER NOT NULL REFERENCES processes (id),\n    client_id  INTEGER NOT NULL REFERENCES clients (id),\n    PRIMARY KEY (process_id, client_id)\n);\n\nCREATE TABLE documents\n(\n    id                  UUID PRIMARY KEY,\n    process_id          INTEGER   NOT NULL REFERENCES processes (id),\n    created_at          TIMESTAMP NOT NULL,\n    model_version       VARCHAR   NOT NULL,\n    correlation_message VARCHAR,\n    transfer_contract   VARCHAR,\n    issued              JSONB,\n    issuer_connector    JSONB     NOT NULL,\n    content_version     VARCHAR,\n    recipient_connector JSONB,\n    sender_agent        VARCHAR,\n    recipient_agent     JSONB,\n    payload             BYTEA,\n    payload_type        VARCHAR,\n    message_id          VARCHAR\n);\n\n" rows_affected=0 rows_returned=0 elapsed=14.525993ms elapsed_secs=0.014525993
2025-04-09T04:40:05.261786Z DEBUG sqlx::query: summary="INSERT INTO _sqlx_migrations ( …" db.statement="\n\n\n    INSERT INTO _sqlx_migrations ( version, description, success, checksum, execution_time )\n    VALUES ( $1, $2, TRUE, $3, -1 )\n                \n" rows_affected=1 rows_returned=0 elapsed=671.69µs elapsed_secs=0.00067169
2025-04-09T04:40:05.262399Z DEBUG sqlx::query: summary="COMMIT" db.statement="" rows_affected=0 rows_returned=0 elapsed=540.435µs elapsed_secs=0.000540435
2025-04-09T04:40:05.263286Z DEBUG sqlx::query: summary="UPDATE _sqlx_migrations SET execution_time …" db.statement="\n\n\n    UPDATE _sqlx_migrations\n    SET execution_time = $1\n    WHERE version = $2\n                \n" rows_affected=1 rows_returned=0 elapsed=824.083µs elapsed_secs=0.000824083
2025-04-09T04:40:05.263541Z DEBUG sqlx::query: summary="SELECT current_database()" db.statement="" rows_affected=0 rows_returned=1 elapsed=209.309µs elapsed_secs=0.000209309
2025-04-09T04:40:05.264021Z DEBUG sqlx::query: summary="SELECT pg_advisory_unlock($1)" db.statement="" rows_affected=1 rows_returned=1 elapsed=421.294µs elapsed_secs=0.000421294
2025-04-09T04:40:05.264077Z TRACE clearing_house_app: Initializing Process store
2025-04-09T04:40:05.264087Z TRACE clearing_house_app: Initializing Document store
2025-04-09T04:40:05.264094Z TRACE clearing_house_app: Initializing services
2025-04-09T04:40:05.310874Z DEBUG ids_daps_client: Issued TokenRequest (requestDAT): eyJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJAdHlwZSI6ImlkczpEYXRSZXF1ZXN0VG9rZW4iLCJAY29udGV4dCI6Imh0dHBzOi8vdzNpZC5vcmcvaWRzYS9jb250ZXh0cy9jb250ZXh0Lmpzb25sZCIsImlzcyI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1Iiwic3ViIjoiNjU6NTU6Q0U6MzI6Nzk6QjQ6MUE6QkQ6MjM6OTE6RDE6Mjc6NEE6Q0U6MDU6QkM6MEE6RDk6OTI6RTU6a2V5aWQ6NjU6NTU6Q0U6MzI6Nzk6QjQ6MUE6QkQ6MjM6OTE6RDE6Mjc6NEE6Q0U6MDU6QkM6MEE6RDk6OTI6RTUiLCJpZCI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1IiwianRpIjoiZDQzMjgyYjYtOTFiZC00MDBkLWEyZjgtOWFlNTNkMmQwZTEzIiwiYXVkIjoiaWRzYzpJRFNfQ09OTkVDVE9SU19BTEwiLCJpYXQiOjE3NDQxNzM2MDUsImV4cCI6MTc0NDE3NzIwNSwibmJmIjoxNzQ0MTczNjA1fQ.SrnaWBuuq77UdAUMvJAQutq73wZl1ANCMgiNzVhdv3O8xk0YtrOeh_NcWrhMr9XqLHoGi4YWCzELq0M-JsTZDdkKzOAiOE1207liKDqChFt8RGXC6ZXUkOVkPwnPYfyqUgaQAo9i19bcqi3PEhr1zQJ3vpkuD3dfov4LxZq9ftvGD9J22sOKdR4QSQOuHTL-BXMyMpZXLC4EvATJjTZofB3Sn4fCcNgSvh5d3lhO2bTkBKwCa44IUvc22oKQAMZUURHTn5BBxxxj5CLndxsaLyR9SEUmj0JuR6H0mZaQbqy23DIh9kiMLsu1GgYo6T58O6k37nLPBK9C_jbJKdquzQ
2025-04-09T04:40:05.311126Z DEBUG reqwest::connect: starting new connection: http://localhost:32768/    
2025-04-09T04:40:05.377508Z TRACE create_process::common: Payload: [123, 10, 32, 32, 34, 111, 119, 110, 101, 114, 115, 34, 58, 32, 91, 10, 32, 32, 32, 32, 34, 54, 53, 58, 53, 53, 58, 67, 69, 58, 51, 50, 58, 55, 57, 58, 66, 52, 58, 49, 65, 58, 66, 68, 58, 50, 51, 58, 57, 49, 58, 68, 49, 58, 50, 55, 58, 52, 65, 58, 67, 69, 58, 48, 53, 58, 66, 67, 58, 48, 65, 58, 68, 57, 58, 57, 50, 58, 69, 53, 58, 107, 101, 121, 105, 100, 58, 54, 53, 58, 53, 53, 58, 67, 69, 58, 51, 50, 58, 55, 57, 58, 66, 52, 58, 49, 65, 58, 66, 68, 58, 50, 51, 58, 57, 49, 58, 68, 49, 58, 50, 55, 58, 52, 65, 58, 67, 69, 58, 48, 53, 58, 66, 67, 58, 48, 65, 58, 68, 57, 58, 57, 50, 58, 69, 53, 34, 10, 32, 32, 93, 10, 125]
2025-04-09T04:40:05.377975Z DEBUG clearing_house_app::model::claims: Length of `header` is 2207 bytes
2025-04-09T04:40:05.378029Z DEBUG clearing_house_app::model::claims: Length of `payload` is 153 bytes
2025-04-09T04:40:05.378192Z TRACE clearing_house_app::model::claims: Header: IdsHeader {
    context: Some(
        {
            "idsc": "https://w3id.org/idsa/code/",
            "ids": "https://w3id.org/idsa/core/",
        },
    ),
    type_message: RequestMessage,
    id: Some(
        "c3bc9ecb-45bb-412d-8c27-3e5a54f752b0",
    ),
    pid: None,
    model_version: "test",
    correlation_message: None,
    issued: ComplexTime(
        InfoModelTimeStamp {
            format: Some(
                "http://www.w3.org/2001/XMLSchema#dateTimeStamp",
            ),
            value: 2025-04-09T04:40:05.333142802+00:00,
        },
    ),
    issuer_connector: SimpleId(
        "test-connector",
    ),
    sender_agent: SimpleId(
        "https://w3id.org/idsa/core/ClearingHouse",
    ),
    recipient_connector: None,
    recipient_agent: None,
    transfer_contract: None,
    content_version: None,
    security_token: Some(
        SecurityToken {
            type_message: DAPSToken,
            id: Some(
                "https://w3id.org/idsa/autogen/dynamicAttributeToken/ca7b458a-76d4-451c-9dac-83958396629d",
            ),
            token_format: Some(
                ComplexId(
                    InfoModelComplexId {
                        id: "https://w3id.org/idsa/code/JWT",
                    },
                ),
            ),
            token_value: "eyJ0eXAiOiJhdCtqd3QiLCJraWQiOiI4ZTEwMmM2MDQ5ZTcxYWY4NWExMWFlMmY4YTkyYjVmOWRmZDFhNjhiYjQ0Y2QzMzczMTk1MWE0NTY1ZTc0ZGY3IiwiYWxnIjoiUlMyNTYifQ.eyJzY29wZSI6Imlkc2M6SURTX0NPTk5FQ1RPUl9BVFRSSUJVVEVTX0FMTCIsImF1ZCI6WyJpZHNjOklEU19DT05ORUNUT1JTX0FMTCJdLCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjQ1NjciLCJzdWIiOiI2NTo1NTpDRTozMjo3OTpCNDoxQTpCRDoyMzo5MTpEMToyNzo0QTpDRTowNTpCQzowQTpEOTo5MjpFNTprZXlpZDo2NTo1NTpDRTozMjo3OTpCNDoxQTpCRDoyMzo5MTpEMToyNzo0QTpDRTowNTpCQzowQTpEOTo5MjpFNSIsIm5iZiI6MTc0NDE3MzYwNSwiaWF0IjoxNzQ0MTczNjA1LCJqdGkiOiJhNTU1MGQ3Mi01MDI5LTQ1NTYtODdiYy0xMDU1ZGRhMTllZDEiLCJleHAiOjE3NDQxNzcyMDUsImNsaWVudF9pZCI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1Iiwic2VjdXJpdHlQcm9maWxlIjoiaWRzYzpCQVNFX1NFQ1VSSVRZX1BST0ZJTEUiLCJyZWZlcnJpbmdDb25uZWN0b3IiOiJodHRwOi8vbG9jYWxob3N0IiwiQHR5cGUiOiJpZHM6RGF0UGF5bG9hZCIsIkBjb250ZXh0IjoiaHR0cHM6Ly93M2lkLm9yZy9pZHNhL2NvbnRleHRzL2NvbnRleHQuanNvbmxkIiwidHJhbnNwb3J0Q2VydHNTaGEyNTYiOiJhMmZhMzgyZTkyOGZiNGRlNDJhYjdmZmI4OGZhZjdjMTdlODgyYjQ3MThiZWEwZDkwMzcyMDk2Mjk3ZWI3Nzc0In0.Qsa_m011IIGXWyLORGZrx_Leugvz_p1fgkBMvqZzqtogc51fjAtSqicBuA9GyPzePmChsn35xhCCFsOuAkBXXUbgBxldhmRdpzI6F6vIsTvHoEkEHb8fAT096vTuyjEOkLuUOQXaInYvhJjvBOsX2MJ1o9ci5Jk3eEyWOJJMOxxoOtunA4FCJ2ubm1zmPmvo2Y3urew_Vd0Ha-vIQfss40ZX7BSLNw8lRFUJ7rlroZzgyHWnhjeQHfLZkkfwtgv2QnUg5bR8SViNNwXVDeCrYabETgI0Wn9Y8YbPpPsYHnuKMcpzVldLWfOf9Qad0AAW9qfj5-_rTuqhFA13b_vWeQ",
        },
    ),
    authorization_token: None,
}
2025-04-09T04:40:05.378250Z TRACE clearing_house_app::model::claims: Payload: b"{\n  \"owners\": [\n    \"65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5\"\n  ]\n}"
2025-04-09T04:40:05.378273Z DEBUG clearing_house_app::model::claims: Validating the DAPS Token ...
2025-04-09T04:40:05.378287Z DEBUG ids_daps_client: Checking cache...
2025-04-09T04:40:05.378303Z  INFO ids_daps_client: Cache is empty, updating...
2025-04-09T04:40:05.378440Z DEBUG reqwest::connect: starting new connection: http://localhost:32768/    
2025-04-09T04:40:05.386900Z DEBUG ids_daps_client: Validation result: Ok(TokenData { header: Header { typ: Some("at+jwt"), alg: RS256, cty: None, jku: None, jwk: None, kid: Some("8e102c6049e71af85a11ae2f8a92b5f9dfd1a68bb44cd33731951a4565e74df7"), x5u: None, x5c: None, x5t: None, x5t_s256: None }, claims: DatClaims { type_: "ids:DatPayload", context_: "https://w3id.org/idsa/contexts/context.jsonld", referring_connector: "http://localhost", security_profile: "idsc:BASE_SECURITY_PROFILE", issued_at: 1744173605, expires_at: 1744177205, not_before: 1744173605, subject: "65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5", audience: Multiple(["idsc:IDS_CONNECTORS_ALL"]), issuer: "http://localhost:4567", jwt_id: "a5550d72-5029-4556-87bc-1055dda19ed1" } })
2025-04-09T04:40:05.387875Z DEBUG ids_daps_client: Issued TokenRequest (requestDAT): eyJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJAdHlwZSI6ImlkczpEYXRSZXF1ZXN0VG9rZW4iLCJAY29udGV4dCI6Imh0dHBzOi8vdzNpZC5vcmcvaWRzYS9jb250ZXh0cy9jb250ZXh0Lmpzb25sZCIsImlzcyI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1Iiwic3ViIjoiNjU6NTU6Q0U6MzI6Nzk6QjQ6MUE6QkQ6MjM6OTE6RDE6Mjc6NEE6Q0U6MDU6QkM6MEE6RDk6OTI6RTU6a2V5aWQ6NjU6NTU6Q0U6MzI6Nzk6QjQ6MUE6QkQ6MjM6OTE6RDE6Mjc6NEE6Q0U6MDU6QkM6MEE6RDk6OTI6RTUiLCJpZCI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1IiwianRpIjoiZWYwMTQ5MDUtYmNjYy00YWQzLWI5ZjEtZjhiZDJhZTYyNjc4IiwiYXVkIjoiaWRzYzpJRFNfQ09OTkVDVE9SU19BTEwiLCJpYXQiOjE3NDQxNzM2MDUsImV4cCI6MTc0NDE3NzIwNSwibmJmIjoxNzQ0MTczNjA1fQ.Dz0vWCdMtZCaCM4gn4_5ClNUc3UuoLR0zjRGxCI2RnNnT4DFE04_eclRdv7DI_4S5OHApYyzECEm8FWO3V1QUHXB521DEivil-2UB7R-nhg6ZqQIAymhre8MXCS2gLW82y7H8KCLroXwCt-8m9r46rBno7MJoH4PHiSiYtqHYZWgtlE4wZo9EZG40roLP_89-OT1Ap5A1Xp2TfYXtN1lhUbubAXoe7BH-jlVsuYwHDD9QPSVWWRDk7CXQucJcXDMARJaAWUDcxBW6M8Fj8tZbuUg-rn5VozFhiPtPgMCEQbsRZOqOaN0MyDhyxPmRqGQXD7hMCi4hAoNZvfdhH-zMQ
2025-04-09T04:40:05.398067Z TRACE clearing_house_app::services::logging_service: ...user '"65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5"'
2025-04-09T04:40:05.398108Z TRACE clearing_house_app::services::logging_service: OwnerList: 'OwnerList {
    owners: [
        "65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5",
    ],
}'
2025-04-09T04:40:05.400455Z DEBUG sqlx::query: summary="SELECT p.process_id, p.created_at, ARRAY_AGG(c.client_id) …" db.statement="\n\nSELECT p.process_id, p.created_at, ARRAY_AGG(c.client_id) AS owners FROM processes p\n        LEFT JOIN process_owners po ON p.id = po.process_id\n        LEFT JOIN clients c ON po.client_id = c.id\n        WHERE p.process_id = $1\n        GROUP BY p.process_id, p.created_at\n" rows_affected=0 rows_returned=0 elapsed=1.984822ms elapsed_secs=0.001984822
2025-04-09T04:40:05.400514Z  INFO clearing_house_app::services::logging_service: Requested pid 'f0e6326a-aa67-4cf1-a96d-f7f69e5c09f0' by 65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5 does not exist and will have 2 owners (65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5, MDS_EDC_CONNECTOR). Creating...
2025-04-09T04:40:05.405082Z DEBUG sqlx::query: summary="INSERT INTO processes (process_id) …" db.statement="\n\nINSERT INTO processes (process_id) VALUES ($1) RETURNING id\n" rows_affected=0 rows_returned=1 elapsed=1.364759ms elapsed_secs=0.001364759
2025-04-09T04:40:05.407320Z DEBUG sqlx::query: summary="SELECT id FROM clients …" db.statement="\n\nSELECT id FROM clients WHERE client_id = $1\n" rows_affected=0 rows_returned=0 elapsed=2.160519ms elapsed_secs=0.002160519
2025-04-09T04:40:05.407970Z DEBUG sqlx::query: summary="INSERT INTO clients (client_id) …" db.statement="\n\nINSERT INTO clients (client_id) VALUES ($1) RETURNING id\n" rows_affected=0 rows_returned=1 elapsed=593.654µs elapsed_secs=0.000593654
2025-04-09T04:40:05.408865Z DEBUG sqlx::query: summary="INSERT INTO process_owners (process_id, …" db.statement="\n\nINSERT INTO process_owners (process_id, client_id) VALUES ($1, $2)\n" rows_affected=1 rows_returned=0 elapsed=827.329µs elapsed_secs=0.000827329
2025-04-09T04:40:05.409142Z DEBUG sqlx::query: summary="SELECT id FROM clients …" db.statement="\n\nSELECT id FROM clients WHERE client_id = $1\n" rows_affected=0 rows_returned=0 elapsed=216.542µs elapsed_secs=0.000216542
2025-04-09T04:40:05.409425Z DEBUG sqlx::query: summary="INSERT INTO clients (client_id) …" db.statement="\n\nINSERT INTO clients (client_id) VALUES ($1) RETURNING id\n" rows_affected=0 rows_returned=1 elapsed=233.334µs elapsed_secs=0.000233334
2025-04-09T04:40:05.409743Z DEBUG sqlx::query: summary="INSERT INTO process_owners (process_id, …" db.statement="\n\nINSERT INTO process_owners (process_id, client_id) VALUES ($1, $2)\n" rows_affected=1 rows_returned=0 elapsed=254.302µs elapsed_secs=0.000254302
2025-04-09T04:40:05.410132Z DEBUG sqlx::query: summary="COMMIT" db.statement="" rows_affected=0 rows_returned=0 elapsed=342.088µs elapsed_secs=0.000342088
2025-04-09T04:40:05.411333Z DEBUG ids_daps_client: Issued TokenRequest (requestDAT): eyJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJAdHlwZSI6ImlkczpEYXRSZXF1ZXN0VG9rZW4iLCJAY29udGV4dCI6Imh0dHBzOi8vdzNpZC5vcmcvaWRzYS9jb250ZXh0cy9jb250ZXh0Lmpzb25sZCIsImlzcyI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1Iiwic3ViIjoiNjU6NTU6Q0U6MzI6Nzk6QjQ6MUE6QkQ6MjM6OTE6RDE6Mjc6NEE6Q0U6MDU6QkM6MEE6RDk6OTI6RTU6a2V5aWQ6NjU6NTU6Q0U6MzI6Nzk6QjQ6MUE6QkQ6MjM6OTE6RDE6Mjc6NEE6Q0U6MDU6QkM6MEE6RDk6OTI6RTUiLCJpZCI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1IiwianRpIjoiZTU0NzY1NzgtZDFhMS00MjA3LWIwNjEtOTFlMmRkN2ZmYzA3IiwiYXVkIjoiaWRzYzpJRFNfQ09OTkVDVE9SU19BTEwiLCJpYXQiOjE3NDQxNzM2MDUsImV4cCI6MTc0NDE3NzIwNSwibmJmIjoxNzQ0MTczNjA1fQ.I7uzEcY0gE8rAy1tveSnOnZUFu-rvV4D_Yy1hb1cvBHjC95BV6SLWWjLh7frEmXlQZ69KmJjq-wf82lPGWkP6tG9L3Cntln9fEDkhbTfggk2a-jKDgL8hA_l7-1iQQ1rG3K4Tc4yj8Ic1luCm7CZrJnTw0zXaLBQ6v3dcXQrJKcp664c_Xz46KKOikGWmUPAaYyFuXS1XSWjBro1IGSyyrCRQwj0ditaPdDb9tDGSQi_gO3XegQyBjtG3YeSVSshHlNe7N26YZrCiLcDYw5LUyRK7JqLIql9nydKhWhDO9Wq2Z27RO5UpJ39-IYjcl65WOVESWXThdoAC9WB9jXe2A
2025-04-09T04:40:05.421184Z TRACE create_process::common: Payload: [123, 10, 32, 32, 34, 102, 111, 111, 34, 58, 32, 34, 72, 101, 108, 108, 111, 32, 87, 111, 114, 108, 100, 34, 44, 10, 32, 32, 34, 109, 115, 103, 34, 58, 32, 34, 77, 68, 83, 34, 10, 125]
2025-04-09T04:40:05.421546Z DEBUG clearing_house_app::model::claims: Length of `header` is 2203 bytes
2025-04-09T04:40:05.421598Z DEBUG clearing_house_app::model::claims: Length of `payload` is 42 bytes
2025-04-09T04:40:05.421726Z TRACE clearing_house_app::model::claims: Header: IdsHeader {
    context: Some(
        {
            "idsc": "https://w3id.org/idsa/code/",
            "ids": "https://w3id.org/idsa/core/",
        },
    ),
    type_message: LogMessage,
    id: Some(
        "c3bc9ecb-45bb-412d-8c27-3e5a54f752b0",
    ),
    pid: None,
    model_version: "test",
    correlation_message: None,
    issued: ComplexTime(
        InfoModelTimeStamp {
            format: Some(
                "http://www.w3.org/2001/XMLSchema#dateTimeStamp",
            ),
            value: 2025-04-09T04:40:05.421061848+00:00,
        },
    ),
    issuer_connector: SimpleId(
        "test-connector",
    ),
    sender_agent: SimpleId(
        "https://w3id.org/idsa/core/ClearingHouse",
    ),
    recipient_connector: None,
    recipient_agent: None,
    transfer_contract: None,
    content_version: None,
    security_token: Some(
        SecurityToken {
            type_message: DAPSToken,
            id: Some(
                "https://w3id.org/idsa/autogen/dynamicAttributeToken/a68fb5d9-c8fb-47bc-82d8-d0ff81b21f9e",
            ),
            token_format: Some(
                ComplexId(
                    InfoModelComplexId {
                        id: "https://w3id.org/idsa/code/JWT",
                    },
                ),
            ),
            token_value: "eyJ0eXAiOiJhdCtqd3QiLCJraWQiOiI4ZTEwMmM2MDQ5ZTcxYWY4NWExMWFlMmY4YTkyYjVmOWRmZDFhNjhiYjQ0Y2QzMzczMTk1MWE0NTY1ZTc0ZGY3IiwiYWxnIjoiUlMyNTYifQ.eyJzY29wZSI6Imlkc2M6SURTX0NPTk5FQ1RPUl9BVFRSSUJVVEVTX0FMTCIsImF1ZCI6WyJpZHNjOklEU19DT05ORUNUT1JTX0FMTCJdLCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjQ1NjciLCJzdWIiOiI2NTo1NTpDRTozMjo3OTpCNDoxQTpCRDoyMzo5MTpEMToyNzo0QTpDRTowNTpCQzowQTpEOTo5MjpFNTprZXlpZDo2NTo1NTpDRTozMjo3OTpCNDoxQTpCRDoyMzo5MTpEMToyNzo0QTpDRTowNTpCQzowQTpEOTo5MjpFNSIsIm5iZiI6MTc0NDE3MzYwNSwiaWF0IjoxNzQ0MTczNjA1LCJqdGkiOiI3MDQxODk0Ny1iYWY0LTRkODYtYWRiOS1kYmVhMDYxNTliYmQiLCJleHAiOjE3NDQxNzcyMDUsImNsaWVudF9pZCI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1Iiwic2VjdXJpdHlQcm9maWxlIjoiaWRzYzpCQVNFX1NFQ1VSSVRZX1BST0ZJTEUiLCJyZWZlcnJpbmdDb25uZWN0b3IiOiJodHRwOi8vbG9jYWxob3N0IiwiQHR5cGUiOiJpZHM6RGF0UGF5bG9hZCIsIkBjb250ZXh0IjoiaHR0cHM6Ly93M2lkLm9yZy9pZHNhL2NvbnRleHRzL2NvbnRleHQuanNvbmxkIiwidHJhbnNwb3J0Q2VydHNTaGEyNTYiOiJhMmZhMzgyZTkyOGZiNGRlNDJhYjdmZmI4OGZhZjdjMTdlODgyYjQ3MThiZWEwZDkwMzcyMDk2Mjk3ZWI3Nzc0In0.JLa7d-u_tcb38jxEyIOsIYmYtQN2iGAeAJKYlI-gf9nxKqlXc_qOL2pqL_zTGC9IHfpqP55o-bJ1yX0MMpFotUSwD14q76a9aFkRLsllwUKva0y-vORXOhhDZKYwiQ_pUqHO1X74NcAnd_jHNSonD4gFI6bAD35JUvcgp9dHKDzxxlkwrZ6CKrAzLr_zPuvBzOJ7nrIIYLwOPUcJSYv0Kh67zApT8iMPFMcUJbQ0AbS2waasy4KKSSk5SUOB9Z6UY9QvpFo6KnFmtUZtUY6qXgT8DEb1uWJQjPbvjm2SISQJ7RahukrV4XdTTAQ4uvEeW0qYp6MpSXmOabmbyr44GQ",
        },
    ),
    authorization_token: None,
}
2025-04-09T04:40:05.421771Z TRACE clearing_house_app::model::claims: Payload: b"{\n  \"foo\": \"Hello World\",\n  \"msg\": \"MDS\"\n}"
2025-04-09T04:40:05.421798Z DEBUG clearing_house_app::model::claims: Validating the DAPS Token ...
2025-04-09T04:40:05.421810Z DEBUG ids_daps_client: Checking cache...
2025-04-09T04:40:05.421837Z DEBUG ids_daps_client: Cache is up-to-date
2025-04-09T04:40:05.422091Z DEBUG ids_daps_client: Validation result: Ok(TokenData { header: Header { typ: Some("at+jwt"), alg: RS256, cty: None, jku: None, jwk: None, kid: Some("8e102c6049e71af85a11ae2f8a92b5f9dfd1a68bb44cd33731951a4565e74df7"), x5u: None, x5c: None, x5t: None, x5t_s256: None }, claims: DatClaims { type_: "ids:DatPayload", context_: "https://w3id.org/idsa/contexts/context.jsonld", referring_connector: "http://localhost", security_profile: "idsc:BASE_SECURITY_PROFILE", issued_at: 1744173605, expires_at: 1744177205, not_before: 1744173605, subject: "65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5", audience: Multiple(["idsc:IDS_CONNECTORS_ALL"]), issuer: "http://localhost:4567", jwt_id: "70418947-baf4-4d86-adb9-dbea06159bbd" } })
2025-04-09T04:40:05.423040Z DEBUG ids_daps_client: Issued TokenRequest (requestDAT): eyJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJAdHlwZSI6ImlkczpEYXRSZXF1ZXN0VG9rZW4iLCJAY29udGV4dCI6Imh0dHBzOi8vdzNpZC5vcmcvaWRzYS9jb250ZXh0cy9jb250ZXh0Lmpzb25sZCIsImlzcyI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1Iiwic3ViIjoiNjU6NTU6Q0U6MzI6Nzk6QjQ6MUE6QkQ6MjM6OTE6RDE6Mjc6NEE6Q0U6MDU6QkM6MEE6RDk6OTI6RTU6a2V5aWQ6NjU6NTU6Q0U6MzI6Nzk6QjQ6MUE6QkQ6MjM6OTE6RDE6Mjc6NEE6Q0U6MDU6QkM6MEE6RDk6OTI6RTUiLCJpZCI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1IiwianRpIjoiZmE3Yzk4NGYtODcyZi00MTM2LWI4YjMtNjQ1ZDAwYjBmZGNkIiwiYXVkIjoiaWRzYzpJRFNfQ09OTkVDVE9SU19BTEwiLCJpYXQiOjE3NDQxNzM2MDUsImV4cCI6MTc0NDE3NzIwNSwibmJmIjoxNzQ0MTczNjA1fQ.WcRTIvC2b463vmItXoBCKrqd3LyZs7_MnDMxxYX6SUII_2GowWam5GI2XARlpUPCijzkex6IaW1MOHaa16nHbi_j9vFZH5DzMqjRLN_qYA83SOVMQ-3T2LcC2zTgCZvMGAaPfJGlKNqeoj9h9RJJArMuaddi1_8cqSuERRSHym0h3sOBi6YTyHrIxWAo8diDlgh3n6k3vURuP6anaZ6BTxcuB7fAD0XHPw_OORg90r6gNx_uuLhLDqqHiDLLU2g7540rIJTQwbZJ9t5jlfnMssJdNdkpjBCtzZrTKLqhNpOoEZ12F4K1UropZPtk-YBIpjGAISaEajpQhKFVEowSJg
2025-04-09T04:40:05.432394Z TRACE clearing_house_app::services::logging_service: ...user '65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5'
2025-04-09T04:40:05.433429Z DEBUG sqlx::query: summary="SELECT p.process_id, p.created_at, ARRAY_AGG(c.client_id) …" db.statement="\n\nSELECT p.process_id, p.created_at, ARRAY_AGG(c.client_id) AS owners FROM processes p\n        LEFT JOIN process_owners po ON p.id = po.process_id\n        LEFT JOIN clients c ON po.client_id = c.id\n        WHERE p.process_id = $1\n        GROUP BY p.process_id, p.created_at\n" rows_affected=0 rows_returned=1 elapsed=703.509µs elapsed_secs=0.000703509
2025-04-09T04:40:05.433508Z  INFO clearing_house_app::services::logging_service: User '65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5' authorized for 'f0e6326a-aa67-4cf1-a96d-f7f69e5c09f0'
2025-04-09T04:40:05.433520Z DEBUG clearing_house_app::services::logging_service: transforming message to document...
2025-04-09T04:40:05.433546Z DEBUG clearing_house_app::services::logging_service: Storing document...
2025-04-09T04:40:05.433619Z TRACE create_enc_document: clearing_house_app::services::document_service: ...user '"65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5"'
2025-04-09T04:40:05.434622Z DEBUG create_enc_document: sqlx::query: summary="SELECT id FROM documents …" db.statement="\n\nSELECT id FROM documents WHERE id = $1\n" rows_affected=0 rows_returned=0 elapsed=773.238µs elapsed_secs=0.000773238
2025-04-09T04:40:05.434691Z TRACE create_enc_document: clearing_house_app::services::document_service: storing document ....
2025-04-09T04:40:05.436229Z DEBUG create_enc_document: sqlx::query: summary="INSERT INTO documents (id, …" db.statement="\n\nINSERT INTO documents\n        (id, process_id, created_at, model_version, correlation_message,\n        transfer_contract, issued, issuer_connector, content_version, recipient_connector,\n        sender_agent, recipient_agent, payload, payload_type, message_id)\n        VALUES\n        ($1, (SELECT id from processes where process_id = $2), $3, $4, $5,\n        $6, $7, $8, $9, $10,\n        $11, $12, $13, $14, $15)\n" rows_affected=1 rows_returned=0 elapsed=1.243802ms elapsed_secs=0.001243802
2025-04-09T04:40:05.436320Z DEBUG clearing_house_app::services::logging_service: Creating receipt...
2025-04-09T04:40:05.436365Z DEBUG clearing_house_app::services::logging_service: ...done. Signing receipt...
2025-04-09T04:40:05.437823Z TRACE create_process::common: Content-Type: Ok("multipart/form-data; boundary=ea8b856a4c93e045-59797ceee312202a-89bf482e5151e615-e8ea4ebb73fba934")
2025-04-09T04:40:05.437860Z TRACE create_process::common: Body: b"--ea8b856a4c93e045-59797ceee312202a-89bf482e5151e615-e8ea4ebb73fba934\r\nContent-Disposition: form-data; name=\"header\"\r\nContent-Type: application/json\r\n\r\n{\"@context\":{\"idsc\":\"https://w3id.org/idsa/code/\",\"ids\":\"https://w3id.org/idsa/core/\"},\"@type\":\"ids:MessageProcessedNotificationMessage\",\"@id\":\"https://w3id.org/idsa/autogen/MessageProcessedNotification/9104b792-78e0-4a2c-bf7e-c080ca291c15\",\"ids:modelVersion\":\"4.1.0\",\"ids:correlationMessage\":\"c3bc9ecb-45bb-412d-8c27-3e5a54f752b0\",\"ids:issued\":{\"@type\":\"http://www.w3.org/2001/XMLSchema#dateTimeStamp\",\"@value\":\"2025-04-09T04:40:05.437673768Z\"},\"ids:issuerConnector\":\"http://localhost:8080\",\"ids:senderAgent\":\"https://w3id.org/idsa/core/ClearingHouse\",\"ids:securityToken\":{\"@type\":\"ids:DynamicAttributeToken\",\"ids:tokenFormat\":\"https://w3id.org/idsa/code/token/JWT\",\"ids:tokenValue\":\"eyJ0eXAiOiJhdCtqd3QiLCJraWQiOiI4ZTEwMmM2MDQ5ZTcxYWY4NWExMWFlMmY4YTkyYjVmOWRmZDFhNjhiYjQ0Y2QzMzczMTk1MWE0NTY1ZTc0ZGY3IiwiYWxnIjoiUlMyNTYifQ.eyJzY29wZSI6Imlkc2M6SURTX0NPTk5FQ1RPUl9BVFRSSUJVVEVTX0FMTCIsImF1ZCI6WyJpZHNjOklEU19DT05ORUNUT1JTX0FMTCJdLCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjQ1NjciLCJzdWIiOiI2NTo1NTpDRTozMjo3OTpCNDoxQTpCRDoyMzo5MTpEMToyNzo0QTpDRTowNTpCQzowQTpEOTo5MjpFNTprZXlpZDo2NTo1NTpDRTozMjo3OTpCNDoxQTpCRDoyMzo5MTpEMToyNzo0QTpDRTowNTpCQzowQTpEOTo5MjpFNSIsIm5iZiI6MTc0NDE3MzYwNSwiaWF0IjoxNzQ0MTczNjA1LCJqdGkiOiJlYjQ4YWE2OC0yMTBlLTQ3ZTItOTQ0Yi03YWFkOTJkYWFlZWIiLCJleHAiOjE3NDQxNzcyMDUsImNsaWVudF9pZCI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1Iiwic2VjdXJpdHlQcm9maWxlIjoiaWRzYzpCQVNFX1NFQ1VSSVRZX1BST0ZJTEUiLCJyZWZlcnJpbmdDb25uZWN0b3IiOiJodHRwOi8vbG9jYWxob3N0IiwiQHR5cGUiOiJpZHM6RGF0UGF5bG9hZCIsIkBjb250ZXh0IjoiaHR0cHM6Ly93M2lkLm9yZy9pZHNhL2NvbnRleHRzL2NvbnRleHQuanNvbmxkIiwidHJhbnNwb3J0Q2VydHNTaGEyNTYiOiJhMmZhMzgyZTkyOGZiNGRlNDJhYjdmZmI4OGZhZjdjMTdlODgyYjQ3MThiZWEwZDkwMzcyMDk2Mjk3ZWI3Nzc0In0.WWuMKEXcb431KRTRTyuHvN_s0mwbnsmMRmMUD5oSzVrDJ8schVEdgO9d0ka3DpJbzawC10VPI81b_DNKHpc_us8zS5P_A7PjST4eovzMzx9NKXo9g7jc69Yvb3Qx_ykeaxJHVRqBYYv4MjO3B-JHy3f7BPhDElyYzLGmz9Kg5FHhs1G91ijNF2p75Xr3KwqoZx0P-MgW_cwS5cy3HhFpG-C7t-8E2Bc_JkIYpWfscpchDRWGIIPT_h1ndjp1Vy78BleYr0UUkktRX3h8RwD7o3GwHcn3ERi6_9B0W5HBmY21qFVyK03GsprphLhEt4xp939wW2N_4kLa8hG25XPDkA\"}}\r\n--ea8b856a4c93e045-59797ceee312202a-89bf482e5151e615-e8ea4ebb73fba934\r\nContent-Disposition: form-data; name=\"payload\"\r\nContent-Type: application/json\r\n\r\n{\"data\":\"eyJhbGciOiJQUzUxMiIsImtpZCI6ImlPUi8rQzNUTVNmTWsxU01qems0WDZjVDJLVXQrNFZ2LzJNQ2RCai9JM3cifQ.eyJ0aW1lc3RhbXAiOjE3NDQxNzM2MDUsInByb2Nlc3NfaWQiOiJmMGU2MzI2YS1hYTY3LTRjZjEtYTk2ZC1mN2Y2OWU1YzA5ZjAiLCJkb2N1bWVudF9pZCI6ImJkYjE5OWU3LTJhMjEtNDNhZi1hOWY1LWVjYTExNTM3YWIzYyIsInBheWxvYWQiOiJ7XCJmb29cIjpcIkhlbGxvIFdvcmxkXCIsXCJtc2dcIjpcIk1EU1wifSIsImNsaWVudF9pZCI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1IiwiY2xlYXJpbmdfaG91c2VfdmVyc2lvbiI6IjIuMC4wIn0.j3WYdiPgqMHp8U3UUm8FvaL567-H0ndteVEwj7YSho72yhalxOe7MN0NTKlEHxoy1GKiPmDgxyPC3MTr4s71R8_EI4oRuOT-t3eD-vHzQkbj-pTyJ0yf3ARm3ItxlqhafVdUQsJ8HqY3r4WREL4p8CVradH-5rdbmcbDxKVRF6BfcEoAK97jCah1rj1GnF2884sSjbSDoCNJihWrcY02EFBvwpjFqPt-2FmnZ7BMLQ5-oPu_U6CkFXuvLFsaGDHGuBFX9scEPQ4g5ntadtJv0NHP3rMCMAyBZxX_zZIYlPOmk3dw_NImyN0pSOTJw9aEUXwZOU_VkknhQPGvykIhgA\"}\r\n--ea8b856a4c93e045-59797ceee312202a-89bf482e5151e615-e8ea4ebb73fba934--"
2025-04-09T04:40:05.438044Z  INFO multipart::server: Multipart::with_boundary(_, "ea8b856a4c93e045-59797ceee312202a-89bf482e5151e615-e8ea4ebb73fba934")    
2025-04-09T04:40:05.438149Z TRACE create_process::common: Field headers: FieldHeaders { name: "header", filename: None, content_type: Some("application/json") }
2025-04-09T04:40:05.438304Z TRACE create_process::common: Field headers: FieldHeaders { name: "payload", filename: None, content_type: Some("application/json") }
2025-04-09T04:40:05.438574Z DEBUG create_process: Decoded Receipt: TokenData { header: Header { typ: None, alg: PS512, cty: None, jku: None, jwk: None, kid: Some("iOR/+C3TMSfMk1SMjzk4X6cT2KUt+4Vv/2MCdBj/I3w"), x5u: None, x5c: None, x5t: None, x5t_s256: None }, claims: DataTransaction { timestamp: 1744173605, process_id: "f0e6326a-aa67-4cf1-a96d-f7f69e5c09f0", document_id: "bdb199e7-2a21-43af-a9f5-eca11537ab3c", payload: "{\"foo\":\"Hello World\",\"msg\":\"MDS\"}", client_id: "65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5", clearing_house_version: "2.0.0" } }
2025-04-09T04:40:05.438664Z DEBUG create_process: Decoded Receipt Payload: DataTransaction { timestamp: 1744173605, process_id: "f0e6326a-aa67-4cf1-a96d-f7f69e5c09f0", document_id: "bdb199e7-2a21-43af-a9f5-eca11537ab3c", payload: "{\"foo\":\"Hello World\",\"msg\":\"MDS\"}", client_id: "65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5", clearing_house_version: "2.0.0" }
2025-04-09T04:40:05.439595Z DEBUG ids_daps_client: Issued TokenRequest (requestDAT): eyJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJAdHlwZSI6ImlkczpEYXRSZXF1ZXN0VG9rZW4iLCJAY29udGV4dCI6Imh0dHBzOi8vdzNpZC5vcmcvaWRzYS9jb250ZXh0cy9jb250ZXh0Lmpzb25sZCIsImlzcyI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1Iiwic3ViIjoiNjU6NTU6Q0U6MzI6Nzk6QjQ6MUE6QkQ6MjM6OTE6RDE6Mjc6NEE6Q0U6MDU6QkM6MEE6RDk6OTI6RTU6a2V5aWQ6NjU6NTU6Q0U6MzI6Nzk6QjQ6MUE6QkQ6MjM6OTE6RDE6Mjc6NEE6Q0U6MDU6QkM6MEE6RDk6OTI6RTUiLCJpZCI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1IiwianRpIjoiOTNmNDVmMzUtNWY4My00MDliLTg2MmItZTNlM2EzZjEwY2U1IiwiYXVkIjoiaWRzYzpJRFNfQ09OTkVDVE9SU19BTEwiLCJpYXQiOjE3NDQxNzM2MDUsImV4cCI6MTc0NDE3NzIwNSwibmJmIjoxNzQ0MTczNjA1fQ.HvzGMpwjzqDtMBjQeglEmPUBTWo-4JJKYdm1i82nVTdtxeipl_48P627FSpTmC2rssbt6nTqGIv7ApXI2xLn4msWrZmOaJ4_sAti5zhnxBhj5VVHLVxAu-45KK3oqOjIVsbPxQwyHLr8PNVCbqlrDzWvdVtzRe742vkVkOi15eD0JyadloNgdjqrS4qos5CQbd_NjilgNKS5vHtoOWEdo_ozlmHRrznBFXKOjSYCq-BdQUMuhFaTX1FlJ8OT0taHyEFr5R7BisT1tnwihFlmb9DIDnPA9CZGg6e2SzqkyGeS4GWHyFSUJFoN9MqyYJtIBa9SpiEuYIp0XGPaTkBfWg
2025-04-09T04:40:05.450249Z DEBUG clearing_house_app::model::claims: Length of `header` is 2205 bytes
2025-04-09T04:40:05.450409Z TRACE clearing_house_app::model::claims: Header: IdsHeader {
    context: Some(
        {
            "idsc": "https://w3id.org/idsa/code/",
            "ids": "https://w3id.org/idsa/core/",
        },
    ),
    type_message: QueryMessage,
    id: Some(
        "c3bc9ecb-45bb-412d-8c27-3e5a54f752b0",
    ),
    pid: None,
    model_version: "test",
    correlation_message: None,
    issued: ComplexTime(
        InfoModelTimeStamp {
            format: Some(
                "http://www.w3.org/2001/XMLSchema#dateTimeStamp",
            ),
            value: 2025-04-09T04:40:05.449889052+00:00,
        },
    ),
    issuer_connector: SimpleId(
        "test-connector",
    ),
    sender_agent: SimpleId(
        "https://w3id.org/idsa/core/ClearingHouse",
    ),
    recipient_connector: None,
    recipient_agent: None,
    transfer_contract: None,
    content_version: None,
    security_token: Some(
        SecurityToken {
            type_message: DAPSToken,
            id: Some(
                "https://w3id.org/idsa/autogen/dynamicAttributeToken/bec2bc61-599c-484f-86f8-8a786831473c",
            ),
            token_format: Some(
                ComplexId(
                    InfoModelComplexId {
                        id: "https://w3id.org/idsa/code/JWT",
                    },
                ),
            ),
            token_value: "eyJ0eXAiOiJhdCtqd3QiLCJraWQiOiI4ZTEwMmM2MDQ5ZTcxYWY4NWExMWFlMmY4YTkyYjVmOWRmZDFhNjhiYjQ0Y2QzMzczMTk1MWE0NTY1ZTc0ZGY3IiwiYWxnIjoiUlMyNTYifQ.eyJzY29wZSI6Imlkc2M6SURTX0NPTk5FQ1RPUl9BVFRSSUJVVEVTX0FMTCIsImF1ZCI6WyJpZHNjOklEU19DT05ORUNUT1JTX0FMTCJdLCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjQ1NjciLCJzdWIiOiI2NTo1NTpDRTozMjo3OTpCNDoxQTpCRDoyMzo5MTpEMToyNzo0QTpDRTowNTpCQzowQTpEOTo5MjpFNTprZXlpZDo2NTo1NTpDRTozMjo3OTpCNDoxQTpCRDoyMzo5MTpEMToyNzo0QTpDRTowNTpCQzowQTpEOTo5MjpFNSIsIm5iZiI6MTc0NDE3MzYwNSwiaWF0IjoxNzQ0MTczNjA1LCJqdGkiOiI4MTczNjQyYS04NjQ0LTQxZDQtYTYwNy1mOTVmNGViYTE0MWUiLCJleHAiOjE3NDQxNzcyMDUsImNsaWVudF9pZCI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1Iiwic2VjdXJpdHlQcm9maWxlIjoiaWRzYzpCQVNFX1NFQ1VSSVRZX1BST0ZJTEUiLCJyZWZlcnJpbmdDb25uZWN0b3IiOiJodHRwOi8vbG9jYWxob3N0IiwiQHR5cGUiOiJpZHM6RGF0UGF5bG9hZCIsIkBjb250ZXh0IjoiaHR0cHM6Ly93M2lkLm9yZy9pZHNhL2NvbnRleHRzL2NvbnRleHQuanNvbmxkIiwidHJhbnNwb3J0Q2VydHNTaGEyNTYiOiJhMmZhMzgyZTkyOGZiNGRlNDJhYjdmZmI4OGZhZjdjMTdlODgyYjQ3MThiZWEwZDkwMzcyMDk2Mjk3ZWI3Nzc0In0.Cj3D8sjTni0jCKdVaFtQEzD1SJl5g02Kb7Io8N2Q9MXXPSFVfmCc3as2ao6dvFUB9gqK9T-uYzlExRE-T9fDV5NwJgcxJwmNvoPET-TYvkWtu_fGRxAGWR1pQ1CSaxEHy5F_DtsIr5N_WokyY4bCmVVDwsttilRXBuQYkMGVMzDMTW5hD3cgGgGnwxC0jPLRZD4xmLbKMbbVCcXtFJZPiDaPptGyWyERw71_pvlVLMax6GK2a5jTJqPIxs_ma9AD4OYhFwOJGwjMXu-SaFPfLf2wX_d5659qI2SvsLaFdekor81Cb2GXf_MJInuQGbOTXZ3iywshGNrOEJQXqSTtCg",
        },
    ),
    authorization_token: None,
}
2025-04-09T04:40:05.450467Z DEBUG clearing_house_app::model::claims: Validating the DAPS Token ...
2025-04-09T04:40:05.450481Z DEBUG ids_daps_client: Checking cache...
2025-04-09T04:40:05.450499Z DEBUG ids_daps_client: Cache is up-to-date
2025-04-09T04:40:05.450734Z DEBUG ids_daps_client: Validation result: Ok(TokenData { header: Header { typ: Some("at+jwt"), alg: RS256, cty: None, jku: None, jwk: None, kid: Some("8e102c6049e71af85a11ae2f8a92b5f9dfd1a68bb44cd33731951a4565e74df7"), x5u: None, x5c: None, x5t: None, x5t_s256: None }, claims: DatClaims { type_: "ids:DatPayload", context_: "https://w3id.org/idsa/contexts/context.jsonld", referring_connector: "http://localhost", security_profile: "idsc:BASE_SECURITY_PROFILE", issued_at: 1744173605, expires_at: 1744177205, not_before: 1744173605, subject: "65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5", audience: Multiple(["idsc:IDS_CONNECTORS_ALL"]), issuer: "http://localhost:4567", jwt_id: "8173642a-8644-41d4-a607-f95f4eba141e" } })
2025-04-09T04:40:05.451691Z DEBUG ids_daps_client: Issued TokenRequest (requestDAT): eyJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJAdHlwZSI6ImlkczpEYXRSZXF1ZXN0VG9rZW4iLCJAY29udGV4dCI6Imh0dHBzOi8vdzNpZC5vcmcvaWRzYS9jb250ZXh0cy9jb250ZXh0Lmpzb25sZCIsImlzcyI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1Iiwic3ViIjoiNjU6NTU6Q0U6MzI6Nzk6QjQ6MUE6QkQ6MjM6OTE6RDE6Mjc6NEE6Q0U6MDU6QkM6MEE6RDk6OTI6RTU6a2V5aWQ6NjU6NTU6Q0U6MzI6Nzk6QjQ6MUE6QkQ6MjM6OTE6RDE6Mjc6NEE6Q0U6MDU6QkM6MEE6RDk6OTI6RTUiLCJpZCI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1IiwianRpIjoiN2JiYWEwZjYtM2QwZC00ZDE0LTk5YjYtNWNiNDBhMjUwMGRmIiwiYXVkIjoiaWRzYzpJRFNfQ09OTkVDVE9SU19BTEwiLCJpYXQiOjE3NDQxNzM2MDUsImV4cCI6MTc0NDE3NzIwNSwibmJmIjoxNzQ0MTczNjA1fQ.A-1nZ0XufAnDl4kXCp-cUzZuM-mShO-hVcJKWwXQ0RuSIt4Pg3RTWaFBy7yM6al0biti6WVbW9q7BOJUKMNLZrJBlN0MUM71Wp9MZxqidXwabTCLmo4c1OA6e-uwA5E6JjfAGLNxmVSFu8GdWtK2J4AjdXxbYZV1T9neEtpKuYr811URaTvCBlLIMRHE9iJbEmpiSJMVct85iAKAHuOu8daKsDFTV92wMjqZTsZB6e5zYUusP0Ty2w5WK4cwfGH20FNoLTri9GIVZlRAaG0qPixrLuFUkmEDtnRvKb8iHMLsoMbygJyZcUMKpIqqiVRRfJ56_4Ye6xLx-DfSIihrpw
2025-04-09T04:40:05.464143Z DEBUG clearing_house_app::services::logging_service: page: None, size:None and sort:None
2025-04-09T04:40:05.464172Z TRACE clearing_house_app::services::logging_service: ...user '65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5'
2025-04-09T04:40:05.465879Z DEBUG sqlx::query: summary="SELECT p.process_id, p.created_at, ARRAY_AGG(c.client_id) …" db.statement="\n\nSELECT p.process_id, p.created_at, ARRAY_AGG(c.client_id) AS owners FROM processes p\n        LEFT JOIN process_owners po ON p.id = po.process_id\n        LEFT JOIN clients c ON po.client_id = c.id\n        WHERE p.process_id = $1\n        GROUP BY p.process_id, p.created_at\n" rows_affected=0 rows_returned=1 elapsed=1.379446ms elapsed_secs=0.001379446
2025-04-09T04:40:05.465983Z  INFO clearing_house_app::services::logging_service: User '65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5' authorized for 'f0e6326a-aa67-4cf1-a96d-f7f69e5c09f0'
2025-04-09T04:40:05.466062Z DEBUG get_enc_documents_for_pid: clearing_house_app::services::document_service: Trying to retrieve documents for pid 'f0e6326a-aa67-4cf1-a96d-f7f69e5c09f0'...
2025-04-09T04:40:05.466085Z TRACE get_enc_documents_for_pid: clearing_house_app::services::document_service: ...user '"65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5:keyid:65:55:CE:32:79:B4:1A:BD:23:91:D1:27:4A:CE:05:BC:0A:D9:92:E5"'
2025-04-09T04:40:05.466095Z DEBUG get_enc_documents_for_pid: clearing_house_app::services::document_service: ...page: Some(1), size:Some(100) and sort:Some(Descending)
2025-04-09T04:40:05.466117Z DEBUG get_enc_documents_for_pid: clearing_house_app::model: ... validating dates: now: 2025-04-09T04:40:05.466106021 , from: None , to: None
2025-04-09T04:40:05.466133Z DEBUG get_enc_documents_for_pid: clearing_house_app::services::document_service: ... using pagination with page: 1, size:100 and sort:Descending
2025-04-09T04:40:05.467742Z DEBUG get_enc_documents_for_pid: sqlx::query: summary="SELECT documents.id, processes.process_id, documents.created_at, …" db.statement="\n\nSELECT documents.id, processes.process_id, documents.created_at, model_version, correlation_message,\n        transfer_contract, issued, issuer_connector, content_version, recipient_connector,\n        sender_agent, recipient_agent, payload, payload_type, message_id\n        FROM documents\n        LEFT JOIN processes ON processes.id = documents.process_id\n        WHERE processes.process_id = $1 AND documents.created_at BETWEEN $2 AND $3\n        ORDER BY created_at DESC\n        LIMIT $4 OFFSET $5\n" rows_affected=1 rows_returned=1 elapsed=1.287034ms elapsed_secs=0.001287034
2025-04-09T04:40:05.468068Z TRACE create_process::common: Content-Type: Ok("multipart/form-data; boundary=35a4a9f691abc525-2d2e83643e0ccec5-060cc23cb8119148-d8e62850e01574e5")
2025-04-09T04:40:05.468108Z TRACE create_process::common: Body: b"--35a4a9f691abc525-2d2e83643e0ccec5-060cc23cb8119148-d8e62850e01574e5\r\nContent-Disposition: form-data; name=\"header\"\r\nContent-Type: application/json\r\n\r\n{\"@context\":{\"idsc\":\"https://w3id.org/idsa/code/\",\"ids\":\"https://w3id.org/idsa/core/\"},\"@type\":\"ids:ResultMessage\",\"@id\":\"https://w3id.org/idsa/autogen/MessageProcessedNotification/cf40d564-7500-4a20-9925-b206a76a0152\",\"ids:modelVersion\":\"4.1.0\",\"ids:correlationMessage\":\"c3bc9ecb-45bb-412d-8c27-3e5a54f752b0\",\"ids:issued\":{\"@type\":\"http://www.w3.org/2001/XMLSchema#dateTimeStamp\",\"@value\":\"2025-04-09T04:40:05.467894438Z\"},\"ids:issuerConnector\":\"http://localhost:8080\",\"ids:senderAgent\":\"https://w3id.org/idsa/core/ClearingHouse\",\"ids:securityToken\":{\"@type\":\"ids:DynamicAttributeToken\",\"ids:tokenFormat\":\"https://w3id.org/idsa/code/token/JWT\",\"ids:tokenValue\":\"eyJ0eXAiOiJhdCtqd3QiLCJraWQiOiI4ZTEwMmM2MDQ5ZTcxYWY4NWExMWFlMmY4YTkyYjVmOWRmZDFhNjhiYjQ0Y2QzMzczMTk1MWE0NTY1ZTc0ZGY3IiwiYWxnIjoiUlMyNTYifQ.eyJzY29wZSI6Imlkc2M6SURTX0NPTk5FQ1RPUl9BVFRSSUJVVEVTX0FMTCIsImF1ZCI6WyJpZHNjOklEU19DT05ORUNUT1JTX0FMTCJdLCJpc3MiOiJodHRwOi8vbG9jYWxob3N0OjQ1NjciLCJzdWIiOiI2NTo1NTpDRTozMjo3OTpCNDoxQTpCRDoyMzo5MTpEMToyNzo0QTpDRTowNTpCQzowQTpEOTo5MjpFNTprZXlpZDo2NTo1NTpDRTozMjo3OTpCNDoxQTpCRDoyMzo5MTpEMToyNzo0QTpDRTowNTpCQzowQTpEOTo5MjpFNSIsIm5iZiI6MTc0NDE3MzYwNSwiaWF0IjoxNzQ0MTczNjA1LCJqdGkiOiIyNDY1YTc2NS0zMjg5LTQyOTktYWYwOC0wZTJlODk1MzJlMjkiLCJleHAiOjE3NDQxNzcyMDUsImNsaWVudF9pZCI6IjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1OmtleWlkOjY1OjU1OkNFOjMyOjc5OkI0OjFBOkJEOjIzOjkxOkQxOjI3OjRBOkNFOjA1OkJDOjBBOkQ5OjkyOkU1Iiwic2VjdXJpdHlQcm9maWxlIjoiaWRzYzpCQVNFX1NFQ1VSSVRZX1BST0ZJTEUiLCJyZWZlcnJpbmdDb25uZWN0b3IiOiJodHRwOi8vbG9jYWxob3N0IiwiQHR5cGUiOiJpZHM6RGF0UGF5bG9hZCIsIkBjb250ZXh0IjoiaHR0cHM6Ly93M2lkLm9yZy9pZHNhL2NvbnRleHRzL2NvbnRleHQuanNvbmxkIiwidHJhbnNwb3J0Q2VydHNTaGEyNTYiOiJhMmZhMzgyZTkyOGZiNGRlNDJhYjdmZmI4OGZhZjdjMTdlODgyYjQ3MThiZWEwZDkwMzcyMDk2Mjk3ZWI3Nzc0In0.TvpLUJBBPRCqZcsQHgk4SQHceGEY0naVLDDVGcceYTHqUAM8nGDPIz2-tfhTqOP-N3IZ2qpnf5_Bor4kxQGdkc7PoyGKLpOFiGJlwPGl8qB22fMob2YU77KwLZ3rFps4s9g-39FZTcjJ6vo4jMDic0mIDUsKBFB6w2UiLb0_jwE72zwH-Fv8HojiAJz00lY4IBca4s4OBQJdRUq6X5ugsd-OuvBpnylQaElXlYgRcPv7N4ZXLfgtPA4jJeYLg3zCRFrFmTtYESHeY-aNdZJOm66RsvcMvWx_GYM55LWp8rbrIBYBHw7LwzzEjqr_sAhl512LMVXpsET__abL2AtFiA\"}}\r\n--35a4a9f691abc525-2d2e83643e0ccec5-060cc23cb8119148-d8e62850e01574e5\r\nContent-Disposition: form-data; name=\"payload\"\r\nContent-Type: application/json\r\n\r\n{\"date_from\":\"2025-03-26 00:00:00\",\"date_to\":\"2025-04-09 04:40:06\",\"page\":1,\"size\":100,\"order\":\"desc\",\"documents\":[{\"header\":{\"@context\":{\"ids\":\"https://w3id.org/idsa/core/\",\"idsc\":\"https://w3id.org/idsa/code/\"},\"@type\":\"ids:Message\",\"@id\":\"c3bc9ecb-45bb-412d-8c27-3e5a54f752b0\",\"ids:modelVersion\":\"test\",\"ids:issued\":{\"@type\":\"http://www.w3.org/2001/XMLSchema#dateTimeStamp\",\"@value\":\"2025-04-09T04:40:05.421061848Z\"},\"ids:issuerConnector\":\"test-connector\",\"ids:senderAgent\":\"https://w3id.org/idsa/core/ClearingHouse\",\"ids:securityToken\":null},\"payload\":\"{\\\"foo\\\":\\\"Hello World\\\",\\\"msg\\\":\\\"MDS\\\"}\"}]}\r\n--35a4a9f691abc525-2d2e83643e0ccec5-060cc23cb8119148-d8e62850e01574e5--"
2025-04-09T04:40:05.468278Z  INFO multipart::server: Multipart::with_boundary(_, "35a4a9f691abc525-2d2e83643e0ccec5-060cc23cb8119148-d8e62850e01574e5")    
2025-04-09T04:40:05.468420Z TRACE create_process::common: Field headers: FieldHeaders { name: "header", filename: None, content_type: Some("application/json") }
2025-04-09T04:40:05.468581Z TRACE create_process::common: Field headers: FieldHeaders { name: "payload", filename: None, content_type: Some("application/json") }
2025-04-09T04:40:05.468684Z  INFO create_process: IDS Query Result: IdsQueryResult { date_from: "2025-03-26 00:00:00", date_to: "2025-04-09 04:40:06", page: 1, size: 100, order: "desc", documents: [IdsMessage { header: IdsHeader { context: Some({"ids": "https://w3id.org/idsa/core/", "idsc": "https://w3id.org/idsa/code/"}), type_message: Message, id: Some("c3bc9ecb-45bb-412d-8c27-3e5a54f752b0"), pid: None, model_version: "test", correlation_message: None, issued: ComplexTime(InfoModelTimeStamp { format: Some("http://www.w3.org/2001/XMLSchema#dateTimeStamp"), value: 2025-04-09T04:40:05.421061848+00:00 }), issuer_connector: SimpleId("test-connector"), sender_agent: SimpleId("https://w3id.org/idsa/core/ClearingHouse"), recipient_connector: None, recipient_agent: None, transfer_contract: None, content_version: None, security_token: None, authorization_token: None }, payload: Some("{\"foo\":\"Hello World\",\"msg\":\"MDS\"}"), payload_type: None }] }
2025-04-09T04:40:05.468804Z TRACE create_process::common: Payload: [34, 116, 101, 115, 116, 34]
2025-04-09T04:40:05.469105Z DEBUG clearing_house_app::model::claims: Length of `header` is 738 bytes
2025-04-09T04:40:05.469155Z DEBUG clearing_house_app::model::claims: Length of `payload` is 6 bytes
2025-04-09T04:40:05.469243Z TRACE clearing_house_app::model::claims: Header: IdsHeader {
    context: Some(
        {
            "idsc": "https://w3id.org/idsa/code/",
            "ids": "https://w3id.org/idsa/core/",
        },
    ),
    type_message: LogMessage,
    id: Some(
        "c3bc9ecb-45bb-412d-8c27-3e5a54f752b0",
    ),
    pid: None,
    model_version: "test",
    correlation_message: None,
    issued: ComplexTime(
        InfoModelTimeStamp {
            format: Some(
                "http://www.w3.org/2001/XMLSchema#dateTimeStamp",
            ),
            value: 2025-04-09T04:40:05.468747154+00:00,
        },
    ),
    issuer_connector: SimpleId(
        "test-connector",
    ),
    sender_agent: SimpleId(
        "https://w3id.org/idsa/core/ClearingHouse",
    ),
    recipient_connector: None,
    recipient_agent: None,
    transfer_contract: None,
    content_version: None,
    security_token: Some(
        SecurityToken {
            type_message: DAPSToken,
            id: Some(
                "https://w3id.org/idsa/autogen/dynamicAttributeToken/9f273abe-6244-44e1-89cd-4dc6ba351757",
            ),
            token_format: Some(
                ComplexId(
                    InfoModelComplexId {
                        id: "https://w3id.org/idsa/code/JWT",
                    },
                ),
            ),
            token_value: "test",
        },
    ),
    authorization_token: None,
}
2025-04-09T04:40:05.469290Z TRACE clearing_house_app::model::claims: Payload: b"\"test\""
2025-04-09T04:40:05.469301Z DEBUG clearing_house_app::model::claims: Validating the DAPS Token ...
2025-04-09T04:40:05.469313Z DEBUG ids_daps_client: Checking cache...
2025-04-09T04:40:05.469330Z DEBUG ids_daps_client: Cache is up-to-date
2025-04-09T04:40:05.469393Z DEBUG ids_daps_client: Validation result: Err(Error(InvalidToken))
2025-04-09T04:40:05.469425Z ERROR clearing_house_app::model::claims: Invalid DAPS Token
2025-04-09T04:40:05.472114Z TRACE testcontainers::core::containers::async_container: Drop was called for container 0da8c480cda70769a761a49e7f4a7b884a07d1a210517a1daff1cc934d0a03e1, cleaning up    
2025-04-09T04:40:05.624036Z DEBUG testcontainers::core::containers::async_container: Container 0da8c480cda70769a761a49e7f4a7b884a07d1a210517a1daff1cc934d0a03e1 was successfully dropped    
2025-04-09T04:40:05.625336Z TRACE testcontainers::core::containers::async_container: Drop was called for container bd9496f1e5e71040eb2521039a8c93a1c54afcb313d37fad840afa93efe2cc71, cleaning up    
2025-04-09T04:40:05.743853Z DEBUG testcontainers::core::containers::async_container: Container bd9496f1e5e71040eb2521039a8c93a1c54afcb313d37fad840afa93efe2cc71 was successfully dropped    
test log_message ... ok

test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 13.30s

     Running tests/public_key.rs (target/debug/deps/public_key-ddf38d8ee48b4bda)

running 1 test
2025-04-09T04:40:06.958774Z  INFO clearing_house_app: Config read successfully! Initializing application ...
2025-04-09T04:40:06.962932Z  INFO clearing_house_app: Connecting to database
2025-04-09T04:40:06.969875Z  INFO clearing_house_app: Migrating database
2025-04-09T04:40:07.042521Z  INFO public_key: JWKS: JwkSet {
    keys: [
        Jwk {
            common: CommonParameters {
                public_key_use: None,
                key_operations: None,
                key_algorithm: None,
                key_id: Some(
                    "iOR/+C3TMSfMk1SMjzk4X6cT2KUt+4Vv/2MCdBj/I3w",
                ),
                x509_url: None,
                x509_chain: None,
                x509_sha1_fingerprint: None,
                x509_sha256_fingerprint: None,
            },
            algorithm: RSA(
                RSAKeyParameters {
                    key_type: RSA,
                    n: "rAkK0DfyoiNtFgO4CkkfEIU3mk8gyjtm6OQsZy4OxNW6hsbj7kr7o4nQelaaVWiRbyY_QZ2PeOGG3g7gH8RzrXCrYun6zCEm9VJU04zHrbIzqifktiCe7fXpecrlT3rAX0UgEnztpO9dyOJ_FjVgeZges6VO1mBjDKeC1TM5UdMHnbpdIUQ-52CSStfLvHUgLnBGS1eNb2Mvrwk51-TlkOelkepawY3IESAlXMIbxo4MQc6q_nmU_Sj5k1b_SrXffentDW_43NfWFmjENz2j4AU6wHWU0t6-clwGb6alKOZ_C2Q_iz4Ryq7NO4MZPGtRhBd-FJQjkkWIv_5-Y7QX9Q",
                    e: "AQAB",
                },
            ),
        },
    ],
}
test retrieve_public_key ... ok

test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.45s

   Doc-tests clearing_house_app

running 0 tests

test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s

fix(app): Fix status codes of IDS responses

005520f

schoenenberg self-assigned this Apr 9, 2025

schoenenberg requested review from dhommen and jannotti-glaucio April 9, 2025 04:37

dhommen approved these changes Apr 9, 2025

View reviewed changes

jannotti-glaucio approved these changes Apr 9, 2025

View reviewed changes

jannotti-glaucio merged commit 1590a2f into development Apr 11, 2025
1 check passed

jannotti-glaucio deleted the fix/response-status-code branch April 11, 2025 13:02

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

fix(app): Fix status codes of IDS responses #134

fix(app): Fix status codes of IDS responses #134

schoenenberg commented Apr 9, 2025

github-actions bot commented Apr 9, 2025

fix(app): Fix status codes of IDS responses #134

fix(app): Fix status codes of IDS responses #134

Conversation

schoenenberg commented Apr 9, 2025

What this PR changes/adds

Why it does that

Further notes

Linked Issue(s)

github-actions bot commented Apr 9, 2025

Clearinghouse App Build report

Formatter report ("cargo fmt --check"):

Linter report ("cargo clippy"):

Test log