{"data": {"kwargs": "{'category': 'dbt', 'action': 'invocation', 'label': 'start', 'context': [, , ]}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'invocation', 'label': 'start', 'context': [, , ]}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.167339Z"}} {"data": {"log_version": 3, "version": "=1.9.8"}, "info": {"category": "", "code": "A001", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "info", "msg": "Running with dbt=1.9.8", "name": "MainReportVersion", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.169794Z"}} {"data": {"args": {"cache_selected_only": "False", "debug": "False", "empty": "None", "fail_fast": "False", "indirect_selection": "eager", "introspect": "True", "invocation_command": "dbt report -p .", "log_cache_events": "False", "log_format": "json", "log_path": "/Users/nazareno/workspace/data-etl/code/data_tests/edr_target", "no_print": "None", "partial_parse": "True", "printer_width": "80", "profiles_dir": "/Users/nazareno/workspace/data-etl/code/data_tests", "quiet": "True", "send_anonymous_usage_stats": "True", "static_parser": "True", "target_path": "None", "use_colors": "True", "use_experimental_parser": "False", "version_check": "True", "warn_error": "None", "warn_error_options": "WarnErrorOptions(include=[], exclude=[])", "write_json": "True"}}, "info": {"category": "", "code": "A002", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "running dbt with arguments {'printer_width': '80', 'indirect_selection': 'eager', 'log_cache_events': 'False', 'write_json': 'True', 'partial_parse': 'True', 'cache_selected_only': 'False', 'warn_error': 'None', 'debug': 'False', 'profiles_dir': '/Users/nazareno/workspace/data-etl/code/data_tests', 'log_path': '/Users/nazareno/workspace/data-etl/code/data_tests/edr_target', 'fail_fast': 'False', 'version_check': 'True', 'use_colors': 'True', 'use_experimental_parser': 'False', 'empty': 'None', 'quiet': 'True', 'no_print': 'None', 'log_format': 'json', 'static_parser': 'True', 'invocation_command': 'dbt report -p .', 'warn_error_options': 'WarnErrorOptions(include=[], exclude=[])', 'target_path': 'None', 'introspect': 'True', 'send_anonymous_usage_stats': 'True'}", "name": "MainReportArgs", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.170080Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'project_id', 'label': '03c355b0-9e65-48f9-84db-f23c8fb12c40', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'project_id', 'label': '03c355b0-9e65-48f9-84db-f23c8fb12c40', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.228810Z"}} {"data": {"path": "/var/folders/3f/smk7vhhx09x5sf3_vqvn9qrm0000gn/T/dbt-downloads-dfju5qui"}, "info": {"category": "", "code": "M028", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Set downloads directory='/var/folders/3f/smk7vhhx09x5sf3_vqvn9qrm0000gn/T/dbt-downloads-dfju5qui'", "name": "DepsSetDownloadDirectory", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.280162Z"}} {"data": {"url": "https://hub.getdbt.com/api/v1/index.json"}, "info": {"category": "", "code": "M022", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Making package index registry request: GET https://hub.getdbt.com/api/v1/index.json", "name": "RegistryIndexProgressGETRequest", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.280464Z"}} {"data": {"resp_code": 200, "url": "https://hub.getdbt.com/api/v1/index.json"}, "info": {"category": "", "code": "M023", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Response from registry index: GET https://hub.getdbt.com/api/v1/index.json 200", "name": "RegistryIndexProgressGETResponse", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.322439Z"}} {"data": {"url": "https://hub.getdbt.com/api/v1/dbt-labs/dbt_utils.json"}, "info": {"category": "", "code": "M008", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Making package registry request: GET https://hub.getdbt.com/api/v1/dbt-labs/dbt_utils.json", "name": "RegistryProgressGETRequest", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.323636Z"}} {"data": {"resp_code": 200, "url": "https://hub.getdbt.com/api/v1/dbt-labs/dbt_utils.json"}, "info": {"category": "", "code": "M009", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Response from registry: GET https://hub.getdbt.com/api/v1/dbt-labs/dbt_utils.json 200", "name": "RegistryProgressGETResponse", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.366556Z"}} {"data": {"url": "https://hub.getdbt.com/api/v1/elementary-data/elementary.json"}, "info": {"category": "", "code": "M008", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Making package registry request: GET https://hub.getdbt.com/api/v1/elementary-data/elementary.json", "name": "RegistryProgressGETRequest", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.370661Z"}} {"data": {"resp_code": 200, "url": "https://hub.getdbt.com/api/v1/elementary-data/elementary.json"}, "info": {"category": "", "code": "M009", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Response from registry: GET https://hub.getdbt.com/api/v1/elementary-data/elementary.json 200", "name": "RegistryProgressGETResponse", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.418577Z"}} {"data": {"package_name": "dbt-labs/dbt_utils"}, "info": {"category": "", "code": "M014", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "info", "msg": "Installing dbt-labs/dbt_utils", "name": "DepsStartPackageInstall", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.423373Z"}} {"data": {"version_name": "version 0.8.6"}, "info": {"category": "", "code": "M015", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "info", "msg": "Installed from version 0.8.6", "name": "DepsInstallInfo", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.738599Z"}} {"data": {"version_latest": "1.3.0"}, "info": {"category": "", "code": "M016", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "info", "msg": "Updated version available: 1.3.0", "name": "DepsUpdateAvailable", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.738957Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'package', 'label': '03c355b0-9e65-48f9-84db-f23c8fb12c40', 'property_': 'install', 'context': [, ]}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'package', 'label': '03c355b0-9e65-48f9-84db-f23c8fb12c40', 'property_': 'install', 'context': [, ]}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.739204Z"}} {"data": {"package_name": "elementary-data/elementary"}, "info": {"category": "", "code": "M014", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "info", "msg": "Installing elementary-data/elementary", "name": "DepsStartPackageInstall", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:14.739470Z"}} {"data": {"version_name": "version 0.19.1"}, "info": {"category": "", "code": "M015", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "info", "msg": "Installed from version 0.19.1", "name": "DepsInstallInfo", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.075217Z"}} {"data": {}, "info": {"category": "", "code": "M017", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "info", "msg": "Up to date!", "name": "DepsUpToDate", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.075517Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'package', 'label': '03c355b0-9e65-48f9-84db-f23c8fb12c40', 'property_': 'install', 'context': [, ]}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'package', 'label': '03c355b0-9e65-48f9-84db-f23c8fb12c40', 'property_': 'install', 'context': [, ]}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.075725Z"}} {"data": {"msg": ""}, "info": {"category": "", "code": "Z017", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "info", "msg": "", "name": "Formatting", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.075973Z"}} {"data": {"packages": ["dbt-labs/dbt_utils"]}, "info": {"category": "", "code": "M019", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "info", "msg": "Updates available for packages: ['dbt-labs/dbt_utils'] \nUpdate your versions in packages.yml, then run dbt deps", "name": "DepsNotifyUpdatesAvailable", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.076158Z"}} {"data": {"command_name": "deps", "command_success": true, "command_wall_clock_time": 0.94428337, "process_in_blocks": "0", "process_kernel_time": 0.245589, "process_mem_max_rss": "141082624", "process_out_blocks": "0", "process_user_time": 0.851564}, "info": {"category": "", "code": "Z051", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Resource report: {\"command_name\": \"deps\", \"command_success\": true, \"command_wall_clock_time\": 0.94428337, \"process_in_blocks\": \"0\", \"process_kernel_time\": 0.245589, \"process_mem_max_rss\": \"141082624\", \"process_out_blocks\": \"0\", \"process_user_time\": 0.851564}", "name": "ResourceReport", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.076737Z"}} {"data": {"command": "cli deps", "completed_at": "2025-07-16T19:10:15.077008Z", "elapsed": 0.94462514, "success": true}, "info": {"category": "", "code": "Q039", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Command `cli deps` succeeded at 16:10:15.077008 after 0.94 seconds", "name": "CommandCompleted", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.077058Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'invocation', 'label': 'end', 'context': [, , ]}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'invocation', 'label': 'end', 'context': [, , ]}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.077280Z"}} {"data": {}, "info": {"category": "", "code": "Z042", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "Flushing usage events", "name": "FlushEvents", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.077481Z"}} {"data": {}, "info": {"category": "", "code": "Z043", "extra": {}, "invocation_id": "03c355b0-9e65-48f9-84db-f23c8fb12c40", "level": "debug", "msg": "An error was encountered while trying to flush usage events", "name": "FlushEventsFailure", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.754113Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'invocation', 'label': 'start', 'context': [, , ]}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'invocation', 'label': 'start', 'context': [, , ]}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.763763Z"}} {"data": {"log_version": 3, "version": "=1.9.8"}, "info": {"category": "", "code": "A001", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "info", "msg": "Running with dbt=1.9.8", "name": "MainReportVersion", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.764629Z"}} {"data": {"args": {"cache_selected_only": "False", "debug": "False", "empty": "None", "fail_fast": "False", "indirect_selection": "eager", "introspect": "True", "invocation_command": "dbt report -p .", "log_cache_events": "False", "log_format": "json", "log_path": "/Users/nazareno/workspace/data-etl/code/data_tests/edr_target", "no_print": "None", "partial_parse": "True", "printer_width": "80", "profiles_dir": "/Users/nazareno/workspace/data-etl/code/data_tests", "quiet": "True", "send_anonymous_usage_stats": "True", "static_parser": "True", "target_path": "None", "use_colors": "True", "use_experimental_parser": "False", "version_check": "True", "warn_error": "None", "warn_error_options": "WarnErrorOptions(include=[], exclude=[])", "write_json": "True"}}, "info": {"category": "", "code": "A002", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "running dbt with arguments {'printer_width': '80', 'indirect_selection': 'eager', 'log_cache_events': 'False', 'write_json': 'True', 'partial_parse': 'True', 'cache_selected_only': 'False', 'warn_error': 'None', 'debug': 'False', 'profiles_dir': '/Users/nazareno/workspace/data-etl/code/data_tests', 'log_path': '/Users/nazareno/workspace/data-etl/code/data_tests/edr_target', 'fail_fast': 'False', 'version_check': 'True', 'use_colors': 'True', 'use_experimental_parser': 'False', 'empty': 'None', 'quiet': 'True', 'no_print': 'None', 'log_format': 'json', 'static_parser': 'True', 'invocation_command': 'dbt report -p .', 'warn_error_options': 'WarnErrorOptions(include=[], exclude=[])', 'target_path': 'None', 'introspect': 'True', 'send_anonymous_usage_stats': 'True'}", "name": "MainReportArgs", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.765408Z"}} {"data": {"profile_name": "elementary", "target_name": "default"}, "info": {"category": "", "code": "A005", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "info", "msg": "target not specified in profile 'elementary', using 'default'", "name": "MissingProfileTarget", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.766740Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'project_id', 'label': '0cdb0d84-2a14-456c-a634-a502ea4e3940', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'project_id', 'label': '0cdb0d84-2a14-456c-a634-a502ea4e3940', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.827695Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'adapter_info', 'label': '0cdb0d84-2a14-456c-a634-a502ea4e3940', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'adapter_info', 'label': '0cdb0d84-2a14-456c-a634-a502ea4e3940', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.854342Z"}} {"data": {"adapter_name": "postgres", "adapter_version": "=1.9.0"}, "info": {"category": "", "code": "E034", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "info", "msg": "Registered adapter: postgres=1.9.0", "name": "AdapterRegistered", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.855196Z"}} {"data": {"checksum": "2898e47e730fa32147e5ae95c02f42c1d51b8fd2b81922b9b731f0208caf4dff", "profile": "", "target": "", "vars": "{}", "version": "1.9.8"}, "info": {"category": "", "code": "I025", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "checksum: 2898e47e730fa32147e5ae95c02f42c1d51b8fd2b81922b9b731f0208caf4dff, vars: {}, profile: , target: , version: 1.9.8", "name": "StateCheckVarsHash", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:15.944006Z"}} {"data": {"added": 0, "changed": 0, "deleted": 0}, "info": {"category": "", "code": "I040", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Partial parsing enabled: 0 files deleted, 0 files added, 0 files changed.", "name": "PartialParsingEnabled", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:16.089763Z"}} {"data": {}, "info": {"category": "", "code": "I017", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Partial parsing enabled, no changes found, skipping parsing", "name": "PartialParsingSkipParsing", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:16.090118Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'load_project', 'label': '0cdb0d84-2a14-456c-a634-a502ea4e3940', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'load_project', 'label': '0cdb0d84-2a14-456c-a634-a502ea4e3940', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:16.127257Z"}} {"data": {"artifact_path": "/Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/manifest.json", "artifact_type": "WritableManifest"}, "info": {"category": "", "code": "P001", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Wrote artifact WritableManifest to /Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/manifest.json", "name": "ArtifactWritten", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:16.242310Z"}} {"data": {"artifact_path": "/Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/semantic_manifest.json", "artifact_type": "SemanticManifest"}, "info": {"category": "", "code": "P001", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Wrote artifact SemanticManifest to /Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/semantic_manifest.json", "name": "ArtifactWritten", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:16.244374Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'resource_counts', 'label': '0cdb0d84-2a14-456c-a634-a502ea4e3940', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'resource_counts', 'label': '0cdb0d84-2a14-456c-a634-a502ea4e3940', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:16.248306Z"}} {"data": {"stat_line": "41 models, 2 operations, 6 data tests, 6 sources, 1479 macros"}, "info": {"category": "", "code": "W006", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "info", "msg": "Found 41 models, 2 operations, 6 data tests, 6 sources, 1479 macros", "name": "FoundStats", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:16.248642Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'runnable_timing', 'label': '0cdb0d84-2a14-456c-a634-a502ea4e3940', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'runnable_timing', 'label': '0cdb0d84-2a14-456c-a634-a502ea4e3940', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:16.248836Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E005", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Acquiring new postgres connection 'macro_log_macro_results'", "name": "NewConnection", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:16.249148Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:16.249367Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "BEGIN"}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "On macro_log_macro_results: BEGIN", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:16.249558Z"}} {"data": {"connection_state": "init"}, "info": {"category": "", "code": "E037", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Opening a new connection, currently in state init", "name": "NewConnectionOpening", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:16.249739Z"}} {"data": {"elapsed": 1.1674099, "query_id": "", "status": "BEGIN"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "SQL status: BEGIN in 1.167 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.417754Z"}} {"data": {"conn_name": "macro_log_macro_results"}, "info": {"category": "", "code": "E018", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "On macro_log_macro_results: COMMIT", "name": "SQLCommit", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.420077Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.421207Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "COMMIT"}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "On macro_log_macro_results: COMMIT", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.422245Z"}} {"data": {"elapsed": 0.14289075, "query_id": "", "status": "COMMIT"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "SQL status: COMMIT in 0.143 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.566043Z"}} {"data": {"conn_name": "macro_log_macro_results", "database": "pipelinedb", "schema": "scoring_dbt_elementary"}, "info": {"category": "", "code": "E013", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "On \"macro_log_macro_results\": cache miss for schema \"pipelinedb.scoring_dbt_elementary\", this is inefficient", "name": "CacheMiss", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.588236Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.623775Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "BEGIN"}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "On macro_log_macro_results: BEGIN", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.624135Z"}} {"data": {"elapsed": 0.14277291, "query_id": "", "status": "BEGIN"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "SQL status: BEGIN in 0.143 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.767585Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.769336Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "/* {\"app\": \"dbt\", \"dbt_version\": \"1.9.8\", \"profile_name\": \"elementary\", \"target_name\": \"default\", \"connection_name\": \"macro_log_macro_results\"} */\nselect\n 'pipelinedb' as database,\n tablename as name,\n schemaname as schema,\n 'table' as type\n from pg_tables\n where schemaname ilike 'scoring_dbt_elementary'\n union all\n select\n 'pipelinedb' as database,\n viewname as name,\n schemaname as schema,\n 'view' as type\n from pg_views\n where schemaname ilike 'scoring_dbt_elementary'\n union all\n select\n 'pipelinedb' as database,\n matviewname as name,\n schemaname as schema,\n 'materialized_view' as type\n from pg_matviews\n where schemaname ilike 'scoring_dbt_elementary'\n "}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "On macro_log_macro_results: /* {\"app\": \"dbt\", \"dbt_version\": \"1.9.8\", \"profile_name\": \"elementary\", \"target_name\": \"default\", \"connection_name\": \"macro_log_macro_results\"} */\nselect\n 'pipelinedb' as database,\n tablename as name,\n schemaname as schema,\n 'table' as type\n from pg_tables\n where schemaname ilike 'scoring_dbt_elementary'\n union all\n select\n 'pipelinedb' as database,\n viewname as name,\n schemaname as schema,\n 'view' as type\n from pg_views\n where schemaname ilike 'scoring_dbt_elementary'\n union all\n select\n 'pipelinedb' as database,\n matviewname as name,\n schemaname as schema,\n 'materialized_view' as type\n from pg_matviews\n where schemaname ilike 'scoring_dbt_elementary'\n ", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.770278Z"}} {"data": {"elapsed": 0.1634165, "query_id": "", "status": "SELECT 30"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "SQL status: SELECT 30 in 0.163 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.935152Z"}} {"data": {"database": "pipelinedb", "relations": [{"database": "pipelinedb", "identifier": "dbt_groups", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_metrics", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_models", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_run_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_seeds", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "elementary_test_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_source_freshness_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_snapshots", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_exposures", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_sources", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "data_monitoring_metrics", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_columns", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_invocations", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "test_result_rows", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "schema_columns_snapshot", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_tests", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "metadata", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "job_run_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "model_run_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "seed_run_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "snapshot_run_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "alerts_dbt_source_freshness", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_artifacts_hashes", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "alerts_anomaly_detection", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "alerts_dbt_tests", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "alerts_schema_changes", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "alerts_dbt_models", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "metrics_anomaly_score", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "monitors_runs", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "anomaly_threshold_sensitivity", "schema": "scoring_dbt_elementary"}], "schema": "scoring_dbt_elementary"}, "info": {"category": "", "code": "E014", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "While listing relations in database=pipelinedb, schema=scoring_dbt_elementary, found: dbt_groups, dbt_metrics, dbt_models, dbt_run_results, dbt_seeds, elementary_test_results, dbt_source_freshness_results, dbt_snapshots, dbt_exposures, dbt_sources, data_monitoring_metrics, dbt_columns, dbt_invocations, test_result_rows, schema_columns_snapshot, dbt_tests, metadata, job_run_results, model_run_results, seed_run_results, snapshot_run_results, alerts_dbt_source_freshness, dbt_artifacts_hashes, alerts_anomaly_detection, alerts_dbt_tests, alerts_schema_changes, alerts_dbt_models, metrics_anomaly_score, monitors_runs, anomaly_threshold_sensitivity", "name": "ListRelations", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.945719Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.952665Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "/* {\"app\": \"dbt\", \"dbt_version\": \"1.9.8\", \"profile_name\": \"elementary\", \"target_name\": \"default\", \"connection_name\": \"macro_log_macro_results\"} */\n\n \n select * from \"pipelinedb\".\"scoring_dbt_elementary\".\"dbt_invocations\" order by generated_at desc limit 1\n \n "}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "On macro_log_macro_results: /* {\"app\": \"dbt\", \"dbt_version\": \"1.9.8\", \"profile_name\": \"elementary\", \"target_name\": \"default\", \"connection_name\": \"macro_log_macro_results\"} */\n\n \n select * from \"pipelinedb\".\"scoring_dbt_elementary\".\"dbt_invocations\" order by generated_at desc limit 1\n \n ", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:17.953198Z"}} {"data": {"elapsed": 0.15893891, "query_id": "", "status": "SELECT 1"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "SQL status: SELECT 1 in 0.159 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.112964Z"}} {"data": {"msg": "Elementary: --ELEMENTARY-MACRO-OUTPUT-START--[{\"invocation_id\": \"e27bd45b-4368-49b9-9861-d30979035dc9\", \"job_id\": null, \"job_name\": null, \"job_run_id\": null, \"run_started_at\": \"2025-07-16 18:46:09\", \"run_completed_at\": \"2025-07-16 19:02:20\", \"generated_at\": \"2025-07-16 19:02:20\", \"created_at\": \"2025-07-16T19:02:20.397761\", \"command\": \"run\", \"dbt_version\": \"1.9.8\", \"elementary_version\": \"0.19.0\", \"full_refresh\": true, \"invocation_vars\": \"{}\", \"vars\": \"{\\\"jebena_project_group_id\\\": 1, \\\"jebena_answer_modified_on_cutoff\\\": \\\"{{ env_var('JEBENA_ANSWER_MODIFIED_ON_CUTOFF', '2025-06-01') }}\\\"}\", \"target_name\": \"dev\", \"target_database\": \"pipelinedb\", \"target_schema\": \"scoring_dbt\", \"target_profile_name\": \"pipeline\", \"threads\": 4, \"selected\": \"[\\\"tag:requires_dbt_test\\\"]\", \"yaml_selector\": null, \"project_id\": null, \"project_name\": \"scoring_dbt\", \"env\": null, \"env_id\": null, \"cause_category\": null, \"cause\": null, \"pull_request_id\": null, \"git_sha\": null, \"orchestrator\": null, \"dbt_user\": null, \"job_url\": null, \"job_run_url\": null, \"account_id\": null, \"target_adapter_specific_fields\": \"{\\\"user\\\": \\\"ZPKmjZBhIlcapHj6nR2f7TkJzk1yL0Oa\\\"}\"}]--ELEMENTARY-MACRO-OUTPUT-END--"}, "info": {"category": "", "code": "I062", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "info", "msg": "Elementary: --ELEMENTARY-MACRO-OUTPUT-START--[{\"invocation_id\": \"e27bd45b-4368-49b9-9861-d30979035dc9\", \"job_id\": null, \"job_name\": null, \"job_run_id\": null, \"run_started_at\": \"2025-07-16 18:46:09\", \"run_completed_at\": \"2025-07-16 19:02:20\", \"generated_at\": \"2025-07-16 19:02:20\", \"created_at\": \"2025-07-16T19:02:20.397761\", \"command\": \"run\", \"dbt_version\": \"1.9.8\", \"elementary_version\": \"0.19.0\", \"full_refresh\": true, \"invocation_vars\": \"{}\", \"vars\": \"{\\\"jebena_project_group_id\\\": 1, \\\"jebena_answer_modified_on_cutoff\\\": \\\"{{ env_var('JEBENA_ANSWER_MODIFIED_ON_CUTOFF', '2025-06-01') }}\\\"}\", \"target_name\": \"dev\", \"target_database\": \"pipelinedb\", \"target_schema\": \"scoring_dbt\", \"target_profile_name\": \"pipeline\", \"threads\": 4, \"selected\": \"[\\\"tag:requires_dbt_test\\\"]\", \"yaml_selector\": null, \"project_id\": null, \"project_name\": \"scoring_dbt\", \"env\": null, \"env_id\": null, \"cause_category\": null, \"cause\": null, \"pull_request_id\": null, \"git_sha\": null, \"orchestrator\": null, \"dbt_user\": null, \"job_url\": null, \"job_run_url\": null, \"account_id\": null, \"target_adapter_specific_fields\": \"{\\\"user\\\": \\\"ZPKmjZBhIlcapHj6nR2f7TkJzk1yL0Oa\\\"}\"}]--ELEMENTARY-MACRO-OUTPUT-END--", "name": "JinjaLogInfo", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.125566Z"}} {"data": {"conn_name": "macro_log_macro_results"}, "info": {"category": "", "code": "E012", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "On macro_log_macro_results: ROLLBACK", "name": "Rollback", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.126390Z"}} {"data": {"conn_name": "macro_log_macro_results"}, "info": {"category": "", "code": "E010", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "On macro_log_macro_results: Close", "name": "ConnectionClosed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.271442Z"}} {"data": {"artifact_path": "/Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/run_results.json", "artifact_type": "RunResultsArtifact"}, "info": {"category": "", "code": "P001", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Wrote artifact RunResultsArtifact to /Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/run_results.json", "name": "ArtifactWritten", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.286133Z"}} {"data": {"command_name": "run-operation", "command_success": true, "command_wall_clock_time": 2.525899, "process_in_blocks": "0", "process_kernel_time": 0.351028, "process_mem_max_rss": "170541056", "process_out_blocks": "0", "process_user_time": 1.354335}, "info": {"category": "", "code": "Z051", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Resource report: {\"command_name\": \"run-operation\", \"command_success\": true, \"command_wall_clock_time\": 2.525899, \"process_in_blocks\": \"0\", \"process_kernel_time\": 0.351028, \"process_mem_max_rss\": \"170541056\", \"process_out_blocks\": \"0\", \"process_user_time\": 1.354335}", "name": "ResourceReport", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.286788Z"}} {"data": {"command": "cli run-operation", "completed_at": "2025-07-16T19:10:18.287337Z", "elapsed": 2.5265799, "success": true}, "info": {"category": "", "code": "Q039", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Command `cli run-operation` succeeded at 16:10:18.287337 after 2.53 seconds", "name": "CommandCompleted", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.287434Z"}} {"data": {"conn_name": "macro_log_macro_results"}, "info": {"category": "", "code": "E008", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Connection 'macro_log_macro_results' was properly closed.", "name": "ConnectionClosedInCleanup", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.287879Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'invocation', 'label': 'end', 'context': [, , ]}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'invocation', 'label': 'end', 'context': [, , ]}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.288296Z"}} {"data": {}, "info": {"category": "", "code": "Z042", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "Flushing usage events", "name": "FlushEvents", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.288755Z"}} {"data": {}, "info": {"category": "", "code": "Z043", "extra": {}, "invocation_id": "0cdb0d84-2a14-456c-a634-a502ea4e3940", "level": "debug", "msg": "An error was encountered while trying to flush usage events", "name": "FlushEventsFailure", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.896647Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'invocation', 'label': 'start', 'context': [, , ]}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'invocation', 'label': 'start', 'context': [, , ]}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.912430Z"}} {"data": {"log_version": 3, "version": "=1.9.8"}, "info": {"category": "", "code": "A001", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "info", "msg": "Running with dbt=1.9.8", "name": "MainReportVersion", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.913151Z"}} {"data": {"args": {"cache_selected_only": "False", "debug": "False", "empty": "None", "fail_fast": "False", "indirect_selection": "eager", "introspect": "True", "invocation_command": "dbt report -p .", "log_cache_events": "False", "log_format": "json", "log_path": "/Users/nazareno/workspace/data-etl/code/data_tests/edr_target", "no_print": "None", "partial_parse": "True", "printer_width": "80", "profiles_dir": "/Users/nazareno/workspace/data-etl/code/data_tests", "quiet": "True", "send_anonymous_usage_stats": "True", "static_parser": "True", "target_path": "None", "use_colors": "True", "use_experimental_parser": "False", "version_check": "True", "warn_error": "None", "warn_error_options": "WarnErrorOptions(include=[], exclude=[])", "write_json": "True"}}, "info": {"category": "", "code": "A002", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "running dbt with arguments {'printer_width': '80', 'indirect_selection': 'eager', 'log_cache_events': 'False', 'write_json': 'True', 'partial_parse': 'True', 'cache_selected_only': 'False', 'warn_error': 'None', 'debug': 'False', 'profiles_dir': '/Users/nazareno/workspace/data-etl/code/data_tests', 'log_path': '/Users/nazareno/workspace/data-etl/code/data_tests/edr_target', 'fail_fast': 'False', 'version_check': 'True', 'use_colors': 'True', 'use_experimental_parser': 'False', 'empty': 'None', 'quiet': 'True', 'no_print': 'None', 'log_format': 'json', 'static_parser': 'True', 'invocation_command': 'dbt report -p .', 'warn_error_options': 'WarnErrorOptions(include=[], exclude=[])', 'target_path': 'None', 'introspect': 'True', 'send_anonymous_usage_stats': 'True'}", "name": "MainReportArgs", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.913661Z"}} {"data": {"profile_name": "elementary", "target_name": "default"}, "info": {"category": "", "code": "A005", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "info", "msg": "target not specified in profile 'elementary', using 'default'", "name": "MissingProfileTarget", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.914758Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'project_id', 'label': '64930f80-c34f-443c-b3ee-5932d5e3e052', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'project_id', 'label': '64930f80-c34f-443c-b3ee-5932d5e3e052', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.934287Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'adapter_info', 'label': '64930f80-c34f-443c-b3ee-5932d5e3e052', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'adapter_info', 'label': '64930f80-c34f-443c-b3ee-5932d5e3e052', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.935454Z"}} {"data": {"adapter_name": "postgres", "adapter_version": "=1.9.0"}, "info": {"category": "", "code": "E034", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "info", "msg": "Registered adapter: postgres=1.9.0", "name": "AdapterRegistered", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.935872Z"}} {"data": {"checksum": "2898e47e730fa32147e5ae95c02f42c1d51b8fd2b81922b9b731f0208caf4dff", "profile": "", "target": "", "vars": "{}", "version": "1.9.8"}, "info": {"category": "", "code": "I025", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "checksum: 2898e47e730fa32147e5ae95c02f42c1d51b8fd2b81922b9b731f0208caf4dff, vars: {}, profile: , target: , version: 1.9.8", "name": "StateCheckVarsHash", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:18.944469Z"}} {"data": {"added": 0, "changed": 0, "deleted": 0}, "info": {"category": "", "code": "I040", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Partial parsing enabled: 0 files deleted, 0 files added, 0 files changed.", "name": "PartialParsingEnabled", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:19.002506Z"}} {"data": {}, "info": {"category": "", "code": "I017", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Partial parsing enabled, no changes found, skipping parsing", "name": "PartialParsingSkipParsing", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:19.002794Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'load_project', 'label': '64930f80-c34f-443c-b3ee-5932d5e3e052', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'load_project', 'label': '64930f80-c34f-443c-b3ee-5932d5e3e052', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:19.041373Z"}} {"data": {"artifact_path": "/Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/manifest.json", "artifact_type": "WritableManifest"}, "info": {"category": "", "code": "P001", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Wrote artifact WritableManifest to /Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/manifest.json", "name": "ArtifactWritten", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:19.079140Z"}} {"data": {"artifact_path": "/Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/semantic_manifest.json", "artifact_type": "SemanticManifest"}, "info": {"category": "", "code": "P001", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Wrote artifact SemanticManifest to /Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/semantic_manifest.json", "name": "ArtifactWritten", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:19.080187Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'resource_counts', 'label': '64930f80-c34f-443c-b3ee-5932d5e3e052', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'resource_counts', 'label': '64930f80-c34f-443c-b3ee-5932d5e3e052', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:19.082994Z"}} {"data": {"stat_line": "41 models, 2 operations, 6 data tests, 6 sources, 1479 macros"}, "info": {"category": "", "code": "W006", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "info", "msg": "Found 41 models, 2 operations, 6 data tests, 6 sources, 1479 macros", "name": "FoundStats", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:19.083264Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'runnable_timing', 'label': '64930f80-c34f-443c-b3ee-5932d5e3e052', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'runnable_timing', 'label': '64930f80-c34f-443c-b3ee-5932d5e3e052', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:19.083448Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E005", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Acquiring new postgres connection 'macro_log_macro_results'", "name": "NewConnection", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:19.083749Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:19.083982Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "BEGIN"}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "On macro_log_macro_results: BEGIN", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:19.084176Z"}} {"data": {"connection_state": "init"}, "info": {"category": "", "code": "E037", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Opening a new connection, currently in state init", "name": "NewConnectionOpening", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:19.084358Z"}} {"data": {"elapsed": 1.2770066, "query_id": "", "status": "BEGIN"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "SQL status: BEGIN in 1.277 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:20.361685Z"}} {"data": {"conn_name": "macro_log_macro_results"}, "info": {"category": "", "code": "E018", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "On macro_log_macro_results: COMMIT", "name": "SQLCommit", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:20.363271Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:20.364198Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "COMMIT"}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "On macro_log_macro_results: COMMIT", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:20.364980Z"}} {"data": {"elapsed": 0.142694, "query_id": "", "status": "COMMIT"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "SQL status: COMMIT in 0.143 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:20.508533Z"}} {"data": {"msg": "Elementary: --ELEMENTARY-MACRO-OUTPUT-START--[\"postgres\", \"localhost\"]--ELEMENTARY-MACRO-OUTPUT-END--"}, "info": {"category": "", "code": "I062", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "info", "msg": "Elementary: --ELEMENTARY-MACRO-OUTPUT-START--[\"postgres\", \"localhost\"]--ELEMENTARY-MACRO-OUTPUT-END--", "name": "JinjaLogInfo", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:20.520901Z"}} {"data": {"conn_name": "macro_log_macro_results"}, "info": {"category": "", "code": "E010", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "On macro_log_macro_results: Close", "name": "ConnectionClosed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:20.521286Z"}} {"data": {"artifact_path": "/Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/run_results.json", "artifact_type": "RunResultsArtifact"}, "info": {"category": "", "code": "P001", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Wrote artifact RunResultsArtifact to /Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/run_results.json", "name": "ArtifactWritten", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:20.523058Z"}} {"data": {"command_name": "run-operation", "command_success": true, "command_wall_clock_time": 1.6130388, "process_in_blocks": "0", "process_kernel_time": 0.383705, "process_mem_max_rss": "179486720", "process_out_blocks": "0", "process_user_time": 1.567641}, "info": {"category": "", "code": "Z051", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Resource report: {\"command_name\": \"run-operation\", \"command_success\": true, \"command_wall_clock_time\": 1.6130388, \"process_in_blocks\": \"0\", \"process_kernel_time\": 0.383705, \"process_mem_max_rss\": \"179486720\", \"process_out_blocks\": \"0\", \"process_user_time\": 1.567641}", "name": "ResourceReport", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:20.523323Z"}} {"data": {"command": "cli run-operation", "completed_at": "2025-07-16T19:10:20.523579Z", "elapsed": 1.6133572, "success": true}, "info": {"category": "", "code": "Q039", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Command `cli run-operation` succeeded at 16:10:20.523579 after 1.61 seconds", "name": "CommandCompleted", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:20.523623Z"}} {"data": {"conn_name": "macro_log_macro_results"}, "info": {"category": "", "code": "E008", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Connection 'macro_log_macro_results' was properly closed.", "name": "ConnectionClosedInCleanup", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:20.523833Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'invocation', 'label': 'end', 'context': [, , ]}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'invocation', 'label': 'end', 'context': [, , ]}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:20.524038Z"}} {"data": {}, "info": {"category": "", "code": "Z042", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "Flushing usage events", "name": "FlushEvents", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:20.524271Z"}} {"data": {}, "info": {"category": "", "code": "Z043", "extra": {}, "invocation_id": "64930f80-c34f-443c-b3ee-5932d5e3e052", "level": "debug", "msg": "An error was encountered while trying to flush usage events", "name": "FlushEventsFailure", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.141506Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'invocation', 'label': 'start', 'context': [, , ]}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'invocation', 'label': 'start', 'context': [, , ]}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.152641Z"}} {"data": {"log_version": 3, "version": "=1.9.8"}, "info": {"category": "", "code": "A001", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "info", "msg": "Running with dbt=1.9.8", "name": "MainReportVersion", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.153359Z"}} {"data": {"args": {"cache_selected_only": "False", "debug": "False", "empty": "None", "fail_fast": "False", "indirect_selection": "eager", "introspect": "True", "invocation_command": "dbt report -p .", "log_cache_events": "False", "log_format": "json", "log_path": "/Users/nazareno/workspace/data-etl/code/data_tests/edr_target", "no_print": "None", "partial_parse": "True", "printer_width": "80", "profiles_dir": "/Users/nazareno/workspace/data-etl/code/data_tests", "quiet": "True", "send_anonymous_usage_stats": "True", "static_parser": "True", "target_path": "None", "use_colors": "True", "use_experimental_parser": "False", "version_check": "True", "warn_error": "None", "warn_error_options": "WarnErrorOptions(include=[], exclude=[])", "write_json": "True"}}, "info": {"category": "", "code": "A002", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "running dbt with arguments {'printer_width': '80', 'indirect_selection': 'eager', 'log_cache_events': 'False', 'write_json': 'True', 'partial_parse': 'True', 'cache_selected_only': 'False', 'warn_error': 'None', 'debug': 'False', 'profiles_dir': '/Users/nazareno/workspace/data-etl/code/data_tests', 'log_path': '/Users/nazareno/workspace/data-etl/code/data_tests/edr_target', 'fail_fast': 'False', 'version_check': 'True', 'use_colors': 'True', 'use_experimental_parser': 'False', 'empty': 'None', 'quiet': 'True', 'no_print': 'None', 'log_format': 'json', 'static_parser': 'True', 'invocation_command': 'dbt report -p .', 'warn_error_options': 'WarnErrorOptions(include=[], exclude=[])', 'target_path': 'None', 'introspect': 'True', 'send_anonymous_usage_stats': 'True'}", "name": "MainReportArgs", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.153946Z"}} {"data": {"profile_name": "elementary", "target_name": "default"}, "info": {"category": "", "code": "A005", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "info", "msg": "target not specified in profile 'elementary', using 'default'", "name": "MissingProfileTarget", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.154852Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'project_id', 'label': '144d8558-c354-41e7-829d-cbf1438c93e4', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'project_id', 'label': '144d8558-c354-41e7-829d-cbf1438c93e4', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.166274Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'adapter_info', 'label': '144d8558-c354-41e7-829d-cbf1438c93e4', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'adapter_info', 'label': '144d8558-c354-41e7-829d-cbf1438c93e4', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.167102Z"}} {"data": {"adapter_name": "postgres", "adapter_version": "=1.9.0"}, "info": {"category": "", "code": "E034", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "info", "msg": "Registered adapter: postgres=1.9.0", "name": "AdapterRegistered", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.167398Z"}} {"data": {"checksum": "2898e47e730fa32147e5ae95c02f42c1d51b8fd2b81922b9b731f0208caf4dff", "profile": "", "target": "", "vars": "{}", "version": "1.9.8"}, "info": {"category": "", "code": "I025", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "checksum: 2898e47e730fa32147e5ae95c02f42c1d51b8fd2b81922b9b731f0208caf4dff, vars: {}, profile: , target: , version: 1.9.8", "name": "StateCheckVarsHash", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.173469Z"}} {"data": {"added": 0, "changed": 0, "deleted": 0}, "info": {"category": "", "code": "I040", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Partial parsing enabled: 0 files deleted, 0 files added, 0 files changed.", "name": "PartialParsingEnabled", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.227459Z"}} {"data": {}, "info": {"category": "", "code": "I017", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Partial parsing enabled, no changes found, skipping parsing", "name": "PartialParsingSkipParsing", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.227745Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'load_project', 'label': '144d8558-c354-41e7-829d-cbf1438c93e4', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'load_project', 'label': '144d8558-c354-41e7-829d-cbf1438c93e4', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.233194Z"}} {"data": {"artifact_path": "/Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/manifest.json", "artifact_type": "WritableManifest"}, "info": {"category": "", "code": "P001", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Wrote artifact WritableManifest to /Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/manifest.json", "name": "ArtifactWritten", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.300152Z"}} {"data": {"artifact_path": "/Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/semantic_manifest.json", "artifact_type": "SemanticManifest"}, "info": {"category": "", "code": "P001", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Wrote artifact SemanticManifest to /Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/semantic_manifest.json", "name": "ArtifactWritten", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.301153Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'resource_counts', 'label': '144d8558-c354-41e7-829d-cbf1438c93e4', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'resource_counts', 'label': '144d8558-c354-41e7-829d-cbf1438c93e4', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.304436Z"}} {"data": {"stat_line": "41 models, 2 operations, 6 data tests, 6 sources, 1479 macros"}, "info": {"category": "", "code": "W006", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "info", "msg": "Found 41 models, 2 operations, 6 data tests, 6 sources, 1479 macros", "name": "FoundStats", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.304749Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'runnable_timing', 'label': '144d8558-c354-41e7-829d-cbf1438c93e4', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'runnable_timing', 'label': '144d8558-c354-41e7-829d-cbf1438c93e4', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.304941Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E005", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Acquiring new postgres connection 'macro_log_macro_results'", "name": "NewConnection", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.305226Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.305435Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "BEGIN"}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "On macro_log_macro_results: BEGIN", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.305629Z"}} {"data": {"connection_state": "init"}, "info": {"category": "", "code": "E037", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Opening a new connection, currently in state init", "name": "NewConnectionOpening", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:21.305813Z"}} {"data": {"elapsed": 1.1083779, "query_id": "", "status": "BEGIN"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "SQL status: BEGIN in 1.108 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:22.414636Z"}} {"data": {"conn_name": "macro_log_macro_results"}, "info": {"category": "", "code": "E018", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "On macro_log_macro_results: COMMIT", "name": "SQLCommit", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:22.416875Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:22.417860Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "COMMIT"}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "On macro_log_macro_results: COMMIT", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:22.418647Z"}} {"data": {"elapsed": 0.14370604, "query_id": "", "status": "COMMIT"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "SQL status: COMMIT in 0.144 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:22.563496Z"}} {"data": {"msg": "Elementary: --ELEMENTARY-MACRO-OUTPUT-START--\"pipelinedb.scoring_dbt_elementary\"--ELEMENTARY-MACRO-OUTPUT-END--"}, "info": {"category": "", "code": "I062", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "info", "msg": "Elementary: --ELEMENTARY-MACRO-OUTPUT-START--\"pipelinedb.scoring_dbt_elementary\"--ELEMENTARY-MACRO-OUTPUT-END--", "name": "JinjaLogInfo", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:22.577388Z"}} {"data": {"conn_name": "macro_log_macro_results"}, "info": {"category": "", "code": "E010", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "On macro_log_macro_results: Close", "name": "ConnectionClosed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:22.578301Z"}} {"data": {"artifact_path": "/Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/run_results.json", "artifact_type": "RunResultsArtifact"}, "info": {"category": "", "code": "P001", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Wrote artifact RunResultsArtifact to /Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/run_results.json", "name": "ArtifactWritten", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:22.579902Z"}} {"data": {"command_name": "run-operation", "command_success": true, "command_wall_clock_time": 1.4298635, "process_in_blocks": "0", "process_kernel_time": 0.411684, "process_mem_max_rss": "182714368", "process_out_blocks": "0", "process_user_time": 1.785902}, "info": {"category": "", "code": "Z051", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Resource report: {\"command_name\": \"run-operation\", \"command_success\": true, \"command_wall_clock_time\": 1.4298635, \"process_in_blocks\": \"0\", \"process_kernel_time\": 0.411684, \"process_mem_max_rss\": \"182714368\", \"process_out_blocks\": \"0\", \"process_user_time\": 1.785902}", "name": "ResourceReport", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:22.580459Z"}} {"data": {"command": "cli run-operation", "completed_at": "2025-07-16T19:10:22.580963Z", "elapsed": 1.4304979, "success": true}, "info": {"category": "", "code": "Q039", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Command `cli run-operation` succeeded at 16:10:22.580963 after 1.43 seconds", "name": "CommandCompleted", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:22.581053Z"}} {"data": {"conn_name": "macro_log_macro_results"}, "info": {"category": "", "code": "E008", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Connection 'macro_log_macro_results' was properly closed.", "name": "ConnectionClosedInCleanup", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:22.581482Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'invocation', 'label': 'end', 'context': [, , ]}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'invocation', 'label': 'end', 'context': [, , ]}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:22.581875Z"}} {"data": {}, "info": {"category": "", "code": "Z042", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "Flushing usage events", "name": "FlushEvents", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:22.582341Z"}} {"data": {}, "info": {"category": "", "code": "Z043", "extra": {}, "invocation_id": "144d8558-c354-41e7-829d-cbf1438c93e4", "level": "debug", "msg": "An error was encountered while trying to flush usage events", "name": "FlushEventsFailure", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.207089Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'invocation', 'label': 'start', 'context': [, , ]}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'invocation', 'label': 'start', 'context': [, , ]}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.221245Z"}} {"data": {"log_version": 3, "version": "=1.9.8"}, "info": {"category": "", "code": "A001", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "info", "msg": "Running with dbt=1.9.8", "name": "MainReportVersion", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.222033Z"}} {"data": {"args": {"cache_selected_only": "False", "debug": "False", "empty": "None", "fail_fast": "False", "indirect_selection": "eager", "introspect": "True", "invocation_command": "dbt report -p .", "log_cache_events": "False", "log_format": "json", "log_path": "/Users/nazareno/workspace/data-etl/code/data_tests/edr_target", "no_print": "None", "partial_parse": "True", "printer_width": "80", "profiles_dir": "/Users/nazareno/workspace/data-etl/code/data_tests", "quiet": "True", "send_anonymous_usage_stats": "True", "static_parser": "True", "target_path": "None", "use_colors": "True", "use_experimental_parser": "False", "version_check": "True", "warn_error": "None", "warn_error_options": "WarnErrorOptions(include=[], exclude=[])", "write_json": "True"}}, "info": {"category": "", "code": "A002", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "running dbt with arguments {'printer_width': '80', 'indirect_selection': 'eager', 'log_cache_events': 'False', 'write_json': 'True', 'partial_parse': 'True', 'cache_selected_only': 'False', 'warn_error': 'None', 'debug': 'False', 'profiles_dir': '/Users/nazareno/workspace/data-etl/code/data_tests', 'log_path': '/Users/nazareno/workspace/data-etl/code/data_tests/edr_target', 'fail_fast': 'False', 'version_check': 'True', 'use_colors': 'True', 'use_experimental_parser': 'False', 'empty': 'None', 'quiet': 'True', 'no_print': 'None', 'log_format': 'json', 'static_parser': 'True', 'invocation_command': 'dbt report -p .', 'warn_error_options': 'WarnErrorOptions(include=[], exclude=[])', 'target_path': 'None', 'introspect': 'True', 'send_anonymous_usage_stats': 'True'}", "name": "MainReportArgs", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.222625Z"}} {"data": {"profile_name": "elementary", "target_name": "default"}, "info": {"category": "", "code": "A005", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "info", "msg": "target not specified in profile 'elementary', using 'default'", "name": "MissingProfileTarget", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.223901Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'project_id', 'label': 'd9f472d5-8aea-46dc-8560-2805f93937ff', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'project_id', 'label': 'd9f472d5-8aea-46dc-8560-2805f93937ff', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.244815Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'adapter_info', 'label': 'd9f472d5-8aea-46dc-8560-2805f93937ff', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'adapter_info', 'label': 'd9f472d5-8aea-46dc-8560-2805f93937ff', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.246078Z"}} {"data": {"adapter_name": "postgres", "adapter_version": "=1.9.0"}, "info": {"category": "", "code": "E034", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "info", "msg": "Registered adapter: postgres=1.9.0", "name": "AdapterRegistered", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.246495Z"}} {"data": {"checksum": "2898e47e730fa32147e5ae95c02f42c1d51b8fd2b81922b9b731f0208caf4dff", "profile": "", "target": "", "vars": "{}", "version": "1.9.8"}, "info": {"category": "", "code": "I025", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "checksum: 2898e47e730fa32147e5ae95c02f42c1d51b8fd2b81922b9b731f0208caf4dff, vars: {}, profile: , target: , version: 1.9.8", "name": "StateCheckVarsHash", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.255830Z"}} {"data": {"added": 0, "changed": 0, "deleted": 0}, "info": {"category": "", "code": "I040", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Partial parsing enabled: 0 files deleted, 0 files added, 0 files changed.", "name": "PartialParsingEnabled", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.313563Z"}} {"data": {}, "info": {"category": "", "code": "I017", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Partial parsing enabled, no changes found, skipping parsing", "name": "PartialParsingSkipParsing", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.313848Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'load_project', 'label': 'd9f472d5-8aea-46dc-8560-2805f93937ff', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'load_project', 'label': 'd9f472d5-8aea-46dc-8560-2805f93937ff', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.319437Z"}} {"data": {"artifact_path": "/Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/manifest.json", "artifact_type": "WritableManifest"}, "info": {"category": "", "code": "P001", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Wrote artifact WritableManifest to /Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/manifest.json", "name": "ArtifactWritten", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.387055Z"}} {"data": {"artifact_path": "/Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/semantic_manifest.json", "artifact_type": "SemanticManifest"}, "info": {"category": "", "code": "P001", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Wrote artifact SemanticManifest to /Users/nazareno/workspace/data-etl/.venv/lib/python3.10/site-packages/elementary/monitor/dbt_project/target/semantic_manifest.json", "name": "ArtifactWritten", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.388045Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'resource_counts', 'label': 'd9f472d5-8aea-46dc-8560-2805f93937ff', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'resource_counts', 'label': 'd9f472d5-8aea-46dc-8560-2805f93937ff', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.390981Z"}} {"data": {"stat_line": "41 models, 2 operations, 6 data tests, 6 sources, 1479 macros"}, "info": {"category": "", "code": "W006", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "info", "msg": "Found 41 models, 2 operations, 6 data tests, 6 sources, 1479 macros", "name": "FoundStats", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.391266Z"}} {"data": {"kwargs": "{'category': 'dbt', 'action': 'runnable_timing', 'label': 'd9f472d5-8aea-46dc-8560-2805f93937ff', 'context': []}"}, "info": {"category": "", "code": "Z040", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Sending event: {'category': 'dbt', 'action': 'runnable_timing', 'label': 'd9f472d5-8aea-46dc-8560-2805f93937ff', 'context': []}", "name": "SendingEvent", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.391449Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E005", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Acquiring new postgres connection 'macro_log_macro_results'", "name": "NewConnection", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.391716Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.391906Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "BEGIN"}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "On macro_log_macro_results: BEGIN", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.392072Z"}} {"data": {"connection_state": "init"}, "info": {"category": "", "code": "E037", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Opening a new connection, currently in state init", "name": "NewConnectionOpening", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:23.392230Z"}} {"data": {"elapsed": 1.071429, "query_id": "", "status": "BEGIN"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "SQL status: BEGIN in 1.071 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:24.464121Z"}} {"data": {"conn_name": "macro_log_macro_results"}, "info": {"category": "", "code": "E018", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "On macro_log_macro_results: COMMIT", "name": "SQLCommit", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:24.466189Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:24.467276Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "COMMIT"}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "On macro_log_macro_results: COMMIT", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:24.468205Z"}} {"data": {"elapsed": 0.14501554, "query_id": "", "status": "COMMIT"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "SQL status: COMMIT in 0.145 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:24.614281Z"}} {"data": {"conn_name": "macro_log_macro_results", "database": "pipelinedb", "schema": "scoring_dbt_elementary"}, "info": {"category": "", "code": "E013", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "On \"macro_log_macro_results\": cache miss for schema \"pipelinedb.scoring_dbt_elementary\", this is inefficient", "name": "CacheMiss", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:24.670863Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:24.673151Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "BEGIN"}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "On macro_log_macro_results: BEGIN", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:24.673445Z"}} {"data": {"elapsed": 0.14238234, "query_id": "", "status": "BEGIN"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "SQL status: BEGIN in 0.142 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:24.816311Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:24.817719Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "/* {\"app\": \"dbt\", \"dbt_version\": \"1.9.8\", \"profile_name\": \"elementary\", \"target_name\": \"default\", \"connection_name\": \"macro_log_macro_results\"} */\nselect\n 'pipelinedb' as database,\n tablename as name,\n schemaname as schema,\n 'table' as type\n from pg_tables\n where schemaname ilike 'scoring_dbt_elementary'\n union all\n select\n 'pipelinedb' as database,\n viewname as name,\n schemaname as schema,\n 'view' as type\n from pg_views\n where schemaname ilike 'scoring_dbt_elementary'\n union all\n select\n 'pipelinedb' as database,\n matviewname as name,\n schemaname as schema,\n 'materialized_view' as type\n from pg_matviews\n where schemaname ilike 'scoring_dbt_elementary'\n "}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "On macro_log_macro_results: /* {\"app\": \"dbt\", \"dbt_version\": \"1.9.8\", \"profile_name\": \"elementary\", \"target_name\": \"default\", \"connection_name\": \"macro_log_macro_results\"} */\nselect\n 'pipelinedb' as database,\n tablename as name,\n schemaname as schema,\n 'table' as type\n from pg_tables\n where schemaname ilike 'scoring_dbt_elementary'\n union all\n select\n 'pipelinedb' as database,\n viewname as name,\n schemaname as schema,\n 'view' as type\n from pg_views\n where schemaname ilike 'scoring_dbt_elementary'\n union all\n select\n 'pipelinedb' as database,\n matviewname as name,\n schemaname as schema,\n 'materialized_view' as type\n from pg_matviews\n where schemaname ilike 'scoring_dbt_elementary'\n ", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:24.818485Z"}} {"data": {"elapsed": 0.15975837, "query_id": "", "status": "SELECT 30"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "SQL status: SELECT 30 in 0.160 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:24.979493Z"}} {"data": {"database": "pipelinedb", "relations": [{"database": "pipelinedb", "identifier": "dbt_groups", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_metrics", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_models", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_run_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_seeds", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "elementary_test_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_source_freshness_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_snapshots", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_exposures", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_sources", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "data_monitoring_metrics", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_columns", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_invocations", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "test_result_rows", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "schema_columns_snapshot", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_tests", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "metadata", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "job_run_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "model_run_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "seed_run_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "snapshot_run_results", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "alerts_dbt_source_freshness", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "dbt_artifacts_hashes", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "alerts_anomaly_detection", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "alerts_dbt_tests", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "alerts_schema_changes", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "alerts_dbt_models", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "metrics_anomaly_score", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "monitors_runs", "schema": "scoring_dbt_elementary"}, {"database": "pipelinedb", "identifier": "anomaly_threshold_sensitivity", "schema": "scoring_dbt_elementary"}], "schema": "scoring_dbt_elementary"}, "info": {"category": "", "code": "E014", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "While listing relations in database=pipelinedb, schema=scoring_dbt_elementary, found: dbt_groups, dbt_metrics, dbt_models, dbt_run_results, dbt_seeds, elementary_test_results, dbt_source_freshness_results, dbt_snapshots, dbt_exposures, dbt_sources, data_monitoring_metrics, dbt_columns, dbt_invocations, test_result_rows, schema_columns_snapshot, dbt_tests, metadata, job_run_results, model_run_results, seed_run_results, snapshot_run_results, alerts_dbt_source_freshness, dbt_artifacts_hashes, alerts_anomaly_detection, alerts_dbt_tests, alerts_schema_changes, alerts_dbt_models, metrics_anomaly_score, monitors_runs, anomaly_threshold_sensitivity", "name": "ListRelations", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:24.992764Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:25.014893Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "/* {\"app\": \"dbt\", \"dbt_version\": \"1.9.8\", \"profile_name\": \"elementary\", \"target_name\": \"default\", \"connection_name\": \"macro_log_macro_results\"} */\n\n \n \n \n\n create temporary table \"ordered_test_results__tmp_20250716191024996842161025007421\"\n \n \n as\n \n (\n \n with test_results as (\n \n with elementary_test_results as (\n select * from \"pipelinedb\".\"scoring_dbt_elementary\".\"elementary_test_results\"\n \n where \n ((\n current_timestamp::timestamp\n)::date - (cast(detected_at as timestamp))::date)\n < 7\n \n ),\n\n dbt_run_results as (\n select * from \"pipelinedb\".\"scoring_dbt_elementary\".\"dbt_run_results\"\n \n where \n ((\n current_timestamp::timestamp\n)::date - (cast(execute_completed_at as timestamp))::date)\n < 7\n \n ),\n\n dbt_tests as (\n select * from \"pipelinedb\".\"scoring_dbt_elementary\".\"dbt_tests\"\n ),\n\n dbt_models as (\n select * from \"pipelinedb\".\"scoring_dbt_elementary\".\"dbt_models\"\n ),\n\n dbt_sources as (\n select * from \"pipelinedb\".\"scoring_dbt_elementary\".\"dbt_sources\"\n ),\n\n dbt_artifacts as (\n select unique_id, meta, tags, owner from dbt_models\n union all\n select unique_id, meta, tags, owner from dbt_sources\n ),\n\n first_time_test_occurred as (\n select \n min(detected_at) as first_time_occurred,\n test_unique_id\n from elementary_test_results\n group by test_unique_id\n )\n\n select\n elementary_test_results.id,\n \n case\n when elementary_test_results.test_type = 'schema_change' then elementary_test_results.test_unique_id\n \n when dbt_tests.short_name = 'dimension_anomalies' then elementary_test_results.test_unique_id\n else coalesce(elementary_test_results.test_unique_id, 'None') || '.' || coalesce(nullif(elementary_test_results.column_name, ''), 'None') || '.' || coalesce(elementary_test_results.test_sub_type, 'None')\n end as elementary_unique_id,\n elementary_test_results.invocation_id,\n elementary_test_results.data_issue_id,\n elementary_test_results.test_execution_id,\n elementary_test_results.test_unique_id,\n elementary_test_results.model_unique_id,\n elementary_test_results.detected_at,\n elementary_test_results.database_name,\n elementary_test_results.schema_name,\n elementary_test_results.table_name,\n elementary_test_results.column_name,\n elementary_test_results.test_type,\n elementary_test_results.test_sub_type,\n elementary_test_results.test_results_description,\n elementary_test_results.owners,\n elementary_test_results.tags,\n elementary_test_results.test_results_query,\n elementary_test_results.other,\n case\n when dbt_tests.short_name is not null then dbt_tests.short_name\n else elementary_test_results.test_name\n end as test_name,\n elementary_test_results.test_params,\n elementary_test_results.severity,\n elementary_test_results.status,\n \n dbt_tests.short_name,\n elementary_test_results.test_alias,\n elementary_test_results.failures,\n elementary_test_results.result_rows,\n dbt_tests.original_path,\n dbt_tests.meta,\n dbt_tests.description as test_description,\n dbt_tests.package_name,\n dbt_tests.tags as test_tags,\n dbt_artifacts.meta as model_meta,\n dbt_artifacts.tags as model_tags,\n dbt_artifacts.owner as model_owner,\n first_occurred.first_time_occurred as test_created_at,\n dbt_run_results.execution_time as execution_time\n from elementary_test_results\n join dbt_tests on elementary_test_results.test_unique_id = dbt_tests.unique_id\n left join first_time_test_occurred first_occurred on elementary_test_results.test_unique_id = first_occurred.test_unique_id\n left join dbt_artifacts on elementary_test_results.model_unique_id = dbt_artifacts.unique_id\n left join dbt_run_results on elementary_test_results.test_execution_id = dbt_run_results.model_execution_id\n\n ),\n\n ordered_test_results as (\n select\n *,\n \n ((\n current_timestamp::timestamp\n)::date - (cast(detected_at as timestamp))::date)\n as days_diff,\n \n rank() over (partition by elementary_unique_id order by cast(detected_at as timestamp) desc) as invocations_rank_index\n from test_results\n )\n\n select \n test_results.id,\n test_results.invocation_id,\n test_results.test_execution_id,\n test_results.model_unique_id,\n test_results.test_unique_id,\n test_results.elementary_unique_id,\n cast(test_results.detected_at as timestamp) as detected_at,\n test_results.database_name,\n test_results.schema_name,\n test_results.table_name,\n test_results.column_name,\n test_results.test_type,\n test_results.test_sub_type,\n test_results.test_results_description,\n test_results.test_description,\n test_results.original_path,\n test_results.package_name,\n test_results.owners,\n test_results.model_owner,\n test_results.tags,\n test_results.test_tags,\n test_results.model_tags,\n test_results.meta,\n test_results.model_meta,\n case when test_results.invocations_rank_index = 1 then test_results.test_results_query else NULL end as test_results_query,\n test_results.other,\n test_results.test_name,\n test_results.test_params,\n test_results.severity,\n test_results.status,\n test_results.execution_time,\n test_results.days_diff,\n test_results.invocations_rank_index,\n test_results.failures,\n test_results.result_rows\n from ordered_test_results as test_results\n where test_results.invocations_rank_index <= 720\n order by test_results.elementary_unique_id, test_results.invocations_rank_index desc\n );\n \n "}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "On macro_log_macro_results: /* {\"app\": \"dbt\", \"dbt_version\": \"1.9.8\", \"profile_name\": \"elementary\", \"target_name\": \"default\", \"connection_name\": \"macro_log_macro_results\"} */\n\n \n \n \n\n create temporary table \"ordered_test_results__tmp_20250716191024996842161025007421\"\n \n \n as\n \n (\n \n with test_results as (\n \n with elementary_test_results as (\n select * from \"pipelinedb\".\"scoring_dbt_elementary\".\"elementary_test_results\"\n \n where \n ((\n current_timestamp::timestamp\n)::date - (cast(detected_at as timestamp))::date)\n < 7\n \n ),\n\n dbt_run_results as (\n select * from \"pipelinedb\".\"scoring_dbt_elementary\".\"dbt_run_results\"\n \n where \n ((\n current_timestamp::timestamp\n)::date - (cast(execute_completed_at as timestamp))::date)\n < 7\n \n ),\n\n dbt_tests as (\n select * from \"pipelinedb\".\"scoring_dbt_elementary\".\"dbt_tests\"\n ),\n\n dbt_models as (\n select * from \"pipelinedb\".\"scoring_dbt_elementary\".\"dbt_models\"\n ),\n\n dbt_sources as (\n select * from \"pipelinedb\".\"scoring_dbt_elementary\".\"dbt_sources\"\n ),\n\n dbt_artifacts as (\n select unique_id, meta, tags, owner from dbt_models\n union all\n select unique_id, meta, tags, owner from dbt_sources\n ),\n\n first_time_test_occurred as (\n select \n min(detected_at) as first_time_occurred,\n test_unique_id\n from elementary_test_results\n group by test_unique_id\n )\n\n select\n elementary_test_results.id,\n \n case\n when elementary_test_results.test_type = 'schema_change' then elementary_test_results.test_unique_id\n \n when dbt_tests.short_name = 'dimension_anomalies' then elementary_test_results.test_unique_id\n else coalesce(elementary_test_results.test_unique_id, 'None') || '.' || coalesce(nullif(elementary_test_results.column_name, ''), 'None') || '.' || coalesce(elementary_test_results.test_sub_type, 'None')\n end as elementary_unique_id,\n elementary_test_results.invocation_id,\n elementary_test_results.data_issue_id,\n elementary_test_results.test_execution_id,\n elementary_test_results.test_unique_id,\n elementary_test_results.model_unique_id,\n elementary_test_results.detected_at,\n elementary_test_results.database_name,\n elementary_test_results.schema_name,\n elementary_test_results.table_name,\n elementary_test_results.column_name,\n elementary_test_results.test_type,\n elementary_test_results.test_sub_type,\n elementary_test_results.test_results_description,\n elementary_test_results.owners,\n elementary_test_results.tags,\n elementary_test_results.test_results_query,\n elementary_test_results.other,\n case\n when dbt_tests.short_name is not null then dbt_tests.short_name\n else elementary_test_results.test_name\n end as test_name,\n elementary_test_results.test_params,\n elementary_test_results.severity,\n elementary_test_results.status,\n \n dbt_tests.short_name,\n elementary_test_results.test_alias,\n elementary_test_results.failures,\n elementary_test_results.result_rows,\n dbt_tests.original_path,\n dbt_tests.meta,\n dbt_tests.description as test_description,\n dbt_tests.package_name,\n dbt_tests.tags as test_tags,\n dbt_artifacts.meta as model_meta,\n dbt_artifacts.tags as model_tags,\n dbt_artifacts.owner as model_owner,\n first_occurred.first_time_occurred as test_created_at,\n dbt_run_results.execution_time as execution_time\n from elementary_test_results\n join dbt_tests on elementary_test_results.test_unique_id = dbt_tests.unique_id\n left join first_time_test_occurred first_occurred on elementary_test_results.test_unique_id = first_occurred.test_unique_id\n left join dbt_artifacts on elementary_test_results.model_unique_id = dbt_artifacts.unique_id\n left join dbt_run_results on elementary_test_results.test_execution_id = dbt_run_results.model_execution_id\n\n ),\n\n ordered_test_results as (\n select\n *,\n \n ((\n current_timestamp::timestamp\n)::date - (cast(detected_at as timestamp))::date)\n as days_diff,\n \n rank() over (partition by elementary_unique_id order by cast(detected_at as timestamp) desc) as invocations_rank_index\n from test_results\n )\n\n select \n test_results.id,\n test_results.invocation_id,\n test_results.test_execution_id,\n test_results.model_unique_id,\n test_results.test_unique_id,\n test_results.elementary_unique_id,\n cast(test_results.detected_at as timestamp) as detected_at,\n test_results.database_name,\n test_results.schema_name,\n test_results.table_name,\n test_results.column_name,\n test_results.test_type,\n test_results.test_sub_type,\n test_results.test_results_description,\n test_results.test_description,\n test_results.original_path,\n test_results.package_name,\n test_results.owners,\n test_results.model_owner,\n test_results.tags,\n test_results.test_tags,\n test_results.model_tags,\n test_results.meta,\n test_results.model_meta,\n case when test_results.invocations_rank_index = 1 then test_results.test_results_query else NULL end as test_results_query,\n test_results.other,\n test_results.test_name,\n test_results.test_params,\n test_results.severity,\n test_results.status,\n test_results.execution_time,\n test_results.days_diff,\n test_results.invocations_rank_index,\n test_results.failures,\n test_results.result_rows\n from ordered_test_results as test_results\n where test_results.invocations_rank_index <= 720\n order by test_results.elementary_unique_id, test_results.invocations_rank_index desc\n );\n \n ", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:25.015676Z"}} {"data": {"elapsed": 0.6438341, "query_id": "", "status": "SELECT 1038"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "SQL status: SELECT 1038 in 0.644 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:25.660681Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:25.665715Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "/* {\"app\": \"dbt\", \"dbt_version\": \"1.9.8\", \"profile_name\": \"elementary\", \"target_name\": \"default\", \"connection_name\": \"macro_log_macro_results\"} */\n\n \n select * from \"ordered_test_results__tmp_20250716191024996842161025007421\"\n \n "}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "On macro_log_macro_results: /* {\"app\": \"dbt\", \"dbt_version\": \"1.9.8\", \"profile_name\": \"elementary\", \"target_name\": \"default\", \"connection_name\": \"macro_log_macro_results\"} */\n\n \n select * from \"ordered_test_results__tmp_20250716191024996842161025007421\"\n \n ", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:25.666978Z"}} {"data": {"elapsed": 0.46507832, "query_id": "", "status": "SELECT 1038"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "SQL status: SELECT 1038 in 0.465 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:26.132889Z"}} {"data": {"conn_name": "macro_log_macro_results", "conn_type": "postgres"}, "info": {"category": "", "code": "E015", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "Using postgres connection \"macro_log_macro_results\"", "name": "ConnectionUsed", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:26.176164Z"}} {"data": {"conn_name": "macro_log_macro_results", "sql": "/* {\"app\": \"dbt\", \"dbt_version\": \"1.9.8\", \"profile_name\": \"elementary\", \"target_name\": \"default\", \"connection_name\": \"macro_log_macro_results\"} */\n\n \n select\n elementary_test_results_id,\n result_row\n from \"pipelinedb\".\"scoring_dbt_elementary\".\"test_result_rows\"\n where \n ((\n current_timestamp::timestamp\n)::date - (cast(detected_at as timestamp))::date)\n < 7\n \n and elementary_test_results_id in (\n select distinct id \n from \"ordered_test_results__tmp_20250716191024996842161025007421\"\n where invocations_rank_index = 1\n )\n \n \n "}, "info": {"category": "", "code": "E016", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "On macro_log_macro_results: /* {\"app\": \"dbt\", \"dbt_version\": \"1.9.8\", \"profile_name\": \"elementary\", \"target_name\": \"default\", \"connection_name\": \"macro_log_macro_results\"} */\n\n \n select\n elementary_test_results_id,\n result_row\n from \"pipelinedb\".\"scoring_dbt_elementary\".\"test_result_rows\"\n where \n ((\n current_timestamp::timestamp\n)::date - (cast(detected_at as timestamp))::date)\n < 7\n \n and elementary_test_results_id in (\n select distinct id \n from \"ordered_test_results__tmp_20250716191024996842161025007421\"\n where invocations_rank_index = 1\n )\n \n \n ", "name": "SQLQuery", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:26.176520Z"}} {"data": {"elapsed": 3.1426728, "query_id": "", "status": "SELECT 464"}, "info": {"category": "", "code": "E017", "extra": {}, "invocation_id": "d9f472d5-8aea-46dc-8560-2805f93937ff", "level": "debug", "msg": "SQL status: SELECT 464 in 3.143 seconds", "name": "SQLQueryStatus", "pid": 8848, "thread": "MainThread", "ts": "2025-07-16T19:10:29.319757Z"}}