2025-10-26 15:57:00,161 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:57:00,161 - INFO - ya.test - pytest_runtest_setup: test_sql_suite[plan-jointest/join2.test]
2025-10-26 15:57:00,161 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:57:00,161 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 15:57:00,163 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_postgres.py::TestPGSQL, test_name: test_sql_suite[plan-jointest/join2.test])
2025-10-26 15:57:00,264 - INFO - root - assert_statement: Executed statement at line 9, suite from postgres/jointest/join2.test, in 0.101 seconds
2025-10-26 15:57:00,291 - INFO - root - assert_statement: Executed statement at line 14, suite from postgres/jointest/join2.test, in 0.026 seconds
2025-10-26 15:57:00,324 - INFO - root - assert_statement: Executed statement at line 17, suite from postgres/jointest/join2.test, in 0.033 seconds
2025-10-26 15:57:00,350 - INFO - root - assert_statement: Executed statement at line 20, suite from postgres/jointest/join2.test, in 0.025 seconds
2025-10-26 15:57:00,375 - INFO - root - assert_statement: Executed statement at line 23, suite from postgres/jointest/join2.test, in 0.024 seconds
2025-10-26 15:57:00,399 - INFO - root - assert_statement: Executed statement at line 26, suite from postgres/jointest/join2.test, in 0.024 seconds
2025-10-26 15:57:00,423 - INFO - root - assert_statement: Executed statement at line 29, suite from postgres/jointest/join2.test, in 0.024 seconds
2025-10-26 15:57:00,451 - INFO - root - assert_statement: Executed statement at line 32, suite from postgres/jointest/join2.test, in 0.028 seconds
2025-10-26 15:57:00,496 - INFO - root - assert_statement: Executed statement at line 35, suite from postgres/jointest/join2.test, in 0.044 seconds
2025-10-26 15:57:00,537 - INFO - root - assert_statement: Executed statement at line 48, suite from postgres/jointest/join2.test, in 0.041 seconds
2025-10-26 15:57:00,584 - INFO - root - assert_statement: Executed statement at line 56, suite from postgres/jointest/join2.test, in 0.047 seconds
2025-10-26 15:57:00,614 - INFO - root - assert_statement: Executed statement at line 64, suite from postgres/jointest/join2.test, in 0.030 seconds
2025-10-26 15:57:00,615 - INFO - root - assert_statement: Executed statement at line 75, suite from postgres/jointest/join2.test, in 0.000 seconds
2025-10-26 15:57:00,615 - INFO - root - assert_statement: Executed statement at line 86, suite from postgres/jointest/join2.test, in 0.000 seconds
2025-10-26 15:57:00,615 - INFO - root - assert_statement: Executed statement at line 98, suite from postgres/jointest/join2.test, in 0.000 seconds
2025-10-26 15:57:00,615 - INFO - root - assert_statement: Executed statement at line 111, suite from postgres/jointest/join2.test, in 0.000 seconds
2025-10-26 15:57:00,616 - INFO - root - assert_statement: Executed statement at line 123, suite from postgres/jointest/join2.test, in 0.000 seconds
2025-10-26 15:57:00,616 - INFO - root - assert_statement: Executed statement at line 138, suite from postgres/jointest/join2.test, in 0.000 seconds
2025-10-26 15:57:00,616 - INFO - root - assert_statement: Executed statement at line 155, suite from postgres/jointest/join2.test, in 0.000 seconds
2025-10-26 15:57:00,674 - INFO - root - assert_statement: Executed statement at line 172, suite from postgres/jointest/join2.test, in 0.058 seconds
2025-10-26 15:57:00,714 - INFO - root - assert_statement: Executed statement at line 179, suite from postgres/jointest/join2.test, in 0.039 seconds
2025-10-26 15:57:00,743 - INFO - root - assert_statement: Executed statement at line 182, suite from postgres/jointest/join2.test, in 0.029 seconds
2025-10-26 15:57:00,765 - INFO - root - assert_statement: Executed statement at line 185, suite from postgres/jointest/join2.test, in 0.021 seconds
2025-10-26 15:57:00,790 - INFO - root - assert_statement: Executed statement at line 188, suite from postgres/jointest/join2.test, in 0.025 seconds
2025-10-26 15:57:00,814 - INFO - root - assert_statement: Executed statement at line 191, suite from postgres/jointest/join2.test, in 0.023 seconds
2025-10-26 15:57:00,840 - INFO - root - assert_statement: Executed statement at line 194, suite from postgres/jointest/join2.test, in 0.026 seconds
2025-10-26 15:57:00,874 - INFO - root - assert_statement: Executed statement at line 197, suite from postgres/jointest/join2.test, in 0.034 seconds
2025-10-26 15:57:00,898 - INFO - root - assert_statement: Executed statement at line 200, suite from postgres/jointest/join2.test, in 0.024 seconds
2025-10-26 15:57:00,926 - INFO - root - assert_statement: Executed statement at line 203, suite from postgres/jointest/join2.test, in 0.028 seconds
2025-10-26 15:57:00,951 - INFO - root - assert_statement: Executed statement at line 206, suite from postgres/jointest/join2.test, in 0.025 seconds
2025-10-26 15:57:00,980 - INFO - root - assert_statement: Executed statement at line 209, suite from postgres/jointest/join2.test, in 0.028 seconds
2025-10-26 15:57:00,995 - INFO - root - assert_statement: Executed statement at line 212, suite from postgres/jointest/join2.test, in 0.015 seconds
2025-10-26 15:57:01,010 - INFO - root - assert_statement: Executed statement at line 216, suite from postgres/jointest/join2.test, in 0.015 seconds
2025-10-26 15:57:01,067 - INFO - root - assert_statement: Executed statement at line 220, suite from postgres/jointest/join2.test, in 0.056 seconds
2025-10-26 15:57:01,117 - INFO - root - assert_statement: Executed statement at line 225, suite from postgres/jointest/join2.test, in 0.050 seconds
2025-10-26 15:57:01,204 - INFO - root - assert_statement: Executed statement at line 230, suite from postgres/jointest/join2.test, in 0.086 seconds
2025-10-26 15:57:01,309 - INFO - root - assert_statement: Executed statement at line 236, suite from postgres/jointest/join2.test, in 0.105 seconds
2025-10-26 15:57:01,415 - INFO - root - assert_statement: Executed statement at line 242, suite from postgres/jointest/join2.test, in 0.106 seconds
2025-10-26 15:57:01,535 - INFO - root - assert_statement: Executed statement at line 248, suite from postgres/jointest/join2.test, in 0.120 seconds
2025-10-26 15:57:01,574 - INFO - root - assert_statement: Executed statement at line 255, suite from postgres/jointest/join2.test, in 0.039 seconds
2025-10-26 15:57:01,576 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-26 15:57:01,591 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-26 15:57:04,594 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:61108/1
2025-10-26 15:57:04,594 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/64326
2025-10-26 15:57:04,595 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/14350
2025-10-26 15:57:04,595 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/61108
2025-10-26 15:57:04,595 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/31923
2025-10-26 15:57:04,892 - DEBUG - ya.test - _verify_test: Verifying [test_postgres.TestPGSQL.test_sql_suite[plan-jointest/join2.test] in ydb/tests/functional/suite_tests] canonical results
2025-10-26 15:57:05,007 - DEBUG - ya.test - execute: Executing '['/usr/bin/diff', '-U1', '/home/runner/actions_runner/_work/ydb/ydb/ydb/tests/functional/suite_tests/canondata/test_postgres.TestPGSQL.test_sql_suite_plan-jointest_join2.test_/query_10.plan', '/home/runner/.ya/build/build_root/muiu/00076e/canon_tmpl05714xy/query_10.plan']' in 'None' (waiting)
2025-10-26 15:57:05,009 - DEBUG - ya.test - execute: Command pid: 1615085
2025-10-26 15:57:05,110 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-26 15:57:05,110 - DEBUG - ya.test - _save_outputs: Command (pid 1615085) rc: 1
2025-10-26 15:57:05,111 - DEBUG - ya.test - _save_outputs: Command (pid 1615085) elapsed time (sec): 0.1006155014038086
2025-10-26 15:57:05,111 - DEBUG - ya.test - _save_outputs: Command (pid 1615085) wtime: 0.103
2025-10-26 15:57:05,111 - DEBUG - ya.test - _save_outputs: Command (pid 1615085) output:
--- /home/runner/actions_runner/_work/ydb/ydb/ydb/tests/functional/suite_tests/canondata/test_postgres.TestPGSQL.test_sql_suite_plan-jointest_join2.test_/query_10.plan	2025-10-26 14:49:10.554334852 +0000
+++ /home/runner/.ya/build/build_root/muiu/00076e/canon_tmpl05714xy/query_10.plan	2025-10-26 15:57:01.302503685 +0000
@@ -171,3 +171,3 @@
                                                                     {
-                                                                        "CTE Name": "precompute_0_1",
+                                                                        "CTE Name": "precompute_0_0",
                                                                         "Node Type": "ConstantExpr",
@@ -176,3 +176,3 @@
                                                                                 "Inputs": [],
-                                                                                "Iterator": "precompute_0_1",
+                                                                                "Iterator": "precompute_0_0",
                                                                                 "Name": "Iterator"
@@ -194,3 +194,3 @@
                                                     {
-                                                        "CTE Name": "precompute_0_0",
+                                                        "CTE Name": "precompute_0_1",
                                                         "Node Type": "ConstantExpr",
@@ -199,3 +199,3 @@
                                                                 "Inputs": [],
-                                                                "Iterator": "precompute_0_0",
+                                                                "Iterator": "precompute_0_1",
                                                                 "Name": "Iterator"
@@ -225,3 +225,3 @@
                     {
-                        "CTE Name": "precompute_0_1",
+                        "CTE Name": "precompute_0_0",
                         "Node Type": "Aggregate",
@@ -229,3 +229,3 @@
                             {
-                                "Input": "precompute_0_1",
+                                "Input": "precompute_0_0",
                                 "Inputs": [],

2025-10-26 15:57:05,111 - DEBUG - ya.test - _save_outputs: Command (pid 1615085) errors:

2025-10-26 15:57:05,115 - DEBUG - ya.test - execute: Executing '['/usr/bin/diff', '-U1', '/home/runner/actions_runner/_work/ydb/ydb/ydb/tests/functional/suite_tests/canondata/test_postgres.TestPGSQL.test_sql_suite_plan-jointest_join2.test_/query_11.plan', '/home/runner/.ya/build/build_root/muiu/00076e/canon_tmprkp13ohb/query_11.plan']' in 'None' (waiting)
2025-10-26 15:57:05,116 - DEBUG - ya.test - execute: Command pid: 1615086
2025-10-26 15:57:05,216 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-26 15:57:05,217 - DEBUG - ya.test - _save_outputs: Command (pid 1615086) rc: 1
2025-10-26 15:57:05,217 - DEBUG - ya.test - _save_outputs: Command (pid 1615086) elapsed time (sec): 0.10053133964538574
2025-10-26 15:57:05,217 - DEBUG - ya.test - _save_outputs: Command (pid 1615086) wtime: 0.102
2025-10-26 15:57:05,217 - DEBUG - ya.test - _save_outputs: Command (pid 1615086) output:
--- /home/runner/actions_runner/_work/ydb/ydb/ydb/tests/functional/suite_tests/canondata/test_postgres.TestPGSQL.test_sql_suite_plan-jointest_join2.test_/query_11.plan	2025-10-26 14:49:10.554334852 +0000
+++ /home/runner/.ya/build/build_root/muiu/00076e/canon_tmprkp13ohb/query_11.plan	2025-10-26 15:57:01.410506114 +0000
@@ -180,3 +180,3 @@
                                                                     {
-                                                                        "CTE Name": "precompute_0_1",
+                                                                        "CTE Name": "precompute_0_0",
                                                                         "Node Type": "ConstantExpr",
@@ -185,3 +185,3 @@
                                                                                 "Inputs": [],
-                                                                                "Iterator": "precompute_0_1",
+                                                                                "Iterator": "precompute_0_0",
                                                                                 "Name": "Iterator"
@@ -203,3 +203,3 @@
                                                     {
-                                                        "CTE Name": "precompute_0_0",
+                                                        "CTE Name": "precompute_0_1",
                                                         "Node Type": "ConstantExpr",
@@ -208,3 +208,3 @@
                                                                 "Inputs": [],
-                                                                "Iterator": "precompute_0_0",
+                                                                "Iterator": "precompute_0_1",
                                                                 "Name": "Iterator"
@@ -234,3 +234,3 @@
                     {
-                        "CTE Name": "precompute_0_1",
+                        "CTE Name": "precompute_0_0",
                         "Node Type": "Aggregate",
@@ -238,3 +238,3 @@
                             {
-                                "Input": "precompute_0_1",
+                                "Input": "precompute_0_0",
                                 "Inputs": [],

2025-10-26 15:57:05,217 - DEBUG - ya.test - _save_outputs: Command (pid 1615086) errors:

2025-10-26 15:57:05,226 - DEBUG - ya.test - execute: Executing '['/usr/bin/diff', '-U1', '/home/runner/actions_runner/_work/ydb/ydb/ydb/tests/functional/suite_tests/canondata/test_postgres.TestPGSQL.test_sql_suite_plan-jointest_join2.test_/query_9.plan', '/home/runner/.ya/build/build_root/muiu/00076e/canon_tmpxycsyjfj/query_9.plan']' in 'None' (waiting)
2025-10-26 15:57:05,227 - DEBUG - ya.test - execute: Command pid: 1615088
2025-10-26 15:57:05,328 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-26 15:57:05,328 - DEBUG - ya.test - _save_outputs: Command (pid 1615088) rc: 1
2025-10-26 15:57:05,328 - DEBUG - ya.test - _save_outputs: Command (pid 1615088) elapsed time (sec): 0.10055160522460938
2025-10-26 15:57:05,329 - DEBUG - ya.test - _save_outputs: Command (pid 1615088) wtime: 0.102
2025-10-26 15:57:05,329 - DEBUG - ya.test - _save_outputs: Command (pid 1615088) output:
--- /home/runner/actions_runner/_work/ydb/ydb/ydb/tests/functional/suite_tests/canondata/test_postgres.TestPGSQL.test_sql_suite_plan-jointest_join2.test_/query_9.plan	2025-10-26 14:49:10.554334852 +0000
+++ /home/runner/.ya/build/build_root/muiu/00076e/canon_tmpxycsyjfj/query_9.plan	2025-10-26 15:57:01.198501348 +0000
@@ -171,3 +171,3 @@
                                                                     {
-                                                                        "CTE Name": "precompute_0_1",
+                                                                        "CTE Name": "precompute_0_0",
                                                                         "Node Type": "ConstantExpr",
@@ -176,3 +176,3 @@
                                                                                 "Inputs": [],
-                                                                                "Iterator": "precompute_0_1",
+                                                                                "Iterator": "precompute_0_0",
                                                                                 "Name": "Iterator"
@@ -194,3 +194,3 @@
                                                     {
-                                                        "CTE Name": "precompute_0_0",
+                                                        "CTE Name": "precompute_0_1",
                                                         "Node Type": "ConstantExpr",
@@ -199,3 +199,3 @@
                                                                 "Inputs": [],
-                                                                "Iterator": "precompute_0_0",
+                                                                "Iterator": "precompute_0_1",
                                                                 "Name": "Iterator"
@@ -225,3 +225,3 @@
                     {
-                        "CTE Name": "precompute_0_1",
+                        "CTE Name": "precompute_0_0",
                         "Node Type": "Aggregate",
@@ -229,3 +229,3 @@
                             {
-                                "Input": "precompute_0_1",
+                                "Input": "precompute_0_0",
                                 "Inputs": [],

2025-10-26 15:57:05,329 - DEBUG - ya.test - _save_outputs: Command (pid 1615088) errors:

2025-10-26 15:57:05,330 - DEBUG - ya.test - verify: Test test_postgres.TestPGSQL.test_sql_suite[plan-jointest/join2.test] status: fail
2025-10-26 15:57:05,331 - DEBUG - ya.test - verify: 
Test results differ from canonical:
test_result['query_10.plan']: files content differs:
'--- /home/runner/actions_runner/_work/ydb/ydb/ydb/tests/functional/suite_tests/canondata/test_postgres.TestPGSQL.test_sql_suite_plan-jointest_join2.test_/query_10.plan	2025-10-26 14:49:10.554334852 +0000
+++ /home/runner/.ya/build/build_root/muiu/00076e/canon_tmpl05714xy/query_10.plan	2025-10-26 15:57:01.302503685 +0000
@@ -171,3 +171,3 @@
                                                                     {
-                                                                        "CTE Name": "precompute_0_1",
+                                                                        "CTE Name": "precompute_0_0",
                                                                         "Node Type": "ConstantExpr",
@@ -176,3 +176,3 @@
                                                                                 "Inputs": [],
-                                                                                "Iterator": "precompute_0_1",
+                                                                         ...'
see full diff /home/runner/.ya/build/build_root/muiu/00076e/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk2/testing_out_stuff/test_postgres.TestPGSQL.test_sql_suite[plan-jointest/join2.test]/query_10.plan.diff
test_result['query_11.plan']: files content differs:
'--- /home/runner/actions_runner/_work/ydb/ydb/ydb/tests/functional/suite_tests/canondata/test_postgres.TestPGSQL.test_sql_suite_plan-jointest_join2.test_/query_11.plan	2025-10-26 14:49:10.554334852 +0000
+++ /home/runner/.ya/build/build_root/muiu/00076e/canon_tmprkp13ohb/query_11.plan	2025-10-26 15:57:01.410506114 +0000
@@ -180,3 +180,3 @@
                                                                     {
-                                                                        "CTE Name": "precompute_0_1",
+                                                                        "CTE Name": "precompute_0_0",
                                                                         "Node Type": "ConstantExpr",
@@ -185,3 +185,3 @@
                                                                                 "Inputs": [],
-                                                                                "Iterator": "precompute_0_1",
+                                                                         ...'
see full diff /home/runner/.ya/build/build_root/muiu/00076e/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk2/testing_out_stuff/test_postgres.TestPGSQL.test_sql_suite[plan-jointest/join2.test]/query_11.plan.diff
test_result['query_9.plan']: files content differs:
'--- /home/runner/actions_runner/_work/ydb/ydb/ydb/tests/functional/suite_tests/canondata/test_postgres.TestPGSQL.test_sql_suite_plan-jointest_join2.test_/query_9.plan	2025-10-26 14:49:10.554334852 +0000
+++ /home/runner/.ya/build/build_root/muiu/00076e/canon_tmpxycsyjfj/query_9.plan	2025-10-26 15:57:01.198501348 +0000
@@ -171,3 +171,3 @@
                                                                     {
-                                                                        "CTE Name": "precompute_0_1",
+                                                                        "CTE Name": "precompute_0_0",
                                                                         "Node Type": "ConstantExpr",
@@ -176,3 +176,3 @@
                                                                                 "Inputs": [],
-                                                                                "Iterator": "precompute_0_1",
+                                                                           ...'
see full diff /home/runner/.ya/build/build_root/muiu/00076e/ydb/tests/functional/suite_tests/test-results/py3test/testing_out_stuff/test_postgres/chunk2/testing_out_stuff/test_postgres.TestPGSQL.test_sql_suite[plan-jointest/join2.test]/query_9.plan.diff
