2022-07-29 13:52:31 INFO i.a.v.j.JsonSchemaValidator(test):71 - JSON schema validation failed. errors: $.ssl_method: must be a constant value unencrypted, $.ssl_method: does not have a value in the enumeration [unencrypted] 2022-07-29 13:52:31 INFO i.a.w.t.TemporalAttemptExecution(get):105 - Docker volume job log path: /tmp/workspace/30/0/logs.log 2022-07-29 13:52:31 INFO i.a.w.t.TemporalAttemptExecution(get):110 - Executing worker wrapper. Airbyte version: 0.39.41-alpha 2022-07-29 13:52:31 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/source-file:0.2.14 exists... 2022-07-29 13:52:31 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/source-file:0.2.14 was found locally. 2022-07-29 13:52:31 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:52:31 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/0 --log-driver none --name source-file-check-30-0-ljxnu --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e USE_STREAM_CAPABLE_STATE=true -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e WORKER_JOB_ATTEMPT=0 -e WORKER_CONNECTOR_IMAGE=airbyte/source-file:0.2.14 -e AIRBYTE_VERSION=0.39.41-alpha -e WORKER_JOB_ID=30 airbyte/source-file:0.2.14 check --config source_config.json 2022-07-29 13:52:34 INFO i.a.w.i.DefaultAirbyteStreamFactory(internalLog):99 - Checking access to https://opendata.dwd.de/climate_environment/CDC/regional_averages_DE/annual/sunshine_duration/regional_averages_sd_year.txt... 2022-07-29 13:52:34 INFO i.a.w.i.DefaultAirbyteStreamFactory(internalLog):99 - TransportParams: None 2022-07-29 13:52:34 INFO i.a.w.i.DefaultAirbyteStreamFactory(internalLog):99 - Check succeeded 2022-07-29 13:52:34 INFO i.a.w.t.TemporalAttemptExecution(get):131 - Stopping cancellation check scheduling... 2022-07-29 13:52:34 INFO i.a.w.t.TemporalAttemptExecution(get):105 - Docker volume job log path: /tmp/workspace/30/0/logs.log 2022-07-29 13:52:34 INFO i.a.w.t.TemporalAttemptExecution(get):110 - Executing worker wrapper. Airbyte version: 0.39.41-alpha 2022-07-29 13:52:34 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/destination-mssql:0.1.20 exists... 2022-07-29 13:52:34 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/destination-mssql:0.1.20 was found locally. 2022-07-29 13:52:34 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:52:34 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/0 --log-driver none --name destination-mssql-check-30-0-rwptj --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e USE_STREAM_CAPABLE_STATE=true -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e WORKER_JOB_ATTEMPT=0 -e WORKER_CONNECTOR_IMAGE=airbyte/destination-mssql:0.1.20 -e AIRBYTE_VERSION=0.39.41-alpha -e WORKER_JOB_ID=30 airbyte/destination-mssql:0.1.20 check --config source_config.json 2022-07-29 13:52:35 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Class path contains multiple SLF4J bindings. 2022-07-29 13:52:35 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Found binding in [jar:file:/airbyte/lib/log4j-slf4j-impl-2.17.1.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:35 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Found binding in [jar:file:/airbyte/lib/slf4j-reload4j-1.7.36.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:35 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Found binding in [jar:file:/airbyte/lib/logback-classic-1.2.3.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:35 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Found binding in [jar:file:/airbyte/lib/slf4j-log4j12-1.7.25.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:35 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. 2022-07-29 13:52:35 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] 2022-07-29 13:52:35 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:35 INFO i.a.i.d.m.MSSQLDestination(main):104 - starting destination: class io.airbyte.integrations.destination.mssql.MSSQLDestination 2022-07-29 13:52:36 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:36 INFO i.a.i.b.IntegrationCliParser(parseOptions):118 - integration args: {check=null, config=source_config.json} 2022-07-29 13:52:36 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:36 INFO i.a.i.b.IntegrationRunner(runInternal):123 - Running integration: io.airbyte.integrations.base.ssh.SshWrappedDestination 2022-07-29 13:52:36 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:36 INFO i.a.i.b.IntegrationRunner(runInternal):124 - Command: CHECK 2022-07-29 13:52:36 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:36 INFO i.a.i.b.IntegrationRunner(runInternal):125 - Integration config: IntegrationConfig{command=CHECK, configPath='source_config.json', catalogPath='null', statePath='null'} 2022-07-29 13:52:36 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:36 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword order - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:52:36 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:36 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword examples - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:52:36 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:36 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword airbyte_secret - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:52:36 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:36 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword multiline - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:52:36 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:36 INFO i.a.i.b.s.SshTunnel(getInstance):170 - Starting connection with method: NO_TUNNEL 2022-07-29 13:52:36 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:36 INFO c.z.h.HikariDataSource():80 - HikariPool-1 - Starting... 2022-07-29 13:52:36 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:36 INFO c.z.h.HikariDataSource():82 - HikariPool-1 - Start completed. 2022-07-29 13:52:37 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:37 INFO c.z.h.HikariDataSource(close):350 - HikariPool-1 - Shutdown initiated... 2022-07-29 13:52:37 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:37 INFO c.z.h.HikariDataSource(close):352 - HikariPool-1 - Shutdown completed. 2022-07-29 13:52:37 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:37 INFO i.a.i.b.IntegrationRunner(runInternal):171 - Completed integration: io.airbyte.integrations.base.ssh.SshWrappedDestination 2022-07-29 13:52:37 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:37 INFO i.a.i.d.m.MSSQLDestination(main):106 - completed destination: class io.airbyte.integrations.destination.mssql.MSSQLDestination 2022-07-29 13:52:37 INFO i.a.w.t.TemporalAttemptExecution(get):131 - Stopping cancellation check scheduling... 2022-07-29 13:52:37 INFO i.a.w.t.TemporalAttemptExecution(get):105 - Docker volume job log path: /tmp/workspace/30/0/logs.log 2022-07-29 13:52:37 INFO i.a.w.t.TemporalAttemptExecution(get):110 - Executing worker wrapper. Airbyte version: 0.39.41-alpha 2022-07-29 13:52:37 INFO i.a.w.g.DefaultReplicationWorker(run):116 - start sync worker. job id: 30 attempt id: 0 2022-07-29 13:52:37 INFO i.a.w.g.DefaultReplicationWorker(run):128 - configured sync modes: {null.DWD_DE_annual_sunshine-duration_regional-averages=full_refresh - overwrite} 2022-07-29 13:52:37 INFO i.a.w.i.DefaultAirbyteDestination(start):69 - Running destination... 2022-07-29 13:52:37 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/destination-mssql:0.1.20 exists... 2022-07-29 13:52:37 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/destination-mssql:0.1.20 was found locally. 2022-07-29 13:52:37 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:52:37 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/0 --log-driver none --name destination-mssql-write-30-0-fodwz --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e USE_STREAM_CAPABLE_STATE=true -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e WORKER_JOB_ATTEMPT=0 -e WORKER_CONNECTOR_IMAGE=airbyte/destination-mssql:0.1.20 -e AIRBYTE_VERSION=0.39.41-alpha -e WORKER_JOB_ID=30 airbyte/destination-mssql:0.1.20 write --config destination_config.json --catalog destination_catalog.json 2022-07-29 13:52:37 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/source-file:0.2.14 exists... 2022-07-29 13:52:37 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/source-file:0.2.14 was found locally. 2022-07-29 13:52:37 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:52:37 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/0 --log-driver none --name source-file-read-30-0-qkdjb --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e USE_STREAM_CAPABLE_STATE=true -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e WORKER_JOB_ATTEMPT=0 -e WORKER_CONNECTOR_IMAGE=airbyte/source-file:0.2.14 -e AIRBYTE_VERSION=0.39.41-alpha -e WORKER_JOB_ID=30 airbyte/source-file:0.2.14 read --config source_config.json --catalog source_catalog.json --state input_state.json 2022-07-29 13:52:37 INFO i.a.w.g.DefaultReplicationWorker(lambda$getDestinationOutputRunnable$7):406 - Destination output thread started. 2022-07-29 13:52:37 INFO i.a.w.g.DefaultReplicationWorker(run):170 - Waiting for source and destination threads to complete. 2022-07-29 13:52:37 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):299 - Replication thread started. 2022-07-29 13:52:37 destination > SLF4J: Class path contains multiple SLF4J bindings. 2022-07-29 13:52:37 destination > SLF4J: Found binding in [jar:file:/airbyte/lib/log4j-slf4j-impl-2.17.1.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:37 destination > SLF4J: Found binding in [jar:file:/airbyte/lib/slf4j-reload4j-1.7.36.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:37 destination > SLF4J: Found binding in [jar:file:/airbyte/lib/logback-classic-1.2.3.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:37 destination > SLF4J: Found binding in [jar:file:/airbyte/lib/slf4j-log4j12-1.7.25.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:37 destination > SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. 2022-07-29 13:52:37 destination > SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] 2022-07-29 13:52:38 destination > 2022-07-29 13:52:38 INFO i.a.i.d.m.MSSQLDestination(main):104 - starting destination: class io.airbyte.integrations.destination.mssql.MSSQLDestination 2022-07-29 13:52:38 destination > 2022-07-29 13:52:38 INFO i.a.i.b.IntegrationCliParser(parseOptions):118 - integration args: {catalog=destination_catalog.json, write=null, config=destination_config.json} 2022-07-29 13:52:38 destination > 2022-07-29 13:52:38 INFO i.a.i.b.IntegrationRunner(runInternal):123 - Running integration: io.airbyte.integrations.base.ssh.SshWrappedDestination 2022-07-29 13:52:38 destination > 2022-07-29 13:52:38 INFO i.a.i.b.IntegrationRunner(runInternal):124 - Command: WRITE 2022-07-29 13:52:38 destination > 2022-07-29 13:52:38 INFO i.a.i.b.IntegrationRunner(runInternal):125 - Integration config: IntegrationConfig{command=WRITE, configPath='destination_config.json', catalogPath='destination_catalog.json', statePath='null'} 2022-07-29 13:52:38 destination > 2022-07-29 13:52:38 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword order - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:52:38 destination > 2022-07-29 13:52:38 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword examples - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:52:38 destination > 2022-07-29 13:52:38 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword airbyte_secret - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:52:38 destination > 2022-07-29 13:52:38 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword multiline - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:52:39 destination > 2022-07-29 13:52:39 INFO i.a.i.b.s.SshTunnel(getInstance):170 - Starting connection with method: NO_TUNNEL 2022-07-29 13:52:39 destination > 2022-07-29 13:52:39 INFO c.z.h.HikariDataSource():80 - HikariPool-1 - Starting... 2022-07-29 13:52:39 destination > 2022-07-29 13:52:39 INFO c.z.h.HikariDataSource():82 - HikariPool-1 - Start completed. 2022-07-29 13:52:39 destination > 2022-07-29 13:52:39 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$toWriteConfig$0):97 - Write config: WriteConfig{streamName=elt_DWD_DE_annual_sunshine-duration_regional-averages, namespace=null, outputSchemaName=AIRBYTE_ELT, tmpTableName=_airbyte_tmp_tla_elt_DWD_DE_annual_sunshine_duration_regional_averages, outputTableName=_airbyte_raw_elt_DWD_DE_annual_sunshine_duration_regional_averages, syncMode=overwrite} 2022-07-29 13:52:39 destination > 2022-07-29 13:52:39 INFO i.a.i.d.b.BufferedStreamConsumer(startTracked):116 - class io.airbyte.integrations.destination.buffered_stream_consumer.BufferedStreamConsumer started. 2022-07-29 13:52:39 destination > 2022-07-29 13:52:39 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):122 - Preparing tmp tables in destination started for 1 streams 2022-07-29 13:52:39 destination > 2022-07-29 13:52:39 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):126 - Preparing tmp table in destination started for stream elt_DWD_DE_annual_sunshine-duration_regional-averages. schema: AIRBYTE_ELT, tmp table name: _airbyte_tmp_tla_elt_DWD_DE_annual_sunshine_duration_regional_averages 2022-07-29 13:52:39 source > Reading DWD_DE_annual_sunshine-duration_regional-averages (https://opendata.dwd.de/climate_environment/CDC/regional_averages_DE/annual/sunshine_duration/regional_averages_sd_year.txt)... 2022-07-29 13:52:39 source > TransportParams: None 2022-07-29 13:52:39 destination > 2022-07-29 13:52:39 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):132 - Preparing tables in destination completed. 2022-07-29 13:52:39 source > /airbyte/integration_code/source_file/client.py:364: FutureWarning: Passing a set as an indexer is deprecated and will raise in a future version. Use a list instead. 2022-07-29 13:52:39 source > yield from df[columns].to_dict(orient="records") 2022-07-29 13:52:40 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):328 - Source has no more messages, closing connection. 2022-07-29 13:52:40 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):336 - Total records read: 71 (29 KB) 2022-07-29 13:52:40 INFO i.a.w.g.DefaultReplicationWorker(run):175 - One of source or destination thread complete. Waiting on the other. 2022-07-29 13:52:40 destination > 2022-07-29 13:52:40 INFO i.a.i.b.FailureTrackingAirbyteMessageConsumer(close):65 - Airbyte message consumer: succeeded. 2022-07-29 13:52:40 destination > 2022-07-29 13:52:40 INFO i.a.i.d.b.BufferedStreamConsumer(close):171 - executing on success close procedure. 2022-07-29 13:52:40 destination > 2022-07-29 13:52:40 INFO i.a.i.d.r.InMemoryRecordBufferingStrategy(lambda$flushAll$1):86 - Flushing elt_DWD_DE_annual_sunshine-duration_regional-averages: 71 records (119 KB) 2022-07-29 13:52:40 destination > 2022-07-29 13:52:40 INFO i.a.i.d.j.SqlOperations(onDestinationCloseOperations):137 - No onDestinationCloseOperations required for this destination. 2022-07-29 13:52:40 destination > 2022-07-29 13:52:40 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):162 - Finalizing tables in destination started for 1 streams 2022-07-29 13:52:40 destination > 2022-07-29 13:52:40 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):167 - Finalizing stream elt_DWD_DE_annual_sunshine-duration_regional-averages. schema AIRBYTE_ELT, tmp table _airbyte_tmp_tla_elt_DWD_DE_annual_sunshine_duration_regional_averages, final table _airbyte_raw_elt_DWD_DE_annual_sunshine_duration_regional_averages 2022-07-29 13:52:40 destination > 2022-07-29 13:52:40 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):180 - Executing finalization of tables. 2022-07-29 13:52:40 destination > 2022-07-29 13:52:40 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):182 - Finalizing tables in destination completed. 2022-07-29 13:52:40 destination > 2022-07-29 13:52:40 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):185 - Cleaning tmp tables in destination started for 1 streams 2022-07-29 13:52:40 destination > 2022-07-29 13:52:40 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):189 - Cleaning tmp table in destination started for stream elt_DWD_DE_annual_sunshine-duration_regional-averages. schema AIRBYTE_ELT, tmp table name: _airbyte_tmp_tla_elt_DWD_DE_annual_sunshine_duration_regional_averages 2022-07-29 13:52:40 destination > 2022-07-29 13:52:40 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):194 - Cleaning tmp tables in destination completed. 2022-07-29 13:52:40 destination > 2022-07-29 13:52:40 INFO i.a.i.b.IntegrationRunner(runInternal):171 - Completed integration: io.airbyte.integrations.base.ssh.SshWrappedDestination 2022-07-29 13:52:40 destination > 2022-07-29 13:52:40 INFO i.a.i.d.m.MSSQLDestination(main):106 - completed destination: class io.airbyte.integrations.destination.mssql.MSSQLDestination 2022-07-29 13:52:40 INFO i.a.w.g.DefaultReplicationWorker(run):177 - Source and destination threads complete. 2022-07-29 13:52:40 INFO i.a.w.g.DefaultReplicationWorker(run):240 - sync summary: io.airbyte.config.ReplicationAttemptSummary@a42f870[status=completed,recordsSynced=71,bytesSynced=30672,startTime=1659102757293,endTime=1659102760538,totalStats=io.airbyte.config.SyncStats@2c388274[recordsEmitted=71,bytesEmitted=30672,stateMessagesEmitted=0,recordsCommitted=71],streamStats=[io.airbyte.config.StreamSyncStats@6593a4eb[streamName=elt_DWD_DE_annual_sunshine-duration_regional-averages,stats=io.airbyte.config.SyncStats@57d70e34[recordsEmitted=71,bytesEmitted=30672,stateMessagesEmitted=,recordsCommitted=71]]]] 2022-07-29 13:52:40 INFO i.a.w.g.DefaultReplicationWorker(run):269 - Source did not output any state messages 2022-07-29 13:52:40 WARN i.a.w.g.DefaultReplicationWorker(run):277 - State capture: No new state, falling back on input state: io.airbyte.config.State@4acbedc6[state={}] 2022-07-29 13:52:40 INFO i.a.w.t.TemporalAttemptExecution(get):131 - Stopping cancellation check scheduling... 2022-07-29 13:52:40 INFO i.a.w.t.s.ReplicationActivityImpl(lambda$replicate$3):161 - sync summary: io.airbyte.config.StandardSyncOutput@66f7b386[standardSyncSummary=io.airbyte.config.StandardSyncSummary@68db4acc[status=completed,recordsSynced=71,bytesSynced=30672,startTime=1659102757293,endTime=1659102760538,totalStats=io.airbyte.config.SyncStats@2c388274[recordsEmitted=71,bytesEmitted=30672,stateMessagesEmitted=0,recordsCommitted=71],streamStats=[io.airbyte.config.StreamSyncStats@6593a4eb[streamName=elt_DWD_DE_annual_sunshine-duration_regional-averages,stats=io.airbyte.config.SyncStats@57d70e34[recordsEmitted=71,bytesEmitted=30672,stateMessagesEmitted=,recordsCommitted=71]]]],normalizationSummary=,state=io.airbyte.config.State@4acbedc6[state={}],outputCatalog=io.airbyte.protocol.models.ConfiguredAirbyteCatalog@261a7a4f[streams=[io.airbyte.protocol.models.ConfiguredAirbyteStream@392a10be[stream=io.airbyte.protocol.models.AirbyteStream@629aef9e[name=elt_DWD_DE_annual_sunshine-duration_regional-averages,jsonSchema={"type":"object","$schema":"http://json-schema.org/draft-07/schema#","properties":{"Jahr":{"type":["number","null"]},"Bayern":{"type":["number","null"]},"Hessen":{"type":["number","null"]},"Sachsen":{"type":["number","null"]},"Saarland":{"type":["number","null"]},"Thueringen":{"type":["number","null"]},"Brandenburg":{"type":["number","null"]},"Deutschland":{"type":["number","null"]},"Niedersachsen":{"type":["number","null"]},"Sachsen-Anhalt":{"type":["number","null"]},"Rheinland-Pfalz":{"type":["number","null"]},"Baden-Wuerttemberg":{"type":["number","null"]},"Brandenburg/Berlin":{"type":["number","null"]},"Schleswig-Holstein":{"type":["number","null"]},"Nordrhein-Westfalen":{"type":["number","null"]},"Mecklenburg-Vorpommern":{"type":["number","null"]},"Thueringen/Sachsen-Anhalt":{"type":["number","null"]},"Niedersachsen/Hamburg/Bremen":{"type":["number","null"]}}},supportedSyncModes=[full_refresh],sourceDefinedCursor=,defaultCursorField=[],sourceDefinedPrimaryKey=[],namespace=,additionalProperties={}],syncMode=full_refresh,cursorField=[],destinationSyncMode=overwrite,primaryKey=[],additionalProperties={}]],additionalProperties={}],failures=[]] 2022-07-29 13:52:40 INFO i.a.w.t.TemporalUtils(withBackgroundHeartbeat):291 - Stopping temporal heartbeating... 2022-07-29 13:52:40 INFO i.a.w.t.TemporalAttemptExecution(get):105 - Docker volume job log path: /tmp/workspace/30/0/logs.log 2022-07-29 13:52:40 INFO i.a.w.t.TemporalAttemptExecution(get):110 - Executing worker wrapper. Airbyte version: 0.39.41-alpha 2022-07-29 13:52:40 INFO i.a.w.g.DefaultNormalizationWorker(run):50 - Running normalization. 2022-07-29 13:52:40 INFO i.a.w.n.DefaultNormalizationRunner(runProcess):122 - Running with normalization version: airbyte/normalization-mssql:0.2.12 2022-07-29 13:52:40 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/normalization-mssql:0.2.12 exists... 2022-07-29 13:52:40 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/normalization-mssql:0.2.12 was found locally. 2022-07-29 13:52:40 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:52:40 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/0/normalize --log-driver none --name normalization-mssql-normalize-30-0-fwrxp --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e AIRBYTE_VERSION=0.39.41-alpha airbyte/normalization-mssql:0.2.12 run --integration-type mssql --config destination_config.json --catalog destination_catalog.json 2022-07-29 13:52:40 normalization > Running: transform-config --config destination_config.json --integration-type mssql --out /data/30/0/normalize 2022-07-29 13:52:41 normalization > Namespace(config='destination_config.json', integration_type=, out='/data/30/0/normalize') 2022-07-29 13:52:41 normalization > transform_mssql 2022-07-29 13:52:41 normalization > Running: transform-catalog --integration-type mssql --profile-config-dir /data/30/0/normalize --catalog destination_catalog.json --out /data/30/0/normalize/models/generated/ --json-column _airbyte_data 2022-07-29 13:52:41 normalization > Processing destination_catalog.json... 2022-07-29 13:52:41 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:52:41 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:52:41 normalization > Generating airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab1.sql from elt_DWD_DE_annual_sunshine-duration_regional-averages 2022-07-29 13:52:41 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:52:41 normalization > Generating airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab2.sql from elt_DWD_DE_annual_sunshine-duration_regional-averages 2022-07-29 13:52:41 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:52:41 normalization > Truncating _airbyte_elt_dwd_de_annual_sun__ion_regional_averages_hashid (#60) to _airbyte_elt_dwd_de_a_ional_averages_hashid (#44) 2022-07-29 13:52:41 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:52:41 normalization > Generating airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab3.sql from elt_DWD_DE_annual_sunshine-duration_regional-averages 2022-07-29 13:52:41 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:52:41 normalization > Truncating _airbyte_elt_dwd_de_annual_sun__ion_regional_averages_hashid (#60) to _airbyte_elt_dwd_de_a_ional_averages_hashid (#44) 2022-07-29 13:52:41 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:52:41 normalization > Generating airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql from elt_DWD_DE_annual_sunshine-duration_regional-averages 2022-07-29 13:52:41 normalization > detected no config file for ssh, assuming ssh is off. 2022-07-29 13:52:44 normalization > [--event-buffer-size EVENT_BUFFER_SIZE] 2022-07-29 13:52:44 normalization > --event-buffer-size EVENT_BUFFER_SIZE 2022-07-29 13:52:44 normalization > 2022-07-29 13:52:44 normalization > DBT >=1.0.0 detected; using 10K event buffer size 2022-07-29 13:52:44 normalization > 2022-07-29 13:52:47 normalization > 13:52:47 Running with dbt=1.0.0 2022-07-29 13:52:47 normalization > 13:52:47 Partial parse save file not found. Starting full parse. 2022-07-29 13:52:49 normalization > 13:52:49 [WARNING]: Configuration paths exist in your dbt_project.yml file which do not apply to any resources. 2022-07-29 13:52:49 normalization > There are 2 unused configuration paths: 2022-07-29 13:52:49 normalization > - models.airbyte_utils.generated.airbyte_views 2022-07-29 13:52:49 normalization > - models.airbyte_utils.generated.airbyte_incremental 2022-07-29 13:52:49 normalization > 2022-07-29 13:52:49 normalization > 13:52:49 Found 4 models, 0 tests, 0 snapshots, 0 analyses, 580 macros, 0 operations, 0 seed files, 1 source, 0 exposures, 0 metrics 2022-07-29 13:52:49 normalization > 13:52:49 2022-07-29 13:52:49 normalization > 13:52:49 Concurrency: 8 threads (target='prod') 2022-07-29 13:52:49 normalization > 13:52:49 2022-07-29 13:52:49 normalization > 13:52:49 1 of 1 START table model airbyte_elt.elt_dwd_de_annual_sun__ion_regional_averages....................................... [RUN] 2022-07-29 13:52:49 normalization > 13:52:49 1 of 1 ERROR creating table model airbyte_elt.elt_dwd_de_annual_sun__ion_regional_averages.............................. [ERROR in 0.04s] 2022-07-29 13:52:49 normalization > 13:52:49 2022-07-29 13:52:49 normalization > 13:52:49 Finished running 1 table model in 0.36s. 2022-07-29 13:52:49 normalization > 13:52:49 2022-07-29 13:52:49 normalization > 13:52:49 Completed with 1 error and 0 warnings: 2022-07-29 13:52:49 normalization > 13:52:49 2022-07-29 13:52:49 normalization > 13:52:49 Compilation Error in model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:52:49 normalization > 13:52:49 When searching for a relation, dbt found an approximate match. Instead of guessing 2022-07-29 13:52:49 normalization > 13:52:49 which relation to use, dbt will move on. Please delete "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages", or rename it to be less ambiguous. 2022-07-29 13:52:49 normalization > 13:52:49 Searched for: "AIRBYTE_ELT"."airbyte_elt"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:52:49 normalization > 13:52:49 Found: "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:52:49 normalization > 13:52:49 2022-07-29 13:52:49 normalization > 13:52:49 > in macro materialization_table_default (macros/materializations/models/table/table.sql) 2022-07-29 13:52:49 normalization > 13:52:49 > called by model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:52:49 normalization > 13:52:49 2022-07-29 13:52:49 normalization > 13:52:49 Done. PASS=0 WARN=0 ERROR=1 SKIP=0 TOTAL=1 2022-07-29 13:52:50 normalization > 2022-07-29 13:52:50 normalization > Diagnosing dbt debug to check if destination is available for dbt and well configured (1): 2022-07-29 13:52:50 normalization > 2022-07-29 13:52:52 normalization > 13:52:52 Running with dbt=1.0.0 2022-07-29 13:52:52 normalization > dbt version: 1.0.0 2022-07-29 13:52:52 normalization > python version: 3.9.9 2022-07-29 13:52:52 normalization > python path: /usr/local/bin/python 2022-07-29 13:52:52 normalization > os info: Linux-4.18.0-372.16.1.el8_6.x86_64-x86_64-with-glibc2.31 2022-07-29 13:52:52 normalization > Using profiles.yml file at /data/30/0/normalize/profiles.yml 2022-07-29 13:52:52 normalization > Using dbt_project.yml file at /data/30/0/normalize/dbt_project.yml 2022-07-29 13:52:52 normalization > 2022-07-29 13:52:53 normalization > Configuration: 2022-07-29 13:52:53 normalization > profiles.yml file [OK found and valid] 2022-07-29 13:52:53 normalization > dbt_project.yml file [OK found and valid] 2022-07-29 13:52:53 normalization > 2022-07-29 13:52:53 normalization > Required dependencies: 2022-07-29 13:52:53 normalization > - git [OK found] 2022-07-29 13:52:53 normalization > 2022-07-29 13:52:53 normalization > Connection: 2022-07-29 13:52:53 normalization > server: 172.18.105.119 2022-07-29 13:52:53 normalization > database: AIRBYTE_ELT 2022-07-29 13:52:53 normalization > schema: AIRBYTE_ELT 2022-07-29 13:52:53 normalization > port: 1433 2022-07-29 13:52:53 normalization > UID: AIRBYTE_ELT 2022-07-29 13:52:53 normalization > client_id: None 2022-07-29 13:52:53 normalization > authentication: sql 2022-07-29 13:52:53 normalization > encrypt: False 2022-07-29 13:52:53 normalization > trust_cert: False 2022-07-29 13:52:53 normalization > Connection test: [OK connection ok] 2022-07-29 13:52:53 normalization > 2022-07-29 13:52:53 normalization > All checks passed! 2022-07-29 13:52:53 normalization > 2022-07-29 13:52:53 normalization > Forward dbt output logs to diagnose/debug errors (0): 2022-07-29 13:52:53 normalization > 2022-07-29 13:52:53 normalization > 2022-07-29 13:52:53 normalization > 2022-07-29 13:52:53 normalization > ============================== 2022-07-29 13:52:47.545237 | 82a1a9ff-a56a-4b0a-8924-1723b7f6a0b7 ============================== 2022-07-29 13:52:53 normalization > 13:52:47.545237 [info ] [MainThread]: Running with dbt=1.0.0 2022-07-29 13:52:53 normalization > 13:52:47.545840 [debug] [MainThread]: running dbt with arguments Namespace(record_timing_info=None, debug=None, log_format=None, write_json=None, use_colors=None, printer_width=None, warn_error=None, version_check=None, partial_parse=None, single_threaded=False, use_experimental_parser=None, static_parser=None, profiles_dir='/data/30/0/normalize', send_anonymous_usage_stats=None, fail_fast=None, event_buffer_size='10000', project_dir='/data/30/0/normalize', profile=None, target=None, vars='{}', log_cache_events=False, threads=None, select=None, exclude=None, selector_name=None, state=None, defer=None, full_refresh=False, cls=, which='run', rpc_method='run') 2022-07-29 13:52:53 normalization > 13:52:47.546067 [debug] [MainThread]: Tracking: do not track 2022-07-29 13:52:53 normalization > 13:52:47.574570 [info ] [MainThread]: Partial parse save file not found. Starting full parse. 2022-07-29 13:52:53 normalization > 13:52:47.623362 [debug] [MainThread]: Parsing macros/clean_tmp_tables.sql 2022-07-29 13:52:53 normalization > 13:52:47.628444 [debug] [MainThread]: Parsing macros/configuration.sql 2022-07-29 13:52:53 normalization > 13:52:47.632876 [debug] [MainThread]: Parsing macros/get_custom_schema.sql 2022-07-29 13:52:53 normalization > 13:52:47.633941 [debug] [MainThread]: Parsing macros/incremental.sql 2022-07-29 13:52:53 normalization > 13:52:47.646645 [debug] [MainThread]: Parsing macros/should_full_refresh.sql 2022-07-29 13:52:53 normalization > 13:52:47.653456 [debug] [MainThread]: Parsing macros/star_intersect.sql 2022-07-29 13:52:53 normalization > 13:52:47.662150 [debug] [MainThread]: Parsing macros/cross_db_utils/array.sql 2022-07-29 13:52:53 normalization > 13:52:47.722672 [debug] [MainThread]: Parsing macros/cross_db_utils/columns.sql 2022-07-29 13:52:53 normalization > 13:52:47.726537 [debug] [MainThread]: Parsing macros/cross_db_utils/concat.sql 2022-07-29 13:52:53 normalization > 13:52:47.729982 [debug] [MainThread]: Parsing macros/cross_db_utils/current_timestamp.sql 2022-07-29 13:52:53 normalization > 13:52:47.730785 [debug] [MainThread]: Parsing macros/cross_db_utils/datatypes.sql 2022-07-29 13:52:53 normalization > 13:52:47.754288 [debug] [MainThread]: Parsing macros/cross_db_utils/except.sql 2022-07-29 13:52:53 normalization > 13:52:47.755345 [debug] [MainThread]: Parsing macros/cross_db_utils/hash.sql 2022-07-29 13:52:53 normalization > 13:52:47.756144 [debug] [MainThread]: Parsing macros/cross_db_utils/json_operations.sql 2022-07-29 13:52:53 normalization > 13:52:47.807201 [debug] [MainThread]: Parsing macros/cross_db_utils/quote.sql 2022-07-29 13:52:53 normalization > 13:52:47.809498 [debug] [MainThread]: Parsing macros/cross_db_utils/surrogate_key.sql 2022-07-29 13:52:53 normalization > 13:52:47.812172 [debug] [MainThread]: Parsing macros/cross_db_utils/type_conversions.sql 2022-07-29 13:52:53 normalization > 13:52:47.824683 [debug] [MainThread]: Parsing macros/schema_tests/equal_rowcount.sql 2022-07-29 13:52:53 normalization > 13:52:47.826424 [debug] [MainThread]: Parsing macros/schema_tests/equality.sql 2022-07-29 13:52:53 normalization > 13:52:47.839341 [debug] [MainThread]: Parsing macros/adapters.sql 2022-07-29 13:52:53 normalization > 13:52:47.839650 [debug] [MainThread]: Parsing macros/adapters/columns.sql 2022-07-29 13:52:53 normalization > 13:52:47.848246 [debug] [MainThread]: Parsing macros/adapters/freshness.sql 2022-07-29 13:52:53 normalization > 13:52:47.848850 [debug] [MainThread]: Parsing macros/adapters/indexes.sql 2022-07-29 13:52:53 normalization > 13:52:47.866120 [debug] [MainThread]: Parsing macros/adapters/metadata.sql 2022-07-29 13:52:53 normalization > 13:52:47.875015 [debug] [MainThread]: Parsing macros/adapters/persist_docs.sql 2022-07-29 13:52:53 normalization > 13:52:47.875291 [debug] [MainThread]: Parsing macros/adapters/relation.sql 2022-07-29 13:52:53 normalization > 13:52:47.885715 [debug] [MainThread]: Parsing macros/adapters/schema.sql 2022-07-29 13:52:53 normalization > 13:52:47.891945 [debug] [MainThread]: Parsing macros/materializations/models/incremental/merge.sql 2022-07-29 13:52:53 normalization > 13:52:47.895071 [debug] [MainThread]: Parsing macros/materializations/models/table/create_table_as.sql 2022-07-29 13:52:53 normalization > 13:52:47.898832 [debug] [MainThread]: Parsing macros/materializations/models/view/create_view_as.sql 2022-07-29 13:52:53 normalization > 13:52:47.900834 [debug] [MainThread]: Parsing macros/materializations/seeds/helpers.sql 2022-07-29 13:52:53 normalization > 13:52:47.912695 [debug] [MainThread]: Parsing macros/materializations/snapshots/snapshot.sql 2022-07-29 13:52:53 normalization > 13:52:47.914680 [debug] [MainThread]: Parsing macros/materializations/snapshots/snapshot_merge.sql 2022-07-29 13:52:53 normalization > 13:52:47.915656 [debug] [MainThread]: Parsing macros/materializations/snapshots/strategies.sql 2022-07-29 13:52:53 normalization > 13:52:47.917566 [debug] [MainThread]: Parsing macros/materializations/tests/helpers.sql 2022-07-29 13:52:53 normalization > 13:52:47.919214 [debug] [MainThread]: Parsing macros/materializations/tests/test.sql 2022-07-29 13:52:53 normalization > 13:52:47.926472 [debug] [MainThread]: Parsing macros/adapters/columns.sql 2022-07-29 13:52:53 normalization > 13:52:47.940334 [debug] [MainThread]: Parsing macros/adapters/freshness.sql 2022-07-29 13:52:53 normalization > 13:52:47.944490 [debug] [MainThread]: Parsing macros/adapters/indexes.sql 2022-07-29 13:52:53 normalization > 13:52:47.948362 [debug] [MainThread]: Parsing macros/adapters/metadata.sql 2022-07-29 13:52:53 normalization > 13:52:47.958389 [debug] [MainThread]: Parsing macros/adapters/persist_docs.sql 2022-07-29 13:52:53 normalization > 13:52:47.964759 [debug] [MainThread]: Parsing macros/adapters/relation.sql 2022-07-29 13:52:53 normalization > 13:52:47.978612 [debug] [MainThread]: Parsing macros/adapters/schema.sql 2022-07-29 13:52:53 normalization > 13:52:47.981759 [debug] [MainThread]: Parsing macros/etc/datetime.sql 2022-07-29 13:52:53 normalization > 13:52:47.993733 [debug] [MainThread]: Parsing macros/etc/statement.sql 2022-07-29 13:52:53 normalization > 13:52:48.000197 [debug] [MainThread]: Parsing macros/generic_test_sql/accepted_values.sql 2022-07-29 13:52:53 normalization > 13:52:48.002172 [debug] [MainThread]: Parsing macros/generic_test_sql/not_null.sql 2022-07-29 13:52:53 normalization > 13:52:48.002927 [debug] [MainThread]: Parsing macros/generic_test_sql/relationships.sql 2022-07-29 13:52:53 normalization > 13:52:48.004122 [debug] [MainThread]: Parsing macros/generic_test_sql/unique.sql 2022-07-29 13:52:53 normalization > 13:52:48.005194 [debug] [MainThread]: Parsing macros/get_custom_name/get_custom_alias.sql 2022-07-29 13:52:53 normalization > 13:52:48.007298 [debug] [MainThread]: Parsing macros/get_custom_name/get_custom_database.sql 2022-07-29 13:52:53 normalization > 13:52:48.009518 [debug] [MainThread]: Parsing macros/get_custom_name/get_custom_schema.sql 2022-07-29 13:52:53 normalization > 13:52:48.013151 [debug] [MainThread]: Parsing macros/materializations/configs.sql 2022-07-29 13:52:53 normalization > 13:52:48.016976 [debug] [MainThread]: Parsing macros/materializations/hooks.sql 2022-07-29 13:52:53 normalization > 13:52:48.022651 [debug] [MainThread]: Parsing macros/materializations/models/incremental/column_helpers.sql 2022-07-29 13:52:53 normalization > 13:52:48.030329 [debug] [MainThread]: Parsing macros/materializations/models/incremental/incremental.sql 2022-07-29 13:52:53 normalization > 13:52:48.050106 [debug] [MainThread]: Parsing macros/materializations/models/incremental/is_incremental.sql 2022-07-29 13:52:53 normalization > 13:52:48.052674 [debug] [MainThread]: Parsing macros/materializations/models/incremental/merge.sql 2022-07-29 13:52:53 normalization > 13:52:48.069217 [debug] [MainThread]: Parsing macros/materializations/models/incremental/on_schema_change.sql 2022-07-29 13:52:53 normalization > 13:52:48.090174 [debug] [MainThread]: Parsing macros/materializations/models/table/create_table_as.sql 2022-07-29 13:52:53 normalization > 13:52:48.094342 [debug] [MainThread]: Parsing macros/materializations/models/table/table.sql 2022-07-29 13:52:53 normalization > 13:52:48.104401 [debug] [MainThread]: Parsing macros/materializations/models/view/create_or_replace_view.sql 2022-07-29 13:52:53 normalization > 13:52:48.108304 [debug] [MainThread]: Parsing macros/materializations/models/view/create_view_as.sql 2022-07-29 13:52:53 normalization > 13:52:48.111613 [debug] [MainThread]: Parsing macros/materializations/models/view/helpers.sql 2022-07-29 13:52:53 normalization > 13:52:48.113444 [debug] [MainThread]: Parsing macros/materializations/models/view/view.sql 2022-07-29 13:52:53 normalization > 13:52:48.123277 [debug] [MainThread]: Parsing macros/materializations/seeds/helpers.sql 2022-07-29 13:52:53 normalization > 13:52:48.145369 [debug] [MainThread]: Parsing macros/materializations/seeds/seed.sql 2022-07-29 13:52:53 normalization > 13:52:48.154357 [debug] [MainThread]: Parsing macros/materializations/snapshots/helpers.sql 2022-07-29 13:52:53 normalization > 13:52:48.170081 [debug] [MainThread]: Parsing macros/materializations/snapshots/snapshot.sql 2022-07-29 13:52:53 normalization > 13:52:48.188814 [debug] [MainThread]: Parsing macros/materializations/snapshots/snapshot_merge.sql 2022-07-29 13:52:53 normalization > 13:52:48.191183 [debug] [MainThread]: Parsing macros/materializations/snapshots/strategies.sql 2022-07-29 13:52:53 normalization > 13:52:48.216250 [debug] [MainThread]: Parsing macros/materializations/tests/helpers.sql 2022-07-29 13:52:53 normalization > 13:52:48.218859 [debug] [MainThread]: Parsing macros/materializations/tests/test.sql 2022-07-29 13:52:53 normalization > 13:52:48.225381 [debug] [MainThread]: Parsing macros/materializations/tests/where_subquery.sql 2022-07-29 13:52:53 normalization > 13:52:48.227969 [debug] [MainThread]: Parsing tests/generic/builtin.sql 2022-07-29 13:52:53 normalization > 13:52:48.232292 [debug] [MainThread]: Parsing macros/cross_db_utils/_is_ephemeral.sql 2022-07-29 13:52:53 normalization > 13:52:48.235008 [debug] [MainThread]: Parsing macros/cross_db_utils/_is_relation.sql 2022-07-29 13:52:53 normalization > 13:52:48.236637 [debug] [MainThread]: Parsing macros/cross_db_utils/any_value.sql 2022-07-29 13:52:53 normalization > 13:52:48.238440 [debug] [MainThread]: Parsing macros/cross_db_utils/bool_or.sql 2022-07-29 13:52:53 normalization > 13:52:48.240656 [debug] [MainThread]: Parsing macros/cross_db_utils/cast_bool_to_text.sql 2022-07-29 13:52:53 normalization > 13:52:48.242822 [debug] [MainThread]: Parsing macros/cross_db_utils/concat.sql 2022-07-29 13:52:53 normalization > 13:52:48.244227 [debug] [MainThread]: Parsing macros/cross_db_utils/current_timestamp.sql 2022-07-29 13:52:53 normalization > 13:52:48.249326 [debug] [MainThread]: Parsing macros/cross_db_utils/datatypes.sql 2022-07-29 13:52:53 normalization > 13:52:48.258566 [debug] [MainThread]: Parsing macros/cross_db_utils/date_trunc.sql 2022-07-29 13:52:53 normalization > 13:52:48.260707 [debug] [MainThread]: Parsing macros/cross_db_utils/dateadd.sql 2022-07-29 13:52:53 normalization > 13:52:48.264800 [debug] [MainThread]: Parsing macros/cross_db_utils/datediff.sql 2022-07-29 13:52:53 normalization > 13:52:48.278422 [debug] [MainThread]: Parsing macros/cross_db_utils/escape_single_quotes.sql 2022-07-29 13:52:53 normalization > 13:52:48.281112 [debug] [MainThread]: Parsing macros/cross_db_utils/except.sql 2022-07-29 13:52:53 normalization > 13:52:48.282579 [debug] [MainThread]: Parsing macros/cross_db_utils/hash.sql 2022-07-29 13:52:53 normalization > 13:52:48.284548 [debug] [MainThread]: Parsing macros/cross_db_utils/identifier.sql 2022-07-29 13:52:53 normalization > 13:52:48.286899 [debug] [MainThread]: Parsing macros/cross_db_utils/intersect.sql 2022-07-29 13:52:53 normalization > 13:52:48.288377 [debug] [MainThread]: Parsing macros/cross_db_utils/last_day.sql 2022-07-29 13:52:53 normalization > 13:52:48.293842 [debug] [MainThread]: Parsing macros/cross_db_utils/length.sql 2022-07-29 13:52:53 normalization > 13:52:48.295642 [debug] [MainThread]: Parsing macros/cross_db_utils/literal.sql 2022-07-29 13:52:53 normalization > 13:52:48.296955 [debug] [MainThread]: Parsing macros/cross_db_utils/position.sql 2022-07-29 13:52:53 normalization > 13:52:48.299126 [debug] [MainThread]: Parsing macros/cross_db_utils/replace.sql 2022-07-29 13:52:53 normalization > 13:52:48.300893 [debug] [MainThread]: Parsing macros/cross_db_utils/right.sql 2022-07-29 13:52:53 normalization > 13:52:48.304022 [debug] [MainThread]: Parsing macros/cross_db_utils/safe_cast.sql 2022-07-29 13:52:53 normalization > 13:52:48.306888 [debug] [MainThread]: Parsing macros/cross_db_utils/split_part.sql 2022-07-29 13:52:53 normalization > 13:52:48.309637 [debug] [MainThread]: Parsing macros/cross_db_utils/width_bucket.sql 2022-07-29 13:52:53 normalization > 13:52:48.316935 [debug] [MainThread]: Parsing macros/jinja_helpers/log_info.sql 2022-07-29 13:52:53 normalization > 13:52:48.318526 [debug] [MainThread]: Parsing macros/jinja_helpers/pretty_log_format.sql 2022-07-29 13:52:53 normalization > 13:52:48.320133 [debug] [MainThread]: Parsing macros/jinja_helpers/pretty_time.sql 2022-07-29 13:52:53 normalization > 13:52:48.322067 [debug] [MainThread]: Parsing macros/jinja_helpers/slugify.sql 2022-07-29 13:52:53 normalization > 13:52:48.323791 [debug] [MainThread]: Parsing macros/materializations/insert_by_period_materialization.sql 2022-07-29 13:52:53 normalization > 13:52:48.358495 [debug] [MainThread]: Parsing macros/schema_tests/accepted_range.sql 2022-07-29 13:52:53 normalization > 13:52:48.362087 [debug] [MainThread]: Parsing macros/schema_tests/at_least_one.sql 2022-07-29 13:52:53 normalization > 13:52:48.363900 [debug] [MainThread]: Parsing macros/schema_tests/cardinality_equality.sql 2022-07-29 13:52:53 normalization > 13:52:48.366745 [debug] [MainThread]: Parsing macros/schema_tests/equal_rowcount.sql 2022-07-29 13:52:53 normalization > 13:52:48.369113 [debug] [MainThread]: Parsing macros/schema_tests/equality.sql 2022-07-29 13:52:53 normalization > 13:52:48.374396 [debug] [MainThread]: Parsing macros/schema_tests/expression_is_true.sql 2022-07-29 13:52:53 normalization > 13:52:48.377074 [debug] [MainThread]: Parsing macros/schema_tests/fewer_rows_than.sql 2022-07-29 13:52:53 normalization > 13:52:48.379292 [debug] [MainThread]: Parsing macros/schema_tests/mutually_exclusive_ranges.sql 2022-07-29 13:52:53 normalization > 13:52:48.390991 [debug] [MainThread]: Parsing macros/schema_tests/not_accepted_values.sql 2022-07-29 13:52:53 normalization > 13:52:48.394022 [debug] [MainThread]: Parsing macros/schema_tests/not_constant.sql 2022-07-29 13:52:53 normalization > 13:52:48.396339 [debug] [MainThread]: Parsing macros/schema_tests/not_null_proportion.sql 2022-07-29 13:52:53 normalization > 13:52:48.399542 [debug] [MainThread]: Parsing macros/schema_tests/recency.sql 2022-07-29 13:52:53 normalization > 13:52:48.402096 [debug] [MainThread]: Parsing macros/schema_tests/relationships_where.sql 2022-07-29 13:52:53 normalization > 13:52:48.405281 [debug] [MainThread]: Parsing macros/schema_tests/sequential_values.sql 2022-07-29 13:52:53 normalization > 13:52:48.409403 [debug] [MainThread]: Parsing macros/schema_tests/test_not_null_where.sql 2022-07-29 13:52:53 normalization > 13:52:48.411441 [debug] [MainThread]: Parsing macros/schema_tests/test_unique_where.sql 2022-07-29 13:52:53 normalization > 13:52:48.413472 [debug] [MainThread]: Parsing macros/schema_tests/unique_combination_of_columns.sql 2022-07-29 13:52:53 normalization > 13:52:48.417620 [debug] [MainThread]: Parsing macros/sql/date_spine.sql 2022-07-29 13:52:53 normalization > 13:52:48.423554 [debug] [MainThread]: Parsing macros/sql/generate_series.sql 2022-07-29 13:52:53 normalization > 13:52:48.429557 [debug] [MainThread]: Parsing macros/sql/get_column_values.sql 2022-07-29 13:52:53 normalization > 13:52:48.437044 [debug] [MainThread]: Parsing macros/sql/get_query_results_as_dict.sql 2022-07-29 13:52:53 normalization > 13:52:48.440371 [debug] [MainThread]: Parsing macros/sql/get_relations_by_pattern.sql 2022-07-29 13:52:53 normalization > 13:52:48.445283 [debug] [MainThread]: Parsing macros/sql/get_relations_by_prefix.sql 2022-07-29 13:52:53 normalization > 13:52:48.450295 [debug] [MainThread]: Parsing macros/sql/get_table_types_sql.sql 2022-07-29 13:52:53 normalization > 13:52:48.451993 [debug] [MainThread]: Parsing macros/sql/get_tables_by_pattern_sql.sql 2022-07-29 13:52:53 normalization > 13:52:48.460693 [debug] [MainThread]: Parsing macros/sql/get_tables_by_prefix_sql.sql 2022-07-29 13:52:53 normalization > 13:52:48.463110 [debug] [MainThread]: Parsing macros/sql/groupby.sql 2022-07-29 13:52:53 normalization > 13:52:48.465015 [debug] [MainThread]: Parsing macros/sql/haversine_distance.sql 2022-07-29 13:52:53 normalization > 13:52:48.473424 [debug] [MainThread]: Parsing macros/sql/nullcheck.sql 2022-07-29 13:52:53 normalization > 13:52:48.475828 [debug] [MainThread]: Parsing macros/sql/nullcheck_table.sql 2022-07-29 13:52:53 normalization > 13:52:48.478124 [debug] [MainThread]: Parsing macros/sql/pivot.sql 2022-07-29 13:52:53 normalization > 13:52:48.484180 [debug] [MainThread]: Parsing macros/sql/safe_add.sql 2022-07-29 13:52:53 normalization > 13:52:48.486506 [debug] [MainThread]: Parsing macros/sql/star.sql 2022-07-29 13:52:53 normalization > 13:52:48.492850 [debug] [MainThread]: Parsing macros/sql/surrogate_key.sql 2022-07-29 13:52:53 normalization > 13:52:48.497879 [debug] [MainThread]: Parsing macros/sql/union.sql 2022-07-29 13:52:53 normalization > 13:52:48.512615 [debug] [MainThread]: Parsing macros/sql/unpivot.sql 2022-07-29 13:52:53 normalization > 13:52:48.524207 [debug] [MainThread]: Parsing macros/web/get_url_host.sql 2022-07-29 13:52:53 normalization > 13:52:48.528000 [debug] [MainThread]: Parsing macros/web/get_url_parameter.sql 2022-07-29 13:52:53 normalization > 13:52:48.530486 [debug] [MainThread]: Parsing macros/web/get_url_path.sql 2022-07-29 13:52:53 normalization > 13:52:49.280423 [debug] [MainThread]: 1603: static parser failed on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab1.sql 2022-07-29 13:52:53 normalization > 13:52:49.336214 [debug] [MainThread]: 1602: parser fallback to jinja rendering on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab1.sql 2022-07-29 13:52:53 normalization > 13:52:49.339399 [debug] [MainThread]: 1603: static parser failed on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab2.sql 2022-07-29 13:52:53 normalization > 13:52:49.368701 [debug] [MainThread]: 1602: parser fallback to jinja rendering on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab2.sql 2022-07-29 13:52:53 normalization > 13:52:49.370513 [debug] [MainThread]: 1603: static parser failed on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab3.sql 2022-07-29 13:52:53 normalization > 13:52:49.413122 [debug] [MainThread]: 1602: parser fallback to jinja rendering on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab3.sql 2022-07-29 13:52:53 normalization > 13:52:49.415005 [debug] [MainThread]: 1603: static parser failed on generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql 2022-07-29 13:52:53 normalization > 13:52:49.426155 [debug] [MainThread]: 1602: parser fallback to jinja rendering on generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql 2022-07-29 13:52:53 normalization > 13:52:49.551519 [warn ] [MainThread]: [WARNING]: Configuration paths exist in your dbt_project.yml file which do not apply to any resources. 2022-07-29 13:52:53 normalization > There are 2 unused configuration paths: 2022-07-29 13:52:53 normalization > - models.airbyte_utils.generated.airbyte_views 2022-07-29 13:52:53 normalization > - models.airbyte_utils.generated.airbyte_incremental 2022-07-29 13:52:53 normalization > 2022-07-29 13:52:53 normalization > 13:52:49.591289 [info ] [MainThread]: Found 4 models, 0 tests, 0 snapshots, 0 analyses, 580 macros, 0 operations, 0 seed files, 1 source, 0 exposures, 0 metrics 2022-07-29 13:52:53 normalization > 13:52:49.593147 [info ] [MainThread]: 2022-07-29 13:52:53 normalization > 13:52:49.594082 [debug] [MainThread]: Acquiring new sqlserver connection "master" 2022-07-29 13:52:53 normalization > 13:52:49.595221 [debug] [ThreadPool]: Acquiring new sqlserver connection "list_AIRBYTE_ELT" 2022-07-29 13:52:53 normalization > 13:52:49.652342 [debug] [ThreadPool]: Opening a new connection, currently in state init 2022-07-29 13:52:53 normalization > 13:52:49.681408 [debug] [ThreadPool]: On list_AIRBYTE_ELT: Close 2022-07-29 13:52:53 normalization > 13:52:49.683137 [debug] [ThreadPool]: Acquiring new sqlserver connection "list_AIRBYTE_ELT_airbyte_elt" 2022-07-29 13:52:53 normalization > 13:52:49.692306 [debug] [ThreadPool]: Opening a new connection, currently in state closed 2022-07-29 13:52:53 normalization > 13:52:49.714180 [debug] [ThreadPool]: On list_AIRBYTE_ELT_airbyte_elt: ROLLBACK 2022-07-29 13:52:53 normalization > 13:52:49.714452 [debug] [ThreadPool]: On list_AIRBYTE_ELT_airbyte_elt: Close 2022-07-29 13:52:53 normalization > 13:52:49.715445 [debug] [MainThread]: On master: COMMIT 2022-07-29 13:52:53 normalization > 13:52:49.718044 [info ] [MainThread]: Concurrency: 8 threads (target='prod') 2022-07-29 13:52:53 normalization > 13:52:49.718558 [info ] [MainThread]: 2022-07-29 13:52:53 normalization > 13:52:49.749069 [debug] [Thread-1 ]: Began running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1 2022-07-29 13:52:53 normalization > 13:52:49.750084 [debug] [Thread-1 ]: Acquiring new sqlserver connection "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1" 2022-07-29 13:52:53 normalization > 13:52:49.750371 [debug] [Thread-1 ]: Began compiling node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1 2022-07-29 13:52:53 normalization > 13:52:49.750555 [debug] [Thread-1 ]: Compiling model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1 2022-07-29 13:52:53 normalization > 13:52:49.780595 [debug] [Thread-1 ]: Writing injected SQL for node "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1" 2022-07-29 13:52:53 normalization > 13:52:49.781290 [debug] [Thread-1 ]: finished collecting timing info 2022-07-29 13:52:53 normalization > 13:52:49.781765 [debug] [Thread-1 ]: Finished running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1 2022-07-29 13:52:53 normalization > 13:52:49.784053 [debug] [Thread-3 ]: Began running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2 2022-07-29 13:52:53 normalization > 13:52:49.784601 [debug] [Thread-3 ]: Acquiring new sqlserver connection "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2" 2022-07-29 13:52:53 normalization > 13:52:49.784888 [debug] [Thread-3 ]: Began compiling node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2 2022-07-29 13:52:53 normalization > 13:52:49.785124 [debug] [Thread-3 ]: Compiling model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2 2022-07-29 13:52:53 normalization > 13:52:49.843454 [debug] [Thread-3 ]: Writing injected SQL for node "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2" 2022-07-29 13:52:53 normalization > 13:52:49.843943 [debug] [Thread-3 ]: finished collecting timing info 2022-07-29 13:52:53 normalization > 13:52:49.844404 [debug] [Thread-3 ]: Finished running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2 2022-07-29 13:52:53 normalization > 13:52:49.845139 [debug] [Thread-5 ]: Began running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3 2022-07-29 13:52:53 normalization > 13:52:49.845544 [debug] [Thread-5 ]: Acquiring new sqlserver connection "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3" 2022-07-29 13:52:53 normalization > 13:52:49.845699 [debug] [Thread-5 ]: Began compiling node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3 2022-07-29 13:52:53 normalization > 13:52:49.845896 [debug] [Thread-5 ]: Compiling model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3 2022-07-29 13:52:53 normalization > 13:52:49.906137 [debug] [Thread-5 ]: Writing injected SQL for node "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3" 2022-07-29 13:52:53 normalization > 13:52:49.906613 [debug] [Thread-5 ]: finished collecting timing info 2022-07-29 13:52:53 normalization > 13:52:49.907108 [debug] [Thread-5 ]: Finished running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3 2022-07-29 13:52:53 normalization > 13:52:49.907824 [debug] [Thread-7 ]: Began running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:52:53 normalization > 13:52:49.908143 [info ] [Thread-7 ]: 1 of 1 START table model airbyte_elt.elt_dwd_de_annual_sun__ion_regional_averages....................................... [RUN] 2022-07-29 13:52:53 normalization > 13:52:49.908748 [debug] [Thread-7 ]: Acquiring new sqlserver connection "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:52:53 normalization > 13:52:49.908931 [debug] [Thread-7 ]: Began compiling node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:52:53 normalization > 13:52:49.909096 [debug] [Thread-7 ]: Compiling model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:52:53 normalization > 13:52:49.927923 [debug] [Thread-7 ]: Writing injected SQL for node "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:52:53 normalization > 13:52:49.928372 [debug] [Thread-7 ]: finished collecting timing info 2022-07-29 13:52:53 normalization > 13:52:49.928581 [debug] [Thread-7 ]: Began executing node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:52:53 normalization > 13:52:49.948306 [debug] [Thread-7 ]: finished collecting timing info 2022-07-29 13:52:53 normalization > 13:52:49.948767 [debug] [Thread-7 ]: Compilation Error in model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:52:53 normalization > When searching for a relation, dbt found an approximate match. Instead of guessing 2022-07-29 13:52:53 normalization > which relation to use, dbt will move on. Please delete "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages", or rename it to be less ambiguous. 2022-07-29 13:52:53 normalization > Searched for: "AIRBYTE_ELT"."airbyte_elt"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:52:53 normalization > Found: "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:52:53 normalization > 2022-07-29 13:52:53 normalization > > in macro materialization_table_default (macros/materializations/models/table/table.sql) 2022-07-29 13:52:53 normalization > > called by model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:52:53 normalization > 13:52:49.949159 [error] [Thread-7 ]: 1 of 1 ERROR creating table model airbyte_elt.elt_dwd_de_annual_sun__ion_regional_averages.............................. [ERROR in 0.04s] 2022-07-29 13:52:53 normalization > 13:52:49.949558 [debug] [Thread-7 ]: Finished running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:52:53 normalization > 13:52:49.951114 [debug] [MainThread]: Acquiring new sqlserver connection "master" 2022-07-29 13:52:53 normalization > 13:52:49.951321 [debug] [MainThread]: On master: COMMIT 2022-07-29 13:52:53 normalization > 13:52:49.951700 [info ] [MainThread]: 2022-07-29 13:52:53 normalization > 13:52:49.952004 [info ] [MainThread]: Finished running 1 table model in 0.36s. 2022-07-29 13:52:53 normalization > 13:52:49.952278 [debug] [MainThread]: Connection 'master' was properly closed. 2022-07-29 13:52:53 normalization > 13:52:49.952421 [debug] [MainThread]: Connection 'model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1' was properly closed. 2022-07-29 13:52:53 normalization > 13:52:49.952559 [debug] [MainThread]: Connection 'model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2' was properly closed. 2022-07-29 13:52:53 normalization > 13:52:49.952868 [debug] [MainThread]: Connection 'model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3' was properly closed. 2022-07-29 13:52:53 normalization > 13:52:49.953009 [debug] [MainThread]: Connection 'model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages' was properly closed. 2022-07-29 13:52:53 normalization > 13:52:49.969115 [info ] [MainThread]: 2022-07-29 13:52:53 normalization > 13:52:49.969518 [info ] [MainThread]: Completed with 1 error and 0 warnings: 2022-07-29 13:52:53 normalization > 13:52:49.969962 [info ] [MainThread]: 2022-07-29 13:52:53 normalization > 13:52:49.970234 [error] [MainThread]: Compilation Error in model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:52:53 normalization > 13:52:49.970528 [error] [MainThread]: When searching for a relation, dbt found an approximate match. Instead of guessing 2022-07-29 13:52:53 normalization > 13:52:49.970797 [error] [MainThread]: which relation to use, dbt will move on. Please delete "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages", or rename it to be less ambiguous. 2022-07-29 13:52:53 normalization > 13:52:49.971045 [error] [MainThread]: Searched for: "AIRBYTE_ELT"."airbyte_elt"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:52:53 normalization > 13:52:49.971281 [error] [MainThread]: Found: "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:52:53 normalization > 13:52:49.971526 [error] [MainThread]: 2022-07-29 13:52:53 normalization > 13:52:49.971813 [error] [MainThread]: > in macro materialization_table_default (macros/materializations/models/table/table.sql) 2022-07-29 13:52:53 normalization > 13:52:49.972065 [error] [MainThread]: > called by model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:52:53 normalization > 13:52:49.972323 [info ] [MainThread]: 2022-07-29 13:52:53 normalization > 13:52:49.972577 [info ] [MainThread]: Done. PASS=0 WARN=0 ERROR=1 SKIP=0 TOTAL=1 2022-07-29 13:52:53 INFO i.a.w.t.TemporalAttemptExecution(lambda$getWorkerThread$2):158 - Completing future exceptionally... io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:64) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] Caused by: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:61) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 3 more Suppressed: io.airbyte.workers.exception.WorkerException: Normalization process wasn't successful at io.airbyte.workers.normalization.DefaultNormalizationRunner.close(DefaultNormalizationRunner.java:162) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:49) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] 2022-07-29 13:52:53 INFO i.a.w.t.TemporalAttemptExecution(get):131 - Stopping cancellation check scheduling... 2022-07-29 13:52:53 INFO i.a.w.t.TemporalUtils(withBackgroundHeartbeat):291 - Stopping temporal heartbeating... 2022-07-29 13:52:53 WARN i.t.i.a.POJOActivityTaskHandler(activityFailureToResult):307 - Activity failure. ActivityId=598d9c19-3d96-3c2a-93c1-d2870a6e5d5c, activityType=Normalize, attempt=1 java.lang.RuntimeException: io.temporal.serviceclient.CheckedExceptionWrapper: java.util.concurrent.ExecutionException: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.temporal.TemporalUtils.withBackgroundHeartbeat(TemporalUtils.java:289) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.sync.NormalizationActivityImpl.normalize(NormalizationActivityImpl.java:75) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:104) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:578) ~[?:?] at io.temporal.internal.activity.POJOActivityTaskHandler$POJOActivityInboundCallsInterceptor.execute(POJOActivityTaskHandler.java:214) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.activity.POJOActivityTaskHandler$POJOActivityImplementation.execute(POJOActivityTaskHandler.java:180) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.activity.POJOActivityTaskHandler.handle(POJOActivityTaskHandler.java:120) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.worker.ActivityWorker$TaskHandlerImpl.handle(ActivityWorker.java:204) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.worker.ActivityWorker$TaskHandlerImpl.handle(ActivityWorker.java:164) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.worker.PollTaskExecutor.lambda$process$0(PollTaskExecutor.java:93) ~[temporal-sdk-1.8.1.jar:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) [?:?] at java.lang.Thread.run(Thread.java:1589) [?:?] Caused by: io.temporal.serviceclient.CheckedExceptionWrapper: java.util.concurrent.ExecutionException: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.temporal.serviceclient.CheckedExceptionWrapper.wrap(CheckedExceptionWrapper.java:56) ~[temporal-serviceclient-1.8.1.jar:?] at io.temporal.internal.sync.WorkflowInternal.wrap(WorkflowInternal.java:448) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.activity.Activity.wrap(Activity.java:51) ~[temporal-sdk-1.8.1.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.get(TemporalAttemptExecution.java:135) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.sync.NormalizationActivityImpl.lambda$normalize$3(NormalizationActivityImpl.java:103) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalUtils.withBackgroundHeartbeat(TemporalUtils.java:284) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 12 more Caused by: java.util.concurrent.ExecutionException: io.airbyte.workers.exception.WorkerException: Normalization Failed. at java.util.concurrent.CompletableFuture.reportGet(CompletableFuture.java:396) ~[?:?] at java.util.concurrent.CompletableFuture.get(CompletableFuture.java:2073) ~[?:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.get(TemporalAttemptExecution.java:129) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.sync.NormalizationActivityImpl.lambda$normalize$3(NormalizationActivityImpl.java:103) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalUtils.withBackgroundHeartbeat(TemporalUtils.java:284) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 12 more Caused by: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:64) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 1 more Caused by: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:61) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 1 more Suppressed: io.airbyte.workers.exception.WorkerException: Normalization process wasn't successful at io.airbyte.workers.normalization.DefaultNormalizationRunner.close(DefaultNormalizationRunner.java:162) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:49) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] 2022-07-29 13:52:53 INFO i.a.v.j.JsonSchemaValidator(test):71 - JSON schema validation failed. errors: $.ssl_method: must be a constant value unencrypted, $.ssl_method: does not have a value in the enumeration [unencrypted] 2022-07-29 13:52:53 INFO i.a.w.t.TemporalAttemptExecution(get):105 - Docker volume job log path: /tmp/workspace/30/1/logs.log 2022-07-29 13:52:53 INFO i.a.w.t.TemporalAttemptExecution(get):110 - Executing worker wrapper. Airbyte version: 0.39.41-alpha 2022-07-29 13:52:53 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/source-file:0.2.14 exists... 2022-07-29 13:52:53 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/source-file:0.2.14 was found locally. 2022-07-29 13:52:53 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:52:53 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/1 --log-driver none --name source-file-check-30-1-muhlg --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e USE_STREAM_CAPABLE_STATE=true -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e WORKER_JOB_ATTEMPT=1 -e WORKER_CONNECTOR_IMAGE=airbyte/source-file:0.2.14 -e AIRBYTE_VERSION=0.39.41-alpha -e WORKER_JOB_ID=30 airbyte/source-file:0.2.14 check --config source_config.json 2022-07-29 13:52:55 INFO i.a.w.i.DefaultAirbyteStreamFactory(internalLog):99 - Checking access to https://opendata.dwd.de/climate_environment/CDC/regional_averages_DE/annual/sunshine_duration/regional_averages_sd_year.txt... 2022-07-29 13:52:55 INFO i.a.w.i.DefaultAirbyteStreamFactory(internalLog):99 - TransportParams: None 2022-07-29 13:52:55 INFO i.a.w.i.DefaultAirbyteStreamFactory(internalLog):99 - Check succeeded 2022-07-29 13:52:56 INFO i.a.w.t.TemporalAttemptExecution(get):131 - Stopping cancellation check scheduling... 2022-07-29 13:52:56 INFO i.a.w.t.TemporalAttemptExecution(get):105 - Docker volume job log path: /tmp/workspace/30/1/logs.log 2022-07-29 13:52:56 INFO i.a.w.t.TemporalAttemptExecution(get):110 - Executing worker wrapper. Airbyte version: 0.39.41-alpha 2022-07-29 13:52:56 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/destination-mssql:0.1.20 exists... 2022-07-29 13:52:56 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/destination-mssql:0.1.20 was found locally. 2022-07-29 13:52:56 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:52:56 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/1 --log-driver none --name destination-mssql-check-30-1-awhzk --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e USE_STREAM_CAPABLE_STATE=true -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e WORKER_JOB_ATTEMPT=1 -e WORKER_CONNECTOR_IMAGE=airbyte/destination-mssql:0.1.20 -e AIRBYTE_VERSION=0.39.41-alpha -e WORKER_JOB_ID=30 airbyte/destination-mssql:0.1.20 check --config source_config.json 2022-07-29 13:52:56 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Class path contains multiple SLF4J bindings. 2022-07-29 13:52:56 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Found binding in [jar:file:/airbyte/lib/log4j-slf4j-impl-2.17.1.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:56 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Found binding in [jar:file:/airbyte/lib/slf4j-reload4j-1.7.36.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:56 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Found binding in [jar:file:/airbyte/lib/logback-classic-1.2.3.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:56 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Found binding in [jar:file:/airbyte/lib/slf4j-log4j12-1.7.25.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:56 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. 2022-07-29 13:52:56 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] 2022-07-29 13:52:57 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:57 INFO i.a.i.d.m.MSSQLDestination(main):104 - starting destination: class io.airbyte.integrations.destination.mssql.MSSQLDestination 2022-07-29 13:52:57 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:57 INFO i.a.i.b.IntegrationCliParser(parseOptions):118 - integration args: {check=null, config=source_config.json} 2022-07-29 13:52:57 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:57 INFO i.a.i.b.IntegrationRunner(runInternal):123 - Running integration: io.airbyte.integrations.base.ssh.SshWrappedDestination 2022-07-29 13:52:57 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:57 INFO i.a.i.b.IntegrationRunner(runInternal):124 - Command: CHECK 2022-07-29 13:52:57 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:57 INFO i.a.i.b.IntegrationRunner(runInternal):125 - Integration config: IntegrationConfig{command=CHECK, configPath='source_config.json', catalogPath='null', statePath='null'} 2022-07-29 13:52:57 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:57 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword order - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:52:57 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:57 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword examples - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:52:57 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:57 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword airbyte_secret - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:52:57 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:57 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword multiline - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:52:57 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:57 INFO i.a.i.b.s.SshTunnel(getInstance):170 - Starting connection with method: NO_TUNNEL 2022-07-29 13:52:58 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:58 INFO c.z.h.HikariDataSource():80 - HikariPool-1 - Starting... 2022-07-29 13:52:58 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:58 INFO c.z.h.HikariDataSource():82 - HikariPool-1 - Start completed. 2022-07-29 13:52:58 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:58 INFO c.z.h.HikariDataSource(close):350 - HikariPool-1 - Shutdown initiated... 2022-07-29 13:52:58 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:58 INFO c.z.h.HikariDataSource(close):352 - HikariPool-1 - Shutdown completed. 2022-07-29 13:52:58 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:58 INFO i.a.i.b.IntegrationRunner(runInternal):171 - Completed integration: io.airbyte.integrations.base.ssh.SshWrappedDestination 2022-07-29 13:52:58 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:52:58 INFO i.a.i.d.m.MSSQLDestination(main):106 - completed destination: class io.airbyte.integrations.destination.mssql.MSSQLDestination 2022-07-29 13:52:58 INFO i.a.w.t.TemporalAttemptExecution(get):131 - Stopping cancellation check scheduling... 2022-07-29 13:52:58 INFO i.a.w.t.TemporalAttemptExecution(get):105 - Docker volume job log path: /tmp/workspace/30/1/logs.log 2022-07-29 13:52:58 INFO i.a.w.t.TemporalAttemptExecution(get):110 - Executing worker wrapper. Airbyte version: 0.39.41-alpha 2022-07-29 13:52:58 INFO i.a.w.g.DefaultReplicationWorker(run):116 - start sync worker. job id: 30 attempt id: 1 2022-07-29 13:52:58 INFO i.a.w.g.DefaultReplicationWorker(run):128 - configured sync modes: {null.DWD_DE_annual_sunshine-duration_regional-averages=full_refresh - overwrite} 2022-07-29 13:52:58 INFO i.a.w.i.DefaultAirbyteDestination(start):69 - Running destination... 2022-07-29 13:52:58 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/destination-mssql:0.1.20 exists... 2022-07-29 13:52:58 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/destination-mssql:0.1.20 was found locally. 2022-07-29 13:52:58 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:52:58 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/1 --log-driver none --name destination-mssql-write-30-1-tjows --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e USE_STREAM_CAPABLE_STATE=true -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e WORKER_JOB_ATTEMPT=1 -e WORKER_CONNECTOR_IMAGE=airbyte/destination-mssql:0.1.20 -e AIRBYTE_VERSION=0.39.41-alpha -e WORKER_JOB_ID=30 airbyte/destination-mssql:0.1.20 write --config destination_config.json --catalog destination_catalog.json 2022-07-29 13:52:58 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/source-file:0.2.14 exists... 2022-07-29 13:52:58 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/source-file:0.2.14 was found locally. 2022-07-29 13:52:58 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:52:58 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/1 --log-driver none --name source-file-read-30-1-yvyne --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e USE_STREAM_CAPABLE_STATE=true -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e WORKER_JOB_ATTEMPT=1 -e WORKER_CONNECTOR_IMAGE=airbyte/source-file:0.2.14 -e AIRBYTE_VERSION=0.39.41-alpha -e WORKER_JOB_ID=30 airbyte/source-file:0.2.14 read --config source_config.json --catalog source_catalog.json --state input_state.json 2022-07-29 13:52:58 INFO i.a.w.g.DefaultReplicationWorker(lambda$getDestinationOutputRunnable$7):406 - Destination output thread started. 2022-07-29 13:52:58 INFO i.a.w.g.DefaultReplicationWorker(run):170 - Waiting for source and destination threads to complete. 2022-07-29 13:52:58 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):299 - Replication thread started. 2022-07-29 13:52:59 destination > SLF4J: Class path contains multiple SLF4J bindings. 2022-07-29 13:52:59 destination > SLF4J: Found binding in [jar:file:/airbyte/lib/log4j-slf4j-impl-2.17.1.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:59 destination > SLF4J: Found binding in [jar:file:/airbyte/lib/slf4j-reload4j-1.7.36.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:59 destination > SLF4J: Found binding in [jar:file:/airbyte/lib/logback-classic-1.2.3.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:59 destination > SLF4J: Found binding in [jar:file:/airbyte/lib/slf4j-log4j12-1.7.25.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:52:59 destination > SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. 2022-07-29 13:52:59 destination > SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] 2022-07-29 13:53:00 destination > 2022-07-29 13:53:00 INFO i.a.i.d.m.MSSQLDestination(main):104 - starting destination: class io.airbyte.integrations.destination.mssql.MSSQLDestination 2022-07-29 13:53:00 destination > 2022-07-29 13:53:00 INFO i.a.i.b.IntegrationCliParser(parseOptions):118 - integration args: {catalog=destination_catalog.json, write=null, config=destination_config.json} 2022-07-29 13:53:00 destination > 2022-07-29 13:53:00 INFO i.a.i.b.IntegrationRunner(runInternal):123 - Running integration: io.airbyte.integrations.base.ssh.SshWrappedDestination 2022-07-29 13:53:00 destination > 2022-07-29 13:53:00 INFO i.a.i.b.IntegrationRunner(runInternal):124 - Command: WRITE 2022-07-29 13:53:00 destination > 2022-07-29 13:53:00 INFO i.a.i.b.IntegrationRunner(runInternal):125 - Integration config: IntegrationConfig{command=WRITE, configPath='destination_config.json', catalogPath='destination_catalog.json', statePath='null'} 2022-07-29 13:53:00 destination > 2022-07-29 13:53:00 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword order - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:53:00 destination > 2022-07-29 13:53:00 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword examples - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:53:00 destination > 2022-07-29 13:53:00 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword airbyte_secret - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:53:00 destination > 2022-07-29 13:53:00 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword multiline - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:53:01 destination > 2022-07-29 13:53:01 INFO i.a.i.b.s.SshTunnel(getInstance):170 - Starting connection with method: NO_TUNNEL 2022-07-29 13:53:01 destination > 2022-07-29 13:53:01 INFO c.z.h.HikariDataSource():80 - HikariPool-1 - Starting... 2022-07-29 13:53:01 destination > 2022-07-29 13:53:01 INFO c.z.h.HikariDataSource():82 - HikariPool-1 - Start completed. 2022-07-29 13:53:01 destination > 2022-07-29 13:53:01 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$toWriteConfig$0):97 - Write config: WriteConfig{streamName=elt_DWD_DE_annual_sunshine-duration_regional-averages, namespace=null, outputSchemaName=AIRBYTE_ELT, tmpTableName=_airbyte_tmp_xwz_elt_DWD_DE_annual_sunshine_duration_regional_averages, outputTableName=_airbyte_raw_elt_DWD_DE_annual_sunshine_duration_regional_averages, syncMode=overwrite} 2022-07-29 13:53:01 destination > 2022-07-29 13:53:01 INFO i.a.i.d.b.BufferedStreamConsumer(startTracked):116 - class io.airbyte.integrations.destination.buffered_stream_consumer.BufferedStreamConsumer started. 2022-07-29 13:53:01 destination > 2022-07-29 13:53:01 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):122 - Preparing tmp tables in destination started for 1 streams 2022-07-29 13:53:01 destination > 2022-07-29 13:53:01 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):126 - Preparing tmp table in destination started for stream elt_DWD_DE_annual_sunshine-duration_regional-averages. schema: AIRBYTE_ELT, tmp table name: _airbyte_tmp_xwz_elt_DWD_DE_annual_sunshine_duration_regional_averages 2022-07-29 13:53:01 source > Reading DWD_DE_annual_sunshine-duration_regional-averages (https://opendata.dwd.de/climate_environment/CDC/regional_averages_DE/annual/sunshine_duration/regional_averages_sd_year.txt)... 2022-07-29 13:53:01 source > TransportParams: None 2022-07-29 13:53:01 source > /airbyte/integration_code/source_file/client.py:364: FutureWarning: Passing a set as an indexer is deprecated and will raise in a future version. Use a list instead. 2022-07-29 13:53:01 source > yield from df[columns].to_dict(orient="records") 2022-07-29 13:53:01 destination > 2022-07-29 13:53:01 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):132 - Preparing tables in destination completed. 2022-07-29 13:53:02 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):328 - Source has no more messages, closing connection. 2022-07-29 13:53:02 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):336 - Total records read: 71 (29 KB) 2022-07-29 13:53:02 INFO i.a.w.g.DefaultReplicationWorker(run):175 - One of source or destination thread complete. Waiting on the other. 2022-07-29 13:53:02 destination > 2022-07-29 13:53:02 INFO i.a.i.b.FailureTrackingAirbyteMessageConsumer(close):65 - Airbyte message consumer: succeeded. 2022-07-29 13:53:02 destination > 2022-07-29 13:53:02 INFO i.a.i.d.b.BufferedStreamConsumer(close):171 - executing on success close procedure. 2022-07-29 13:53:02 destination > 2022-07-29 13:53:02 INFO i.a.i.d.r.InMemoryRecordBufferingStrategy(lambda$flushAll$1):86 - Flushing elt_DWD_DE_annual_sunshine-duration_regional-averages: 71 records (119 KB) 2022-07-29 13:53:02 destination > 2022-07-29 13:53:02 INFO i.a.i.d.j.SqlOperations(onDestinationCloseOperations):137 - No onDestinationCloseOperations required for this destination. 2022-07-29 13:53:02 destination > 2022-07-29 13:53:02 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):162 - Finalizing tables in destination started for 1 streams 2022-07-29 13:53:02 destination > 2022-07-29 13:53:02 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):167 - Finalizing stream elt_DWD_DE_annual_sunshine-duration_regional-averages. schema AIRBYTE_ELT, tmp table _airbyte_tmp_xwz_elt_DWD_DE_annual_sunshine_duration_regional_averages, final table _airbyte_raw_elt_DWD_DE_annual_sunshine_duration_regional_averages 2022-07-29 13:53:02 destination > 2022-07-29 13:53:02 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):180 - Executing finalization of tables. 2022-07-29 13:53:02 destination > 2022-07-29 13:53:02 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):182 - Finalizing tables in destination completed. 2022-07-29 13:53:02 destination > 2022-07-29 13:53:02 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):185 - Cleaning tmp tables in destination started for 1 streams 2022-07-29 13:53:02 destination > 2022-07-29 13:53:02 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):189 - Cleaning tmp table in destination started for stream elt_DWD_DE_annual_sunshine-duration_regional-averages. schema AIRBYTE_ELT, tmp table name: _airbyte_tmp_xwz_elt_DWD_DE_annual_sunshine_duration_regional_averages 2022-07-29 13:53:02 destination > 2022-07-29 13:53:02 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):194 - Cleaning tmp tables in destination completed. 2022-07-29 13:53:02 destination > 2022-07-29 13:53:02 INFO i.a.i.b.IntegrationRunner(runInternal):171 - Completed integration: io.airbyte.integrations.base.ssh.SshWrappedDestination 2022-07-29 13:53:02 destination > 2022-07-29 13:53:02 INFO i.a.i.d.m.MSSQLDestination(main):106 - completed destination: class io.airbyte.integrations.destination.mssql.MSSQLDestination 2022-07-29 13:53:02 INFO i.a.w.g.DefaultReplicationWorker(run):177 - Source and destination threads complete. 2022-07-29 13:53:02 INFO i.a.w.g.DefaultReplicationWorker(run):240 - sync summary: io.airbyte.config.ReplicationAttemptSummary@77c80259[status=completed,recordsSynced=71,bytesSynced=30672,startTime=1659102778816,endTime=1659102782314,totalStats=io.airbyte.config.SyncStats@3beaacbf[recordsEmitted=71,bytesEmitted=30672,stateMessagesEmitted=0,recordsCommitted=71],streamStats=[io.airbyte.config.StreamSyncStats@1a055ec6[streamName=elt_DWD_DE_annual_sunshine-duration_regional-averages,stats=io.airbyte.config.SyncStats@6dfe6984[recordsEmitted=71,bytesEmitted=30672,stateMessagesEmitted=,recordsCommitted=71]]]] 2022-07-29 13:53:02 INFO i.a.w.g.DefaultReplicationWorker(run):269 - Source did not output any state messages 2022-07-29 13:53:02 WARN i.a.w.g.DefaultReplicationWorker(run):277 - State capture: No new state, falling back on input state: io.airbyte.config.State@5a1364a8[state={}] 2022-07-29 13:53:02 INFO i.a.w.t.TemporalAttemptExecution(get):131 - Stopping cancellation check scheduling... 2022-07-29 13:53:02 INFO i.a.w.t.s.ReplicationActivityImpl(lambda$replicate$3):161 - sync summary: io.airbyte.config.StandardSyncOutput@4c223570[standardSyncSummary=io.airbyte.config.StandardSyncSummary@fbe97b3[status=completed,recordsSynced=71,bytesSynced=30672,startTime=1659102778816,endTime=1659102782314,totalStats=io.airbyte.config.SyncStats@3beaacbf[recordsEmitted=71,bytesEmitted=30672,stateMessagesEmitted=0,recordsCommitted=71],streamStats=[io.airbyte.config.StreamSyncStats@1a055ec6[streamName=elt_DWD_DE_annual_sunshine-duration_regional-averages,stats=io.airbyte.config.SyncStats@6dfe6984[recordsEmitted=71,bytesEmitted=30672,stateMessagesEmitted=,recordsCommitted=71]]]],normalizationSummary=,state=io.airbyte.config.State@5a1364a8[state={}],outputCatalog=io.airbyte.protocol.models.ConfiguredAirbyteCatalog@5a5f5e0b[streams=[io.airbyte.protocol.models.ConfiguredAirbyteStream@7a7617f2[stream=io.airbyte.protocol.models.AirbyteStream@27223479[name=elt_DWD_DE_annual_sunshine-duration_regional-averages,jsonSchema={"type":"object","$schema":"http://json-schema.org/draft-07/schema#","properties":{"Jahr":{"type":["number","null"]},"Bayern":{"type":["number","null"]},"Hessen":{"type":["number","null"]},"Sachsen":{"type":["number","null"]},"Saarland":{"type":["number","null"]},"Thueringen":{"type":["number","null"]},"Brandenburg":{"type":["number","null"]},"Deutschland":{"type":["number","null"]},"Niedersachsen":{"type":["number","null"]},"Sachsen-Anhalt":{"type":["number","null"]},"Rheinland-Pfalz":{"type":["number","null"]},"Baden-Wuerttemberg":{"type":["number","null"]},"Brandenburg/Berlin":{"type":["number","null"]},"Schleswig-Holstein":{"type":["number","null"]},"Nordrhein-Westfalen":{"type":["number","null"]},"Mecklenburg-Vorpommern":{"type":["number","null"]},"Thueringen/Sachsen-Anhalt":{"type":["number","null"]},"Niedersachsen/Hamburg/Bremen":{"type":["number","null"]}}},supportedSyncModes=[full_refresh],sourceDefinedCursor=,defaultCursorField=[],sourceDefinedPrimaryKey=[],namespace=,additionalProperties={}],syncMode=full_refresh,cursorField=[],destinationSyncMode=overwrite,primaryKey=[],additionalProperties={}]],additionalProperties={}],failures=[]] 2022-07-29 13:53:02 INFO i.a.w.t.TemporalUtils(withBackgroundHeartbeat):291 - Stopping temporal heartbeating... 2022-07-29 13:53:02 INFO i.a.w.t.TemporalAttemptExecution(get):105 - Docker volume job log path: /tmp/workspace/30/1/logs.log 2022-07-29 13:53:02 INFO i.a.w.t.TemporalAttemptExecution(get):110 - Executing worker wrapper. Airbyte version: 0.39.41-alpha 2022-07-29 13:53:02 INFO i.a.w.g.DefaultNormalizationWorker(run):50 - Running normalization. 2022-07-29 13:53:02 INFO i.a.w.n.DefaultNormalizationRunner(runProcess):122 - Running with normalization version: airbyte/normalization-mssql:0.2.12 2022-07-29 13:53:02 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/normalization-mssql:0.2.12 exists... 2022-07-29 13:53:02 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/normalization-mssql:0.2.12 was found locally. 2022-07-29 13:53:02 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:53:02 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/1/normalize --log-driver none --name normalization-mssql-normalize-30-1-gnmdb --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e AIRBYTE_VERSION=0.39.41-alpha airbyte/normalization-mssql:0.2.12 run --integration-type mssql --config destination_config.json --catalog destination_catalog.json 2022-07-29 13:53:02 normalization > Running: transform-config --config destination_config.json --integration-type mssql --out /data/30/1/normalize 2022-07-29 13:53:03 normalization > Namespace(config='destination_config.json', integration_type=, out='/data/30/1/normalize') 2022-07-29 13:53:03 normalization > transform_mssql 2022-07-29 13:53:03 normalization > Running: transform-catalog --integration-type mssql --profile-config-dir /data/30/1/normalize --catalog destination_catalog.json --out /data/30/1/normalize/models/generated/ --json-column _airbyte_data 2022-07-29 13:53:03 normalization > Processing destination_catalog.json... 2022-07-29 13:53:03 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:03 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:03 normalization > Generating airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab1.sql from elt_DWD_DE_annual_sunshine-duration_regional-averages 2022-07-29 13:53:03 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:03 normalization > Generating airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab2.sql from elt_DWD_DE_annual_sunshine-duration_regional-averages 2022-07-29 13:53:03 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:03 normalization > Truncating _airbyte_elt_dwd_de_annual_sun__ion_regional_averages_hashid (#60) to _airbyte_elt_dwd_de_a_ional_averages_hashid (#44) 2022-07-29 13:53:03 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:03 normalization > Generating airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab3.sql from elt_DWD_DE_annual_sunshine-duration_regional-averages 2022-07-29 13:53:03 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:03 normalization > Truncating _airbyte_elt_dwd_de_annual_sun__ion_regional_averages_hashid (#60) to _airbyte_elt_dwd_de_a_ional_averages_hashid (#44) 2022-07-29 13:53:03 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:03 normalization > Generating airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql from elt_DWD_DE_annual_sunshine-duration_regional-averages 2022-07-29 13:53:03 normalization > detected no config file for ssh, assuming ssh is off. 2022-07-29 13:53:06 normalization > [--event-buffer-size EVENT_BUFFER_SIZE] 2022-07-29 13:53:06 normalization > --event-buffer-size EVENT_BUFFER_SIZE 2022-07-29 13:53:06 normalization > 2022-07-29 13:53:06 normalization > DBT >=1.0.0 detected; using 10K event buffer size 2022-07-29 13:53:06 normalization > 2022-07-29 13:53:09 normalization > 13:53:09 Running with dbt=1.0.0 2022-07-29 13:53:09 normalization > 13:53:09 Partial parse save file not found. Starting full parse. 2022-07-29 13:53:11 normalization > 13:53:11 [WARNING]: Configuration paths exist in your dbt_project.yml file which do not apply to any resources. 2022-07-29 13:53:11 normalization > There are 2 unused configuration paths: 2022-07-29 13:53:11 normalization > - models.airbyte_utils.generated.airbyte_views 2022-07-29 13:53:11 normalization > - models.airbyte_utils.generated.airbyte_incremental 2022-07-29 13:53:11 normalization > 2022-07-29 13:53:11 normalization > 13:53:11 Found 4 models, 0 tests, 0 snapshots, 0 analyses, 580 macros, 0 operations, 0 seed files, 1 source, 0 exposures, 0 metrics 2022-07-29 13:53:11 normalization > 13:53:11 2022-07-29 13:53:11 normalization > 13:53:11 Concurrency: 8 threads (target='prod') 2022-07-29 13:53:11 normalization > 13:53:11 2022-07-29 13:53:11 normalization > 13:53:11 1 of 1 START table model airbyte_elt.elt_dwd_de_annual_sun__ion_regional_averages....................................... [RUN] 2022-07-29 13:53:11 normalization > 13:53:11 1 of 1 ERROR creating table model airbyte_elt.elt_dwd_de_annual_sun__ion_regional_averages.............................. [ERROR in 0.04s] 2022-07-29 13:53:11 normalization > 13:53:11 2022-07-29 13:53:11 normalization > 13:53:11 Finished running 1 table model in 0.32s. 2022-07-29 13:53:11 normalization > 13:53:11 2022-07-29 13:53:11 normalization > 13:53:11 Completed with 1 error and 0 warnings: 2022-07-29 13:53:11 normalization > 13:53:11 2022-07-29 13:53:11 normalization > 13:53:11 Compilation Error in model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:53:11 normalization > 13:53:11 When searching for a relation, dbt found an approximate match. Instead of guessing 2022-07-29 13:53:11 normalization > 13:53:11 which relation to use, dbt will move on. Please delete "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages", or rename it to be less ambiguous. 2022-07-29 13:53:11 normalization > 13:53:11 Searched for: "AIRBYTE_ELT"."airbyte_elt"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:11 normalization > 13:53:11 Found: "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:11 normalization > 13:53:11 2022-07-29 13:53:11 normalization > 13:53:11 > in macro materialization_table_default (macros/materializations/models/table/table.sql) 2022-07-29 13:53:11 normalization > 13:53:11 > called by model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:53:11 normalization > 13:53:11 2022-07-29 13:53:11 normalization > 13:53:11 Done. PASS=0 WARN=0 ERROR=1 SKIP=0 TOTAL=1 2022-07-29 13:53:11 normalization > 2022-07-29 13:53:11 normalization > Diagnosing dbt debug to check if destination is available for dbt and well configured (1): 2022-07-29 13:53:11 normalization > 2022-07-29 13:53:14 normalization > 13:53:14 Running with dbt=1.0.0 2022-07-29 13:53:14 normalization > dbt version: 1.0.0 2022-07-29 13:53:14 normalization > python version: 3.9.9 2022-07-29 13:53:14 normalization > python path: /usr/local/bin/python 2022-07-29 13:53:14 normalization > os info: Linux-4.18.0-372.16.1.el8_6.x86_64-x86_64-with-glibc2.31 2022-07-29 13:53:14 normalization > Using profiles.yml file at /data/30/1/normalize/profiles.yml 2022-07-29 13:53:14 normalization > Using dbt_project.yml file at /data/30/1/normalize/dbt_project.yml 2022-07-29 13:53:14 normalization > 2022-07-29 13:53:14 normalization > Configuration: 2022-07-29 13:53:14 normalization > profiles.yml file [OK found and valid] 2022-07-29 13:53:14 normalization > dbt_project.yml file [OK found and valid] 2022-07-29 13:53:14 normalization > 2022-07-29 13:53:14 normalization > Required dependencies: 2022-07-29 13:53:14 normalization > - git [OK found] 2022-07-29 13:53:14 normalization > 2022-07-29 13:53:14 normalization > Connection: 2022-07-29 13:53:14 normalization > server: 172.18.105.119 2022-07-29 13:53:14 normalization > database: AIRBYTE_ELT 2022-07-29 13:53:14 normalization > schema: AIRBYTE_ELT 2022-07-29 13:53:14 normalization > port: 1433 2022-07-29 13:53:14 normalization > UID: AIRBYTE_ELT 2022-07-29 13:53:14 normalization > client_id: None 2022-07-29 13:53:14 normalization > authentication: sql 2022-07-29 13:53:14 normalization > encrypt: False 2022-07-29 13:53:14 normalization > trust_cert: False 2022-07-29 13:53:14 normalization > Connection test: [OK connection ok] 2022-07-29 13:53:14 normalization > 2022-07-29 13:53:14 normalization > All checks passed! 2022-07-29 13:53:14 normalization > 2022-07-29 13:53:14 normalization > Forward dbt output logs to diagnose/debug errors (0): 2022-07-29 13:53:14 normalization > 2022-07-29 13:53:14 normalization > 2022-07-29 13:53:14 normalization > 2022-07-29 13:53:14 normalization > ============================== 2022-07-29 13:53:09.282202 | 27a9836c-c9ba-4238-b3bb-808e5a214a90 ============================== 2022-07-29 13:53:14 normalization > 13:53:09.282202 [info ] [MainThread]: Running with dbt=1.0.0 2022-07-29 13:53:14 normalization > 13:53:09.282789 [debug] [MainThread]: running dbt with arguments Namespace(record_timing_info=None, debug=None, log_format=None, write_json=None, use_colors=None, printer_width=None, warn_error=None, version_check=None, partial_parse=None, single_threaded=False, use_experimental_parser=None, static_parser=None, profiles_dir='/data/30/1/normalize', send_anonymous_usage_stats=None, fail_fast=None, event_buffer_size='10000', project_dir='/data/30/1/normalize', profile=None, target=None, vars='{}', log_cache_events=False, threads=None, select=None, exclude=None, selector_name=None, state=None, defer=None, full_refresh=False, cls=, which='run', rpc_method='run') 2022-07-29 13:53:14 normalization > 13:53:09.283030 [debug] [MainThread]: Tracking: do not track 2022-07-29 13:53:14 normalization > 13:53:09.308280 [info ] [MainThread]: Partial parse save file not found. Starting full parse. 2022-07-29 13:53:14 normalization > 13:53:09.343921 [debug] [MainThread]: Parsing macros/clean_tmp_tables.sql 2022-07-29 13:53:14 normalization > 13:53:09.347763 [debug] [MainThread]: Parsing macros/configuration.sql 2022-07-29 13:53:14 normalization > 13:53:09.350663 [debug] [MainThread]: Parsing macros/get_custom_schema.sql 2022-07-29 13:53:14 normalization > 13:53:09.351458 [debug] [MainThread]: Parsing macros/incremental.sql 2022-07-29 13:53:14 normalization > 13:53:09.361458 [debug] [MainThread]: Parsing macros/should_full_refresh.sql 2022-07-29 13:53:14 normalization > 13:53:09.368276 [debug] [MainThread]: Parsing macros/star_intersect.sql 2022-07-29 13:53:14 normalization > 13:53:09.376996 [debug] [MainThread]: Parsing macros/cross_db_utils/array.sql 2022-07-29 13:53:14 normalization > 13:53:09.435048 [debug] [MainThread]: Parsing macros/cross_db_utils/columns.sql 2022-07-29 13:53:14 normalization > 13:53:09.438943 [debug] [MainThread]: Parsing macros/cross_db_utils/concat.sql 2022-07-29 13:53:14 normalization > 13:53:09.442372 [debug] [MainThread]: Parsing macros/cross_db_utils/current_timestamp.sql 2022-07-29 13:53:14 normalization > 13:53:09.443176 [debug] [MainThread]: Parsing macros/cross_db_utils/datatypes.sql 2022-07-29 13:53:14 normalization > 13:53:09.466525 [debug] [MainThread]: Parsing macros/cross_db_utils/except.sql 2022-07-29 13:53:14 normalization > 13:53:09.467615 [debug] [MainThread]: Parsing macros/cross_db_utils/hash.sql 2022-07-29 13:53:14 normalization > 13:53:09.468395 [debug] [MainThread]: Parsing macros/cross_db_utils/json_operations.sql 2022-07-29 13:53:14 normalization > 13:53:09.519140 [debug] [MainThread]: Parsing macros/cross_db_utils/quote.sql 2022-07-29 13:53:14 normalization > 13:53:09.521318 [debug] [MainThread]: Parsing macros/cross_db_utils/surrogate_key.sql 2022-07-29 13:53:14 normalization > 13:53:09.523987 [debug] [MainThread]: Parsing macros/cross_db_utils/type_conversions.sql 2022-07-29 13:53:14 normalization > 13:53:09.535510 [debug] [MainThread]: Parsing macros/schema_tests/equal_rowcount.sql 2022-07-29 13:53:14 normalization > 13:53:09.537134 [debug] [MainThread]: Parsing macros/schema_tests/equality.sql 2022-07-29 13:53:14 normalization > 13:53:09.545840 [debug] [MainThread]: Parsing macros/adapters.sql 2022-07-29 13:53:14 normalization > 13:53:09.546053 [debug] [MainThread]: Parsing macros/adapters/columns.sql 2022-07-29 13:53:14 normalization > 13:53:09.552468 [debug] [MainThread]: Parsing macros/adapters/freshness.sql 2022-07-29 13:53:14 normalization > 13:53:09.552973 [debug] [MainThread]: Parsing macros/adapters/indexes.sql 2022-07-29 13:53:14 normalization > 13:53:09.565068 [debug] [MainThread]: Parsing macros/adapters/metadata.sql 2022-07-29 13:53:14 normalization > 13:53:09.571202 [debug] [MainThread]: Parsing macros/adapters/persist_docs.sql 2022-07-29 13:53:14 normalization > 13:53:09.571406 [debug] [MainThread]: Parsing macros/adapters/relation.sql 2022-07-29 13:53:14 normalization > 13:53:09.579006 [debug] [MainThread]: Parsing macros/adapters/schema.sql 2022-07-29 13:53:14 normalization > 13:53:09.583297 [debug] [MainThread]: Parsing macros/materializations/models/incremental/merge.sql 2022-07-29 13:53:14 normalization > 13:53:09.585958 [debug] [MainThread]: Parsing macros/materializations/models/table/create_table_as.sql 2022-07-29 13:53:14 normalization > 13:53:09.589708 [debug] [MainThread]: Parsing macros/materializations/models/view/create_view_as.sql 2022-07-29 13:53:14 normalization > 13:53:09.591617 [debug] [MainThread]: Parsing macros/materializations/seeds/helpers.sql 2022-07-29 13:53:14 normalization > 13:53:09.601696 [debug] [MainThread]: Parsing macros/materializations/snapshots/snapshot.sql 2022-07-29 13:53:14 normalization > 13:53:09.603677 [debug] [MainThread]: Parsing macros/materializations/snapshots/snapshot_merge.sql 2022-07-29 13:53:14 normalization > 13:53:09.604519 [debug] [MainThread]: Parsing macros/materializations/snapshots/strategies.sql 2022-07-29 13:53:14 normalization > 13:53:09.606424 [debug] [MainThread]: Parsing macros/materializations/tests/helpers.sql 2022-07-29 13:53:14 normalization > 13:53:09.608063 [debug] [MainThread]: Parsing macros/materializations/tests/test.sql 2022-07-29 13:53:14 normalization > 13:53:09.615297 [debug] [MainThread]: Parsing macros/adapters/columns.sql 2022-07-29 13:53:14 normalization > 13:53:09.629227 [debug] [MainThread]: Parsing macros/adapters/freshness.sql 2022-07-29 13:53:14 normalization > 13:53:09.633397 [debug] [MainThread]: Parsing macros/adapters/indexes.sql 2022-07-29 13:53:14 normalization > 13:53:09.637270 [debug] [MainThread]: Parsing macros/adapters/metadata.sql 2022-07-29 13:53:14 normalization > 13:53:09.647334 [debug] [MainThread]: Parsing macros/adapters/persist_docs.sql 2022-07-29 13:53:14 normalization > 13:53:09.653744 [debug] [MainThread]: Parsing macros/adapters/relation.sql 2022-07-29 13:53:14 normalization > 13:53:09.667619 [debug] [MainThread]: Parsing macros/adapters/schema.sql 2022-07-29 13:53:14 normalization > 13:53:09.670712 [debug] [MainThread]: Parsing macros/etc/datetime.sql 2022-07-29 13:53:14 normalization > 13:53:09.682674 [debug] [MainThread]: Parsing macros/etc/statement.sql 2022-07-29 13:53:14 normalization > 13:53:09.689198 [debug] [MainThread]: Parsing macros/generic_test_sql/accepted_values.sql 2022-07-29 13:53:14 normalization > 13:53:09.691177 [debug] [MainThread]: Parsing macros/generic_test_sql/not_null.sql 2022-07-29 13:53:14 normalization > 13:53:09.691929 [debug] [MainThread]: Parsing macros/generic_test_sql/relationships.sql 2022-07-29 13:53:14 normalization > 13:53:09.693117 [debug] [MainThread]: Parsing macros/generic_test_sql/unique.sql 2022-07-29 13:53:14 normalization > 13:53:09.694071 [debug] [MainThread]: Parsing macros/get_custom_name/get_custom_alias.sql 2022-07-29 13:53:14 normalization > 13:53:09.696164 [debug] [MainThread]: Parsing macros/get_custom_name/get_custom_database.sql 2022-07-29 13:53:14 normalization > 13:53:09.698371 [debug] [MainThread]: Parsing macros/get_custom_name/get_custom_schema.sql 2022-07-29 13:53:14 normalization > 13:53:09.701910 [debug] [MainThread]: Parsing macros/materializations/configs.sql 2022-07-29 13:53:14 normalization > 13:53:09.705403 [debug] [MainThread]: Parsing macros/materializations/hooks.sql 2022-07-29 13:53:14 normalization > 13:53:09.710964 [debug] [MainThread]: Parsing macros/materializations/models/incremental/column_helpers.sql 2022-07-29 13:53:14 normalization > 13:53:09.717510 [debug] [MainThread]: Parsing macros/materializations/models/incremental/incremental.sql 2022-07-29 13:53:14 normalization > 13:53:09.732310 [debug] [MainThread]: Parsing macros/materializations/models/incremental/is_incremental.sql 2022-07-29 13:53:14 normalization > 13:53:09.734448 [debug] [MainThread]: Parsing macros/materializations/models/incremental/merge.sql 2022-07-29 13:53:14 normalization > 13:53:09.749969 [debug] [MainThread]: Parsing macros/materializations/models/incremental/on_schema_change.sql 2022-07-29 13:53:14 normalization > 13:53:09.770970 [debug] [MainThread]: Parsing macros/materializations/models/table/create_table_as.sql 2022-07-29 13:53:14 normalization > 13:53:09.775176 [debug] [MainThread]: Parsing macros/materializations/models/table/table.sql 2022-07-29 13:53:14 normalization > 13:53:09.785332 [debug] [MainThread]: Parsing macros/materializations/models/view/create_or_replace_view.sql 2022-07-29 13:53:14 normalization > 13:53:09.789255 [debug] [MainThread]: Parsing macros/materializations/models/view/create_view_as.sql 2022-07-29 13:53:14 normalization > 13:53:09.792591 [debug] [MainThread]: Parsing macros/materializations/models/view/helpers.sql 2022-07-29 13:53:14 normalization > 13:53:09.794430 [debug] [MainThread]: Parsing macros/materializations/models/view/view.sql 2022-07-29 13:53:14 normalization > 13:53:09.804328 [debug] [MainThread]: Parsing macros/materializations/seeds/helpers.sql 2022-07-29 13:53:14 normalization > 13:53:09.826662 [debug] [MainThread]: Parsing macros/materializations/seeds/seed.sql 2022-07-29 13:53:14 normalization > 13:53:09.835815 [debug] [MainThread]: Parsing macros/materializations/snapshots/helpers.sql 2022-07-29 13:53:14 normalization > 13:53:09.850978 [debug] [MainThread]: Parsing macros/materializations/snapshots/snapshot.sql 2022-07-29 13:53:14 normalization > 13:53:09.867192 [debug] [MainThread]: Parsing macros/materializations/snapshots/snapshot_merge.sql 2022-07-29 13:53:14 normalization > 13:53:09.869468 [debug] [MainThread]: Parsing macros/materializations/snapshots/strategies.sql 2022-07-29 13:53:14 normalization > 13:53:09.894213 [debug] [MainThread]: Parsing macros/materializations/tests/helpers.sql 2022-07-29 13:53:14 normalization > 13:53:09.896762 [debug] [MainThread]: Parsing macros/materializations/tests/test.sql 2022-07-29 13:53:14 normalization > 13:53:09.903323 [debug] [MainThread]: Parsing macros/materializations/tests/where_subquery.sql 2022-07-29 13:53:14 normalization > 13:53:09.905928 [debug] [MainThread]: Parsing tests/generic/builtin.sql 2022-07-29 13:53:14 normalization > 13:53:09.910262 [debug] [MainThread]: Parsing macros/cross_db_utils/_is_ephemeral.sql 2022-07-29 13:53:14 normalization > 13:53:09.913004 [debug] [MainThread]: Parsing macros/cross_db_utils/_is_relation.sql 2022-07-29 13:53:14 normalization > 13:53:09.914625 [debug] [MainThread]: Parsing macros/cross_db_utils/any_value.sql 2022-07-29 13:53:14 normalization > 13:53:09.916443 [debug] [MainThread]: Parsing macros/cross_db_utils/bool_or.sql 2022-07-29 13:53:14 normalization > 13:53:09.918653 [debug] [MainThread]: Parsing macros/cross_db_utils/cast_bool_to_text.sql 2022-07-29 13:53:14 normalization > 13:53:09.920793 [debug] [MainThread]: Parsing macros/cross_db_utils/concat.sql 2022-07-29 13:53:14 normalization > 13:53:09.922165 [debug] [MainThread]: Parsing macros/cross_db_utils/current_timestamp.sql 2022-07-29 13:53:14 normalization > 13:53:09.927010 [debug] [MainThread]: Parsing macros/cross_db_utils/datatypes.sql 2022-07-29 13:53:14 normalization > 13:53:09.935787 [debug] [MainThread]: Parsing macros/cross_db_utils/date_trunc.sql 2022-07-29 13:53:14 normalization > 13:53:09.937904 [debug] [MainThread]: Parsing macros/cross_db_utils/dateadd.sql 2022-07-29 13:53:14 normalization > 13:53:09.942003 [debug] [MainThread]: Parsing macros/cross_db_utils/datediff.sql 2022-07-29 13:53:14 normalization > 13:53:09.955584 [debug] [MainThread]: Parsing macros/cross_db_utils/escape_single_quotes.sql 2022-07-29 13:53:14 normalization > 13:53:09.958210 [debug] [MainThread]: Parsing macros/cross_db_utils/except.sql 2022-07-29 13:53:14 normalization > 13:53:09.959664 [debug] [MainThread]: Parsing macros/cross_db_utils/hash.sql 2022-07-29 13:53:14 normalization > 13:53:09.961642 [debug] [MainThread]: Parsing macros/cross_db_utils/identifier.sql 2022-07-29 13:53:14 normalization > 13:53:09.963998 [debug] [MainThread]: Parsing macros/cross_db_utils/intersect.sql 2022-07-29 13:53:14 normalization > 13:53:09.965452 [debug] [MainThread]: Parsing macros/cross_db_utils/last_day.sql 2022-07-29 13:53:14 normalization > 13:53:09.970851 [debug] [MainThread]: Parsing macros/cross_db_utils/length.sql 2022-07-29 13:53:14 normalization > 13:53:09.972632 [debug] [MainThread]: Parsing macros/cross_db_utils/literal.sql 2022-07-29 13:53:14 normalization > 13:53:09.973958 [debug] [MainThread]: Parsing macros/cross_db_utils/position.sql 2022-07-29 13:53:14 normalization > 13:53:09.976132 [debug] [MainThread]: Parsing macros/cross_db_utils/replace.sql 2022-07-29 13:53:14 normalization > 13:53:09.977915 [debug] [MainThread]: Parsing macros/cross_db_utils/right.sql 2022-07-29 13:53:14 normalization > 13:53:09.981066 [debug] [MainThread]: Parsing macros/cross_db_utils/safe_cast.sql 2022-07-29 13:53:14 normalization > 13:53:09.984024 [debug] [MainThread]: Parsing macros/cross_db_utils/split_part.sql 2022-07-29 13:53:14 normalization > 13:53:09.986683 [debug] [MainThread]: Parsing macros/cross_db_utils/width_bucket.sql 2022-07-29 13:53:14 normalization > 13:53:09.994854 [debug] [MainThread]: Parsing macros/jinja_helpers/log_info.sql 2022-07-29 13:53:14 normalization > 13:53:09.997514 [debug] [MainThread]: Parsing macros/jinja_helpers/pretty_log_format.sql 2022-07-29 13:53:14 normalization > 13:53:09.999375 [debug] [MainThread]: Parsing macros/jinja_helpers/pretty_time.sql 2022-07-29 13:53:14 normalization > 13:53:10.001240 [debug] [MainThread]: Parsing macros/jinja_helpers/slugify.sql 2022-07-29 13:53:14 normalization > 13:53:10.003493 [debug] [MainThread]: Parsing macros/materializations/insert_by_period_materialization.sql 2022-07-29 13:53:14 normalization > 13:53:10.044630 [debug] [MainThread]: Parsing macros/schema_tests/accepted_range.sql 2022-07-29 13:53:14 normalization > 13:53:10.049181 [debug] [MainThread]: Parsing macros/schema_tests/at_least_one.sql 2022-07-29 13:53:14 normalization > 13:53:10.051747 [debug] [MainThread]: Parsing macros/schema_tests/cardinality_equality.sql 2022-07-29 13:53:14 normalization > 13:53:10.055342 [debug] [MainThread]: Parsing macros/schema_tests/equal_rowcount.sql 2022-07-29 13:53:14 normalization > 13:53:10.057709 [debug] [MainThread]: Parsing macros/schema_tests/equality.sql 2022-07-29 13:53:14 normalization > 13:53:10.063633 [debug] [MainThread]: Parsing macros/schema_tests/expression_is_true.sql 2022-07-29 13:53:14 normalization > 13:53:10.067356 [debug] [MainThread]: Parsing macros/schema_tests/fewer_rows_than.sql 2022-07-29 13:53:14 normalization > 13:53:10.069841 [debug] [MainThread]: Parsing macros/schema_tests/mutually_exclusive_ranges.sql 2022-07-29 13:53:14 normalization > 13:53:10.084018 [debug] [MainThread]: Parsing macros/schema_tests/not_accepted_values.sql 2022-07-29 13:53:14 normalization > 13:53:10.087177 [debug] [MainThread]: Parsing macros/schema_tests/not_constant.sql 2022-07-29 13:53:14 normalization > 13:53:10.088978 [debug] [MainThread]: Parsing macros/schema_tests/not_null_proportion.sql 2022-07-29 13:53:14 normalization > 13:53:10.092171 [debug] [MainThread]: Parsing macros/schema_tests/recency.sql 2022-07-29 13:53:14 normalization > 13:53:10.094706 [debug] [MainThread]: Parsing macros/schema_tests/relationships_where.sql 2022-07-29 13:53:14 normalization > 13:53:10.097889 [debug] [MainThread]: Parsing macros/schema_tests/sequential_values.sql 2022-07-29 13:53:14 normalization > 13:53:10.102294 [debug] [MainThread]: Parsing macros/schema_tests/test_not_null_where.sql 2022-07-29 13:53:14 normalization > 13:53:10.104546 [debug] [MainThread]: Parsing macros/schema_tests/test_unique_where.sql 2022-07-29 13:53:14 normalization > 13:53:10.106707 [debug] [MainThread]: Parsing macros/schema_tests/unique_combination_of_columns.sql 2022-07-29 13:53:14 normalization > 13:53:10.111305 [debug] [MainThread]: Parsing macros/sql/date_spine.sql 2022-07-29 13:53:14 normalization > 13:53:10.123557 [debug] [MainThread]: Parsing macros/sql/generate_series.sql 2022-07-29 13:53:14 normalization > 13:53:10.131081 [debug] [MainThread]: Parsing macros/sql/get_column_values.sql 2022-07-29 13:53:14 normalization > 13:53:10.141686 [debug] [MainThread]: Parsing macros/sql/get_query_results_as_dict.sql 2022-07-29 13:53:14 normalization > 13:53:10.147325 [debug] [MainThread]: Parsing macros/sql/get_relations_by_pattern.sql 2022-07-29 13:53:14 normalization > 13:53:10.153130 [debug] [MainThread]: Parsing macros/sql/get_relations_by_prefix.sql 2022-07-29 13:53:14 normalization > 13:53:10.159088 [debug] [MainThread]: Parsing macros/sql/get_table_types_sql.sql 2022-07-29 13:53:14 normalization > 13:53:10.160835 [debug] [MainThread]: Parsing macros/sql/get_tables_by_pattern_sql.sql 2022-07-29 13:53:14 normalization > 13:53:10.173093 [debug] [MainThread]: Parsing macros/sql/get_tables_by_prefix_sql.sql 2022-07-29 13:53:14 normalization > 13:53:10.175743 [debug] [MainThread]: Parsing macros/sql/groupby.sql 2022-07-29 13:53:14 normalization > 13:53:10.178588 [debug] [MainThread]: Parsing macros/sql/haversine_distance.sql 2022-07-29 13:53:14 normalization > 13:53:10.188132 [debug] [MainThread]: Parsing macros/sql/nullcheck.sql 2022-07-29 13:53:14 normalization > 13:53:10.190786 [debug] [MainThread]: Parsing macros/sql/nullcheck_table.sql 2022-07-29 13:53:14 normalization > 13:53:10.193521 [debug] [MainThread]: Parsing macros/sql/pivot.sql 2022-07-29 13:53:14 normalization > 13:53:10.200027 [debug] [MainThread]: Parsing macros/sql/safe_add.sql 2022-07-29 13:53:14 normalization > 13:53:10.202697 [debug] [MainThread]: Parsing macros/sql/star.sql 2022-07-29 13:53:14 normalization > 13:53:10.209868 [debug] [MainThread]: Parsing macros/sql/surrogate_key.sql 2022-07-29 13:53:14 normalization > 13:53:10.215591 [debug] [MainThread]: Parsing macros/sql/union.sql 2022-07-29 13:53:14 normalization > 13:53:10.231140 [debug] [MainThread]: Parsing macros/sql/unpivot.sql 2022-07-29 13:53:14 normalization > 13:53:10.242672 [debug] [MainThread]: Parsing macros/web/get_url_host.sql 2022-07-29 13:53:14 normalization > 13:53:10.245550 [debug] [MainThread]: Parsing macros/web/get_url_parameter.sql 2022-07-29 13:53:14 normalization > 13:53:10.248000 [debug] [MainThread]: Parsing macros/web/get_url_path.sql 2022-07-29 13:53:14 normalization > 13:53:10.889823 [debug] [MainThread]: 1603: static parser failed on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab1.sql 2022-07-29 13:53:14 normalization > 13:53:10.991517 [debug] [MainThread]: 1602: parser fallback to jinja rendering on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab1.sql 2022-07-29 13:53:14 normalization > 13:53:10.993827 [debug] [MainThread]: 1603: static parser failed on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab2.sql 2022-07-29 13:53:14 normalization > 13:53:11.019710 [debug] [MainThread]: 1602: parser fallback to jinja rendering on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab2.sql 2022-07-29 13:53:14 normalization > 13:53:11.021497 [debug] [MainThread]: 1603: static parser failed on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab3.sql 2022-07-29 13:53:14 normalization > 13:53:11.059048 [debug] [MainThread]: 1602: parser fallback to jinja rendering on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab3.sql 2022-07-29 13:53:14 normalization > 13:53:11.060807 [debug] [MainThread]: 1603: static parser failed on generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql 2022-07-29 13:53:14 normalization > 13:53:11.071613 [debug] [MainThread]: 1602: parser fallback to jinja rendering on generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql 2022-07-29 13:53:14 normalization > 13:53:11.161736 [warn ] [MainThread]: [WARNING]: Configuration paths exist in your dbt_project.yml file which do not apply to any resources. 2022-07-29 13:53:14 normalization > There are 2 unused configuration paths: 2022-07-29 13:53:14 normalization > - models.airbyte_utils.generated.airbyte_views 2022-07-29 13:53:14 normalization > - models.airbyte_utils.generated.airbyte_incremental 2022-07-29 13:53:14 normalization > 2022-07-29 13:53:14 normalization > 13:53:11.182370 [info ] [MainThread]: Found 4 models, 0 tests, 0 snapshots, 0 analyses, 580 macros, 0 operations, 0 seed files, 1 source, 0 exposures, 0 metrics 2022-07-29 13:53:14 normalization > 13:53:11.183862 [info ] [MainThread]: 2022-07-29 13:53:14 normalization > 13:53:11.184421 [debug] [MainThread]: Acquiring new sqlserver connection "master" 2022-07-29 13:53:14 normalization > 13:53:11.185348 [debug] [ThreadPool]: Acquiring new sqlserver connection "list_AIRBYTE_ELT" 2022-07-29 13:53:14 normalization > 13:53:11.238582 [debug] [ThreadPool]: Opening a new connection, currently in state init 2022-07-29 13:53:14 normalization > 13:53:11.259013 [debug] [ThreadPool]: On list_AIRBYTE_ELT: Close 2022-07-29 13:53:14 normalization > 13:53:11.260466 [debug] [ThreadPool]: Acquiring new sqlserver connection "list_AIRBYTE_ELT_airbyte_elt" 2022-07-29 13:53:14 normalization > 13:53:11.268699 [debug] [ThreadPool]: Opening a new connection, currently in state closed 2022-07-29 13:53:14 normalization > 13:53:11.283654 [debug] [ThreadPool]: On list_AIRBYTE_ELT_airbyte_elt: ROLLBACK 2022-07-29 13:53:14 normalization > 13:53:11.283975 [debug] [ThreadPool]: On list_AIRBYTE_ELT_airbyte_elt: Close 2022-07-29 13:53:14 normalization > 13:53:11.284983 [debug] [MainThread]: On master: COMMIT 2022-07-29 13:53:14 normalization > 13:53:11.285378 [info ] [MainThread]: Concurrency: 8 threads (target='prod') 2022-07-29 13:53:14 normalization > 13:53:11.285687 [info ] [MainThread]: 2022-07-29 13:53:14 normalization > 13:53:11.303657 [debug] [Thread-1 ]: Began running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1 2022-07-29 13:53:14 normalization > 13:53:11.304433 [debug] [Thread-1 ]: Acquiring new sqlserver connection "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1" 2022-07-29 13:53:14 normalization > 13:53:11.304606 [debug] [Thread-1 ]: Began compiling node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1 2022-07-29 13:53:14 normalization > 13:53:11.304793 [debug] [Thread-1 ]: Compiling model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1 2022-07-29 13:53:14 normalization > 13:53:11.334820 [debug] [Thread-1 ]: Writing injected SQL for node "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1" 2022-07-29 13:53:14 normalization > 13:53:11.335446 [debug] [Thread-1 ]: finished collecting timing info 2022-07-29 13:53:14 normalization > 13:53:11.335913 [debug] [Thread-1 ]: Finished running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1 2022-07-29 13:53:14 normalization > 13:53:11.336408 [debug] [Thread-3 ]: Began running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2 2022-07-29 13:53:14 normalization > 13:53:11.336875 [debug] [Thread-3 ]: Acquiring new sqlserver connection "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2" 2022-07-29 13:53:14 normalization > 13:53:11.337051 [debug] [Thread-3 ]: Began compiling node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2 2022-07-29 13:53:14 normalization > 13:53:11.337203 [debug] [Thread-3 ]: Compiling model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2 2022-07-29 13:53:14 normalization > 13:53:11.391884 [debug] [Thread-3 ]: Writing injected SQL for node "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2" 2022-07-29 13:53:14 normalization > 13:53:11.392348 [debug] [Thread-3 ]: finished collecting timing info 2022-07-29 13:53:14 normalization > 13:53:11.392829 [debug] [Thread-3 ]: Finished running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2 2022-07-29 13:53:14 normalization > 13:53:11.393535 [debug] [Thread-5 ]: Began running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3 2022-07-29 13:53:14 normalization > 13:53:11.393958 [debug] [Thread-5 ]: Acquiring new sqlserver connection "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3" 2022-07-29 13:53:14 normalization > 13:53:11.394116 [debug] [Thread-5 ]: Began compiling node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3 2022-07-29 13:53:14 normalization > 13:53:11.394269 [debug] [Thread-5 ]: Compiling model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3 2022-07-29 13:53:14 normalization > 13:53:11.454525 [debug] [Thread-5 ]: Writing injected SQL for node "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3" 2022-07-29 13:53:14 normalization > 13:53:11.455059 [debug] [Thread-5 ]: finished collecting timing info 2022-07-29 13:53:14 normalization > 13:53:11.455534 [debug] [Thread-5 ]: Finished running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3 2022-07-29 13:53:14 normalization > 13:53:11.456289 [debug] [Thread-7 ]: Began running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:53:14 normalization > 13:53:11.456605 [info ] [Thread-7 ]: 1 of 1 START table model airbyte_elt.elt_dwd_de_annual_sun__ion_regional_averages....................................... [RUN] 2022-07-29 13:53:14 normalization > 13:53:11.457155 [debug] [Thread-7 ]: Acquiring new sqlserver connection "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:14 normalization > 13:53:11.457320 [debug] [Thread-7 ]: Began compiling node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:53:14 normalization > 13:53:11.457474 [debug] [Thread-7 ]: Compiling model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:53:14 normalization > 13:53:11.476049 [debug] [Thread-7 ]: Writing injected SQL for node "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:14 normalization > 13:53:11.476544 [debug] [Thread-7 ]: finished collecting timing info 2022-07-29 13:53:14 normalization > 13:53:11.476712 [debug] [Thread-7 ]: Began executing node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:53:14 normalization > 13:53:11.496485 [debug] [Thread-7 ]: finished collecting timing info 2022-07-29 13:53:14 normalization > 13:53:11.496914 [debug] [Thread-7 ]: Compilation Error in model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:53:14 normalization > When searching for a relation, dbt found an approximate match. Instead of guessing 2022-07-29 13:53:14 normalization > which relation to use, dbt will move on. Please delete "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages", or rename it to be less ambiguous. 2022-07-29 13:53:14 normalization > Searched for: "AIRBYTE_ELT"."airbyte_elt"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:14 normalization > Found: "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:14 normalization > 2022-07-29 13:53:14 normalization > > in macro materialization_table_default (macros/materializations/models/table/table.sql) 2022-07-29 13:53:14 normalization > > called by model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:53:14 normalization > 13:53:11.497294 [error] [Thread-7 ]: 1 of 1 ERROR creating table model airbyte_elt.elt_dwd_de_annual_sun__ion_regional_averages.............................. [ERROR in 0.04s] 2022-07-29 13:53:14 normalization > 13:53:11.497691 [debug] [Thread-7 ]: Finished running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:53:14 normalization > 13:53:11.499393 [debug] [MainThread]: Acquiring new sqlserver connection "master" 2022-07-29 13:53:14 normalization > 13:53:11.499600 [debug] [MainThread]: On master: COMMIT 2022-07-29 13:53:14 normalization > 13:53:11.499986 [info ] [MainThread]: 2022-07-29 13:53:14 normalization > 13:53:11.500272 [info ] [MainThread]: Finished running 1 table model in 0.32s. 2022-07-29 13:53:14 normalization > 13:53:11.500552 [debug] [MainThread]: Connection 'master' was properly closed. 2022-07-29 13:53:14 normalization > 13:53:11.500693 [debug] [MainThread]: Connection 'model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1' was properly closed. 2022-07-29 13:53:14 normalization > 13:53:11.500898 [debug] [MainThread]: Connection 'model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2' was properly closed. 2022-07-29 13:53:14 normalization > 13:53:11.501068 [debug] [MainThread]: Connection 'model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3' was properly closed. 2022-07-29 13:53:14 normalization > 13:53:11.501200 [debug] [MainThread]: Connection 'model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages' was properly closed. 2022-07-29 13:53:14 normalization > 13:53:11.517652 [info ] [MainThread]: 2022-07-29 13:53:14 normalization > 13:53:11.518088 [info ] [MainThread]: Completed with 1 error and 0 warnings: 2022-07-29 13:53:14 normalization > 13:53:11.518429 [info ] [MainThread]: 2022-07-29 13:53:14 normalization > 13:53:11.518684 [error] [MainThread]: Compilation Error in model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:53:14 normalization > 13:53:11.519020 [error] [MainThread]: When searching for a relation, dbt found an approximate match. Instead of guessing 2022-07-29 13:53:14 normalization > 13:53:11.519290 [error] [MainThread]: which relation to use, dbt will move on. Please delete "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages", or rename it to be less ambiguous. 2022-07-29 13:53:14 normalization > 13:53:11.519538 [error] [MainThread]: Searched for: "AIRBYTE_ELT"."airbyte_elt"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:14 normalization > 13:53:11.519792 [error] [MainThread]: Found: "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:14 normalization > 13:53:11.520034 [error] [MainThread]: 2022-07-29 13:53:14 normalization > 13:53:11.520262 [error] [MainThread]: > in macro materialization_table_default (macros/materializations/models/table/table.sql) 2022-07-29 13:53:14 normalization > 13:53:11.520500 [error] [MainThread]: > called by model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:53:14 normalization > 13:53:11.520749 [info ] [MainThread]: 2022-07-29 13:53:14 normalization > 13:53:11.521174 [info ] [MainThread]: Done. PASS=0 WARN=0 ERROR=1 SKIP=0 TOTAL=1 2022-07-29 13:53:14 INFO i.a.w.t.TemporalAttemptExecution(lambda$getWorkerThread$2):158 - Completing future exceptionally... io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:64) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] Caused by: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:61) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 3 more Suppressed: io.airbyte.workers.exception.WorkerException: Normalization process wasn't successful at io.airbyte.workers.normalization.DefaultNormalizationRunner.close(DefaultNormalizationRunner.java:162) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:49) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] 2022-07-29 13:53:14 INFO i.a.w.t.TemporalAttemptExecution(get):131 - Stopping cancellation check scheduling... 2022-07-29 13:53:14 INFO i.a.w.t.TemporalUtils(withBackgroundHeartbeat):291 - Stopping temporal heartbeating... 2022-07-29 13:53:14 WARN i.t.i.a.POJOActivityTaskHandler(activityFailureToResult):307 - Activity failure. ActivityId=bee9a31b-1700-3f9e-ba67-4a48f08be8be, activityType=Normalize, attempt=1 java.lang.RuntimeException: io.temporal.serviceclient.CheckedExceptionWrapper: java.util.concurrent.ExecutionException: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.temporal.TemporalUtils.withBackgroundHeartbeat(TemporalUtils.java:289) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.sync.NormalizationActivityImpl.normalize(NormalizationActivityImpl.java:75) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:104) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:578) ~[?:?] at io.temporal.internal.activity.POJOActivityTaskHandler$POJOActivityInboundCallsInterceptor.execute(POJOActivityTaskHandler.java:214) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.activity.POJOActivityTaskHandler$POJOActivityImplementation.execute(POJOActivityTaskHandler.java:180) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.activity.POJOActivityTaskHandler.handle(POJOActivityTaskHandler.java:120) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.worker.ActivityWorker$TaskHandlerImpl.handle(ActivityWorker.java:204) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.worker.ActivityWorker$TaskHandlerImpl.handle(ActivityWorker.java:164) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.worker.PollTaskExecutor.lambda$process$0(PollTaskExecutor.java:93) ~[temporal-sdk-1.8.1.jar:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) [?:?] at java.lang.Thread.run(Thread.java:1589) [?:?] Caused by: io.temporal.serviceclient.CheckedExceptionWrapper: java.util.concurrent.ExecutionException: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.temporal.serviceclient.CheckedExceptionWrapper.wrap(CheckedExceptionWrapper.java:56) ~[temporal-serviceclient-1.8.1.jar:?] at io.temporal.internal.sync.WorkflowInternal.wrap(WorkflowInternal.java:448) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.activity.Activity.wrap(Activity.java:51) ~[temporal-sdk-1.8.1.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.get(TemporalAttemptExecution.java:135) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.sync.NormalizationActivityImpl.lambda$normalize$3(NormalizationActivityImpl.java:103) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalUtils.withBackgroundHeartbeat(TemporalUtils.java:284) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 12 more Caused by: java.util.concurrent.ExecutionException: io.airbyte.workers.exception.WorkerException: Normalization Failed. at java.util.concurrent.CompletableFuture.reportGet(CompletableFuture.java:396) ~[?:?] at java.util.concurrent.CompletableFuture.get(CompletableFuture.java:2073) ~[?:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.get(TemporalAttemptExecution.java:129) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.sync.NormalizationActivityImpl.lambda$normalize$3(NormalizationActivityImpl.java:103) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalUtils.withBackgroundHeartbeat(TemporalUtils.java:284) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 12 more Caused by: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:64) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 1 more Caused by: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:61) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 1 more Suppressed: io.airbyte.workers.exception.WorkerException: Normalization process wasn't successful at io.airbyte.workers.normalization.DefaultNormalizationRunner.close(DefaultNormalizationRunner.java:162) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:49) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] 2022-07-29 13:53:14 INFO i.a.v.j.JsonSchemaValidator(test):71 - JSON schema validation failed. errors: $.ssl_method: must be a constant value unencrypted, $.ssl_method: does not have a value in the enumeration [unencrypted] 2022-07-29 13:53:15 INFO i.a.w.t.TemporalAttemptExecution(get):105 - Docker volume job log path: /tmp/workspace/30/2/logs.log 2022-07-29 13:53:15 INFO i.a.w.t.TemporalAttemptExecution(get):110 - Executing worker wrapper. Airbyte version: 0.39.41-alpha 2022-07-29 13:53:15 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/source-file:0.2.14 exists... 2022-07-29 13:53:15 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/source-file:0.2.14 was found locally. 2022-07-29 13:53:15 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:53:15 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/2 --log-driver none --name source-file-check-30-2-ouvqq --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e USE_STREAM_CAPABLE_STATE=true -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e WORKER_JOB_ATTEMPT=2 -e WORKER_CONNECTOR_IMAGE=airbyte/source-file:0.2.14 -e AIRBYTE_VERSION=0.39.41-alpha -e WORKER_JOB_ID=30 airbyte/source-file:0.2.14 check --config source_config.json 2022-07-29 13:53:17 INFO i.a.w.i.DefaultAirbyteStreamFactory(internalLog):99 - Checking access to https://opendata.dwd.de/climate_environment/CDC/regional_averages_DE/annual/sunshine_duration/regional_averages_sd_year.txt... 2022-07-29 13:53:17 INFO i.a.w.i.DefaultAirbyteStreamFactory(internalLog):99 - TransportParams: None 2022-07-29 13:53:17 INFO i.a.w.i.DefaultAirbyteStreamFactory(internalLog):99 - Check succeeded 2022-07-29 13:53:17 INFO i.a.w.t.TemporalAttemptExecution(get):131 - Stopping cancellation check scheduling... 2022-07-29 13:53:17 INFO i.a.w.t.TemporalAttemptExecution(get):105 - Docker volume job log path: /tmp/workspace/30/2/logs.log 2022-07-29 13:53:17 INFO i.a.w.t.TemporalAttemptExecution(get):110 - Executing worker wrapper. Airbyte version: 0.39.41-alpha 2022-07-29 13:53:17 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/destination-mssql:0.1.20 exists... 2022-07-29 13:53:17 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/destination-mssql:0.1.20 was found locally. 2022-07-29 13:53:17 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:53:17 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/2 --log-driver none --name destination-mssql-check-30-2-svduv --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e USE_STREAM_CAPABLE_STATE=true -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e WORKER_JOB_ATTEMPT=2 -e WORKER_CONNECTOR_IMAGE=airbyte/destination-mssql:0.1.20 -e AIRBYTE_VERSION=0.39.41-alpha -e WORKER_JOB_ID=30 airbyte/destination-mssql:0.1.20 check --config source_config.json 2022-07-29 13:53:18 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Class path contains multiple SLF4J bindings. 2022-07-29 13:53:18 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Found binding in [jar:file:/airbyte/lib/log4j-slf4j-impl-2.17.1.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:53:18 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Found binding in [jar:file:/airbyte/lib/slf4j-reload4j-1.7.36.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:53:18 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Found binding in [jar:file:/airbyte/lib/logback-classic-1.2.3.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:53:18 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Found binding in [jar:file:/airbyte/lib/slf4j-log4j12-1.7.25.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:53:18 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. 2022-07-29 13:53:18 ERROR i.a.c.i.LineGobbler(voidCall):82 - SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] 2022-07-29 13:53:18 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:18 INFO i.a.i.d.m.MSSQLDestination(main):104 - starting destination: class io.airbyte.integrations.destination.mssql.MSSQLDestination 2022-07-29 13:53:18 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:18 INFO i.a.i.b.IntegrationCliParser(parseOptions):118 - integration args: {check=null, config=source_config.json} 2022-07-29 13:53:18 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:18 INFO i.a.i.b.IntegrationRunner(runInternal):123 - Running integration: io.airbyte.integrations.base.ssh.SshWrappedDestination 2022-07-29 13:53:18 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:18 INFO i.a.i.b.IntegrationRunner(runInternal):124 - Command: CHECK 2022-07-29 13:53:18 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:18 INFO i.a.i.b.IntegrationRunner(runInternal):125 - Integration config: IntegrationConfig{command=CHECK, configPath='source_config.json', catalogPath='null', statePath='null'} 2022-07-29 13:53:19 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:19 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword order - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:53:19 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:19 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword examples - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:53:19 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:19 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword airbyte_secret - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:53:19 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:19 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword multiline - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:53:19 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:19 INFO i.a.i.b.s.SshTunnel(getInstance):170 - Starting connection with method: NO_TUNNEL 2022-07-29 13:53:19 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:19 INFO c.z.h.HikariDataSource():80 - HikariPool-1 - Starting... 2022-07-29 13:53:19 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:19 INFO c.z.h.HikariDataSource():82 - HikariPool-1 - Start completed. 2022-07-29 13:53:19 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:19 INFO c.z.h.HikariDataSource(close):350 - HikariPool-1 - Shutdown initiated... 2022-07-29 13:53:19 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:19 INFO c.z.h.HikariDataSource(close):352 - HikariPool-1 - Shutdown completed. 2022-07-29 13:53:20 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:20 INFO i.a.i.b.IntegrationRunner(runInternal):171 - Completed integration: io.airbyte.integrations.base.ssh.SshWrappedDestination 2022-07-29 13:53:20 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):61 - 2022-07-29 13:53:20 INFO i.a.i.d.m.MSSQLDestination(main):106 - completed destination: class io.airbyte.integrations.destination.mssql.MSSQLDestination 2022-07-29 13:53:20 INFO i.a.w.t.TemporalAttemptExecution(get):131 - Stopping cancellation check scheduling... 2022-07-29 13:53:20 INFO i.a.w.t.TemporalAttemptExecution(get):105 - Docker volume job log path: /tmp/workspace/30/2/logs.log 2022-07-29 13:53:20 INFO i.a.w.t.TemporalAttemptExecution(get):110 - Executing worker wrapper. Airbyte version: 0.39.41-alpha 2022-07-29 13:53:20 INFO i.a.w.g.DefaultReplicationWorker(run):116 - start sync worker. job id: 30 attempt id: 2 2022-07-29 13:53:20 INFO i.a.w.g.DefaultReplicationWorker(run):128 - configured sync modes: {null.DWD_DE_annual_sunshine-duration_regional-averages=full_refresh - overwrite} 2022-07-29 13:53:20 INFO i.a.w.i.DefaultAirbyteDestination(start):69 - Running destination... 2022-07-29 13:53:20 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/destination-mssql:0.1.20 exists... 2022-07-29 13:53:20 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/destination-mssql:0.1.20 was found locally. 2022-07-29 13:53:20 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:53:20 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/2 --log-driver none --name destination-mssql-write-30-2-fpbbz --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e USE_STREAM_CAPABLE_STATE=true -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e WORKER_JOB_ATTEMPT=2 -e WORKER_CONNECTOR_IMAGE=airbyte/destination-mssql:0.1.20 -e AIRBYTE_VERSION=0.39.41-alpha -e WORKER_JOB_ID=30 airbyte/destination-mssql:0.1.20 write --config destination_config.json --catalog destination_catalog.json 2022-07-29 13:53:20 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/source-file:0.2.14 exists... 2022-07-29 13:53:20 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/source-file:0.2.14 was found locally. 2022-07-29 13:53:20 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:53:20 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/2 --log-driver none --name source-file-read-30-2-wmfan --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e USE_STREAM_CAPABLE_STATE=true -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e WORKER_JOB_ATTEMPT=2 -e WORKER_CONNECTOR_IMAGE=airbyte/source-file:0.2.14 -e AIRBYTE_VERSION=0.39.41-alpha -e WORKER_JOB_ID=30 airbyte/source-file:0.2.14 read --config source_config.json --catalog source_catalog.json --state input_state.json 2022-07-29 13:53:20 INFO i.a.w.g.DefaultReplicationWorker(lambda$getDestinationOutputRunnable$7):406 - Destination output thread started. 2022-07-29 13:53:20 INFO i.a.w.g.DefaultReplicationWorker(run):170 - Waiting for source and destination threads to complete. 2022-07-29 13:53:20 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):299 - Replication thread started. 2022-07-29 13:53:20 destination > SLF4J: Class path contains multiple SLF4J bindings. 2022-07-29 13:53:20 destination > SLF4J: Found binding in [jar:file:/airbyte/lib/log4j-slf4j-impl-2.17.1.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:53:20 destination > SLF4J: Found binding in [jar:file:/airbyte/lib/slf4j-reload4j-1.7.36.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:53:20 destination > SLF4J: Found binding in [jar:file:/airbyte/lib/logback-classic-1.2.3.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:53:20 destination > SLF4J: Found binding in [jar:file:/airbyte/lib/slf4j-log4j12-1.7.25.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2022-07-29 13:53:20 destination > SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. 2022-07-29 13:53:20 destination > SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] 2022-07-29 13:53:21 destination > 2022-07-29 13:53:21 INFO i.a.i.d.m.MSSQLDestination(main):104 - starting destination: class io.airbyte.integrations.destination.mssql.MSSQLDestination 2022-07-29 13:53:21 destination > 2022-07-29 13:53:21 INFO i.a.i.b.IntegrationCliParser(parseOptions):118 - integration args: {catalog=destination_catalog.json, write=null, config=destination_config.json} 2022-07-29 13:53:21 destination > 2022-07-29 13:53:21 INFO i.a.i.b.IntegrationRunner(runInternal):123 - Running integration: io.airbyte.integrations.base.ssh.SshWrappedDestination 2022-07-29 13:53:21 destination > 2022-07-29 13:53:21 INFO i.a.i.b.IntegrationRunner(runInternal):124 - Command: WRITE 2022-07-29 13:53:21 destination > 2022-07-29 13:53:21 INFO i.a.i.b.IntegrationRunner(runInternal):125 - Integration config: IntegrationConfig{command=WRITE, configPath='destination_config.json', catalogPath='destination_catalog.json', statePath='null'} 2022-07-29 13:53:21 destination > 2022-07-29 13:53:21 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword order - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:53:21 destination > 2022-07-29 13:53:21 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword examples - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:53:21 destination > 2022-07-29 13:53:21 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword airbyte_secret - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:53:21 destination > 2022-07-29 13:53:21 WARN c.n.s.JsonMetaSchema(newValidator):338 - Unknown keyword multiline - you should define your own Meta Schema. If the keyword is irrelevant for validation, just use a NonValidationKeyword 2022-07-29 13:53:22 destination > 2022-07-29 13:53:22 INFO i.a.i.b.s.SshTunnel(getInstance):170 - Starting connection with method: NO_TUNNEL 2022-07-29 13:53:22 destination > 2022-07-29 13:53:22 INFO c.z.h.HikariDataSource():80 - HikariPool-1 - Starting... 2022-07-29 13:53:22 destination > 2022-07-29 13:53:22 INFO c.z.h.HikariDataSource():82 - HikariPool-1 - Start completed. 2022-07-29 13:53:22 destination > 2022-07-29 13:53:22 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$toWriteConfig$0):97 - Write config: WriteConfig{streamName=elt_DWD_DE_annual_sunshine-duration_regional-averages, namespace=null, outputSchemaName=AIRBYTE_ELT, tmpTableName=_airbyte_tmp_hds_elt_DWD_DE_annual_sunshine_duration_regional_averages, outputTableName=_airbyte_raw_elt_DWD_DE_annual_sunshine_duration_regional_averages, syncMode=overwrite} 2022-07-29 13:53:22 destination > 2022-07-29 13:53:22 INFO i.a.i.d.b.BufferedStreamConsumer(startTracked):116 - class io.airbyte.integrations.destination.buffered_stream_consumer.BufferedStreamConsumer started. 2022-07-29 13:53:22 destination > 2022-07-29 13:53:22 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):122 - Preparing tmp tables in destination started for 1 streams 2022-07-29 13:53:22 destination > 2022-07-29 13:53:22 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):126 - Preparing tmp table in destination started for stream elt_DWD_DE_annual_sunshine-duration_regional-averages. schema: AIRBYTE_ELT, tmp table name: _airbyte_tmp_hds_elt_DWD_DE_annual_sunshine_duration_regional_averages 2022-07-29 13:53:22 source > Reading DWD_DE_annual_sunshine-duration_regional-averages (https://opendata.dwd.de/climate_environment/CDC/regional_averages_DE/annual/sunshine_duration/regional_averages_sd_year.txt)... 2022-07-29 13:53:22 source > TransportParams: None 2022-07-29 13:53:23 source > /airbyte/integration_code/source_file/client.py:364: FutureWarning: Passing a set as an indexer is deprecated and will raise in a future version. Use a list instead. 2022-07-29 13:53:23 source > yield from df[columns].to_dict(orient="records") 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):132 - Preparing tables in destination completed. 2022-07-29 13:53:23 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):328 - Source has no more messages, closing connection. 2022-07-29 13:53:23 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):336 - Total records read: 71 (29 KB) 2022-07-29 13:53:23 INFO i.a.w.g.DefaultReplicationWorker(run):175 - One of source or destination thread complete. Waiting on the other. 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.b.FailureTrackingAirbyteMessageConsumer(close):65 - Airbyte message consumer: succeeded. 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.d.b.BufferedStreamConsumer(close):171 - executing on success close procedure. 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.d.r.InMemoryRecordBufferingStrategy(lambda$flushAll$1):86 - Flushing elt_DWD_DE_annual_sunshine-duration_regional-averages: 71 records (119 KB) 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.d.j.SqlOperations(onDestinationCloseOperations):137 - No onDestinationCloseOperations required for this destination. 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):162 - Finalizing tables in destination started for 1 streams 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):167 - Finalizing stream elt_DWD_DE_annual_sunshine-duration_regional-averages. schema AIRBYTE_ELT, tmp table _airbyte_tmp_hds_elt_DWD_DE_annual_sunshine_duration_regional_averages, final table _airbyte_raw_elt_DWD_DE_annual_sunshine_duration_regional_averages 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):180 - Executing finalization of tables. 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):182 - Finalizing tables in destination completed. 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):185 - Cleaning tmp tables in destination started for 1 streams 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):189 - Cleaning tmp table in destination started for stream elt_DWD_DE_annual_sunshine-duration_regional-averages. schema AIRBYTE_ELT, tmp table name: _airbyte_tmp_hds_elt_DWD_DE_annual_sunshine_duration_regional_averages 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):194 - Cleaning tmp tables in destination completed. 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.b.IntegrationRunner(runInternal):171 - Completed integration: io.airbyte.integrations.base.ssh.SshWrappedDestination 2022-07-29 13:53:23 destination > 2022-07-29 13:53:23 INFO i.a.i.d.m.MSSQLDestination(main):106 - completed destination: class io.airbyte.integrations.destination.mssql.MSSQLDestination 2022-07-29 13:53:23 INFO i.a.w.g.DefaultReplicationWorker(run):177 - Source and destination threads complete. 2022-07-29 13:53:23 INFO i.a.w.g.DefaultReplicationWorker(run):240 - sync summary: io.airbyte.config.ReplicationAttemptSummary@39889cb7[status=completed,recordsSynced=71,bytesSynced=30672,startTime=1659102800306,endTime=1659102803913,totalStats=io.airbyte.config.SyncStats@6e3ed530[recordsEmitted=71,bytesEmitted=30672,stateMessagesEmitted=0,recordsCommitted=71],streamStats=[io.airbyte.config.StreamSyncStats@6cc3ad32[streamName=elt_DWD_DE_annual_sunshine-duration_regional-averages,stats=io.airbyte.config.SyncStats@1ef65072[recordsEmitted=71,bytesEmitted=30672,stateMessagesEmitted=,recordsCommitted=71]]]] 2022-07-29 13:53:23 INFO i.a.w.g.DefaultReplicationWorker(run):269 - Source did not output any state messages 2022-07-29 13:53:23 WARN i.a.w.g.DefaultReplicationWorker(run):277 - State capture: No new state, falling back on input state: io.airbyte.config.State@63e60a3f[state={}] 2022-07-29 13:53:23 INFO i.a.w.t.TemporalAttemptExecution(get):131 - Stopping cancellation check scheduling... 2022-07-29 13:53:23 INFO i.a.w.t.s.ReplicationActivityImpl(lambda$replicate$3):161 - sync summary: io.airbyte.config.StandardSyncOutput@70d2dcbd[standardSyncSummary=io.airbyte.config.StandardSyncSummary@6101254d[status=completed,recordsSynced=71,bytesSynced=30672,startTime=1659102800306,endTime=1659102803913,totalStats=io.airbyte.config.SyncStats@6e3ed530[recordsEmitted=71,bytesEmitted=30672,stateMessagesEmitted=0,recordsCommitted=71],streamStats=[io.airbyte.config.StreamSyncStats@6cc3ad32[streamName=elt_DWD_DE_annual_sunshine-duration_regional-averages,stats=io.airbyte.config.SyncStats@1ef65072[recordsEmitted=71,bytesEmitted=30672,stateMessagesEmitted=,recordsCommitted=71]]]],normalizationSummary=,state=io.airbyte.config.State@63e60a3f[state={}],outputCatalog=io.airbyte.protocol.models.ConfiguredAirbyteCatalog@59abfc18[streams=[io.airbyte.protocol.models.ConfiguredAirbyteStream@2e55dd3[stream=io.airbyte.protocol.models.AirbyteStream@64b45e06[name=elt_DWD_DE_annual_sunshine-duration_regional-averages,jsonSchema={"type":"object","$schema":"http://json-schema.org/draft-07/schema#","properties":{"Jahr":{"type":["number","null"]},"Bayern":{"type":["number","null"]},"Hessen":{"type":["number","null"]},"Sachsen":{"type":["number","null"]},"Saarland":{"type":["number","null"]},"Thueringen":{"type":["number","null"]},"Brandenburg":{"type":["number","null"]},"Deutschland":{"type":["number","null"]},"Niedersachsen":{"type":["number","null"]},"Sachsen-Anhalt":{"type":["number","null"]},"Rheinland-Pfalz":{"type":["number","null"]},"Baden-Wuerttemberg":{"type":["number","null"]},"Brandenburg/Berlin":{"type":["number","null"]},"Schleswig-Holstein":{"type":["number","null"]},"Nordrhein-Westfalen":{"type":["number","null"]},"Mecklenburg-Vorpommern":{"type":["number","null"]},"Thueringen/Sachsen-Anhalt":{"type":["number","null"]},"Niedersachsen/Hamburg/Bremen":{"type":["number","null"]}}},supportedSyncModes=[full_refresh],sourceDefinedCursor=,defaultCursorField=[],sourceDefinedPrimaryKey=[],namespace=,additionalProperties={}],syncMode=full_refresh,cursorField=[],destinationSyncMode=overwrite,primaryKey=[],additionalProperties={}]],additionalProperties={}],failures=[]] 2022-07-29 13:53:23 INFO i.a.w.t.TemporalUtils(withBackgroundHeartbeat):291 - Stopping temporal heartbeating... 2022-07-29 13:53:24 INFO i.a.w.t.TemporalAttemptExecution(get):105 - Docker volume job log path: /tmp/workspace/30/2/logs.log 2022-07-29 13:53:24 INFO i.a.w.t.TemporalAttemptExecution(get):110 - Executing worker wrapper. Airbyte version: 0.39.41-alpha 2022-07-29 13:53:24 INFO i.a.w.g.DefaultNormalizationWorker(run):50 - Running normalization. 2022-07-29 13:53:24 INFO i.a.w.n.DefaultNormalizationRunner(runProcess):122 - Running with normalization version: airbyte/normalization-mssql:0.2.12 2022-07-29 13:53:24 INFO i.a.c.i.LineGobbler(voidCall):82 - Checking if airbyte/normalization-mssql:0.2.12 exists... 2022-07-29 13:53:24 INFO i.a.c.i.LineGobbler(voidCall):82 - airbyte/normalization-mssql:0.2.12 was found locally. 2022-07-29 13:53:24 INFO i.a.w.p.DockerProcessFactory(create):108 - Creating docker job ID: 30 2022-07-29 13:53:24 INFO i.a.w.p.DockerProcessFactory(create):163 - Preparing command: docker run --rm --init -i -w /data/30/2/normalize --log-driver none --name normalization-mssql-normalize-30-2-yudpl --network host -v airbyte_workspace:/data -v /tmp/airbyte_local:/local -e DEPLOYMENT_MODE=OSS -e WORKER_ENVIRONMENT=DOCKER -e AIRBYTE_ROLE= -e AIRBYTE_VERSION=0.39.41-alpha airbyte/normalization-mssql:0.2.12 run --integration-type mssql --config destination_config.json --catalog destination_catalog.json 2022-07-29 13:53:24 normalization > Running: transform-config --config destination_config.json --integration-type mssql --out /data/30/2/normalize 2022-07-29 13:53:25 normalization > Namespace(config='destination_config.json', integration_type=, out='/data/30/2/normalize') 2022-07-29 13:53:25 normalization > transform_mssql 2022-07-29 13:53:25 normalization > Running: transform-catalog --integration-type mssql --profile-config-dir /data/30/2/normalize --catalog destination_catalog.json --out /data/30/2/normalize/models/generated/ --json-column _airbyte_data 2022-07-29 13:53:25 normalization > Processing destination_catalog.json... 2022-07-29 13:53:25 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:25 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:25 normalization > Generating airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab1.sql from elt_DWD_DE_annual_sunshine-duration_regional-averages 2022-07-29 13:53:25 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:25 normalization > Generating airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab2.sql from elt_DWD_DE_annual_sunshine-duration_regional-averages 2022-07-29 13:53:25 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:25 normalization > Truncating _airbyte_elt_dwd_de_annual_sun__ion_regional_averages_hashid (#60) to _airbyte_elt_dwd_de_a_ional_averages_hashid (#44) 2022-07-29 13:53:25 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:25 normalization > Generating airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab3.sql from elt_DWD_DE_annual_sunshine-duration_regional-averages 2022-07-29 13:53:25 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:25 normalization > Truncating _airbyte_elt_dwd_de_annual_sun__ion_regional_averages_hashid (#60) to _airbyte_elt_dwd_de_a_ional_averages_hashid (#44) 2022-07-29 13:53:25 normalization > Truncating elt_DWD_DE_annual_sunshine_duration_regional_averages (#53) to elt_DWD_DE_annual_sun_ion_regional_averages (#44) 2022-07-29 13:53:25 normalization > Generating airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql from elt_DWD_DE_annual_sunshine-duration_regional-averages 2022-07-29 13:53:25 normalization > detected no config file for ssh, assuming ssh is off. 2022-07-29 13:53:29 normalization > [--event-buffer-size EVENT_BUFFER_SIZE] 2022-07-29 13:53:29 normalization > --event-buffer-size EVENT_BUFFER_SIZE 2022-07-29 13:53:29 normalization > 2022-07-29 13:53:29 normalization > DBT >=1.0.0 detected; using 10K event buffer size 2022-07-29 13:53:29 normalization > 2022-07-29 13:53:32 normalization > 13:53:32 Running with dbt=1.0.0 2022-07-29 13:53:32 normalization > 13:53:32 Partial parse save file not found. Starting full parse. 2022-07-29 13:53:33 normalization > 13:53:33 [WARNING]: Configuration paths exist in your dbt_project.yml file which do not apply to any resources. 2022-07-29 13:53:33 normalization > There are 2 unused configuration paths: 2022-07-29 13:53:33 normalization > - models.airbyte_utils.generated.airbyte_views 2022-07-29 13:53:33 normalization > - models.airbyte_utils.generated.airbyte_incremental 2022-07-29 13:53:33 normalization > 2022-07-29 13:53:33 normalization > 13:53:33 Found 4 models, 0 tests, 0 snapshots, 0 analyses, 580 macros, 0 operations, 0 seed files, 1 source, 0 exposures, 0 metrics 2022-07-29 13:53:33 normalization > 13:53:33 2022-07-29 13:53:33 normalization > 13:53:33 Concurrency: 8 threads (target='prod') 2022-07-29 13:53:33 normalization > 13:53:33 2022-07-29 13:53:34 normalization > 13:53:34 1 of 1 START table model airbyte_elt.elt_dwd_de_annual_sun__ion_regional_averages....................................... [RUN] 2022-07-29 13:53:34 normalization > 13:53:34 1 of 1 ERROR creating table model airbyte_elt.elt_dwd_de_annual_sun__ion_regional_averages.............................. [ERROR in 0.04s] 2022-07-29 13:53:34 normalization > 13:53:34 2022-07-29 13:53:34 normalization > 13:53:34 Finished running 1 table model in 0.32s. 2022-07-29 13:53:34 normalization > 13:53:34 2022-07-29 13:53:34 normalization > 13:53:34 Completed with 1 error and 0 warnings: 2022-07-29 13:53:34 normalization > 13:53:34 2022-07-29 13:53:34 normalization > 13:53:34 Compilation Error in model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:53:34 normalization > 13:53:34 When searching for a relation, dbt found an approximate match. Instead of guessing 2022-07-29 13:53:34 normalization > 13:53:34 which relation to use, dbt will move on. Please delete "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages", or rename it to be less ambiguous. 2022-07-29 13:53:34 normalization > 13:53:34 Searched for: "AIRBYTE_ELT"."airbyte_elt"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:34 normalization > 13:53:34 Found: "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:34 normalization > 13:53:34 2022-07-29 13:53:34 normalization > 13:53:34 > in macro materialization_table_default (macros/materializations/models/table/table.sql) 2022-07-29 13:53:34 normalization > 13:53:34 > called by model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:53:34 normalization > 13:53:34 2022-07-29 13:53:34 normalization > 13:53:34 Done. PASS=0 WARN=0 ERROR=1 SKIP=0 TOTAL=1 2022-07-29 13:53:34 normalization > 2022-07-29 13:53:34 normalization > Diagnosing dbt debug to check if destination is available for dbt and well configured (1): 2022-07-29 13:53:34 normalization > 2022-07-29 13:53:37 normalization > 13:53:37 Running with dbt=1.0.0 2022-07-29 13:53:37 normalization > dbt version: 1.0.0 2022-07-29 13:53:37 normalization > python version: 3.9.9 2022-07-29 13:53:37 normalization > python path: /usr/local/bin/python 2022-07-29 13:53:37 normalization > os info: Linux-4.18.0-372.16.1.el8_6.x86_64-x86_64-with-glibc2.31 2022-07-29 13:53:37 normalization > Using profiles.yml file at /data/30/2/normalize/profiles.yml 2022-07-29 13:53:37 normalization > Using dbt_project.yml file at /data/30/2/normalize/dbt_project.yml 2022-07-29 13:53:37 normalization > 2022-07-29 13:53:37 normalization > Configuration: 2022-07-29 13:53:37 normalization > profiles.yml file [OK found and valid] 2022-07-29 13:53:37 normalization > dbt_project.yml file [OK found and valid] 2022-07-29 13:53:37 normalization > 2022-07-29 13:53:37 normalization > Required dependencies: 2022-07-29 13:53:37 normalization > - git [OK found] 2022-07-29 13:53:37 normalization > 2022-07-29 13:53:37 normalization > Connection: 2022-07-29 13:53:37 normalization > server: 172.18.105.119 2022-07-29 13:53:37 normalization > database: AIRBYTE_ELT 2022-07-29 13:53:37 normalization > schema: AIRBYTE_ELT 2022-07-29 13:53:37 normalization > port: 1433 2022-07-29 13:53:37 normalization > UID: AIRBYTE_ELT 2022-07-29 13:53:37 normalization > client_id: None 2022-07-29 13:53:37 normalization > authentication: sql 2022-07-29 13:53:37 normalization > encrypt: False 2022-07-29 13:53:37 normalization > trust_cert: False 2022-07-29 13:53:37 normalization > Connection test: [OK connection ok] 2022-07-29 13:53:37 normalization > 2022-07-29 13:53:37 normalization > All checks passed! 2022-07-29 13:53:37 normalization > 2022-07-29 13:53:37 normalization > Forward dbt output logs to diagnose/debug errors (0): 2022-07-29 13:53:37 normalization > 2022-07-29 13:53:37 normalization > 2022-07-29 13:53:37 normalization > 2022-07-29 13:53:37 normalization > ============================== 2022-07-29 13:53:32.020679 | ebf875be-917f-4d96-964d-89533f47b695 ============================== 2022-07-29 13:53:37 normalization > 13:53:32.020679 [info ] [MainThread]: Running with dbt=1.0.0 2022-07-29 13:53:37 normalization > 13:53:32.021279 [debug] [MainThread]: running dbt with arguments Namespace(record_timing_info=None, debug=None, log_format=None, write_json=None, use_colors=None, printer_width=None, warn_error=None, version_check=None, partial_parse=None, single_threaded=False, use_experimental_parser=None, static_parser=None, profiles_dir='/data/30/2/normalize', send_anonymous_usage_stats=None, fail_fast=None, event_buffer_size='10000', project_dir='/data/30/2/normalize', profile=None, target=None, vars='{}', log_cache_events=False, threads=None, select=None, exclude=None, selector_name=None, state=None, defer=None, full_refresh=False, cls=, which='run', rpc_method='run') 2022-07-29 13:53:37 normalization > 13:53:32.021506 [debug] [MainThread]: Tracking: do not track 2022-07-29 13:53:37 normalization > 13:53:32.046548 [info ] [MainThread]: Partial parse save file not found. Starting full parse. 2022-07-29 13:53:37 normalization > 13:53:32.081728 [debug] [MainThread]: Parsing macros/clean_tmp_tables.sql 2022-07-29 13:53:37 normalization > 13:53:32.085758 [debug] [MainThread]: Parsing macros/configuration.sql 2022-07-29 13:53:37 normalization > 13:53:32.088793 [debug] [MainThread]: Parsing macros/get_custom_schema.sql 2022-07-29 13:53:37 normalization > 13:53:32.089593 [debug] [MainThread]: Parsing macros/incremental.sql 2022-07-29 13:53:37 normalization > 13:53:32.100675 [debug] [MainThread]: Parsing macros/should_full_refresh.sql 2022-07-29 13:53:37 normalization > 13:53:32.107565 [debug] [MainThread]: Parsing macros/star_intersect.sql 2022-07-29 13:53:37 normalization > 13:53:32.116247 [debug] [MainThread]: Parsing macros/cross_db_utils/array.sql 2022-07-29 13:53:37 normalization > 13:53:32.172021 [debug] [MainThread]: Parsing macros/cross_db_utils/columns.sql 2022-07-29 13:53:37 normalization > 13:53:32.175886 [debug] [MainThread]: Parsing macros/cross_db_utils/concat.sql 2022-07-29 13:53:37 normalization > 13:53:32.179336 [debug] [MainThread]: Parsing macros/cross_db_utils/current_timestamp.sql 2022-07-29 13:53:37 normalization > 13:53:32.180148 [debug] [MainThread]: Parsing macros/cross_db_utils/datatypes.sql 2022-07-29 13:53:37 normalization > 13:53:32.203442 [debug] [MainThread]: Parsing macros/cross_db_utils/except.sql 2022-07-29 13:53:37 normalization > 13:53:32.204520 [debug] [MainThread]: Parsing macros/cross_db_utils/hash.sql 2022-07-29 13:53:37 normalization > 13:53:32.205323 [debug] [MainThread]: Parsing macros/cross_db_utils/json_operations.sql 2022-07-29 13:53:37 normalization > 13:53:32.255961 [debug] [MainThread]: Parsing macros/cross_db_utils/quote.sql 2022-07-29 13:53:37 normalization > 13:53:32.258196 [debug] [MainThread]: Parsing macros/cross_db_utils/surrogate_key.sql 2022-07-29 13:53:37 normalization > 13:53:32.260862 [debug] [MainThread]: Parsing macros/cross_db_utils/type_conversions.sql 2022-07-29 13:53:37 normalization > 13:53:32.272354 [debug] [MainThread]: Parsing macros/schema_tests/equal_rowcount.sql 2022-07-29 13:53:37 normalization > 13:53:32.273915 [debug] [MainThread]: Parsing macros/schema_tests/equality.sql 2022-07-29 13:53:37 normalization > 13:53:32.282481 [debug] [MainThread]: Parsing macros/adapters.sql 2022-07-29 13:53:37 normalization > 13:53:32.282697 [debug] [MainThread]: Parsing macros/adapters/columns.sql 2022-07-29 13:53:37 normalization > 13:53:32.289081 [debug] [MainThread]: Parsing macros/adapters/freshness.sql 2022-07-29 13:53:37 normalization > 13:53:32.289589 [debug] [MainThread]: Parsing macros/adapters/indexes.sql 2022-07-29 13:53:37 normalization > 13:53:32.301649 [debug] [MainThread]: Parsing macros/adapters/metadata.sql 2022-07-29 13:53:37 normalization > 13:53:32.307729 [debug] [MainThread]: Parsing macros/adapters/persist_docs.sql 2022-07-29 13:53:37 normalization > 13:53:32.307954 [debug] [MainThread]: Parsing macros/adapters/relation.sql 2022-07-29 13:53:37 normalization > 13:53:32.315445 [debug] [MainThread]: Parsing macros/adapters/schema.sql 2022-07-29 13:53:37 normalization > 13:53:32.319711 [debug] [MainThread]: Parsing macros/materializations/models/incremental/merge.sql 2022-07-29 13:53:37 normalization > 13:53:32.322356 [debug] [MainThread]: Parsing macros/materializations/models/table/create_table_as.sql 2022-07-29 13:53:37 normalization > 13:53:32.326087 [debug] [MainThread]: Parsing macros/materializations/models/view/create_view_as.sql 2022-07-29 13:53:37 normalization > 13:53:32.327993 [debug] [MainThread]: Parsing macros/materializations/seeds/helpers.sql 2022-07-29 13:53:37 normalization > 13:53:32.338009 [debug] [MainThread]: Parsing macros/materializations/snapshots/snapshot.sql 2022-07-29 13:53:37 normalization > 13:53:32.339993 [debug] [MainThread]: Parsing macros/materializations/snapshots/snapshot_merge.sql 2022-07-29 13:53:37 normalization > 13:53:32.340844 [debug] [MainThread]: Parsing macros/materializations/snapshots/strategies.sql 2022-07-29 13:53:37 normalization > 13:53:32.342747 [debug] [MainThread]: Parsing macros/materializations/tests/helpers.sql 2022-07-29 13:53:37 normalization > 13:53:32.344400 [debug] [MainThread]: Parsing macros/materializations/tests/test.sql 2022-07-29 13:53:37 normalization > 13:53:32.351647 [debug] [MainThread]: Parsing macros/adapters/columns.sql 2022-07-29 13:53:37 normalization > 13:53:32.365505 [debug] [MainThread]: Parsing macros/adapters/freshness.sql 2022-07-29 13:53:37 normalization > 13:53:32.369660 [debug] [MainThread]: Parsing macros/adapters/indexes.sql 2022-07-29 13:53:37 normalization > 13:53:32.373519 [debug] [MainThread]: Parsing macros/adapters/metadata.sql 2022-07-29 13:53:37 normalization > 13:53:32.383578 [debug] [MainThread]: Parsing macros/adapters/persist_docs.sql 2022-07-29 13:53:37 normalization > 13:53:32.390074 [debug] [MainThread]: Parsing macros/adapters/relation.sql 2022-07-29 13:53:37 normalization > 13:53:32.403817 [debug] [MainThread]: Parsing macros/adapters/schema.sql 2022-07-29 13:53:37 normalization > 13:53:32.406974 [debug] [MainThread]: Parsing macros/etc/datetime.sql 2022-07-29 13:53:37 normalization > 13:53:32.418803 [debug] [MainThread]: Parsing macros/etc/statement.sql 2022-07-29 13:53:37 normalization > 13:53:32.425258 [debug] [MainThread]: Parsing macros/generic_test_sql/accepted_values.sql 2022-07-29 13:53:37 normalization > 13:53:32.427250 [debug] [MainThread]: Parsing macros/generic_test_sql/not_null.sql 2022-07-29 13:53:37 normalization > 13:53:32.428011 [debug] [MainThread]: Parsing macros/generic_test_sql/relationships.sql 2022-07-29 13:53:37 normalization > 13:53:32.429222 [debug] [MainThread]: Parsing macros/generic_test_sql/unique.sql 2022-07-29 13:53:37 normalization > 13:53:32.430188 [debug] [MainThread]: Parsing macros/get_custom_name/get_custom_alias.sql 2022-07-29 13:53:37 normalization > 13:53:32.432278 [debug] [MainThread]: Parsing macros/get_custom_name/get_custom_database.sql 2022-07-29 13:53:37 normalization > 13:53:32.434480 [debug] [MainThread]: Parsing macros/get_custom_name/get_custom_schema.sql 2022-07-29 13:53:37 normalization > 13:53:32.438001 [debug] [MainThread]: Parsing macros/materializations/configs.sql 2022-07-29 13:53:37 normalization > 13:53:32.441478 [debug] [MainThread]: Parsing macros/materializations/hooks.sql 2022-07-29 13:53:37 normalization > 13:53:32.447070 [debug] [MainThread]: Parsing macros/materializations/models/incremental/column_helpers.sql 2022-07-29 13:53:37 normalization > 13:53:32.453625 [debug] [MainThread]: Parsing macros/materializations/models/incremental/incremental.sql 2022-07-29 13:53:37 normalization > 13:53:32.468465 [debug] [MainThread]: Parsing macros/materializations/models/incremental/is_incremental.sql 2022-07-29 13:53:37 normalization > 13:53:32.470651 [debug] [MainThread]: Parsing macros/materializations/models/incremental/merge.sql 2022-07-29 13:53:37 normalization > 13:53:32.486062 [debug] [MainThread]: Parsing macros/materializations/models/incremental/on_schema_change.sql 2022-07-29 13:53:37 normalization > 13:53:32.507063 [debug] [MainThread]: Parsing macros/materializations/models/table/create_table_as.sql 2022-07-29 13:53:37 normalization > 13:53:32.511239 [debug] [MainThread]: Parsing macros/materializations/models/table/table.sql 2022-07-29 13:53:37 normalization > 13:53:32.521294 [debug] [MainThread]: Parsing macros/materializations/models/view/create_or_replace_view.sql 2022-07-29 13:53:37 normalization > 13:53:32.525175 [debug] [MainThread]: Parsing macros/materializations/models/view/create_view_as.sql 2022-07-29 13:53:37 normalization > 13:53:32.528488 [debug] [MainThread]: Parsing macros/materializations/models/view/helpers.sql 2022-07-29 13:53:37 normalization > 13:53:32.530343 [debug] [MainThread]: Parsing macros/materializations/models/view/view.sql 2022-07-29 13:53:37 normalization > 13:53:32.540385 [debug] [MainThread]: Parsing macros/materializations/seeds/helpers.sql 2022-07-29 13:53:37 normalization > 13:53:32.562539 [debug] [MainThread]: Parsing macros/materializations/seeds/seed.sql 2022-07-29 13:53:37 normalization > 13:53:32.571479 [debug] [MainThread]: Parsing macros/materializations/snapshots/helpers.sql 2022-07-29 13:53:37 normalization > 13:53:32.586361 [debug] [MainThread]: Parsing macros/materializations/snapshots/snapshot.sql 2022-07-29 13:53:37 normalization > 13:53:32.602428 [debug] [MainThread]: Parsing macros/materializations/snapshots/snapshot_merge.sql 2022-07-29 13:53:37 normalization > 13:53:32.604703 [debug] [MainThread]: Parsing macros/materializations/snapshots/strategies.sql 2022-07-29 13:53:37 normalization > 13:53:32.629375 [debug] [MainThread]: Parsing macros/materializations/tests/helpers.sql 2022-07-29 13:53:37 normalization > 13:53:32.631925 [debug] [MainThread]: Parsing macros/materializations/tests/test.sql 2022-07-29 13:53:37 normalization > 13:53:32.638446 [debug] [MainThread]: Parsing macros/materializations/tests/where_subquery.sql 2022-07-29 13:53:37 normalization > 13:53:32.641042 [debug] [MainThread]: Parsing tests/generic/builtin.sql 2022-07-29 13:53:37 normalization > 13:53:32.645375 [debug] [MainThread]: Parsing macros/cross_db_utils/_is_ephemeral.sql 2022-07-29 13:53:37 normalization > 13:53:32.648105 [debug] [MainThread]: Parsing macros/cross_db_utils/_is_relation.sql 2022-07-29 13:53:37 normalization > 13:53:32.649734 [debug] [MainThread]: Parsing macros/cross_db_utils/any_value.sql 2022-07-29 13:53:37 normalization > 13:53:32.651537 [debug] [MainThread]: Parsing macros/cross_db_utils/bool_or.sql 2022-07-29 13:53:37 normalization > 13:53:32.653750 [debug] [MainThread]: Parsing macros/cross_db_utils/cast_bool_to_text.sql 2022-07-29 13:53:37 normalization > 13:53:32.655913 [debug] [MainThread]: Parsing macros/cross_db_utils/concat.sql 2022-07-29 13:53:37 normalization > 13:53:32.657307 [debug] [MainThread]: Parsing macros/cross_db_utils/current_timestamp.sql 2022-07-29 13:53:37 normalization > 13:53:32.662162 [debug] [MainThread]: Parsing macros/cross_db_utils/datatypes.sql 2022-07-29 13:53:37 normalization > 13:53:32.670910 [debug] [MainThread]: Parsing macros/cross_db_utils/date_trunc.sql 2022-07-29 13:53:37 normalization > 13:53:32.673031 [debug] [MainThread]: Parsing macros/cross_db_utils/dateadd.sql 2022-07-29 13:53:37 normalization > 13:53:32.677125 [debug] [MainThread]: Parsing macros/cross_db_utils/datediff.sql 2022-07-29 13:53:37 normalization > 13:53:32.690733 [debug] [MainThread]: Parsing macros/cross_db_utils/escape_single_quotes.sql 2022-07-29 13:53:37 normalization > 13:53:32.693389 [debug] [MainThread]: Parsing macros/cross_db_utils/except.sql 2022-07-29 13:53:37 normalization > 13:53:32.694878 [debug] [MainThread]: Parsing macros/cross_db_utils/hash.sql 2022-07-29 13:53:37 normalization > 13:53:32.696868 [debug] [MainThread]: Parsing macros/cross_db_utils/identifier.sql 2022-07-29 13:53:37 normalization > 13:53:32.699210 [debug] [MainThread]: Parsing macros/cross_db_utils/intersect.sql 2022-07-29 13:53:37 normalization > 13:53:32.700668 [debug] [MainThread]: Parsing macros/cross_db_utils/last_day.sql 2022-07-29 13:53:37 normalization > 13:53:32.706160 [debug] [MainThread]: Parsing macros/cross_db_utils/length.sql 2022-07-29 13:53:37 normalization > 13:53:32.707950 [debug] [MainThread]: Parsing macros/cross_db_utils/literal.sql 2022-07-29 13:53:37 normalization > 13:53:32.709264 [debug] [MainThread]: Parsing macros/cross_db_utils/position.sql 2022-07-29 13:53:37 normalization > 13:53:32.711426 [debug] [MainThread]: Parsing macros/cross_db_utils/replace.sql 2022-07-29 13:53:37 normalization > 13:53:32.713203 [debug] [MainThread]: Parsing macros/cross_db_utils/right.sql 2022-07-29 13:53:37 normalization > 13:53:32.716327 [debug] [MainThread]: Parsing macros/cross_db_utils/safe_cast.sql 2022-07-29 13:53:37 normalization > 13:53:32.719126 [debug] [MainThread]: Parsing macros/cross_db_utils/split_part.sql 2022-07-29 13:53:37 normalization > 13:53:32.721730 [debug] [MainThread]: Parsing macros/cross_db_utils/width_bucket.sql 2022-07-29 13:53:37 normalization > 13:53:32.728958 [debug] [MainThread]: Parsing macros/jinja_helpers/log_info.sql 2022-07-29 13:53:37 normalization > 13:53:32.730540 [debug] [MainThread]: Parsing macros/jinja_helpers/pretty_log_format.sql 2022-07-29 13:53:37 normalization > 13:53:32.732137 [debug] [MainThread]: Parsing macros/jinja_helpers/pretty_time.sql 2022-07-29 13:53:37 normalization > 13:53:32.733879 [debug] [MainThread]: Parsing macros/jinja_helpers/slugify.sql 2022-07-29 13:53:37 normalization > 13:53:32.735589 [debug] [MainThread]: Parsing macros/materializations/insert_by_period_materialization.sql 2022-07-29 13:53:37 normalization > 13:53:32.770231 [debug] [MainThread]: Parsing macros/schema_tests/accepted_range.sql 2022-07-29 13:53:37 normalization > 13:53:32.773887 [debug] [MainThread]: Parsing macros/schema_tests/at_least_one.sql 2022-07-29 13:53:37 normalization > 13:53:32.775683 [debug] [MainThread]: Parsing macros/schema_tests/cardinality_equality.sql 2022-07-29 13:53:37 normalization > 13:53:32.778566 [debug] [MainThread]: Parsing macros/schema_tests/equal_rowcount.sql 2022-07-29 13:53:37 normalization > 13:53:32.780916 [debug] [MainThread]: Parsing macros/schema_tests/equality.sql 2022-07-29 13:53:37 normalization > 13:53:32.786132 [debug] [MainThread]: Parsing macros/schema_tests/expression_is_true.sql 2022-07-29 13:53:37 normalization > 13:53:32.788747 [debug] [MainThread]: Parsing macros/schema_tests/fewer_rows_than.sql 2022-07-29 13:53:37 normalization > 13:53:32.790987 [debug] [MainThread]: Parsing macros/schema_tests/mutually_exclusive_ranges.sql 2022-07-29 13:53:37 normalization > 13:53:32.802603 [debug] [MainThread]: Parsing macros/schema_tests/not_accepted_values.sql 2022-07-29 13:53:37 normalization > 13:53:32.805637 [debug] [MainThread]: Parsing macros/schema_tests/not_constant.sql 2022-07-29 13:53:37 normalization > 13:53:32.807419 [debug] [MainThread]: Parsing macros/schema_tests/not_null_proportion.sql 2022-07-29 13:53:37 normalization > 13:53:32.810584 [debug] [MainThread]: Parsing macros/schema_tests/recency.sql 2022-07-29 13:53:37 normalization > 13:53:32.813107 [debug] [MainThread]: Parsing macros/schema_tests/relationships_where.sql 2022-07-29 13:53:37 normalization > 13:53:32.816247 [debug] [MainThread]: Parsing macros/schema_tests/sequential_values.sql 2022-07-29 13:53:37 normalization > 13:53:32.820330 [debug] [MainThread]: Parsing macros/schema_tests/test_not_null_where.sql 2022-07-29 13:53:37 normalization > 13:53:32.822368 [debug] [MainThread]: Parsing macros/schema_tests/test_unique_where.sql 2022-07-29 13:53:37 normalization > 13:53:32.824387 [debug] [MainThread]: Parsing macros/schema_tests/unique_combination_of_columns.sql 2022-07-29 13:53:37 normalization > 13:53:32.828565 [debug] [MainThread]: Parsing macros/sql/date_spine.sql 2022-07-29 13:53:37 normalization > 13:53:32.834577 [debug] [MainThread]: Parsing macros/sql/generate_series.sql 2022-07-29 13:53:37 normalization > 13:53:32.840632 [debug] [MainThread]: Parsing macros/sql/get_column_values.sql 2022-07-29 13:53:37 normalization > 13:53:32.848131 [debug] [MainThread]: Parsing macros/sql/get_query_results_as_dict.sql 2022-07-29 13:53:37 normalization > 13:53:32.851493 [debug] [MainThread]: Parsing macros/sql/get_relations_by_pattern.sql 2022-07-29 13:53:37 normalization > 13:53:32.856504 [debug] [MainThread]: Parsing macros/sql/get_relations_by_prefix.sql 2022-07-29 13:53:37 normalization > 13:53:32.861622 [debug] [MainThread]: Parsing macros/sql/get_table_types_sql.sql 2022-07-29 13:53:37 normalization > 13:53:32.863396 [debug] [MainThread]: Parsing macros/sql/get_tables_by_pattern_sql.sql 2022-07-29 13:53:37 normalization > 13:53:32.872625 [debug] [MainThread]: Parsing macros/sql/get_tables_by_prefix_sql.sql 2022-07-29 13:53:37 normalization > 13:53:32.875532 [debug] [MainThread]: Parsing macros/sql/groupby.sql 2022-07-29 13:53:37 normalization > 13:53:32.877715 [debug] [MainThread]: Parsing macros/sql/haversine_distance.sql 2022-07-29 13:53:37 normalization > 13:53:32.886329 [debug] [MainThread]: Parsing macros/sql/nullcheck.sql 2022-07-29 13:53:37 normalization > 13:53:32.888878 [debug] [MainThread]: Parsing macros/sql/nullcheck_table.sql 2022-07-29 13:53:37 normalization > 13:53:32.891212 [debug] [MainThread]: Parsing macros/sql/pivot.sql 2022-07-29 13:53:37 normalization > 13:53:32.897350 [debug] [MainThread]: Parsing macros/sql/safe_add.sql 2022-07-29 13:53:37 normalization > 13:53:32.899769 [debug] [MainThread]: Parsing macros/sql/star.sql 2022-07-29 13:53:37 normalization > 13:53:32.906106 [debug] [MainThread]: Parsing macros/sql/surrogate_key.sql 2022-07-29 13:53:37 normalization > 13:53:32.911141 [debug] [MainThread]: Parsing macros/sql/union.sql 2022-07-29 13:53:37 normalization > 13:53:32.925723 [debug] [MainThread]: Parsing macros/sql/unpivot.sql 2022-07-29 13:53:37 normalization > 13:53:32.936887 [debug] [MainThread]: Parsing macros/web/get_url_host.sql 2022-07-29 13:53:37 normalization > 13:53:32.939685 [debug] [MainThread]: Parsing macros/web/get_url_parameter.sql 2022-07-29 13:53:37 normalization > 13:53:32.941943 [debug] [MainThread]: Parsing macros/web/get_url_path.sql 2022-07-29 13:53:37 normalization > 13:53:33.583965 [debug] [MainThread]: 1603: static parser failed on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab1.sql 2022-07-29 13:53:37 normalization > 13:53:33.636134 [debug] [MainThread]: 1602: parser fallback to jinja rendering on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab1.sql 2022-07-29 13:53:37 normalization > 13:53:33.638315 [debug] [MainThread]: 1603: static parser failed on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab2.sql 2022-07-29 13:53:37 normalization > 13:53:33.663740 [debug] [MainThread]: 1602: parser fallback to jinja rendering on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab2.sql 2022-07-29 13:53:37 normalization > 13:53:33.665553 [debug] [MainThread]: 1603: static parser failed on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab3.sql 2022-07-29 13:53:37 normalization > 13:53:33.702739 [debug] [MainThread]: 1602: parser fallback to jinja rendering on generated/airbyte_ctes/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages_ab3.sql 2022-07-29 13:53:37 normalization > 13:53:33.704526 [debug] [MainThread]: 1603: static parser failed on generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql 2022-07-29 13:53:37 normalization > 13:53:33.715207 [debug] [MainThread]: 1602: parser fallback to jinja rendering on generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql 2022-07-29 13:53:37 normalization > 13:53:33.804343 [warn ] [MainThread]: [WARNING]: Configuration paths exist in your dbt_project.yml file which do not apply to any resources. 2022-07-29 13:53:37 normalization > There are 2 unused configuration paths: 2022-07-29 13:53:37 normalization > - models.airbyte_utils.generated.airbyte_views 2022-07-29 13:53:37 normalization > - models.airbyte_utils.generated.airbyte_incremental 2022-07-29 13:53:37 normalization > 2022-07-29 13:53:37 normalization > 13:53:33.824726 [info ] [MainThread]: Found 4 models, 0 tests, 0 snapshots, 0 analyses, 580 macros, 0 operations, 0 seed files, 1 source, 0 exposures, 0 metrics 2022-07-29 13:53:37 normalization > 13:53:33.826237 [info ] [MainThread]: 2022-07-29 13:53:37 normalization > 13:53:33.826819 [debug] [MainThread]: Acquiring new sqlserver connection "master" 2022-07-29 13:53:37 normalization > 13:53:33.827737 [debug] [ThreadPool]: Acquiring new sqlserver connection "list_AIRBYTE_ELT" 2022-07-29 13:53:37 normalization > 13:53:33.883022 [debug] [ThreadPool]: Opening a new connection, currently in state init 2022-07-29 13:53:37 normalization > 13:53:33.905042 [debug] [ThreadPool]: On list_AIRBYTE_ELT: Close 2022-07-29 13:53:37 normalization > 13:53:33.906515 [debug] [ThreadPool]: Acquiring new sqlserver connection "list_AIRBYTE_ELT_airbyte_elt" 2022-07-29 13:53:37 normalization > 13:53:33.914736 [debug] [ThreadPool]: Opening a new connection, currently in state closed 2022-07-29 13:53:37 normalization > 13:53:33.932886 [debug] [ThreadPool]: On list_AIRBYTE_ELT_airbyte_elt: ROLLBACK 2022-07-29 13:53:37 normalization > 13:53:33.933170 [debug] [ThreadPool]: On list_AIRBYTE_ELT_airbyte_elt: Close 2022-07-29 13:53:37 normalization > 13:53:33.934142 [debug] [MainThread]: On master: COMMIT 2022-07-29 13:53:37 normalization > 13:53:33.934721 [info ] [MainThread]: Concurrency: 8 threads (target='prod') 2022-07-29 13:53:37 normalization > 13:53:33.935065 [info ] [MainThread]: 2022-07-29 13:53:37 normalization > 13:53:33.953418 [debug] [Thread-1 ]: Began running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1 2022-07-29 13:53:37 normalization > 13:53:33.954048 [debug] [Thread-1 ]: Acquiring new sqlserver connection "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1" 2022-07-29 13:53:37 normalization > 13:53:33.954225 [debug] [Thread-1 ]: Began compiling node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1 2022-07-29 13:53:37 normalization > 13:53:33.954470 [debug] [Thread-1 ]: Compiling model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1 2022-07-29 13:53:37 normalization > 13:53:33.984356 [debug] [Thread-1 ]: Writing injected SQL for node "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1" 2022-07-29 13:53:37 normalization > 13:53:33.985037 [debug] [Thread-1 ]: finished collecting timing info 2022-07-29 13:53:37 normalization > 13:53:33.985501 [debug] [Thread-1 ]: Finished running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1 2022-07-29 13:53:37 normalization > 13:53:33.986260 [debug] [Thread-3 ]: Began running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2 2022-07-29 13:53:37 normalization > 13:53:33.986672 [debug] [Thread-3 ]: Acquiring new sqlserver connection "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2" 2022-07-29 13:53:37 normalization > 13:53:33.986848 [debug] [Thread-3 ]: Began compiling node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2 2022-07-29 13:53:37 normalization > 13:53:33.987011 [debug] [Thread-3 ]: Compiling model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2 2022-07-29 13:53:37 normalization > 13:53:34.041882 [debug] [Thread-3 ]: Writing injected SQL for node "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2" 2022-07-29 13:53:37 normalization > 13:53:34.042353 [debug] [Thread-3 ]: finished collecting timing info 2022-07-29 13:53:37 normalization > 13:53:34.042838 [debug] [Thread-3 ]: Finished running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2 2022-07-29 13:53:37 normalization > 13:53:34.043538 [debug] [Thread-5 ]: Began running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3 2022-07-29 13:53:37 normalization > 13:53:34.043983 [debug] [Thread-5 ]: Acquiring new sqlserver connection "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3" 2022-07-29 13:53:37 normalization > 13:53:34.044181 [debug] [Thread-5 ]: Began compiling node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3 2022-07-29 13:53:37 normalization > 13:53:34.044474 [debug] [Thread-5 ]: Compiling model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3 2022-07-29 13:53:37 normalization > 13:53:34.103883 [debug] [Thread-5 ]: Writing injected SQL for node "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3" 2022-07-29 13:53:37 normalization > 13:53:34.104359 [debug] [Thread-5 ]: finished collecting timing info 2022-07-29 13:53:37 normalization > 13:53:34.104866 [debug] [Thread-5 ]: Finished running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3 2022-07-29 13:53:37 normalization > 13:53:34.105655 [debug] [Thread-7 ]: Began running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:53:37 normalization > 13:53:34.105969 [info ] [Thread-7 ]: 1 of 1 START table model airbyte_elt.elt_dwd_de_annual_sun__ion_regional_averages....................................... [RUN] 2022-07-29 13:53:37 normalization > 13:53:34.106543 [debug] [Thread-7 ]: Acquiring new sqlserver connection "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:37 normalization > 13:53:34.106704 [debug] [Thread-7 ]: Began compiling node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:53:37 normalization > 13:53:34.106884 [debug] [Thread-7 ]: Compiling model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:53:37 normalization > 13:53:34.125766 [debug] [Thread-7 ]: Writing injected SQL for node "model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:37 normalization > 13:53:34.126333 [debug] [Thread-7 ]: finished collecting timing info 2022-07-29 13:53:37 normalization > 13:53:34.126511 [debug] [Thread-7 ]: Began executing node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:53:37 normalization > 13:53:34.146366 [debug] [Thread-7 ]: finished collecting timing info 2022-07-29 13:53:37 normalization > 13:53:34.146823 [debug] [Thread-7 ]: Compilation Error in model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:53:37 normalization > When searching for a relation, dbt found an approximate match. Instead of guessing 2022-07-29 13:53:37 normalization > which relation to use, dbt will move on. Please delete "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages", or rename it to be less ambiguous. 2022-07-29 13:53:37 normalization > Searched for: "AIRBYTE_ELT"."airbyte_elt"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:37 normalization > Found: "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:37 normalization > 2022-07-29 13:53:37 normalization > > in macro materialization_table_default (macros/materializations/models/table/table.sql) 2022-07-29 13:53:37 normalization > > called by model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:53:37 normalization > 13:53:34.147219 [error] [Thread-7 ]: 1 of 1 ERROR creating table model airbyte_elt.elt_dwd_de_annual_sun__ion_regional_averages.............................. [ERROR in 0.04s] 2022-07-29 13:53:37 normalization > 13:53:34.147631 [debug] [Thread-7 ]: Finished running node model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages 2022-07-29 13:53:37 normalization > 13:53:34.149347 [debug] [MainThread]: Acquiring new sqlserver connection "master" 2022-07-29 13:53:37 normalization > 13:53:34.149569 [debug] [MainThread]: On master: COMMIT 2022-07-29 13:53:37 normalization > 13:53:34.149933 [info ] [MainThread]: 2022-07-29 13:53:37 normalization > 13:53:34.150232 [info ] [MainThread]: Finished running 1 table model in 0.32s. 2022-07-29 13:53:37 normalization > 13:53:34.150562 [debug] [MainThread]: Connection 'master' was properly closed. 2022-07-29 13:53:37 normalization > 13:53:34.150713 [debug] [MainThread]: Connection 'model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab1' was properly closed. 2022-07-29 13:53:37 normalization > 13:53:34.150874 [debug] [MainThread]: Connection 'model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab2' was properly closed. 2022-07-29 13:53:37 normalization > 13:53:34.151014 [debug] [MainThread]: Connection 'model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages_ab3' was properly closed. 2022-07-29 13:53:37 normalization > 13:53:34.151151 [debug] [MainThread]: Connection 'model.airbyte_utils.elt_dwd_de_annual_sun__ion_regional_averages' was properly closed. 2022-07-29 13:53:37 normalization > 13:53:34.167276 [info ] [MainThread]: 2022-07-29 13:53:37 normalization > 13:53:34.167805 [info ] [MainThread]: Completed with 1 error and 0 warnings: 2022-07-29 13:53:37 normalization > 13:53:34.168199 [info ] [MainThread]: 2022-07-29 13:53:37 normalization > 13:53:34.168645 [error] [MainThread]: Compilation Error in model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:53:37 normalization > 13:53:34.168983 [error] [MainThread]: When searching for a relation, dbt found an approximate match. Instead of guessing 2022-07-29 13:53:37 normalization > 13:53:34.169245 [error] [MainThread]: which relation to use, dbt will move on. Please delete "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages", or rename it to be less ambiguous. 2022-07-29 13:53:37 normalization > 13:53:34.169503 [error] [MainThread]: Searched for: "AIRBYTE_ELT"."airbyte_elt"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:37 normalization > 13:53:34.169766 [error] [MainThread]: Found: "AIRBYTE_ELT"."AIRBYTE_ELT"."elt_dwd_de_annual_sun__ion_regional_averages" 2022-07-29 13:53:37 normalization > 13:53:34.170034 [error] [MainThread]: 2022-07-29 13:53:37 normalization > 13:53:34.170278 [error] [MainThread]: > in macro materialization_table_default (macros/materializations/models/table/table.sql) 2022-07-29 13:53:37 normalization > 13:53:34.170524 [error] [MainThread]: > called by model elt_dwd_de_annual_sun__ion_regional_averages (models/generated/airbyte_tables/airbyte_elt/elt_dwd_de_annual_sun__ion_regional_averages.sql) 2022-07-29 13:53:37 normalization > 13:53:34.170798 [info ] [MainThread]: 2022-07-29 13:53:37 normalization > 13:53:34.171062 [info ] [MainThread]: Done. PASS=0 WARN=0 ERROR=1 SKIP=0 TOTAL=1 2022-07-29 13:53:37 INFO i.a.w.t.TemporalAttemptExecution(lambda$getWorkerThread$2):158 - Completing future exceptionally... io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:64) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] Caused by: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:61) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 3 more Suppressed: io.airbyte.workers.exception.WorkerException: Normalization process wasn't successful at io.airbyte.workers.normalization.DefaultNormalizationRunner.close(DefaultNormalizationRunner.java:162) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:49) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] 2022-07-29 13:53:37 INFO i.a.w.t.TemporalAttemptExecution(get):131 - Stopping cancellation check scheduling... 2022-07-29 13:53:37 INFO i.a.w.t.TemporalUtils(withBackgroundHeartbeat):291 - Stopping temporal heartbeating... 2022-07-29 13:53:37 WARN i.t.i.a.POJOActivityTaskHandler(activityFailureToResult):307 - Activity failure. ActivityId=a07d8d50-e615-3393-8e6b-0a44ac64ed0d, activityType=Normalize, attempt=1 java.lang.RuntimeException: io.temporal.serviceclient.CheckedExceptionWrapper: java.util.concurrent.ExecutionException: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.temporal.TemporalUtils.withBackgroundHeartbeat(TemporalUtils.java:289) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.sync.NormalizationActivityImpl.normalize(NormalizationActivityImpl.java:75) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:104) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:578) ~[?:?] at io.temporal.internal.activity.POJOActivityTaskHandler$POJOActivityInboundCallsInterceptor.execute(POJOActivityTaskHandler.java:214) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.activity.POJOActivityTaskHandler$POJOActivityImplementation.execute(POJOActivityTaskHandler.java:180) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.activity.POJOActivityTaskHandler.handle(POJOActivityTaskHandler.java:120) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.worker.ActivityWorker$TaskHandlerImpl.handle(ActivityWorker.java:204) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.worker.ActivityWorker$TaskHandlerImpl.handle(ActivityWorker.java:164) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.internal.worker.PollTaskExecutor.lambda$process$0(PollTaskExecutor.java:93) ~[temporal-sdk-1.8.1.jar:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) [?:?] at java.lang.Thread.run(Thread.java:1589) [?:?] Caused by: io.temporal.serviceclient.CheckedExceptionWrapper: java.util.concurrent.ExecutionException: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.temporal.serviceclient.CheckedExceptionWrapper.wrap(CheckedExceptionWrapper.java:56) ~[temporal-serviceclient-1.8.1.jar:?] at io.temporal.internal.sync.WorkflowInternal.wrap(WorkflowInternal.java:448) ~[temporal-sdk-1.8.1.jar:?] at io.temporal.activity.Activity.wrap(Activity.java:51) ~[temporal-sdk-1.8.1.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.get(TemporalAttemptExecution.java:135) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.sync.NormalizationActivityImpl.lambda$normalize$3(NormalizationActivityImpl.java:103) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalUtils.withBackgroundHeartbeat(TemporalUtils.java:284) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 12 more Caused by: java.util.concurrent.ExecutionException: io.airbyte.workers.exception.WorkerException: Normalization Failed. at java.util.concurrent.CompletableFuture.reportGet(CompletableFuture.java:396) ~[?:?] at java.util.concurrent.CompletableFuture.get(CompletableFuture.java:2073) ~[?:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.get(TemporalAttemptExecution.java:129) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.sync.NormalizationActivityImpl.lambda$normalize$3(NormalizationActivityImpl.java:103) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalUtils.withBackgroundHeartbeat(TemporalUtils.java:284) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 12 more Caused by: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:64) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 1 more Caused by: io.airbyte.workers.exception.WorkerException: Normalization Failed. at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:61) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] ... 1 more Suppressed: io.airbyte.workers.exception.WorkerException: Normalization process wasn't successful at io.airbyte.workers.normalization.DefaultNormalizationRunner.close(DefaultNormalizationRunner.java:162) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:49) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.general.DefaultNormalizationWorker.run(DefaultNormalizationWorker.java:21) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:155) ~[io.airbyte-airbyte-workers-0.39.41-alpha.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?]