2025-10-26 19:53:30,638 - DEBUG - ya.test - __init__: Reading variables from env_file at /home/runner/.ya/build/build_root/70hy/0003ad/ydb/library/yql/providers/generic/connector/tests/datasource/clickhouse/test-results/py3test/env.json.txt
2025-10-26 19:53:30,638 - DEBUG - ya.test - __init__: Variables loaded: ['COMPOSE_PROJECT_NAME']
2025-10-26 19:53:30,655 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70hy/0003ad/library/recipes/docker_compose/bin/docker-compose', '-f', '/home/runner/.ya/build/build_root/70hy/0003ad/environment/arcadia/ydb/library/yql/providers/generic/connector/tests/datasource/clickhouse/docker-compose.yml', '--log-level', 'DEBUG', '--no-ansi', 'up', '-d', '--build', '--force-recreate']' in '/home/runner/.ya/build/build_root/70hy/0003ad/environment/arcadia/ydb/library/yql/providers/generic/connector/tests/datasource/clickhouse' (waiting)
2025-10-26 19:53:30,657 - DEBUG - ya.test - execute: Command pid: 1998005
2025-10-26 19:53:32,057 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) rc: 0
2025-10-26 19:53:32,057 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) elapsed time (sec): 1.3980650901794434
2025-10-26 19:53:32,057 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) idrss: 0
2025-10-26 19:53:32,057 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) inblock: 0
2025-10-26 19:53:32,058 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) isrss: 0
2025-10-26 19:53:32,058 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) ixrss: 0
2025-10-26 19:53:32,058 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) majflt: 0
2025-10-26 19:53:32,058 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) maxrss: 149492
2025-10-26 19:53:32,058 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) minflt: 17647
2025-10-26 19:53:32,058 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) msgrcv: 0
2025-10-26 19:53:32,058 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) msgsnd: 0
2025-10-26 19:53:32,058 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) nivcsw: 30
2025-10-26 19:53:32,058 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) nsignals: 0
2025-10-26 19:53:32,058 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) nswap: 0
2025-10-26 19:53:32,058 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) nvcsw: 159
2025-10-26 19:53:32,058 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) oublock: 53496
2025-10-26 19:53:32,059 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) stime: 0.097366
2025-10-26 19:53:32,059 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) utime: 0.732088
2025-10-26 19:53:32,059 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) wtime: 1.401
2025-10-26 19:53:32,059 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) output:

