2022-10-07 12:41:52 INFO i.a.v.j.JsonSchemaValidator(test):71 - JSON schema validation failed. errors: $.personal_access_token: object found, string expected 2022-10-07 12:41:52 INFO i.a.v.j.JsonSchemaValidator(test):71 - JSON schema validation failed. errors: $.client_id: is missing but it is required, $.client_secret: is missing but it is required, $.refresh_token: is missing but it is required, $.option_title: must be a constant value OAuth Credentials 2022-10-07 12:42:29 destination > 2022-10-07 12:42:24 INFO i.a.i.d.r.RedshiftDestination(main):63 - starting destination: class io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:42:29 source > Starting syncing SourceAsana 2022-10-07 12:42:29 source > Syncing stream: custom_fields 2022-10-07 12:42:29 destination > 2022-10-07 12:42:24 INFO i.a.i.b.IntegrationCliParser(parseOptions):118 - integration args: {catalog=destination_catalog.json, write=null, config=destination_config.json} 2022-10-07 12:42:29 destination > 2022-10-07 12:42:24 INFO i.a.i.b.IntegrationRunner(runInternal):104 - Running integration: io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:42:29 destination > 2022-10-07 12:42:24 INFO i.a.i.b.IntegrationRunner(runInternal):105 - Command: WRITE 2022-10-07 12:42:29 destination > 2022-10-07 12:42:24 INFO i.a.i.b.IntegrationRunner(runInternal):106 - Integration config: IntegrationConfig{command=WRITE, configPath='destination_config.json', catalogPath='destination_catalog.json', statePath='null'} 2022-10-07 12:42:29 destination > 2022-10-07 12:42:25 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-10-07 12:42:29 destination > 2022-10-07 12:42:25 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-10-07 12:42:29 destination > 2022-10-07 12:42:25 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-10-07 12:42:29 destination > 2022-10-07 12:42:25 WARN i.a.i.d.r.RedshiftDestination(determineUploadMode):54 - The "standard" upload mode is not performant, and is not recommended for production. Please use the Amazon S3 upload mode if you are syncing a large amount of data. 2022-10-07 12:42:29 destination > 2022-10-07 12:42:25 INFO i.a.i.d.j.c.SwitchingDestination(getConsumer):65 - Using destination type: STANDARD 2022-10-07 12:42:29 destination > 2022-10-07 12:42:25 INFO c.z.h.HikariDataSource():80 - HikariPool-1 - Starting... 2022-10-07 12:42:29 destination > 2022-10-07 12:42:25 INFO c.z.h.HikariDataSource():82 - HikariPool-1 - Start completed. 2022-10-07 12:42:29 destination > 2022-10-07 12:42:25 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$toWriteConfig$0):98 - Write config: WriteConfig{streamName=custom_fields, namespace=null, outputSchemaName=airbyte_latest_data, tmpTableName=_airbyte_tmp_dad_custom_fields, outputTableName=_airbyte_raw_custom_fields, syncMode=overwrite} 2022-10-07 12:42:29 destination > 2022-10-07 12:42:25 INFO i.a.i.d.b.BufferedStreamConsumer(startTracked):116 - class io.airbyte.integrations.destination.buffered_stream_consumer.BufferedStreamConsumer started. 2022-10-07 12:42:29 destination > 2022-10-07 12:42:25 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):123 - Preparing tmp tables in destination started for 1 streams 2022-10-07 12:42:29 destination > 2022-10-07 12:42:25 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):127 - Preparing tmp table in destination started for stream custom_fields. schema: airbyte_latest_data, tmp table name: _airbyte_tmp_dad_custom_fields 2022-10-07 12:42:29 destination > 2022-10-07 12:42:26 INFO c.z.h.p.PoolBase(getAndSetNetworkTimeout):536 - HikariPool-1 - Driver does not support get/set network timeout for connections. ([Amazon][JDBC](10220) Driver does not support this optional feature.) 2022-10-07 12:42:29 destination > 2022-10-07 12:42:26 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):133 - Preparing tables in destination completed. 2022-10-07 12:41:52 INFO i.a.w.t.TemporalAttemptExecution(get):108 - Cloud storage job log path: /workspace/854/0/logs.log 2022-10-07 12:41:52 INFO i.a.w.t.TemporalAttemptExecution(get):111 - Executing worker wrapper. Airbyte version: 0.40.6 2022-10-07 12:41:53 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:41:53 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- START CHECK ----- 2022-10-07 12:41:53 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:41:53 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = source-asana-check-854-0-sscgg for airbyte/source-asana:0.1.4 with resources io.airbyte.config.ResourceRequirements@9f6d799[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:41:53 INFO i.a.w.p.KubeProcessFactory(create):103 - source-asana-check-854-0-sscgg stdoutLocalPort = 9016 2022-10-07 12:41:53 INFO i.a.w.p.KubeProcessFactory(create):106 - source-asana-check-854-0-sscgg stderrLocalPort = 9017 2022-10-07 12:41:53 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:41:53 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:41:53 INFO i.a.w.p.KubePodProcess():520 - Creating pod source-asana-check-854-0-sscgg... 2022-10-07 12:41:53 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:41:53 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:41:55 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:41:55 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:41:55 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: source_config.json 2022-10-07 12:41:55 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/086f9dd6-161b-4716-92b2-64129bc6bee2/source_config.json dev/source-asana-check-854-0-sscgg:/config/source_config.json -c init 2022-10-07 12:41:55 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:41:56 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:41:56 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:41:56 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/b3ee1d9d-0f69-4a92-b03b-e67100c27b8c/FINISHED_UPLOADING dev/source-asana-check-854-0-sscgg:/config/FINISHED_UPLOADING -c init 2022-10-07 12:41:56 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:41:56 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:41:56 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:41:57 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:41:57 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:41:58 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:41:58 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.81 2022-10-07 12:41:58 INFO i.a.w.p.KubePodProcess():579 - Using null stdin output stream... 2022-10-07 12:41:59 INFO i.a.w.i.DefaultAirbyteStreamFactory(internalLog):103 - Check succeeded 2022-10-07 12:42:00 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / source-asana-check-854-0-sscgg) - Closed all resources for pod 2022-10-07 12:42:00 INFO i.a.w.t.TemporalAttemptExecution(get):132 - Stopping cancellation check scheduling... 2022-10-07 12:42:00 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:42:00 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- END CHECK ----- 2022-10-07 12:42:00 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:42:00 INFO i.a.w.t.TemporalAttemptExecution(get):108 - Cloud storage job log path: /workspace/854/0/logs.log 2022-10-07 12:42:00 INFO i.a.w.t.TemporalAttemptExecution(get):111 - Executing worker wrapper. Airbyte version: 0.40.6 2022-10-07 12:42:00 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = destination-redshift-check-854-0-adtmo for airbyte/destination-redshift:0.3.49 with resources io.airbyte.config.ResourceRequirements@9f6d799[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:42:00 INFO i.a.w.p.KubeProcessFactory(create):103 - destination-redshift-check-854-0-adtmo stdoutLocalPort = 9030 2022-10-07 12:42:00 INFO i.a.w.p.KubeProcessFactory(create):106 - destination-redshift-check-854-0-adtmo stderrLocalPort = 9031 2022-10-07 12:42:00 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:42:00 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- START CHECK ----- 2022-10-07 12:42:00 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:42:00 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:42:00 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:42:00 INFO i.a.w.p.KubePodProcess():520 - Creating pod destination-redshift-check-854-0-adtmo... 2022-10-07 12:42:00 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:42:00 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:42:03 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:42:03 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:42:03 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: source_config.json 2022-10-07 12:42:03 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/b08e4930-0c8b-4792-9478-c96728d1bd24/source_config.json dev/destination-redshift-check-854-0-adtmo:/config/source_config.json -c init 2022-10-07 12:42:03 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:42:04 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:42:04 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:42:04 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/0a3fdba3-2f75-4d06-b057-4177e99fd14b/FINISHED_UPLOADING dev/destination-redshift-check-854-0-adtmo:/config/FINISHED_UPLOADING -c init 2022-10-07 12:42:04 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:42:04 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:42:04 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:42:06 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:42:06 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:42:07 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:42:07 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.69 2022-10-07 12:42:07 INFO i.a.w.p.KubePodProcess():579 - Using null stdin output stream... 2022-10-07 12:42:10 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:10 INFO i.a.i.d.r.RedshiftDestination(main):63 - starting destination: class io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:42:10 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:10 INFO i.a.i.b.IntegrationCliParser(parseOptions):118 - integration args: {check=null, config=source_config.json} 2022-10-07 12:42:10 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:10 INFO i.a.i.b.IntegrationRunner(runInternal):104 - Running integration: io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:42:10 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:10 INFO i.a.i.b.IntegrationRunner(runInternal):105 - Command: CHECK 2022-10-07 12:42:11 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:10 INFO i.a.i.b.IntegrationRunner(runInternal):106 - Integration config: IntegrationConfig{command=CHECK, configPath='source_config.json', catalogPath='null', statePath='null'} 2022-10-07 12:42:11 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:11 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-10-07 12:42:11 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:11 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-10-07 12:42:11 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:11 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-10-07 12:42:11 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:11 WARN i.a.i.d.r.RedshiftDestination(determineUploadMode):54 - The "standard" upload mode is not performant, and is not recommended for production. Please use the Amazon S3 upload mode if you are syncing a large amount of data. 2022-10-07 12:42:11 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:11 INFO i.a.i.d.j.c.SwitchingDestination(check):55 - Using destination type: STANDARD 2022-10-07 12:42:12 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:12 INFO c.z.h.HikariDataSource():80 - HikariPool-1 - Starting... 2022-10-07 12:42:12 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:12 INFO c.z.h.HikariDataSource():82 - HikariPool-1 - Start completed. 2022-10-07 12:42:13 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:13 INFO c.z.h.p.PoolBase(getAndSetNetworkTimeout):536 - HikariPool-1 - Driver does not support get/set network timeout for connections. ([Amazon][JDBC](10220) Driver does not support this optional feature.) 2022-10-07 12:42:14 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:14 INFO c.z.h.HikariDataSource(close):350 - HikariPool-1 - Shutdown initiated... 2022-10-07 12:42:14 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:14 INFO c.z.h.HikariDataSource(close):352 - HikariPool-1 - Shutdown completed. 2022-10-07 12:42:14 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:14 INFO i.a.i.b.IntegrationRunner(runInternal):152 - Completed integration: io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:42:14 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:42:14 INFO i.a.i.d.r.RedshiftDestination(main):65 - completed destination: class io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:42:16 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / destination-redshift-check-854-0-adtmo) - Closed all resources for pod 2022-10-07 12:42:16 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:42:16 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- END CHECK ----- 2022-10-07 12:42:16 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:42:16 INFO i.a.w.t.TemporalAttemptExecution(get):132 - Stopping cancellation check scheduling... 2022-10-07 12:42:16 INFO i.a.w.t.TemporalAttemptExecution(get):108 - Cloud storage job log path: /workspace/854/0/logs.log 2022-10-07 12:42:16 INFO i.a.w.t.TemporalAttemptExecution(get):111 - Executing worker wrapper. Airbyte version: 0.40.6 2022-10-07 12:42:16 INFO i.a.c.f.EnvVariableFeatureFlags(getEnvOrDefault):49 - Using default value for environment variable LOG_CONNECTOR_MESSAGES: 'false' 2022-10-07 12:42:16 INFO i.a.c.EnvConfigs(getEnvOrDefault):1096 - Using default value for environment variable METRIC_CLIENT: '' 2022-10-07 12:42:16 WARN i.a.m.l.MetricClientFactory(initialize):60 - Metric client is already initialized to 2022-10-07 12:42:16 INFO i.a.c.f.EnvVariableFeatureFlags(getEnvOrDefault):49 - Using default value for environment variable LOG_CONNECTOR_MESSAGES: 'false' 2022-10-07 12:42:16 INFO i.a.w.g.DefaultReplicationWorker(run):122 - start sync worker. job id: 854 attempt id: 0 2022-10-07 12:42:16 INFO i.a.w.g.DefaultReplicationWorker(run):135 - configured sync modes: {null.custom_fields=full_refresh - overwrite} 2022-10-07 12:42:16 INFO i.a.w.i.DefaultAirbyteDestination(start):69 - Running destination... 2022-10-07 12:42:16 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = destination-redshift-write-854-0-meyqk for airbyte/destination-redshift:0.3.49 with resources io.airbyte.config.ResourceRequirements@12f96c6a[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:42:16 INFO i.a.w.p.KubeProcessFactory(create):103 - destination-redshift-write-854-0-meyqk stdoutLocalPort = 9012 2022-10-07 12:42:16 INFO i.a.w.p.KubeProcessFactory(create):106 - destination-redshift-write-854-0-meyqk stderrLocalPort = 9013 2022-10-07 12:42:16 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:42:16 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- START REPLICATION ----- 2022-10-07 12:42:16 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:42:16 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:42:16 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:42:16 INFO i.a.w.p.KubePodProcess():520 - Creating pod destination-redshift-write-854-0-meyqk... 2022-10-07 12:42:17 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:42:17 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:42:19 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:42:19 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:42:19 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: destination_config.json 2022-10-07 12:42:19 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/388e2ad2-8da7-4cc2-8b44-7a7737611b68/destination_config.json dev/destination-redshift-write-854-0-meyqk:/config/destination_config.json -c init 2022-10-07 12:42:19 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:42:19 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:42:19 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: destination_catalog.json 2022-10-07 12:42:19 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/d9b39b36-cbc9-42dc-b653-efe2362951e8/destination_catalog.json dev/destination-redshift-write-854-0-meyqk:/config/destination_catalog.json -c init 2022-10-07 12:42:19 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:42:20 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:42:20 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:42:20 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/922721da-7e19-44bd-8f57-a181b31a7508/FINISHED_UPLOADING dev/destination-redshift-write-854-0-meyqk:/config/FINISHED_UPLOADING -c init 2022-10-07 12:42:20 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:42:20 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:42:20 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:42:22 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:42:22 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:42:23 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:42:23 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.74 2022-10-07 12:42:23 INFO i.a.w.p.KubePodProcess():575 - Creating stdin socket... 2022-10-07 12:42:23 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = source-asana-read-854-0-eyxis for airbyte/source-asana:0.1.4 with resources io.airbyte.config.ResourceRequirements@6336cee7[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:42:23 INFO i.a.w.p.KubeProcessFactory(create):103 - source-asana-read-854-0-eyxis stdoutLocalPort = 9010 2022-10-07 12:42:23 INFO i.a.w.p.KubeProcessFactory(create):106 - source-asana-read-854-0-eyxis stderrLocalPort = 9011 2022-10-07 12:42:23 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:42:23 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:42:23 INFO i.a.w.p.KubePodProcess():520 - Creating pod source-asana-read-854-0-eyxis... 2022-10-07 12:42:23 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:42:23 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:42:26 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:42:26 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:42:26 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: source_config.json 2022-10-07 12:42:26 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/83e5ba48-c801-43a1-b587-64e8c658be33/source_config.json dev/source-asana-read-854-0-eyxis:/config/source_config.json -c init 2022-10-07 12:42:26 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:42:26 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:42:26 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: source_catalog.json 2022-10-07 12:42:26 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/a200ed84-6838-4517-b9db-11982519a32a/source_catalog.json dev/source-asana-read-854-0-eyxis:/config/source_catalog.json -c init 2022-10-07 12:42:26 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:42:27 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:42:27 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:42:27 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/77aefd68-d574-4411-b956-cb7709feb187/FINISHED_UPLOADING dev/source-asana-read-854-0-eyxis:/config/FINISHED_UPLOADING -c init 2022-10-07 12:42:27 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:42:27 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:42:27 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:42:28 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:42:28 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:42:29 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:42:29 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.68 2022-10-07 12:42:29 INFO i.a.w.p.KubePodProcess():579 - Using null stdin output stream... 2022-10-07 12:42:29 INFO i.a.w.g.DefaultReplicationWorker(lambda$getDestinationOutputRunnable$7):435 - Destination output thread started. 2022-10-07 12:42:29 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):322 - Replication thread started. 2022-10-07 12:42:29 INFO i.a.w.g.DefaultReplicationWorker(run):177 - Waiting for source and destination threads to complete. 2022-10-07 12:42:30 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):355 - Source has no more messages, closing connection. 2022-10-07 12:42:31 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / source-asana-read-854-0-eyxis) - Closed all resources for pod 2022-10-07 12:42:33 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / destination-redshift-write-854-0-meyqk) - Closed all resources for pod 2022-10-07 12:42:33 ERROR i.a.w.g.DefaultReplicationWorker(run):188 - Sync worker failed. java.util.concurrent.ExecutionException: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped! at java.util.concurrent.CompletableFuture.reportGet(CompletableFuture.java:396) ~[?:?] at java.util.concurrent.CompletableFuture.get(CompletableFuture.java:2073) ~[?:?] at io.airbyte.workers.general.DefaultReplicationWorker.run(DefaultReplicationWorker.java:181) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.general.DefaultReplicationWorker.run(DefaultReplicationWorker.java:68) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:159) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] Suppressed: io.airbyte.workers.exception.WorkerException: Source process exit with code 1. This warning is normal if the job was cancelled. at io.airbyte.workers.internal.DefaultAirbyteSource.close(DefaultAirbyteSource.java:140) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.general.DefaultReplicationWorker.run(DefaultReplicationWorker.java:145) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.general.DefaultReplicationWorker.run(DefaultReplicationWorker.java:68) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:159) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] Caused by: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped! at io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:359) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804) ~[?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) ~[?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) ~[?:?] ... 1 more Caused by: io.airbyte.workers.exception.WorkerException: Source process exit with code 1. This warning is normal if the job was cancelled. at io.airbyte.workers.internal.DefaultAirbyteSource.close(DefaultAirbyteSource.java:140) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:357) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804) ~[?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) ~[?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) ~[?:?] ... 1 more 2022-10-07 12:42:33 INFO i.a.w.g.DefaultReplicationWorker(run):281 - Source did not output any state messages 2022-10-07 12:42:33 WARN i.a.w.g.DefaultReplicationWorker(run):292 - State capture: No state retained. 2022-10-07 12:42:33 INFO i.a.w.g.DefaultReplicationWorker(run):300 - sync summary: { "status" : "failed", "recordsSynced" : 0, "bytesSynced" : 0, "startTime" : 1665146536933, "endTime" : 1665146553579, "totalStats" : { "recordsEmitted" : 0, "bytesEmitted" : 0, "sourceStateMessagesEmitted" : 0, "destinationStateMessagesEmitted" : 0, "recordsCommitted" : 0, "meanSecondsBeforeSourceStateMessageEmitted" : 0, "maxSecondsBeforeSourceStateMessageEmitted" : 0, "maxSecondsBetweenStateMessageEmittedandCommitted" : 0, "meanSecondsBetweenStateMessageEmittedandCommitted" : 0 }, "streamStats" : [ ] } 2022-10-07 12:42:33 INFO i.a.w.g.DefaultReplicationWorker(run):301 - failures: [ { "failureOrigin" : "source", "failureType" : "system_error", "internalMessage" : "402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype", "externalMessage" : "Custom Fields are not available for free users or guests.", "metadata" : { "attemptNumber" : 0, "jobId" : 854, "from_trace_message" : true, "connector_command" : "read" }, "stacktrace" : "Traceback (most recent call last):\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py\", line 114, in read\n yield from self._read_stream(\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py\", line 179, in _read_stream\n for record in record_iterator:\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py\", line 277, in _read_full_refresh\n for record in records:\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py\", line 421, in read_records\n response = self._send_request(request, request_kwargs)\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py\", line 339, in _send_request\n return backoff_handler(user_backoff_handler)(request, request_kwargs)\n File \"/usr/local/lib/python3.9/site-packages/backoff/_sync.py\", line 105, in retry\n ret = target(*args, **kwargs)\n File \"/usr/local/lib/python3.9/site-packages/backoff/_sync.py\", line 105, in retry\n ret = target(*args, **kwargs)\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py\", line 306, in _send\n raise exc\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py\", line 303, in _send\n response.raise_for_status()\n File \"/usr/local/lib/python3.9/site-packages/requests/models.py\", line 1021, in raise_for_status\n raise HTTPError(http_error_msg, response=self)\nrequests.exceptions.HTTPError: 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype\n", "timestamp" : 1665146550376 }, { "failureOrigin" : "source", "internalMessage" : "io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped!", "externalMessage" : "Something went wrong within the source connector", "metadata" : { "attemptNumber" : 0, "jobId" : 854, "connector_command" : "read" }, "stacktrace" : "java.util.concurrent.CompletionException: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped!\n\tat java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:315)\n\tat java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:320)\n\tat java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1807)\n\tat java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)\n\tat java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)\n\tat java.base/java.lang.Thread.run(Thread.java:1589)\nCaused by: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped!\n\tat io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:359)\n\tat java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804)\n\t... 3 more\nCaused by: io.airbyte.workers.exception.WorkerException: Source process exit with code 1. This warning is normal if the job was cancelled.\n\tat io.airbyte.workers.internal.DefaultAirbyteSource.close(DefaultAirbyteSource.java:140)\n\tat io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:357)\n\t... 4 more\n", "timestamp" : 1665146551115 } ] 2022-10-07 12:42:33 INFO i.a.w.t.TemporalAttemptExecution(get):132 - Stopping cancellation check scheduling... 2022-10-07 12:42:33 INFO i.a.w.t.s.ReplicationActivityImpl(lambda$replicate$3):166 - sync summary: io.airbyte.config.StandardSyncOutput@21bc414f[standardSyncSummary=io.airbyte.config.StandardSyncSummary@19a0e007[status=failed,recordsSynced=0,bytesSynced=0,startTime=1665146536933,endTime=1665146553579,totalStats=io.airbyte.config.SyncStats@c13e673[recordsEmitted=0,bytesEmitted=0,sourceStateMessagesEmitted=0,destinationStateMessagesEmitted=0,recordsCommitted=0,meanSecondsBeforeSourceStateMessageEmitted=0,maxSecondsBeforeSourceStateMessageEmitted=0,maxSecondsBetweenStateMessageEmittedandCommitted=0,meanSecondsBetweenStateMessageEmittedandCommitted=0,additionalProperties={}],streamStats=[]],normalizationSummary=,state=,outputCatalog=io.airbyte.protocol.models.ConfiguredAirbyteCatalog@825e57a[streams=[io.airbyte.protocol.models.ConfiguredAirbyteStream@4b506b08[stream=io.airbyte.protocol.models.AirbyteStream@55ee0abd[name=custom_fields,jsonSchema={"type":["null","object"],"properties":{"gid":{"type":["null","string"]},"type":{"type":["null","string"]},"format":{"type":["null","string"]},"enabled":{"type":["null","boolean"]},"precision":{"type":["null","integer"]},"created_by":{"type":["null","object"],"properties":{"gid":{"type":["null","string"]},"name":{"type":["null","string"]},"resource_type":{"type":["null","string"]}}},"enum_value":{"type":["null","object"],"properties":{"gid":{"type":["null","string"]},"name":{"type":["null","string"]},"color":{"type":["null","string"]},"enabled":{"type":["null","boolean"]},"resource_type":{"type":["null","string"]}}},"text_value":{"type":["null","string"]},"description":{"type":["null","string"]},"custom_label":{"type":["null","string"]},"enum_options":{"type":["null","array"],"items":{"type":["null","object"],"properties":{"gid":{"type":["null","string"]},"name":{"type":["null","string"]},"color":{"type":["null","string"]},"enabled":{"type":["null","boolean"]},"resource_type":{"type":["null","string"]}}}},"number_value":{"type":["null","number"]},"currency_code":{"type":["null","string"]},"display_value":{"type":["null","string"]},"resource_type":{"type":["null","string"]},"resource_subtype":{"type":["null","string"]},"custom_label_position":{"type":["null","string"]},"is_global_to_workspace":{"type":["null","boolean"]},"has_notifications_enabled":{"type":["null","boolean"]}}},supportedSyncModes=[full_refresh],sourceDefinedCursor=,defaultCursorField=[],sourceDefinedPrimaryKey=[[gid]],namespace=,additionalProperties={}],syncMode=full_refresh,cursorField=[],destinationSyncMode=overwrite,primaryKey=[[gid]],additionalProperties={}]],additionalProperties={}],failures=[io.airbyte.config.FailureReason@46dd4e0d[failureOrigin=source,failureType=system_error,internalMessage=402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype,externalMessage=Custom Fields are not available for free users or guests.,metadata=io.airbyte.config.Metadata@61f46d99[additionalProperties={attemptNumber=0, jobId=854, from_trace_message=true, connector_command=read}],stacktrace=Traceback (most recent call last): File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 114, in read yield from self._read_stream( File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 179, in _read_stream for record in record_iterator: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 277, in _read_full_refresh for record in records: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 421, in read_records response = self._send_request(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 339, in _send_request return backoff_handler(user_backoff_handler)(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 306, in _send raise exc File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 303, in _send response.raise_for_status() File "/usr/local/lib/python3.9/site-packages/requests/models.py", line 1021, in raise_for_status raise HTTPError(http_error_msg, response=self) requests.exceptions.HTTPError: 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype ,retryable=,timestamp=1665146550376], io.airbyte.config.FailureReason@29b45284[failureOrigin=source,failureType=,internalMessage=io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped!,externalMessage=Something went wrong within the source connector,metadata=io.airbyte.config.Metadata@caa1eed[additionalProperties={attemptNumber=0, jobId=854, connector_command=read}],stacktrace=java.util.concurrent.CompletionException: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped! at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:315) at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:320) at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1807) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) at java.base/java.lang.Thread.run(Thread.java:1589) Caused by: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped! at io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:359) at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804) ... 3 more Caused by: io.airbyte.workers.exception.WorkerException: Source process exit with code 1. This warning is normal if the job was cancelled. at io.airbyte.workers.internal.DefaultAirbyteSource.close(DefaultAirbyteSource.java:140) at io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:357) ... 4 more ,retryable=,timestamp=1665146551115]]] 2022-10-07 12:42:33 INFO i.a.w.t.TemporalUtils(withBackgroundHeartbeat):312 - Stopping temporal heartbeating... 2022-10-07 12:42:33 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:42:33 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- END REPLICATION ----- 2022-10-07 12:42:33 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:42:33 INFO i.a.w.t.TemporalAttemptExecution(get):108 - Cloud storage job log path: /workspace/854/0/logs.log 2022-10-07 12:42:33 INFO i.a.w.t.TemporalAttemptExecution(get):111 - Executing worker wrapper. Airbyte version: 0.40.6 2022-10-07 12:42:33 INFO i.a.w.n.DefaultNormalizationRunner(runProcess):138 - Running with normalization version: airbyte/normalization-redshift:0.2.21 2022-10-07 12:42:33 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = normalization-redshift-normalize-854-0-zuqzn for airbyte/normalization-redshift:0.2.21 with resources io.airbyte.config.ResourceRequirements@3d92a682[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:42:33 INFO i.a.w.p.KubeProcessFactory(create):103 - normalization-redshift-normalize-854-0-zuqzn stdoutLocalPort = 9036 2022-10-07 12:42:33 INFO i.a.w.p.KubeProcessFactory(create):106 - normalization-redshift-normalize-854-0-zuqzn stderrLocalPort = 9037 2022-10-07 12:42:33 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:42:33 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- START DEFAULT NORMALIZATION ----- 2022-10-07 12:42:33 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:42:33 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:42:33 INFO i.a.w.p.KubePodProcess():520 - Creating pod normalization-redshift-normalize-854-0-zuqzn... 2022-10-07 12:42:33 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:42:33 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:42:34 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:42:36 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:42:36 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:42:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: destination_config.json 2022-10-07 12:42:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/42580ba5-65d7-450b-a137-03308778249a/destination_config.json dev/normalization-redshift-normalize-854-0-zuqzn:/config/destination_config.json -c init 2022-10-07 12:42:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:42:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:42:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: destination_catalog.json 2022-10-07 12:42:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/6364b452-021c-4e75-b17e-1d2b2ec4eb7b/destination_catalog.json dev/normalization-redshift-normalize-854-0-zuqzn:/config/destination_catalog.json -c init 2022-10-07 12:42:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:42:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:42:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:42:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/7f1a8570-26a9-4a13-a42c-2bf5f3f924da/FINISHED_UPLOADING dev/normalization-redshift-normalize-854-0-zuqzn:/config/FINISHED_UPLOADING -c init 2022-10-07 12:42:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:42:37 INFO i.a.w.p.KubePodProcess(waitForInitPodToTerminate):327 - Waiting for init container to terminate before checking exit value... 2022-10-07 12:42:38 INFO i.a.w.p.KubePodProcess(waitForInitPodToTerminate):332 - Init container terminated with exit value 0. 2022-10-07 12:42:38 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):277 - Init was successful; ignoring non-zero kubectl cp exit code for success indicator file. 2022-10-07 12:42:38 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:42:38 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:42:38 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:42:38 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:42:39 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:42:39 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.67 2022-10-07 12:42:39 INFO i.a.w.p.KubePodProcess():579 - Using null stdin output stream... 2022-10-07 12:42:42 INFO i.a.w.n.NormalizationAirbyteStreamFactory(filterOutAndHandleNonAirbyteMessageLines):104 - 2022-10-07 12:42:42 INFO i.a.w.n.NormalizationAirbyteStreamFactory(filterOutAndHandleNonAirbyteMessageLines):104 - 2022-10-07 12:42:30 source > {"errors":[{"message":"Custom Fields are not available for free users or guests.","user_message":"Custom Fields are not available for free users or guests.","help":"For more information on API status codes and how to handle them, read the docs on errors: https://asana.com/developers/documentation/getting-started/errors"}]} 2022-10-07 12:42:30 source > Encountered an exception while reading stream custom_fields Traceback (most recent call last): File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 114, in read yield from self._read_stream( File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 179, in _read_stream for record in record_iterator: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 277, in _read_full_refresh for record in records: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 421, in read_records response = self._send_request(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 339, in _send_request return backoff_handler(user_backoff_handler)(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 306, in _send raise exc File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 303, in _send response.raise_for_status() File "/usr/local/lib/python3.9/site-packages/requests/models.py", line 1021, in raise_for_status raise HTTPError(http_error_msg, response=self) requests.exceptions.HTTPError: 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype 2022-10-07 12:42:30 source > Finished syncing custom_fields 2022-10-07 12:42:30 source > SourceAsana runtimes: Syncing stream custom_fields 0:00:00.879802 2022-10-07 12:42:30 source > 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype Traceback (most recent call last): File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 114, in read yield from self._read_stream( File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 179, in _read_stream for record in record_iterator: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 277, in _read_full_refresh for record in records: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 421, in read_records response = self._send_request(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 339, in _send_request return backoff_handler(user_backoff_handler)(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 306, in _send raise exc File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 303, in _send response.raise_for_status() File "/usr/local/lib/python3.9/site-packages/requests/models.py", line 1021, in raise_for_status raise HTTPError(http_error_msg, response=self) requests.exceptions.HTTPError: 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype The above exception was the direct cause of the following exception: Traceback (most recent call last): File "/airbyte/integration_code/main.py", line 13, in launch(source, sys.argv[1:]) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/entrypoint.py", line 123, in launch for message in source_entrypoint.run(parsed_args): File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/entrypoint.py", line 114, in run for message in generator: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 127, in read raise AirbyteTracedException.from_exception(e, message=display_message) from e airbyte_cdk.utils.traced_exception.AirbyteTracedException: 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype 2022-10-07 12:42:31 destination > 2022-10-07 12:42:31 INFO i.a.i.b.FailureTrackingAirbyteMessageConsumer(close):62 - Airbyte message consumer: succeeded. 2022-10-07 12:42:31 destination > 2022-10-07 12:42:31 INFO i.a.i.d.b.BufferedStreamConsumer(close):171 - executing on success close procedure. 2022-10-07 12:42:31 destination > 2022-10-07 12:42:31 INFO i.a.i.d.r.o.RedshiftSqlOperations(onDestinationCloseOperations):139 - Executing operations for Redshift Destination DB engine... 2022-10-07 12:42:31 destination > 2022-10-07 12:42:31 INFO i.a.i.d.r.o.RedshiftSqlOperations(discoverNotSuperTables):189 - Discovering NOT SUPER table types... 2022-10-07 12:42:31 destination > 2022-10-07 12:42:31 INFO i.a.i.d.r.o.RedshiftSqlOperations(onDestinationCloseOperations):157 - Executing operations for Redshift Destination DB engine completed. 2022-10-07 12:42:31 destination > 2022-10-07 12:42:31 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):163 - Finalizing tables in destination started for 1 streams 2022-10-07 12:42:31 destination > 2022-10-07 12:42:31 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):168 - Finalizing stream custom_fields. schema airbyte_latest_data, tmp table _airbyte_tmp_dad_custom_fields, final table _airbyte_raw_custom_fields 2022-10-07 12:42:31 destination > 2022-10-07 12:42:31 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):181 - Executing finalization of tables. 2022-10-07 12:42:32 destination > 2022-10-07 12:42:32 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):183 - Finalizing tables in destination completed. 2022-10-07 12:42:32 destination > 2022-10-07 12:42:32 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):186 - Cleaning tmp tables in destination started for 1 streams 2022-10-07 12:42:32 destination > 2022-10-07 12:42:32 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):190 - Cleaning tmp table in destination started for stream custom_fields. schema airbyte_latest_data, tmp table name: _airbyte_tmp_dad_custom_fields 2022-10-07 12:42:32 destination > 2022-10-07 12:42:32 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):195 - Cleaning tmp tables in destination completed. 2022-10-07 12:42:32 destination > 2022-10-07 12:42:32 INFO i.a.i.b.IntegrationRunner(runInternal):152 - Completed integration: io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:42:32 destination > 2022-10-07 12:42:32 INFO i.a.i.d.r.RedshiftDestination(main):65 - completed destination: class io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:42:39 normalization > Running: transform-config --config destination_config.json --integration-type redshift --out /config 2022-10-07 12:42:39 normalization > Namespace(config='destination_config.json', integration_type=, out='/config') 2022-10-07 12:42:39 normalization > transform_redshift 2022-10-07 12:42:39 normalization > Running: transform-catalog --integration-type redshift --profile-config-dir /config --catalog destination_catalog.json --out /config/models/generated/ --json-column _airbyte_data 2022-10-07 12:42:40 normalization > Processing destination_catalog.json... 2022-10-07 12:42:40 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_ab1.sql from custom_fields 2022-10-07 12:42:40 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_ab2.sql from custom_fields 2022-10-07 12:42:40 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_ab3.sql from custom_fields 2022-10-07 12:42:40 normalization > Generating airbyte_tables/airbyte_latest_data/custom_fields.sql from custom_fields 2022-10-07 12:42:40 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_created_by_ab1.sql from custom_fields/created_by 2022-10-07 12:42:40 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_created_by_ab2.sql from custom_fields/created_by 2022-10-07 12:42:40 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_created_by_ab3.sql from custom_fields/created_by 2022-10-07 12:42:40 normalization > Generating airbyte_tables/airbyte_latest_data/custom_fields_created_by.sql from custom_fields/created_by 2022-10-07 12:42:40 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_enum_value_ab1.sql from custom_fields/enum_value 2022-10-07 12:42:40 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_enum_value_ab2.sql from custom_fields/enum_value 2022-10-07 12:42:40 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_enum_value_ab3.sql from custom_fields/enum_value 2022-10-07 12:42:40 normalization > Generating airbyte_tables/airbyte_latest_data/custom_fields_enum_value.sql from custom_fields/enum_value 2022-10-07 12:42:40 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_enum_options_ab1.sql from custom_fields/enum_options 2022-10-07 12:42:40 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_enum_options_ab2.sql from custom_fields/enum_options 2022-10-07 12:42:40 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_enum_options_ab3.sql from custom_fields/enum_options 2022-10-07 12:42:40 normalization > Generating airbyte_tables/airbyte_latest_data/custom_fields_enum_options.sql from custom_fields/enum_options 2022-10-07 12:42:40 normalization > detected no config file for ssh, assuming ssh is off. 2022-10-07 12:42:42 normalization > [--event-buffer-size EVENT_BUFFER_SIZE] 2022-10-07 12:42:42 normalization > --event-buffer-size EVENT_BUFFER_SIZE 2022-10-07 12:42:42 normalization > DBT >=1.0.0 detected; using 10K event buffer size 2022-10-07 12:42:45 normalization > Running with dbt=1.0.0 2022-10-07 12:42:45 normalization > Partial parse save file not found. Starting full parse. 2022-10-07 12:42:47 normalization > [WARNING]: Configuration paths exist in your dbt_project.yml file which do not apply to any resources. There are 2 unused configuration paths: - models.airbyte_utils.generated.airbyte_views - models.airbyte_utils.generated.airbyte_incremental 2022-10-07 12:42:47 normalization > Found 16 models, 0 tests, 0 snapshots, 0 analyses, 605 macros, 0 operations, 0 seed files, 1 source, 0 exposures, 0 metrics 2022-10-07 12:42:48 normalization > Concurrency: 4 threads (target='prod') 2022-10-07 12:42:55 normalization > 1 of 4 START table model airbyte_latest_data.custom_fields.............................................................. [RUN] 2022-10-07 12:42:56 normalization > 1 of 4 OK created table model airbyte_latest_data.custom_fields......................................................... [SELECT in 1.13s] 2022-10-07 12:42:59 normalization > 2 of 4 START table model airbyte_latest_data.custom_fields_created_by................................................... [RUN] 2022-10-07 12:43:00 normalization > 2 of 4 OK created table model airbyte_latest_data.custom_fields_created_by.............................................. [SELECT in 0.86s] 2022-10-07 12:43:02 normalization > 3 of 4 START table model airbyte_latest_data.custom_fields_enum_value................................................... [RUN] 2022-10-07 12:43:02 normalization > 4 of 4 START table model airbyte_latest_data.custom_fields_enum_options................................................. [RUN] 2022-10-07 12:43:03 normalization > 4 of 4 OK created table model airbyte_latest_data.custom_fields_enum_options............................................ [SELECT in 0.87s] 2022-10-07 12:43:03 normalization > 3 of 4 OK created table model airbyte_latest_data.custom_fields_enum_value.............................................. [SELECT in 1.15s] 2022-10-07 12:43:03 normalization > Finished running 4 table models in 16.00s. 2022-10-07 12:43:03 normalization > Completed successfully 2022-10-07 12:43:03 normalization > Done. PASS=4 WARN=0 ERROR=0 SKIP=0 TOTAL=4 2022-10-07 12:43:05 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / normalization-redshift-normalize-854-0-zuqzn) - Closed all resources for pod 2022-10-07 12:43:05 INFO i.a.w.g.DefaultNormalizationWorker(run):82 - Normalization executed in 31 seconds. 2022-10-07 12:43:05 INFO i.a.w.g.DefaultNormalizationWorker(run):95 - Normalization summary: io.airbyte.config.NormalizationSummary@6563eed8[startTime=1665146553849,endTime=1665146585264,failures=[]] 2022-10-07 12:43:05 INFO i.a.w.t.TemporalAttemptExecution(get):132 - Stopping cancellation check scheduling... 2022-10-07 12:43:05 INFO i.a.w.t.TemporalUtils(withBackgroundHeartbeat):312 - Stopping temporal heartbeating... 2022-10-07 12:43:05 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:05 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- END DEFAULT NORMALIZATION ----- 2022-10-07 12:43:05 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:06 INFO i.a.v.j.JsonSchemaValidator(test):71 - JSON schema validation failed. errors: $.personal_access_token: object found, string expected 2022-10-07 12:43:37 source > Starting syncing SourceAsana 2022-10-07 12:43:37 destination > 2022-10-07 12:43:33 INFO i.a.i.d.r.RedshiftDestination(main):63 - starting destination: class io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:43:38 destination > 2022-10-07 12:43:33 INFO i.a.i.b.IntegrationCliParser(parseOptions):118 - integration args: {catalog=destination_catalog.json, write=null, config=destination_config.json} 2022-10-07 12:43:38 destination > 2022-10-07 12:43:33 INFO i.a.i.b.IntegrationRunner(runInternal):104 - Running integration: io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:43:38 destination > 2022-10-07 12:43:33 INFO i.a.i.b.IntegrationRunner(runInternal):105 - Command: WRITE 2022-10-07 12:43:38 destination > 2022-10-07 12:43:33 INFO i.a.i.b.IntegrationRunner(runInternal):106 - Integration config: IntegrationConfig{command=WRITE, configPath='destination_config.json', catalogPath='destination_catalog.json', statePath='null'} 2022-10-07 12:43:38 destination > 2022-10-07 12:43:34 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-10-07 12:43:38 destination > 2022-10-07 12:43:34 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-10-07 12:43:38 destination > 2022-10-07 12:43:34 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-10-07 12:43:38 destination > 2022-10-07 12:43:34 WARN i.a.i.d.r.RedshiftDestination(determineUploadMode):54 - The "standard" upload mode is not performant, and is not recommended for production. Please use the Amazon S3 upload mode if you are syncing a large amount of data. 2022-10-07 12:43:38 source > Syncing stream: custom_fields 2022-10-07 12:43:38 destination > 2022-10-07 12:43:34 INFO i.a.i.d.j.c.SwitchingDestination(getConsumer):65 - Using destination type: STANDARD 2022-10-07 12:43:38 destination > 2022-10-07 12:43:34 INFO c.z.h.HikariDataSource():80 - HikariPool-1 - Starting... 2022-10-07 12:43:06 INFO i.a.v.j.JsonSchemaValidator(test):71 - JSON schema validation failed. errors: $.client_id: is missing but it is required, $.client_secret: is missing but it is required, $.refresh_token: is missing but it is required, $.option_title: must be a constant value OAuth Credentials 2022-10-07 12:43:06 INFO i.a.w.t.TemporalAttemptExecution(get):108 - Cloud storage job log path: /workspace/854/1/logs.log 2022-10-07 12:43:06 INFO i.a.w.t.TemporalAttemptExecution(get):111 - Executing worker wrapper. Airbyte version: 0.40.6 2022-10-07 12:43:06 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:06 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = source-asana-check-854-1-gxxsq for airbyte/source-asana:0.1.4 with resources io.airbyte.config.ResourceRequirements@9f6d799[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:43:06 INFO i.a.w.p.KubeProcessFactory(create):103 - source-asana-check-854-1-gxxsq stdoutLocalPort = 9026 2022-10-07 12:43:06 INFO i.a.w.p.KubeProcessFactory(create):106 - source-asana-check-854-1-gxxsq stderrLocalPort = 9027 2022-10-07 12:43:06 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- START CHECK ----- 2022-10-07 12:43:06 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:06 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:43:06 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:43:06 INFO i.a.w.p.KubePodProcess():520 - Creating pod source-asana-check-854-1-gxxsq... 2022-10-07 12:43:06 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:43:06 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:43:07 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:43:07 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:43:07 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: source_config.json 2022-10-07 12:43:07 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/cd15c46c-7ba1-4328-a350-fe1900035ec4/source_config.json dev/source-asana-check-854-1-gxxsq:/config/source_config.json -c init 2022-10-07 12:43:07 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:43:08 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:43:08 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:43:08 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/573fabf4-7848-474c-842c-0f2bbe61ce48/FINISHED_UPLOADING dev/source-asana-check-854-1-gxxsq:/config/FINISHED_UPLOADING -c init 2022-10-07 12:43:08 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:43:08 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:43:08 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:43:10 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:43:10 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:43:11 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:43:11 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.81 2022-10-07 12:43:11 INFO i.a.w.p.KubePodProcess():579 - Using null stdin output stream... 2022-10-07 12:43:14 INFO i.a.w.i.DefaultAirbyteStreamFactory(internalLog):103 - Check succeeded 2022-10-07 12:43:16 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / source-asana-check-854-1-gxxsq) - Closed all resources for pod 2022-10-07 12:43:16 INFO i.a.w.t.TemporalAttemptExecution(get):132 - Stopping cancellation check scheduling... 2022-10-07 12:43:16 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:16 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- END CHECK ----- 2022-10-07 12:43:16 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:16 INFO i.a.w.t.TemporalAttemptExecution(get):108 - Cloud storage job log path: /workspace/854/1/logs.log 2022-10-07 12:43:16 INFO i.a.w.t.TemporalAttemptExecution(get):111 - Executing worker wrapper. Airbyte version: 0.40.6 2022-10-07 12:43:16 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = destination-redshift-check-854-1-eockb for airbyte/destination-redshift:0.3.49 with resources io.airbyte.config.ResourceRequirements@9f6d799[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:43:16 INFO i.a.w.p.KubeProcessFactory(create):103 - destination-redshift-check-854-1-eockb stdoutLocalPort = 9018 2022-10-07 12:43:16 INFO i.a.w.p.KubeProcessFactory(create):106 - destination-redshift-check-854-1-eockb stderrLocalPort = 9020 2022-10-07 12:43:16 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:16 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- START CHECK ----- 2022-10-07 12:43:16 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:16 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:43:16 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:43:16 INFO i.a.w.p.KubePodProcess():520 - Creating pod destination-redshift-check-854-1-eockb... 2022-10-07 12:43:16 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:43:16 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:43:18 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:43:18 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:43:18 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: source_config.json 2022-10-07 12:43:18 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/1837dad4-966f-4634-8da5-8697f5e285d1/source_config.json dev/destination-redshift-check-854-1-eockb:/config/source_config.json -c init 2022-10-07 12:43:18 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:43:19 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:43:19 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:43:19 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/88cf7cd5-d54f-4cc9-b4b6-42bad2d82588/FINISHED_UPLOADING dev/destination-redshift-check-854-1-eockb:/config/FINISHED_UPLOADING -c init 2022-10-07 12:43:19 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:43:19 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:43:19 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:43:21 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:43:21 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:43:22 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:43:22 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.68 2022-10-07 12:43:22 INFO i.a.w.p.KubePodProcess():579 - Using null stdin output stream... 2022-10-07 12:43:23 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:23 INFO i.a.i.d.r.RedshiftDestination(main):63 - starting destination: class io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:43:23 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:23 INFO i.a.i.b.IntegrationCliParser(parseOptions):118 - integration args: {check=null, config=source_config.json} 2022-10-07 12:43:23 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:23 INFO i.a.i.b.IntegrationRunner(runInternal):104 - Running integration: io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:43:23 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:23 INFO i.a.i.b.IntegrationRunner(runInternal):105 - Command: CHECK 2022-10-07 12:43:23 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:23 INFO i.a.i.b.IntegrationRunner(runInternal):106 - Integration config: IntegrationConfig{command=CHECK, configPath='source_config.json', catalogPath='null', statePath='null'} 2022-10-07 12:43:23 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:23 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-10-07 12:43:23 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:23 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-10-07 12:43:23 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:23 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-10-07 12:43:23 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:23 WARN i.a.i.d.r.RedshiftDestination(determineUploadMode):54 - The "standard" upload mode is not performant, and is not recommended for production. Please use the Amazon S3 upload mode if you are syncing a large amount of data. 2022-10-07 12:43:23 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:23 INFO i.a.i.d.j.c.SwitchingDestination(check):55 - Using destination type: STANDARD 2022-10-07 12:43:23 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:23 INFO c.z.h.HikariDataSource():80 - HikariPool-1 - Starting... 2022-10-07 12:43:23 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:23 INFO c.z.h.HikariDataSource():82 - HikariPool-1 - Start completed. 2022-10-07 12:43:24 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:24 INFO c.z.h.p.PoolBase(getAndSetNetworkTimeout):536 - HikariPool-1 - Driver does not support get/set network timeout for connections. ([Amazon][JDBC](10220) Driver does not support this optional feature.) 2022-10-07 12:43:24 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:24 INFO c.z.h.HikariDataSource(close):350 - HikariPool-1 - Shutdown initiated... 2022-10-07 12:43:24 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:24 INFO c.z.h.HikariDataSource(close):352 - HikariPool-1 - Shutdown completed. 2022-10-07 12:43:25 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:25 INFO i.a.i.b.IntegrationRunner(runInternal):152 - Completed integration: io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:43:25 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:43:25 INFO i.a.i.d.r.RedshiftDestination(main):65 - completed destination: class io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:43:26 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / destination-redshift-check-854-1-eockb) - Closed all resources for pod 2022-10-07 12:43:26 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:26 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- END CHECK ----- 2022-10-07 12:43:26 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:26 INFO i.a.w.t.TemporalAttemptExecution(get):132 - Stopping cancellation check scheduling... 2022-10-07 12:43:26 INFO i.a.w.t.TemporalAttemptExecution(get):108 - Cloud storage job log path: /workspace/854/1/logs.log 2022-10-07 12:43:26 INFO i.a.w.t.TemporalAttemptExecution(get):111 - Executing worker wrapper. Airbyte version: 0.40.6 2022-10-07 12:43:26 INFO i.a.c.f.EnvVariableFeatureFlags(getEnvOrDefault):49 - Using default value for environment variable LOG_CONNECTOR_MESSAGES: 'false' 2022-10-07 12:43:26 INFO i.a.c.EnvConfigs(getEnvOrDefault):1096 - Using default value for environment variable METRIC_CLIENT: '' 2022-10-07 12:43:26 WARN i.a.m.l.MetricClientFactory(initialize):60 - Metric client is already initialized to 2022-10-07 12:43:26 INFO i.a.c.f.EnvVariableFeatureFlags(getEnvOrDefault):49 - Using default value for environment variable LOG_CONNECTOR_MESSAGES: 'false' 2022-10-07 12:43:26 INFO i.a.w.g.DefaultReplicationWorker(run):122 - start sync worker. job id: 854 attempt id: 1 2022-10-07 12:43:26 INFO i.a.w.g.DefaultReplicationWorker(run):135 - configured sync modes: {null.custom_fields=full_refresh - overwrite} 2022-10-07 12:43:26 INFO i.a.w.i.DefaultAirbyteDestination(start):69 - Running destination... 2022-10-07 12:43:26 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:26 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- START REPLICATION ----- 2022-10-07 12:43:26 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:26 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = destination-redshift-write-854-1-uyhpw for airbyte/destination-redshift:0.3.49 with resources io.airbyte.config.ResourceRequirements@6af10216[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:43:26 INFO i.a.w.p.KubeProcessFactory(create):103 - destination-redshift-write-854-1-uyhpw stdoutLocalPort = 9034 2022-10-07 12:43:26 INFO i.a.w.p.KubeProcessFactory(create):106 - destination-redshift-write-854-1-uyhpw stderrLocalPort = 9035 2022-10-07 12:43:26 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:43:26 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:43:26 INFO i.a.w.p.KubePodProcess():520 - Creating pod destination-redshift-write-854-1-uyhpw... 2022-10-07 12:43:26 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:43:26 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:43:28 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:43:28 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:43:28 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: destination_config.json 2022-10-07 12:43:28 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/25dac938-5ec6-4cbc-b72c-878fe621fd67/destination_config.json dev/destination-redshift-write-854-1-uyhpw:/config/destination_config.json -c init 2022-10-07 12:43:28 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:43:29 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:43:29 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: destination_catalog.json 2022-10-07 12:43:29 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/5fc5b721-36fa-4320-b9c1-af21c00e2122/destination_catalog.json dev/destination-redshift-write-854-1-uyhpw:/config/destination_catalog.json -c init 2022-10-07 12:43:29 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:43:29 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:43:29 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:43:29 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/a9d370b1-cb3a-4f73-8ec2-6f22074ef0e7/FINISHED_UPLOADING dev/destination-redshift-write-854-1-uyhpw:/config/FINISHED_UPLOADING -c init 2022-10-07 12:43:29 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:43:30 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:43:30 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:43:31 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:43:31 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:43:32 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:43:32 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.69 2022-10-07 12:43:32 INFO i.a.w.p.KubePodProcess():575 - Creating stdin socket... 2022-10-07 12:43:32 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = source-asana-read-854-1-xkqbx for airbyte/source-asana:0.1.4 with resources io.airbyte.config.ResourceRequirements@6fbd79ef[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:43:32 INFO i.a.w.p.KubeProcessFactory(create):103 - source-asana-read-854-1-xkqbx stdoutLocalPort = 9008 2022-10-07 12:43:32 INFO i.a.w.p.KubeProcessFactory(create):106 - source-asana-read-854-1-xkqbx stderrLocalPort = 9009 2022-10-07 12:43:32 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:43:32 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:43:32 INFO i.a.w.p.KubePodProcess():520 - Creating pod source-asana-read-854-1-xkqbx... 2022-10-07 12:43:32 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:43:32 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:43:35 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:43:35 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:43:35 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: source_config.json 2022-10-07 12:43:35 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/58429ea5-4521-4f43-9a53-dc8fa70ca296/source_config.json dev/source-asana-read-854-1-xkqbx:/config/source_config.json -c init 2022-10-07 12:43:35 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:43:35 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:43:35 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: source_catalog.json 2022-10-07 12:43:35 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/fa93beb8-b2cc-4983-a2c1-f370be730e91/source_catalog.json dev/source-asana-read-854-1-xkqbx:/config/source_catalog.json -c init 2022-10-07 12:43:35 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:43:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:43:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:43:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/18565dd7-c9e3-4342-a02a-3846c7cc5dc3/FINISHED_UPLOADING dev/source-asana-read-854-1-xkqbx:/config/FINISHED_UPLOADING -c init 2022-10-07 12:43:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:43:36 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:43:36 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:43:37 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:43:37 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:43:37 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:43:37 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.70 2022-10-07 12:43:37 INFO i.a.w.p.KubePodProcess():579 - Using null stdin output stream... 2022-10-07 12:43:37 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):322 - Replication thread started. 2022-10-07 12:43:37 INFO i.a.w.g.DefaultReplicationWorker(lambda$getDestinationOutputRunnable$7):435 - Destination output thread started. 2022-10-07 12:43:37 INFO i.a.w.g.DefaultReplicationWorker(run):177 - Waiting for source and destination threads to complete. 2022-10-07 12:43:38 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):355 - Source has no more messages, closing connection. 2022-10-07 12:43:39 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / source-asana-read-854-1-xkqbx) - Closed all resources for pod 2022-10-07 12:43:41 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / destination-redshift-write-854-1-uyhpw) - Closed all resources for pod 2022-10-07 12:43:41 ERROR i.a.w.g.DefaultReplicationWorker(run):188 - Sync worker failed. java.util.concurrent.ExecutionException: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped! at java.util.concurrent.CompletableFuture.reportGet(CompletableFuture.java:396) ~[?:?] at java.util.concurrent.CompletableFuture.get(CompletableFuture.java:2073) ~[?:?] at io.airbyte.workers.general.DefaultReplicationWorker.run(DefaultReplicationWorker.java:181) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.general.DefaultReplicationWorker.run(DefaultReplicationWorker.java:68) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:159) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] Suppressed: io.airbyte.workers.exception.WorkerException: Source process exit with code 1. This warning is normal if the job was cancelled. at io.airbyte.workers.internal.DefaultAirbyteSource.close(DefaultAirbyteSource.java:140) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.general.DefaultReplicationWorker.run(DefaultReplicationWorker.java:145) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.general.DefaultReplicationWorker.run(DefaultReplicationWorker.java:68) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:159) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] Caused by: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped! at io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:359) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804) ~[?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) ~[?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) ~[?:?] ... 1 more Caused by: io.airbyte.workers.exception.WorkerException: Source process exit with code 1. This warning is normal if the job was cancelled. at io.airbyte.workers.internal.DefaultAirbyteSource.close(DefaultAirbyteSource.java:140) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:357) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804) ~[?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) ~[?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) ~[?:?] ... 1 more 2022-10-07 12:43:41 INFO i.a.w.g.DefaultReplicationWorker(run):281 - Source did not output any state messages 2022-10-07 12:43:41 WARN i.a.w.g.DefaultReplicationWorker(run):292 - State capture: No state retained. 2022-10-07 12:43:41 INFO i.a.w.g.DefaultReplicationWorker(run):300 - sync summary: { "status" : "failed", "recordsSynced" : 0, "bytesSynced" : 0, "startTime" : 1665146606614, "endTime" : 1665146621528, "totalStats" : { "recordsEmitted" : 0, "bytesEmitted" : 0, "sourceStateMessagesEmitted" : 0, "destinationStateMessagesEmitted" : 0, "recordsCommitted" : 0, "meanSecondsBeforeSourceStateMessageEmitted" : 0, "maxSecondsBeforeSourceStateMessageEmitted" : 0, "maxSecondsBetweenStateMessageEmittedandCommitted" : 0, "meanSecondsBetweenStateMessageEmittedandCommitted" : 0 }, "streamStats" : [ ] } 2022-10-07 12:43:41 INFO i.a.w.g.DefaultReplicationWorker(run):301 - failures: [ { "failureOrigin" : "source", "failureType" : "system_error", "internalMessage" : "402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype", "externalMessage" : "Custom Fields are not available for free users or guests.", "metadata" : { "attemptNumber" : 1, "jobId" : 854, "from_trace_message" : true, "connector_command" : "read" }, "stacktrace" : "Traceback (most recent call last):\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py\", line 114, in read\n yield from self._read_stream(\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py\", line 179, in _read_stream\n for record in record_iterator:\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py\", line 277, in _read_full_refresh\n for record in records:\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py\", line 421, in read_records\n response = self._send_request(request, request_kwargs)\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py\", line 339, in _send_request\n return backoff_handler(user_backoff_handler)(request, request_kwargs)\n File \"/usr/local/lib/python3.9/site-packages/backoff/_sync.py\", line 105, in retry\n ret = target(*args, **kwargs)\n File \"/usr/local/lib/python3.9/site-packages/backoff/_sync.py\", line 105, in retry\n ret = target(*args, **kwargs)\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py\", line 306, in _send\n raise exc\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py\", line 303, in _send\n response.raise_for_status()\n File \"/usr/local/lib/python3.9/site-packages/requests/models.py\", line 1021, in raise_for_status\n raise HTTPError(http_error_msg, response=self)\nrequests.exceptions.HTTPError: 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype\n", "timestamp" : 1665146618342 }, { "failureOrigin" : "source", "internalMessage" : "io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped!", "externalMessage" : "Something went wrong within the source connector", "metadata" : { "attemptNumber" : 1, "jobId" : 854, "connector_command" : "read" }, "stacktrace" : "java.util.concurrent.CompletionException: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped!\n\tat java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:315)\n\tat java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:320)\n\tat java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1807)\n\tat java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)\n\tat java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)\n\tat java.base/java.lang.Thread.run(Thread.java:1589)\nCaused by: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped!\n\tat io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:359)\n\tat java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804)\n\t... 3 more\nCaused by: io.airbyte.workers.exception.WorkerException: Source process exit with code 1. This warning is normal if the job was cancelled.\n\tat io.airbyte.workers.internal.DefaultAirbyteSource.close(DefaultAirbyteSource.java:140)\n\tat io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:357)\n\t... 4 more\n", "timestamp" : 1665146619194 } ] 2022-10-07 12:43:41 INFO i.a.w.t.TemporalAttemptExecution(get):132 - Stopping cancellation check scheduling... 2022-10-07 12:43:41 INFO i.a.w.t.s.ReplicationActivityImpl(lambda$replicate$3):166 - sync summary: io.airbyte.config.StandardSyncOutput@32a5f480[standardSyncSummary=io.airbyte.config.StandardSyncSummary@5864b72b[status=failed,recordsSynced=0,bytesSynced=0,startTime=1665146606614,endTime=1665146621528,totalStats=io.airbyte.config.SyncStats@124877eb[recordsEmitted=0,bytesEmitted=0,sourceStateMessagesEmitted=0,destinationStateMessagesEmitted=0,recordsCommitted=0,meanSecondsBeforeSourceStateMessageEmitted=0,maxSecondsBeforeSourceStateMessageEmitted=0,maxSecondsBetweenStateMessageEmittedandCommitted=0,meanSecondsBetweenStateMessageEmittedandCommitted=0,additionalProperties={}],streamStats=[]],normalizationSummary=,state=,outputCatalog=io.airbyte.protocol.models.ConfiguredAirbyteCatalog@11f5a7de[streams=[io.airbyte.protocol.models.ConfiguredAirbyteStream@c695094[stream=io.airbyte.protocol.models.AirbyteStream@71c973dd[name=custom_fields,jsonSchema={"type":["null","object"],"properties":{"gid":{"type":["null","string"]},"type":{"type":["null","string"]},"format":{"type":["null","string"]},"enabled":{"type":["null","boolean"]},"precision":{"type":["null","integer"]},"created_by":{"type":["null","object"],"properties":{"gid":{"type":["null","string"]},"name":{"type":["null","string"]},"resource_type":{"type":["null","string"]}}},"enum_value":{"type":["null","object"],"properties":{"gid":{"type":["null","string"]},"name":{"type":["null","string"]},"color":{"type":["null","string"]},"enabled":{"type":["null","boolean"]},"resource_type":{"type":["null","string"]}}},"text_value":{"type":["null","string"]},"description":{"type":["null","string"]},"custom_label":{"type":["null","string"]},"enum_options":{"type":["null","array"],"items":{"type":["null","object"],"properties":{"gid":{"type":["null","string"]},"name":{"type":["null","string"]},"color":{"type":["null","string"]},"enabled":{"type":["null","boolean"]},"resource_type":{"type":["null","string"]}}}},"number_value":{"type":["null","number"]},"currency_code":{"type":["null","string"]},"display_value":{"type":["null","string"]},"resource_type":{"type":["null","string"]},"resource_subtype":{"type":["null","string"]},"custom_label_position":{"type":["null","string"]},"is_global_to_workspace":{"type":["null","boolean"]},"has_notifications_enabled":{"type":["null","boolean"]}}},supportedSyncModes=[full_refresh],sourceDefinedCursor=,defaultCursorField=[],sourceDefinedPrimaryKey=[[gid]],namespace=,additionalProperties={}],syncMode=full_refresh,cursorField=[],destinationSyncMode=overwrite,primaryKey=[[gid]],additionalProperties={}]],additionalProperties={}],failures=[io.airbyte.config.FailureReason@20efb520[failureOrigin=source,failureType=system_error,internalMessage=402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype,externalMessage=Custom Fields are not available for free users or guests.,metadata=io.airbyte.config.Metadata@1c982db4[additionalProperties={attemptNumber=1, jobId=854, from_trace_message=true, connector_command=read}],stacktrace=Traceback (most recent call last): File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 114, in read yield from self._read_stream( File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 179, in _read_stream for record in record_iterator: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 277, in _read_full_refresh for record in records: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 421, in read_records response = self._send_request(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 339, in _send_request return backoff_handler(user_backoff_handler)(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 306, in _send raise exc File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 303, in _send response.raise_for_status() File "/usr/local/lib/python3.9/site-packages/requests/models.py", line 1021, in raise_for_status raise HTTPError(http_error_msg, response=self) requests.exceptions.HTTPError: 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype ,retryable=,timestamp=1665146618342], io.airbyte.config.FailureReason@5ab32343[failureOrigin=source,failureType=,internalMessage=io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped!,externalMessage=Something went wrong within the source connector,metadata=io.airbyte.config.Metadata@605ee453[additionalProperties={attemptNumber=1, jobId=854, connector_command=read}],stacktrace=java.util.concurrent.CompletionException: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped! at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:315) at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:320) at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1807) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) at java.base/java.lang.Thread.run(Thread.java:1589) Caused by: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped! at io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:359) at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804) ... 3 more Caused by: io.airbyte.workers.exception.WorkerException: Source process exit with code 1. This warning is normal if the job was cancelled. at io.airbyte.workers.internal.DefaultAirbyteSource.close(DefaultAirbyteSource.java:140) at io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:357) ... 4 more ,retryable=,timestamp=1665146619194]]] 2022-10-07 12:43:41 INFO i.a.w.t.TemporalUtils(withBackgroundHeartbeat):312 - Stopping temporal heartbeating... 2022-10-07 12:43:41 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:41 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- END REPLICATION ----- 2022-10-07 12:43:41 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:41 INFO i.a.w.t.TemporalAttemptExecution(get):108 - Cloud storage job log path: /workspace/854/1/logs.log 2022-10-07 12:43:41 INFO i.a.w.t.TemporalAttemptExecution(get):111 - Executing worker wrapper. Airbyte version: 0.40.6 2022-10-07 12:43:41 INFO i.a.w.n.DefaultNormalizationRunner(runProcess):138 - Running with normalization version: airbyte/normalization-redshift:0.2.21 2022-10-07 12:43:41 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:41 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = normalization-redshift-normalize-854-1-badxd for airbyte/normalization-redshift:0.2.21 with resources io.airbyte.config.ResourceRequirements@2399147e[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:43:41 INFO i.a.w.p.KubeProcessFactory(create):103 - normalization-redshift-normalize-854-1-badxd stdoutLocalPort = 9019 2022-10-07 12:43:41 INFO i.a.w.p.KubeProcessFactory(create):106 - normalization-redshift-normalize-854-1-badxd stderrLocalPort = 9021 2022-10-07 12:43:41 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- START DEFAULT NORMALIZATION ----- 2022-10-07 12:43:41 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:43:41 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:43:41 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:43:41 INFO i.a.w.p.KubePodProcess():520 - Creating pod normalization-redshift-normalize-854-1-badxd... 2022-10-07 12:43:41 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:43:41 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:43:43 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:43:43 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:43:43 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: destination_config.json 2022-10-07 12:43:43 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/3e2dd167-fac4-41e1-8104-8fb90e2837a0/destination_config.json dev/normalization-redshift-normalize-854-1-badxd:/config/destination_config.json -c init 2022-10-07 12:43:44 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:43:44 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:43:44 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: destination_catalog.json 2022-10-07 12:43:44 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/99ce025d-303e-48e6-aa85-7bb45e367aaa/destination_catalog.json dev/normalization-redshift-normalize-854-1-badxd:/config/destination_catalog.json -c init 2022-10-07 12:43:44 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:43:44 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:43:44 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:43:44 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/8d50ba09-4b12-4b56-8f7a-188d0beb13a0/FINISHED_UPLOADING dev/normalization-redshift-normalize-854-1-badxd:/config/FINISHED_UPLOADING -c init 2022-10-07 12:43:44 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:43:45 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:43:45 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:43:46 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:43:46 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:43:47 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:43:47 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.67 2022-10-07 12:43:47 INFO i.a.w.p.KubePodProcess():579 - Using null stdin output stream... 2022-10-07 12:43:50 INFO i.a.w.n.NormalizationAirbyteStreamFactory(filterOutAndHandleNonAirbyteMessageLines):104 - 2022-10-07 12:43:50 INFO i.a.w.n.NormalizationAirbyteStreamFactory(filterOutAndHandleNonAirbyteMessageLines):104 - 2022-10-07 12:43:38 destination > 2022-10-07 12:43:34 INFO c.z.h.HikariDataSource():82 - HikariPool-1 - Start completed. 2022-10-07 12:43:38 destination > 2022-10-07 12:43:34 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$toWriteConfig$0):98 - Write config: WriteConfig{streamName=custom_fields, namespace=null, outputSchemaName=airbyte_latest_data, tmpTableName=_airbyte_tmp_xwm_custom_fields, outputTableName=_airbyte_raw_custom_fields, syncMode=overwrite} 2022-10-07 12:43:38 destination > 2022-10-07 12:43:34 INFO i.a.i.d.b.BufferedStreamConsumer(startTracked):116 - class io.airbyte.integrations.destination.buffered_stream_consumer.BufferedStreamConsumer started. 2022-10-07 12:43:38 destination > 2022-10-07 12:43:34 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):123 - Preparing tmp tables in destination started for 1 streams 2022-10-07 12:43:38 destination > 2022-10-07 12:43:34 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):127 - Preparing tmp table in destination started for stream custom_fields. schema: airbyte_latest_data, tmp table name: _airbyte_tmp_xwm_custom_fields 2022-10-07 12:43:38 destination > 2022-10-07 12:43:35 INFO c.z.h.p.PoolBase(getAndSetNetworkTimeout):536 - HikariPool-1 - Driver does not support get/set network timeout for connections. ([Amazon][JDBC](10220) Driver does not support this optional feature.) 2022-10-07 12:43:38 destination > 2022-10-07 12:43:35 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onStartFunction$1):133 - Preparing tables in destination completed. 2022-10-07 12:43:38 source > {"errors":[{"message":"Custom Fields are not available for free users or guests.","user_message":"Custom Fields are not available for free users or guests.","help":"For more information on API status codes and how to handle them, read the docs on errors: https://asana.com/developers/documentation/getting-started/errors"}]} 2022-10-07 12:43:38 source > Encountered an exception while reading stream custom_fields Traceback (most recent call last): File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 114, in read yield from self._read_stream( File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 179, in _read_stream for record in record_iterator: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 277, in _read_full_refresh for record in records: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 421, in read_records response = self._send_request(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 339, in _send_request return backoff_handler(user_backoff_handler)(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 306, in _send raise exc File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 303, in _send response.raise_for_status() File "/usr/local/lib/python3.9/site-packages/requests/models.py", line 1021, in raise_for_status raise HTTPError(http_error_msg, response=self) requests.exceptions.HTTPError: 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype 2022-10-07 12:43:38 source > Finished syncing custom_fields 2022-10-07 12:43:38 source > SourceAsana runtimes: Syncing stream custom_fields 0:00:00.738748 2022-10-07 12:43:38 source > 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype Traceback (most recent call last): File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 114, in read yield from self._read_stream( File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 179, in _read_stream for record in record_iterator: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 277, in _read_full_refresh for record in records: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 421, in read_records response = self._send_request(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 339, in _send_request return backoff_handler(user_backoff_handler)(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 306, in _send raise exc File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 303, in _send response.raise_for_status() File "/usr/local/lib/python3.9/site-packages/requests/models.py", line 1021, in raise_for_status raise HTTPError(http_error_msg, response=self) requests.exceptions.HTTPError: 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype The above exception was the direct cause of the following exception: Traceback (most recent call last): File "/airbyte/integration_code/main.py", line 13, in launch(source, sys.argv[1:]) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/entrypoint.py", line 123, in launch for message in source_entrypoint.run(parsed_args): File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/entrypoint.py", line 114, in run for message in generator: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 127, in read raise AirbyteTracedException.from_exception(e, message=display_message) from e airbyte_cdk.utils.traced_exception.AirbyteTracedException: 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype 2022-10-07 12:43:39 destination > 2022-10-07 12:43:39 INFO i.a.i.b.FailureTrackingAirbyteMessageConsumer(close):62 - Airbyte message consumer: succeeded. 2022-10-07 12:43:39 destination > 2022-10-07 12:43:39 INFO i.a.i.d.b.BufferedStreamConsumer(close):171 - executing on success close procedure. 2022-10-07 12:43:39 destination > 2022-10-07 12:43:39 INFO i.a.i.d.r.o.RedshiftSqlOperations(onDestinationCloseOperations):139 - Executing operations for Redshift Destination DB engine... 2022-10-07 12:43:39 destination > 2022-10-07 12:43:39 INFO i.a.i.d.r.o.RedshiftSqlOperations(discoverNotSuperTables):189 - Discovering NOT SUPER table types... 2022-10-07 12:43:39 destination > 2022-10-07 12:43:39 INFO i.a.i.d.r.o.RedshiftSqlOperations(onDestinationCloseOperations):157 - Executing operations for Redshift Destination DB engine completed. 2022-10-07 12:43:39 destination > 2022-10-07 12:43:39 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):163 - Finalizing tables in destination started for 1 streams 2022-10-07 12:43:39 destination > 2022-10-07 12:43:39 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):168 - Finalizing stream custom_fields. schema airbyte_latest_data, tmp table _airbyte_tmp_xwm_custom_fields, final table _airbyte_raw_custom_fields 2022-10-07 12:43:40 destination > 2022-10-07 12:43:40 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):181 - Executing finalization of tables. 2022-10-07 12:43:40 destination > 2022-10-07 12:43:40 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):183 - Finalizing tables in destination completed. 2022-10-07 12:43:40 destination > 2022-10-07 12:43:40 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):186 - Cleaning tmp tables in destination started for 1 streams 2022-10-07 12:43:40 destination > 2022-10-07 12:43:40 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):190 - Cleaning tmp table in destination started for stream custom_fields. schema airbyte_latest_data, tmp table name: _airbyte_tmp_xwm_custom_fields 2022-10-07 12:43:40 destination > 2022-10-07 12:43:40 INFO i.a.i.d.j.JdbcBufferedConsumerFactory(lambda$onCloseFunction$3):195 - Cleaning tmp tables in destination completed. 2022-10-07 12:43:40 destination > 2022-10-07 12:43:40 INFO i.a.i.b.IntegrationRunner(runInternal):152 - Completed integration: io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:43:40 destination > 2022-10-07 12:43:40 INFO i.a.i.d.r.RedshiftDestination(main):65 - completed destination: class io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:43:47 normalization > Running: transform-config --config destination_config.json --integration-type redshift --out /config 2022-10-07 12:43:47 normalization > Namespace(config='destination_config.json', integration_type=, out='/config') 2022-10-07 12:43:47 normalization > transform_redshift 2022-10-07 12:43:47 normalization > Running: transform-catalog --integration-type redshift --profile-config-dir /config --catalog destination_catalog.json --out /config/models/generated/ --json-column _airbyte_data 2022-10-07 12:43:48 normalization > Processing destination_catalog.json... 2022-10-07 12:43:48 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_ab1.sql from custom_fields 2022-10-07 12:43:48 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_ab2.sql from custom_fields 2022-10-07 12:43:48 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_ab3.sql from custom_fields 2022-10-07 12:43:48 normalization > Generating airbyte_tables/airbyte_latest_data/custom_fields.sql from custom_fields 2022-10-07 12:43:48 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_created_by_ab1.sql from custom_fields/created_by 2022-10-07 12:43:48 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_created_by_ab2.sql from custom_fields/created_by 2022-10-07 12:43:48 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_created_by_ab3.sql from custom_fields/created_by 2022-10-07 12:43:48 normalization > Generating airbyte_tables/airbyte_latest_data/custom_fields_created_by.sql from custom_fields/created_by 2022-10-07 12:43:48 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_enum_value_ab1.sql from custom_fields/enum_value 2022-10-07 12:43:48 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_enum_value_ab2.sql from custom_fields/enum_value 2022-10-07 12:43:48 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_enum_value_ab3.sql from custom_fields/enum_value 2022-10-07 12:43:48 normalization > Generating airbyte_tables/airbyte_latest_data/custom_fields_enum_value.sql from custom_fields/enum_value 2022-10-07 12:43:48 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_enum_options_ab1.sql from custom_fields/enum_options 2022-10-07 12:43:48 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_enum_options_ab2.sql from custom_fields/enum_options 2022-10-07 12:43:48 normalization > Generating airbyte_ctes/airbyte_latest_data/custom_fields_enum_options_ab3.sql from custom_fields/enum_options 2022-10-07 12:43:48 normalization > Generating airbyte_tables/airbyte_latest_data/custom_fields_enum_options.sql from custom_fields/enum_options 2022-10-07 12:43:48 normalization > detected no config file for ssh, assuming ssh is off. 2022-10-07 12:43:50 normalization > [--event-buffer-size EVENT_BUFFER_SIZE] 2022-10-07 12:43:50 normalization > --event-buffer-size EVENT_BUFFER_SIZE 2022-10-07 12:43:50 normalization > DBT >=1.0.0 detected; using 10K event buffer size 2022-10-07 12:43:53 normalization > Running with dbt=1.0.0 2022-10-07 12:43:53 normalization > Partial parse save file not found. Starting full parse. 2022-10-07 12:43:55 normalization > [WARNING]: Configuration paths exist in your dbt_project.yml file which do not apply to any resources. There are 2 unused configuration paths: - models.airbyte_utils.generated.airbyte_views - models.airbyte_utils.generated.airbyte_incremental 2022-10-07 12:43:55 normalization > Found 16 models, 0 tests, 0 snapshots, 0 analyses, 605 macros, 0 operations, 0 seed files, 1 source, 0 exposures, 0 metrics 2022-10-07 12:43:56 normalization > Concurrency: 4 threads (target='prod') 2022-10-07 12:44:04 normalization > 1 of 4 START table model airbyte_latest_data.custom_fields.............................................................. [RUN] 2022-10-07 12:44:05 normalization > 1 of 4 OK created table model airbyte_latest_data.custom_fields......................................................... [SELECT in 0.77s] 2022-10-07 12:44:08 normalization > 2 of 4 START table model airbyte_latest_data.custom_fields_created_by................................................... [RUN] 2022-10-07 12:44:09 normalization > 2 of 4 OK created table model airbyte_latest_data.custom_fields_created_by.............................................. [SELECT in 1.25s] 2022-10-07 12:44:10 normalization > 3 of 4 START table model airbyte_latest_data.custom_fields_enum_value................................................... [RUN] 2022-10-07 12:44:11 normalization > 4 of 4 START table model airbyte_latest_data.custom_fields_enum_options................................................. [RUN] 2022-10-07 12:44:11 normalization > 3 of 4 OK created table model airbyte_latest_data.custom_fields_enum_value.............................................. [SELECT in 0.84s] 2022-10-07 12:44:11 normalization > 4 of 4 OK created table model airbyte_latest_data.custom_fields_enum_options............................................ [SELECT in 0.74s] 2022-10-07 12:44:11 normalization > Finished running 4 table models in 16.57s. 2022-10-07 12:44:12 normalization > Completed successfully 2022-10-07 12:44:12 normalization > Done. PASS=4 WARN=0 ERROR=0 SKIP=0 TOTAL=4 2022-10-07 12:44:12 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / normalization-redshift-normalize-854-1-badxd) - Closed all resources for pod 2022-10-07 12:44:12 INFO i.a.w.g.DefaultNormalizationWorker(run):82 - Normalization executed in 30 seconds. 2022-10-07 12:44:12 INFO i.a.w.g.DefaultNormalizationWorker(run):95 - Normalization summary: io.airbyte.config.NormalizationSummary@3b003a8[startTime=1665146621867,endTime=1665146652636,failures=[]] 2022-10-07 12:44:12 INFO i.a.w.t.TemporalAttemptExecution(get):132 - Stopping cancellation check scheduling... 2022-10-07 12:44:12 INFO i.a.w.t.TemporalUtils(withBackgroundHeartbeat):312 - Stopping temporal heartbeating... 2022-10-07 12:44:12 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:12 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- END DEFAULT NORMALIZATION ----- 2022-10-07 12:44:12 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:13 INFO i.a.v.j.JsonSchemaValidator(test):71 - JSON schema validation failed. errors: $.personal_access_token: object found, string expected 2022-10-07 12:44:13 INFO i.a.v.j.JsonSchemaValidator(test):71 - JSON schema validation failed. errors: $.client_id: is missing but it is required, $.client_secret: is missing but it is required, $.refresh_token: is missing but it is required, $.option_title: must be a constant value OAuth Credentials 2022-10-07 12:44:41 source > Starting syncing SourceAsana 2022-10-07 12:44:41 destination > 2022-10-07 12:44:35 INFO i.a.i.d.r.RedshiftDestination(main):63 - starting destination: class io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:44:41 destination > 2022-10-07 12:44:36 INFO i.a.i.b.IntegrationCliParser(parseOptions):118 - integration args: {catalog=destination_catalog.json, write=null, config=destination_config.json} 2022-10-07 12:44:41 destination > 2022-10-07 12:44:36 INFO i.a.i.b.IntegrationRunner(runInternal):104 - Running integration: io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:44:41 destination > 2022-10-07 12:44:36 INFO i.a.i.b.IntegrationRunner(runInternal):105 - Command: WRITE 2022-10-07 12:44:41 destination > 2022-10-07 12:44:36 INFO i.a.i.b.IntegrationRunner(runInternal):106 - Integration config: IntegrationConfig{command=WRITE, configPath='destination_config.json', catalogPath='destination_catalog.json', statePath='null'} 2022-10-07 12:44:41 destination > 2022-10-07 12:44: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-10-07 12:44:41 destination > 2022-10-07 12:44: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-10-07 12:44:41 destination > 2022-10-07 12:44: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-10-07 12:44:41 destination > 2022-10-07 12:44:36 WARN i.a.i.d.r.RedshiftDestination(determineUploadMode):54 - The "standard" upload mode is not performant, and is not recommended for production. Please use the Amazon S3 upload mode if you are syncing a large amount of data. 2022-10-07 12:44:41 destination > 2022-10-07 12:44:36 INFO i.a.i.d.j.c.SwitchingDestination(getConsumer):65 - Using destination type: STANDARD 2022-10-07 12:44:41 destination > 2022-10-07 12:44:36 INFO c.z.h.HikariDataSource():80 - HikariPool-1 - Starting... 2022-10-07 12:44:41 source > Syncing stream: custom_fields 2022-10-07 12:44:13 INFO i.a.w.t.TemporalAttemptExecution(get):108 - Cloud storage job log path: /workspace/854/2/logs.log 2022-10-07 12:44:13 INFO i.a.w.t.TemporalAttemptExecution(get):111 - Executing worker wrapper. Airbyte version: 0.40.6 2022-10-07 12:44:13 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = source-asana-check-854-2-mtzfo for airbyte/source-asana:0.1.4 with resources io.airbyte.config.ResourceRequirements@9f6d799[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:44:13 INFO i.a.w.p.KubeProcessFactory(create):103 - source-asana-check-854-2-mtzfo stdoutLocalPort = 9023 2022-10-07 12:44:13 INFO i.a.w.p.KubeProcessFactory(create):106 - source-asana-check-854-2-mtzfo stderrLocalPort = 9015 2022-10-07 12:44:13 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:13 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- START CHECK ----- 2022-10-07 12:44:13 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:13 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:44:13 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:44:13 INFO i.a.w.p.KubePodProcess():520 - Creating pod source-asana-check-854-2-mtzfo... 2022-10-07 12:44:14 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:44:14 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:44:15 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:44:15 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:44:15 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: source_config.json 2022-10-07 12:44:15 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/c4393c85-5393-4ebd-9009-d5810edf39d7/source_config.json dev/source-asana-check-854-2-mtzfo:/config/source_config.json -c init 2022-10-07 12:44:15 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:44:16 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:44:16 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:44:16 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/6076c78f-8523-46d5-a2e5-88602384a5f9/FINISHED_UPLOADING dev/source-asana-check-854-2-mtzfo:/config/FINISHED_UPLOADING -c init 2022-10-07 12:44:16 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:44:16 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:44:16 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:44:17 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:44:17 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:44:18 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:44:18 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.70 2022-10-07 12:44:18 INFO i.a.w.p.KubePodProcess():579 - Using null stdin output stream... 2022-10-07 12:44:18 INFO i.a.w.i.DefaultAirbyteStreamFactory(internalLog):103 - Check succeeded 2022-10-07 12:44:19 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / source-asana-check-854-2-mtzfo) - Closed all resources for pod 2022-10-07 12:44:19 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:19 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- END CHECK ----- 2022-10-07 12:44:19 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:19 INFO i.a.w.t.TemporalAttemptExecution(get):132 - Stopping cancellation check scheduling... 2022-10-07 12:44:19 INFO i.a.w.t.TemporalAttemptExecution(get):108 - Cloud storage job log path: /workspace/854/2/logs.log 2022-10-07 12:44:19 INFO i.a.w.t.TemporalAttemptExecution(get):111 - Executing worker wrapper. Airbyte version: 0.40.6 2022-10-07 12:44:19 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = destination-redshift-check-854-2-xsedv for airbyte/destination-redshift:0.3.49 with resources io.airbyte.config.ResourceRequirements@9f6d799[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:44:19 INFO i.a.w.p.KubeProcessFactory(create):103 - destination-redshift-check-854-2-xsedv stdoutLocalPort = 9028 2022-10-07 12:44:19 INFO i.a.w.p.KubeProcessFactory(create):106 - destination-redshift-check-854-2-xsedv stderrLocalPort = 9029 2022-10-07 12:44:19 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:44:19 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:19 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:44:19 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- START CHECK ----- 2022-10-07 12:44:19 INFO i.a.w.p.KubePodProcess():520 - Creating pod destination-redshift-check-854-2-xsedv... 2022-10-07 12:44:19 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:20 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:44:20 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:44:22 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:44:22 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:44:22 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: source_config.json 2022-10-07 12:44:22 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/e640b9de-c324-40cb-a4c9-7cf1a255a91e/source_config.json dev/destination-redshift-check-854-2-xsedv:/config/source_config.json -c init 2022-10-07 12:44:22 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:44:22 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:44:22 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:44:22 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/24a2d3ca-1cca-4277-8eec-f1df4dff8c4b/FINISHED_UPLOADING dev/destination-redshift-check-854-2-xsedv:/config/FINISHED_UPLOADING -c init 2022-10-07 12:44:22 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:44:23 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:44:23 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:44:23 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:44:23 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:44:25 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:44:25 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.68 2022-10-07 12:44:25 INFO i.a.w.p.KubePodProcess():579 - Using null stdin output stream... 2022-10-07 12:44:25 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:25 INFO i.a.i.d.r.RedshiftDestination(main):63 - starting destination: class io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:44:25 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:25 INFO i.a.i.b.IntegrationCliParser(parseOptions):118 - integration args: {check=null, config=source_config.json} 2022-10-07 12:44:25 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:25 INFO i.a.i.b.IntegrationRunner(runInternal):104 - Running integration: io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:44:25 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:25 INFO i.a.i.b.IntegrationRunner(runInternal):105 - Command: CHECK 2022-10-07 12:44:25 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:25 INFO i.a.i.b.IntegrationRunner(runInternal):106 - Integration config: IntegrationConfig{command=CHECK, configPath='source_config.json', catalogPath='null', statePath='null'} 2022-10-07 12:44:26 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:26 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-10-07 12:44:26 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:26 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-10-07 12:44:26 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:26 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-10-07 12:44:26 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:26 WARN i.a.i.d.r.RedshiftDestination(determineUploadMode):54 - The "standard" upload mode is not performant, and is not recommended for production. Please use the Amazon S3 upload mode if you are syncing a large amount of data. 2022-10-07 12:44:26 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:26 INFO i.a.i.d.j.c.SwitchingDestination(check):55 - Using destination type: STANDARD 2022-10-07 12:44:26 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:26 INFO c.z.h.HikariDataSource():80 - HikariPool-1 - Starting... 2022-10-07 12:44:26 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:26 INFO c.z.h.HikariDataSource():82 - HikariPool-1 - Start completed. 2022-10-07 12:44:27 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:27 INFO c.z.h.p.PoolBase(getAndSetNetworkTimeout):536 - HikariPool-1 - Driver does not support get/set network timeout for connections. ([Amazon][JDBC](10220) Driver does not support this optional feature.) 2022-10-07 12:44:27 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:27 INFO c.z.h.HikariDataSource(close):350 - HikariPool-1 - Shutdown initiated... 2022-10-07 12:44:27 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:27 INFO c.z.h.HikariDataSource(close):352 - HikariPool-1 - Shutdown completed. 2022-10-07 12:44:27 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:27 INFO i.a.i.b.IntegrationRunner(runInternal):152 - Completed integration: io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:44:27 INFO i.a.w.i.DefaultAirbyteStreamFactory(lambda$create$0):62 - 2022-10-07 12:44:27 INFO i.a.i.d.r.RedshiftDestination(main):65 - completed destination: class io.airbyte.integrations.destination.redshift.RedshiftDestination 2022-10-07 12:44:28 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / destination-redshift-check-854-2-xsedv) - Closed all resources for pod 2022-10-07 12:44:28 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:28 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- END CHECK ----- 2022-10-07 12:44:28 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:28 INFO i.a.w.t.TemporalAttemptExecution(get):132 - Stopping cancellation check scheduling... 2022-10-07 12:44:29 INFO i.a.w.t.TemporalAttemptExecution(get):108 - Cloud storage job log path: /workspace/854/2/logs.log 2022-10-07 12:44:29 INFO i.a.w.t.TemporalAttemptExecution(get):111 - Executing worker wrapper. Airbyte version: 0.40.6 2022-10-07 12:44:29 INFO i.a.c.f.EnvVariableFeatureFlags(getEnvOrDefault):49 - Using default value for environment variable LOG_CONNECTOR_MESSAGES: 'false' 2022-10-07 12:44:29 INFO i.a.c.EnvConfigs(getEnvOrDefault):1096 - Using default value for environment variable METRIC_CLIENT: '' 2022-10-07 12:44:29 WARN i.a.m.l.MetricClientFactory(initialize):60 - Metric client is already initialized to 2022-10-07 12:44:29 INFO i.a.c.f.EnvVariableFeatureFlags(getEnvOrDefault):49 - Using default value for environment variable LOG_CONNECTOR_MESSAGES: 'false' 2022-10-07 12:44:29 INFO i.a.w.g.DefaultReplicationWorker(run):122 - start sync worker. job id: 854 attempt id: 2 2022-10-07 12:44:29 INFO i.a.w.g.DefaultReplicationWorker(run):135 - configured sync modes: {null.custom_fields=full_refresh - overwrite} 2022-10-07 12:44:29 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:29 INFO i.a.w.i.DefaultAirbyteDestination(start):69 - Running destination... 2022-10-07 12:44:29 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- START REPLICATION ----- 2022-10-07 12:44:29 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:29 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = destination-redshift-write-854-2-ggxch for airbyte/destination-redshift:0.3.49 with resources io.airbyte.config.ResourceRequirements@56152f75[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:44:29 INFO i.a.w.p.KubeProcessFactory(create):103 - destination-redshift-write-854-2-ggxch stdoutLocalPort = 9032 2022-10-07 12:44:29 INFO i.a.w.p.KubeProcessFactory(create):106 - destination-redshift-write-854-2-ggxch stderrLocalPort = 9033 2022-10-07 12:44:29 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:44:29 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:44:29 INFO i.a.w.p.KubePodProcess():520 - Creating pod destination-redshift-write-854-2-ggxch... 2022-10-07 12:44:29 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:44:29 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:44:31 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:44:31 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:44:31 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: destination_config.json 2022-10-07 12:44:31 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/dce0a6a2-1fb4-47df-9dfe-6d60ba48b182/destination_config.json dev/destination-redshift-write-854-2-ggxch:/config/destination_config.json -c init 2022-10-07 12:44:31 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:44:31 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:44:31 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: destination_catalog.json 2022-10-07 12:44:31 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/75af574e-6eb3-4780-a190-60deddcdc3a6/destination_catalog.json dev/destination-redshift-write-854-2-ggxch:/config/destination_catalog.json -c init 2022-10-07 12:44:31 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:44:31 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:44:31 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:44:31 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/d0723272-af90-4be4-95e1-e228c16e5f23/FINISHED_UPLOADING dev/destination-redshift-write-854-2-ggxch:/config/FINISHED_UPLOADING -c init 2022-10-07 12:44:31 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:44:32 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:44:32 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:44:33 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:44:33 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:44:34 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:44:34 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.69 2022-10-07 12:44:34 INFO i.a.w.p.KubePodProcess():575 - Creating stdin socket... 2022-10-07 12:44:34 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = source-asana-read-854-2-ymobs for airbyte/source-asana:0.1.4 with resources io.airbyte.config.ResourceRequirements@649113f8[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:44:34 INFO i.a.w.p.KubeProcessFactory(create):103 - source-asana-read-854-2-ymobs stdoutLocalPort = 9022 2022-10-07 12:44:34 INFO i.a.w.p.KubeProcessFactory(create):106 - source-asana-read-854-2-ymobs stderrLocalPort = 9014 2022-10-07 12:44:34 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:44:34 INFO i.a.w.p.KubePodProcess():520 - Creating pod source-asana-read-854-2-ymobs... 2022-10-07 12:44:34 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:44:34 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:44:35 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:44:37 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:44:37 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:44:37 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: source_config.json 2022-10-07 12:44:37 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/8370f118-9c4a-4b40-bdb7-9c6804db761a/source_config.json dev/source-asana-read-854-2-ymobs:/config/source_config.json -c init 2022-10-07 12:44:37 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:44:38 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:44:38 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: source_catalog.json 2022-10-07 12:44:38 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/4a875934-a5dc-4d46-83ed-3ee48a455ad2/source_catalog.json dev/source-asana-read-854-2-ymobs:/config/source_catalog.json -c init 2022-10-07 12:44:38 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:44:38 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:44:38 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:44:38 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/cfaa5609-3d61-449c-bc30-346490194d8f/FINISHED_UPLOADING dev/source-asana-read-854-2-ymobs:/config/FINISHED_UPLOADING -c init 2022-10-07 12:44:38 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:44:38 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:44:38 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:44:40 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:44:40 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:44:41 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:44:41 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.74 2022-10-07 12:44:41 INFO i.a.w.p.KubePodProcess():579 - Using null stdin output stream... 2022-10-07 12:44:41 INFO i.a.w.g.DefaultReplicationWorker(lambda$getDestinationOutputRunnable$7):435 - Destination output thread started. 2022-10-07 12:44:41 INFO i.a.w.g.DefaultReplicationWorker(run):177 - Waiting for source and destination threads to complete. 2022-10-07 12:44:41 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):322 - Replication thread started. 2022-10-07 12:44:42 INFO i.a.w.g.DefaultReplicationWorker(lambda$getReplicationRunnable$6):355 - Source has no more messages, closing connection. 2022-10-07 12:44:43 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / source-asana-read-854-2-ymobs) - Closed all resources for pod 2022-10-07 12:44:46 INFO i.a.w.p.KubePodProcess(close):737 - (pod: dev / destination-redshift-write-854-2-ggxch) - Closed all resources for pod 2022-10-07 12:44:46 ERROR i.a.w.g.DefaultReplicationWorker(run):188 - Sync worker failed. java.util.concurrent.ExecutionException: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped! at java.util.concurrent.CompletableFuture.reportGet(CompletableFuture.java:396) ~[?:?] at java.util.concurrent.CompletableFuture.get(CompletableFuture.java:2073) ~[?:?] at io.airbyte.workers.general.DefaultReplicationWorker.run(DefaultReplicationWorker.java:181) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.general.DefaultReplicationWorker.run(DefaultReplicationWorker.java:68) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:159) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] Suppressed: io.airbyte.workers.exception.WorkerException: Source process exit with code 1. This warning is normal if the job was cancelled. at io.airbyte.workers.internal.DefaultAirbyteSource.close(DefaultAirbyteSource.java:140) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.general.DefaultReplicationWorker.run(DefaultReplicationWorker.java:145) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.general.DefaultReplicationWorker.run(DefaultReplicationWorker.java:68) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.temporal.TemporalAttemptExecution.lambda$getWorkerThread$2(TemporalAttemptExecution.java:159) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at java.lang.Thread.run(Thread.java:1589) [?:?] Caused by: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped! at io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:359) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804) ~[?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) ~[?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) ~[?:?] ... 1 more Caused by: io.airbyte.workers.exception.WorkerException: Source process exit with code 1. This warning is normal if the job was cancelled. at io.airbyte.workers.internal.DefaultAirbyteSource.close(DefaultAirbyteSource.java:140) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:357) ~[io.airbyte-airbyte-workers-0.40.6.jar:?] at java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804) ~[?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) ~[?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) ~[?:?] ... 1 more 2022-10-07 12:44:46 INFO i.a.w.g.DefaultReplicationWorker(run):281 - Source did not output any state messages 2022-10-07 12:44:46 WARN i.a.w.g.DefaultReplicationWorker(run):292 - State capture: No state retained. 2022-10-07 12:44:46 INFO i.a.w.g.DefaultReplicationWorker(run):300 - sync summary: { "status" : "failed", "recordsSynced" : 0, "bytesSynced" : 0, "startTime" : 1665146669105, "endTime" : 1665146686263, "totalStats" : { "recordsEmitted" : 0, "bytesEmitted" : 0, "sourceStateMessagesEmitted" : 0, "destinationStateMessagesEmitted" : 0, "recordsCommitted" : 0, "meanSecondsBeforeSourceStateMessageEmitted" : 0, "maxSecondsBeforeSourceStateMessageEmitted" : 0, "maxSecondsBetweenStateMessageEmittedandCommitted" : 0, "meanSecondsBetweenStateMessageEmittedandCommitted" : 0 }, "streamStats" : [ ] } 2022-10-07 12:44:46 INFO i.a.w.g.DefaultReplicationWorker(run):301 - failures: [ { "failureOrigin" : "source", "failureType" : "system_error", "internalMessage" : "402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype", "externalMessage" : "Custom Fields are not available for free users or guests.", "metadata" : { "attemptNumber" : 2, "jobId" : 854, "from_trace_message" : true, "connector_command" : "read" }, "stacktrace" : "Traceback (most recent call last):\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py\", line 114, in read\n yield from self._read_stream(\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py\", line 179, in _read_stream\n for record in record_iterator:\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py\", line 277, in _read_full_refresh\n for record in records:\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py\", line 421, in read_records\n response = self._send_request(request, request_kwargs)\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py\", line 339, in _send_request\n return backoff_handler(user_backoff_handler)(request, request_kwargs)\n File \"/usr/local/lib/python3.9/site-packages/backoff/_sync.py\", line 105, in retry\n ret = target(*args, **kwargs)\n File \"/usr/local/lib/python3.9/site-packages/backoff/_sync.py\", line 105, in retry\n ret = target(*args, **kwargs)\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py\", line 306, in _send\n raise exc\n File \"/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py\", line 303, in _send\n response.raise_for_status()\n File \"/usr/local/lib/python3.9/site-packages/requests/models.py\", line 1021, in raise_for_status\n raise HTTPError(http_error_msg, response=self)\nrequests.exceptions.HTTPError: 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype\n", "timestamp" : 1665146682932 }, { "failureOrigin" : "source", "internalMessage" : "io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped!", "externalMessage" : "Something went wrong within the source connector", "metadata" : { "attemptNumber" : 2, "jobId" : 854, "connector_command" : "read" }, "stacktrace" : "java.util.concurrent.CompletionException: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped!\n\tat java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:315)\n\tat java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:320)\n\tat java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1807)\n\tat java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)\n\tat java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)\n\tat java.base/java.lang.Thread.run(Thread.java:1589)\nCaused by: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped!\n\tat io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:359)\n\tat java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804)\n\t... 3 more\nCaused by: io.airbyte.workers.exception.WorkerException: Source process exit with code 1. This warning is normal if the job was cancelled.\n\tat io.airbyte.workers.internal.DefaultAirbyteSource.close(DefaultAirbyteSource.java:140)\n\tat io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:357)\n\t... 4 more\n", "timestamp" : 1665146683795 } ] 2022-10-07 12:44:46 INFO i.a.w.t.TemporalAttemptExecution(get):132 - Stopping cancellation check scheduling... 2022-10-07 12:44:46 INFO i.a.w.t.s.ReplicationActivityImpl(lambda$replicate$3):166 - sync summary: io.airbyte.config.StandardSyncOutput@525c0633[standardSyncSummary=io.airbyte.config.StandardSyncSummary@2da64b28[status=failed,recordsSynced=0,bytesSynced=0,startTime=1665146669105,endTime=1665146686263,totalStats=io.airbyte.config.SyncStats@61584679[recordsEmitted=0,bytesEmitted=0,sourceStateMessagesEmitted=0,destinationStateMessagesEmitted=0,recordsCommitted=0,meanSecondsBeforeSourceStateMessageEmitted=0,maxSecondsBeforeSourceStateMessageEmitted=0,maxSecondsBetweenStateMessageEmittedandCommitted=0,meanSecondsBetweenStateMessageEmittedandCommitted=0,additionalProperties={}],streamStats=[]],normalizationSummary=,state=,outputCatalog=io.airbyte.protocol.models.ConfiguredAirbyteCatalog@7a9ab201[streams=[io.airbyte.protocol.models.ConfiguredAirbyteStream@48c54eff[stream=io.airbyte.protocol.models.AirbyteStream@5725b3c4[name=custom_fields,jsonSchema={"type":["null","object"],"properties":{"gid":{"type":["null","string"]},"type":{"type":["null","string"]},"format":{"type":["null","string"]},"enabled":{"type":["null","boolean"]},"precision":{"type":["null","integer"]},"created_by":{"type":["null","object"],"properties":{"gid":{"type":["null","string"]},"name":{"type":["null","string"]},"resource_type":{"type":["null","string"]}}},"enum_value":{"type":["null","object"],"properties":{"gid":{"type":["null","string"]},"name":{"type":["null","string"]},"color":{"type":["null","string"]},"enabled":{"type":["null","boolean"]},"resource_type":{"type":["null","string"]}}},"text_value":{"type":["null","string"]},"description":{"type":["null","string"]},"custom_label":{"type":["null","string"]},"enum_options":{"type":["null","array"],"items":{"type":["null","object"],"properties":{"gid":{"type":["null","string"]},"name":{"type":["null","string"]},"color":{"type":["null","string"]},"enabled":{"type":["null","boolean"]},"resource_type":{"type":["null","string"]}}}},"number_value":{"type":["null","number"]},"currency_code":{"type":["null","string"]},"display_value":{"type":["null","string"]},"resource_type":{"type":["null","string"]},"resource_subtype":{"type":["null","string"]},"custom_label_position":{"type":["null","string"]},"is_global_to_workspace":{"type":["null","boolean"]},"has_notifications_enabled":{"type":["null","boolean"]}}},supportedSyncModes=[full_refresh],sourceDefinedCursor=,defaultCursorField=[],sourceDefinedPrimaryKey=[[gid]],namespace=,additionalProperties={}],syncMode=full_refresh,cursorField=[],destinationSyncMode=overwrite,primaryKey=[[gid]],additionalProperties={}]],additionalProperties={}],failures=[io.airbyte.config.FailureReason@746d5cd7[failureOrigin=source,failureType=system_error,internalMessage=402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype,externalMessage=Custom Fields are not available for free users or guests.,metadata=io.airbyte.config.Metadata@5bc8e2e1[additionalProperties={attemptNumber=2, jobId=854, from_trace_message=true, connector_command=read}],stacktrace=Traceback (most recent call last): File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 114, in read yield from self._read_stream( File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 179, in _read_stream for record in record_iterator: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/abstract_source.py", line 277, in _read_full_refresh for record in records: File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 421, in read_records response = self._send_request(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 339, in _send_request return backoff_handler(user_backoff_handler)(request, request_kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/backoff/_sync.py", line 105, in retry ret = target(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 306, in _send raise exc File "/usr/local/lib/python3.9/site-packages/airbyte_cdk/sources/streams/http/http.py", line 303, in _send response.raise_for_status() File "/usr/local/lib/python3.9/site-packages/requests/models.py", line 1021, in raise_for_status raise HTTPError(http_error_msg, response=self) requests.exceptions.HTTPError: 402 Client Error: Payment Required for url: https://app.asana.com/api/1.0/workspaces/9185902480088/custom_fields?limit=100&opt_fields=gid%2Cresource_type%2Ccreated_by.gid%2Ccurrency_code%2Ccustom_label%2Ccustom_label_position%2Cdescription%2Cdisplay_value%2Cenabled%2Cenum_options.gid%2Cenum_value.gid%2Cformat%2Chas_notifications_enabled%2Cis_global_to_workspace%2Cnumber_value%2Cprecision%2Cresource_subtype%2Ctext_value%2Ctype ,retryable=,timestamp=1665146682932], io.airbyte.config.FailureReason@3918fdd1[failureOrigin=source,failureType=,internalMessage=io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped!,externalMessage=Something went wrong within the source connector,metadata=io.airbyte.config.Metadata@43d9c2a5[additionalProperties={attemptNumber=2, jobId=854, connector_command=read}],stacktrace=java.util.concurrent.CompletionException: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped! at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:315) at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:320) at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1807) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642) at java.base/java.lang.Thread.run(Thread.java:1589) Caused by: io.airbyte.workers.general.DefaultReplicationWorker$SourceException: Source cannot be stopped! at io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:359) at java.base/java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804) ... 3 more Caused by: io.airbyte.workers.exception.WorkerException: Source process exit with code 1. This warning is normal if the job was cancelled. at io.airbyte.workers.internal.DefaultAirbyteSource.close(DefaultAirbyteSource.java:140) at io.airbyte.workers.general.DefaultReplicationWorker.lambda$getReplicationRunnable$6(DefaultReplicationWorker.java:357) ... 4 more ,retryable=,timestamp=1665146683795]]] 2022-10-07 12:44:46 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:46 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- END REPLICATION ----- 2022-10-07 12:44:46 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:46 INFO i.a.w.t.TemporalUtils(withBackgroundHeartbeat):312 - Stopping temporal heartbeating... 2022-10-07 12:44:46 INFO i.a.w.t.TemporalAttemptExecution(get):108 - Cloud storage job log path: /workspace/854/2/logs.log 2022-10-07 12:44:46 INFO i.a.w.t.TemporalAttemptExecution(get):111 - Executing worker wrapper. Airbyte version: 0.40.6 2022-10-07 12:44:46 INFO i.a.w.n.DefaultNormalizationRunner(runProcess):138 - Running with normalization version: airbyte/normalization-redshift:0.2.21 2022-10-07 12:44:46 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:46 INFO i.a.c.i.LineGobbler(voidCall):114 - ----- START DEFAULT NORMALIZATION ----- 2022-10-07 12:44:46 INFO i.a.c.i.LineGobbler(voidCall):114 - 2022-10-07 12:44:46 INFO i.a.w.p.KubeProcessFactory(create):100 - Attempting to start pod = normalization-redshift-normalize-854-2-jrdum for airbyte/normalization-redshift:0.2.21 with resources io.airbyte.config.ResourceRequirements@4d62987f[cpuRequest=,cpuLimit=,memoryRequest=,memoryLimit=] 2022-10-07 12:44:46 INFO i.a.w.p.KubeProcessFactory(create):103 - normalization-redshift-normalize-854-2-jrdum stdoutLocalPort = 9024 2022-10-07 12:44:46 INFO i.a.w.p.KubeProcessFactory(create):106 - normalization-redshift-normalize-854-2-jrdum stderrLocalPort = 9025 2022-10-07 12:44:46 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):589 - Creating stdout socket server... 2022-10-07 12:44:46 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):607 - Creating stderr socket server... 2022-10-07 12:44:46 INFO i.a.w.p.KubePodProcess():520 - Creating pod normalization-redshift-normalize-854-2-jrdum... 2022-10-07 12:44:46 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):309 - Waiting for init container to be ready before copying files... 2022-10-07 12:44:46 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):313 - Init container present.. 2022-10-07 12:44:48 INFO i.a.w.p.KubePodProcess(waitForInitPodToRun):316 - Init container ready.. 2022-10-07 12:44:48 INFO i.a.w.p.KubePodProcess():551 - Copying files... 2022-10-07 12:44:48 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: destination_config.json 2022-10-07 12:44:48 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/a52067d1-545a-4e33-9d52-89ec65a6a718/destination_config.json dev/normalization-redshift-normalize-854-2-jrdum:/config/destination_config.json -c init 2022-10-07 12:44:48 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:44:49 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:44:49 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: destination_catalog.json 2022-10-07 12:44:49 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/779c7a56-fcc3-4899-9e8e-1c3f47113ae4/destination_catalog.json dev/normalization-redshift-normalize-854-2-jrdum:/config/destination_catalog.json -c init 2022-10-07 12:44:49 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:44:49 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:44:49 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):258 - Uploading file: FINISHED_UPLOADING 2022-10-07 12:44:49 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):266 - kubectl cp /tmp/ae02b0b0-b69e-491e-b93d-c91c870d593d/FINISHED_UPLOADING dev/normalization-redshift-normalize-854-2-jrdum:/config/FINISHED_UPLOADING -c init 2022-10-07 12:44:49 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):269 - Waiting for kubectl cp to complete 2022-10-07 12:44:49 INFO i.a.w.p.KubePodProcess(copyFilesToKubeConfigVolume):283 - kubectl cp complete, closing process 2022-10-07 12:44:49 INFO i.a.w.p.KubePodProcess():554 - Waiting until pod is ready... 2022-10-07 12:44:51 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$9):598 - Setting stdout... 2022-10-07 12:44:51 INFO i.a.w.p.KubePodProcess(lambda$setupStdOutAndStdErrListeners$10):610 - Setting stderr... 2022-10-07 12:44:52 INFO i.a.w.p.KubePodProcess():570 - Reading pod IP... 2022-10-07 12:44:52 INFO i.a.w.p.KubePodProcess():572 - Pod IP: 14.0.3.81 2022-10-07 12:44:52 INFO i.a.w.p.KubePodProcess():579 - Using null stdin output stream... 2022-10-07 12:44:55 INFO i.a.w.n.NormalizationAirbyteStreamFactory(filterOutAndHandleNonAirbyteMessageLines):104 - 2022-10-07 12:44:55 INFO i.a.w.n.NormalizationAirbyteStreamFactory(filterOutAndHandleNonAirbyteMessageLines):104 -