2025-10-26 19:53:32,059 - DEBUG - ya.test - _save_outputs: Command (pid 1998005) errors:
Using configuration files: /home/runner/.ya/build/build_root/70hy/0003ad/environment/arcadia/ydb/library/yql/providers/generic/connector/tests/datasource/clickhouse/docker-compose.yml
Trying paths: ['/home/runner/.docker/config.json', '/home/runner/.dockercfg']
No config file found
Trying paths: ['/home/runner/.docker/config.json', '/home/runner/.dockercfg']
No config file found
http://localhost:None "GET /v1.30/networks/clickhouse_default HTTP/1.1" 404 51
http://localhost:None "GET /v1.30/info HTTP/1.1" 200 None
http://localhost:None "GET /v1.30/networks/clickhouse_default HTTP/1.1" 404 51
Creating network "clickhouse_default" with the default driver
http://localhost:None "POST /v1.30/networks/create HTTP/1.1" 201 87
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=0&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=0&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=1&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.service%3Dclickhouse%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=1&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.service%3Dclickhouse%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=1&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.service%3Dfq-connector-go%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=1&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.service%3Dfq-connector-go%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
http://localhost:None "GET /v1.30/images/mirror.gcr.io/clickhouse/clickhouse-server:23-alpine%40sha256:d75017307e76d1bca81a5ac7ada94620567782c0610541f525d1e443e23f76e3/json HTTP/1.1" 200 None
http://localhost:None "GET /v1.30/images/ghcr.io/ydb-platform/fq-connector-go:v0.4.9%40sha256:3a1fe086be50c0edbae2c2b284aee5ce76bd056d7f46cb460919ec37a6f8ab5c/json HTTP/1.1" 200 None
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=1&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.service%3Dclickhouse%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=1&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.service%3Dclickhouse%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=1&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.service%3Dfq-connector-go%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=1&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.service%3Dfq-connector-go%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
Pending: {<Service: clickhouse>, <Service: fq-connector-go>}
Starting producer thread for <Service: clickhouse>
Starting producer thread for <Service: fq-connector-go>
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=1&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.service%3Dclickhouse%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=1&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.service%3Dclickhouse%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
Creating fq-tests-ch-clickhouse ... 
Pending: {ServiceName(project='clickhouse', service='clickhouse', number=1)}
Starting producer thread for ServiceName(project='clickhouse', service='clickhouse', number=1)
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=1&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.service%3Dfq-connector-go%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
http://localhost:None "GET /v1.30/images/mirror.gcr.io/clickhouse/clickhouse-server:23-alpine%40sha256:d75017307e76d1bca81a5ac7ada94620567782c0610541f525d1e443e23f76e3/json HTTP/1.1" 200 None
http://localhost:None "GET /v1.30/containers/json?limit=-1&all=1&size=0&trunc_cmd=0&filters=%7B%22label%22%3A+%5B%22com.docker.compose.project%3Dclickhouse%22%2C+%22com.docker.compose.service%3Dfq-connector-go%22%2C+%22com.docker.compose.oneoff%3DFalse%22%5D%7D HTTP/1.1" 200 3
Creating fq-tests-ch-fq-connector-go ... 
Pending: {ServiceName(project='clickhouse', service='fq-connector-go', number=1)}
http://localhost:None "GET /v1.30/images/mirror.gcr.io/clickhouse/clickhouse-server:23-alpine%40sha256:d75017307e76d1bca81a5ac7ada94620567782c0610541f525d1e443e23f76e3/json HTTP/1.1" 200 None
Starting producer thread for ServiceName(project='clickhouse', service='fq-connector-go', number=1)
Added config hash: 3d6898ea3c87a3fcbd6d84ec2734f0f24fec79e2fff47fae2848b8fab6d7e196
http://localhost:None "GET /v1.30/images/ghcr.io/ydb-platform/fq-connector-go:v0.4.9%40sha256:3a1fe086be50c0edbae2c2b284aee5ce76bd056d7f46cb460919ec37a6f8ab5c/json HTTP/1.1" 200 None
http://localhost:None "GET /v1.30/images/ghcr.io/ydb-platform/fq-connector-go:v0.4.9%40sha256:3a1fe086be50c0edbae2c2b284aee5ce76bd056d7f46cb460919ec37a6f8ab5c/json HTTP/1.1" 200 None
Added config hash: 65622bb2cd26eab6145ef081e81a559b2597f2af53079b595d97e1e3c5231190
Pending: set()
Pending: set()
Pending: set()
http://localhost:None "POST /v1.30/containers/create?name=fq-tests-ch-fq-connector-go HTTP/1.1" 201 88
http://localhost:None "POST /v1.30/containers/create?name=fq-tests-ch-clickhouse HTTP/1.1" 201 88
http://localhost:None "GET /v1.30/containers/944bfdd769724082e90ff0c9e03d64d484d2fe563a0a3f9190edf6699772de4c/json HTTP/1.1" 200 None
http://localhost:None "GET /v1.30/containers/09900d9807bba67f1646245cf4c637603c46aac5b9272c70fa046f3fe2ade68e/json HTTP/1.1" 200 None
Pending: set()
Pending: set()
Pending: set()
Pending: set()
Pending: set()
Pending: set()
Pending: set()
Pending: set()
Pending: set()
Pending: set()
Pending: set()
Pending: set()
http://localhost:None "POST /v1.30/containers/09900d9807bba67f1646245cf4c637603c46aac5b9272c70fa046f3fe2ade68e/start HTTP/1.1" 204 0
Finished processing: ServiceName(project='clickhouse', service='clickhouse', number=1)
Creating fq-tests-ch-clickhouse      ... done
Pending: set()
Finished processing: <Service: clickhouse>
Pending: set()
http://localhost:None "POST /v1.30/containers/944bfdd769724082e90ff0c9e03d64d484d2fe563a0a3f9190edf6699772de4c/start HTTP/1.1" 204 0
Finished processing: ServiceName(project='clickhouse', service='fq-connector-go', number=1)
Creating fq-tests-ch-fq-connector-go ... done
Pending: set()
Finished processing: <Service: fq-connector-go>
Pending: set()

2025-10-26 19:53:32,060 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
