Created
August 23, 2019 16:40
-
-
Save edtan/c46189f04fb96bc2ad44f2676c03cf43 to your computer and use it in GitHub Desktop.
Concourse web logs showing db queries for get-step and task-step
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:14.117795659Z","level":"debug","source":"atc","message":"atc.build-tracker.track.lock.acquire.acquired","data":{"build":8,"build_id":8,"id":[1,8],"job":"test","pipeline":"simple","session":"18.239.1.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:14.232974783Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query-row","data":{"duration":"114.352104ms","query":"SELECT b.id, b.name, b.job_id, b.team_id, b.status, b.manually_triggered, b.scheduled, b.schema, b.private_plan, b.public_plan, b.create_time, b.start_time, b.end_time, b.reap_time, j.name, b.pipeline_id, p.name, t.name, b.nonce, b.drained, b.aborted, b.completed FROM builds b LEFT OUTER JOIN jobs j ON b.job_id = j.id LEFT OUTER JOIN pipelines p ON b.pipeline_id = p.id LEFT OUTER JOIN teams t ON b.team_id = t.id WHERE b.id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:14.308710046Z","level":"info","source":"atc","message":"atc.build-tracker.track.running","data":{"build":8,"job":"test","pipeline":"simple","session":"18.239"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:14.424896024Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query-row","data":{"duration":"116.042972ms","query":"SELECT aborted = true FROM builds WHERE id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:14.540879007Z","level":"info","source":"atc","message":"atc.build-tracker.track.get-step.initializing","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.3","step-name":"git-repo"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:14.740595583Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"165.752695ms","query":"SELECT builds.completed FROM builds WHERE builds.id = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:14.844895717Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"104.160585ms","query":"SELECT type, version, payload FROM pipeline_build_events_1 WHERE build_id = $1 ORDER BY event_id ASC OFFSET $2 LIMIT $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:15.244264057Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query","data":{"duration":"107.9503ms","query":"SELECT w.name, w.version, w.addr, w.state, w.baggageclaim_url, w.certs_path, w.http_proxy_url, w.https_proxy_url, w.no_proxy, w.active_containers, w.active_volumes, w.resource_types, w.platform, w.tags, t.name, w.team_id, w.start_time, w.expires, w.ephemeral FROM workers w LEFT JOIN teams t ON w.team_id = t.id JOIN containers c ON c.worker_name = w.name WHERE (c.build_id = $1 AND c.plan_id = $2 AND c.team_id = $3)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:15.306714387Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query","data":{"duration":"62.334774ms","query":"SELECT w.name, w.version, w.addr, w.state, w.baggageclaim_url, w.certs_path, w.http_proxy_url, w.https_proxy_url, w.no_proxy, w.active_containers, w.active_volumes, w.resource_types, w.platform, w.tags, t.name, w.team_id, w.start_time, w.expires, w.ephemeral FROM workers w LEFT JOIN teams t ON w.team_id = t.id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:15.371644644Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query","data":{"duration":"64.213721ms","query":"SELECT worker_name, COUNT(*) FROM containers WHERE build_id IS NOT NULL GROUP BY worker_name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:15.617133325Z","level":"info","source":"atc","message":"atc.build-tracker.track.get-step.starting","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.3","step-name":"git-repo"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:15.653769626Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query-row","data":{"duration":"35.710758ms","query":"SELECT wbrt.id, wbrt.version FROM worker_base_resource_types wbrt LEFT JOIN base_resource_types brt ON brt.id = wbrt.base_resource_type_id LEFT JOIN workers w ON w.name = wbrt.worker_name WHERE brt.name = $1 AND wbrt.worker_name = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:15.745594651Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query-row","data":{"duration":"91.656092ms","query":"SELECT id FROM worker_resource_caches WHERE resource_cache_id = $1 AND worker_base_resource_type_id = $2 FOR SHARE","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:15.746109648Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"133.380658ms","query":"SELECT builds.completed FROM builds WHERE builds.id = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:15.861360222Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query-row","data":{"duration":"115.566054ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.worker_name = $1 AND v.worker_resource_cache_id = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:15.867804432Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"121.594354ms","query":"SELECT type, version, payload FROM pipeline_build_events_1 WHERE build_id = $1 ORDER BY event_id ASC OFFSET $2 LIMIT $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.062994737Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query-row","data":{"duration":"98.477842ms","query":"SELECT metadata FROM resource_caches WHERE id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.063137093Z","level":"debug","source":"atc","message":"atc.build-tracker.track.get-step.found-initialized-versioned-source","data":{"build":8,"job":"test","job-id":1,"metadata":[{"name":"commit","value":"e009b90b605f286af3799d53548d1a5cf36cf3aa"},{"name":"author","value":"Alex Suraci"},{"name":"author_date","value":"2019-07-29 11:46:00 -0400"},{"name":"branch","value":"master"},{"name":"message","value":"Merge pull request #263 from eliotstocker/master\n\nAllow filtering in or out commits based on strings within commit message"}],"pipeline":"simple","session":"18.239.3","step-name":"git-repo","version":{"ref":"e009b90b605f286af3799d53548d1a5cf36cf3aa"}}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.155126946Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query-row","data":{"duration":"91.786273ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id WHERE p.id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.215801824Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query-row","data":{"duration":"60.258283ms","query":"SELECT r.id, r.name, r.type, r.config, r.check_error, rs.last_check_start_time, rs.last_check_end_time, r.pipeline_id, r.nonce, r.resource_config_id, r.resource_config_scope_id, p.name, t.name, rs.check_error, rp.version, rp.comment_text FROM resources r JOIN pipelines p ON p.id = r.pipeline_id JOIN teams t ON t.id = p.team_id LEFT JOIN resource_config_scopes rs ON r.resource_config_scope_id = rs.id LEFT JOIN resource_pins rp ON rp.resource_id = r.id WHERE r.active = $1 AND r.name = $2 AND r.pipeline_id = $3","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.293969353Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.exec","data":{"duration":"77.845136ms","query":"UPDATE resource_config_versions SET metadata = $1 WHERE resource_config_scope_id = $2 AND version_md5 = md5($3)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.321114619Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"53.318904ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.432707726Z","level":"info","source":"atc","message":"atc.build-tracker.track.get-step.finished","data":{"build":8,"exit-status":0,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.3","step-name":"git-repo"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.586506403Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"101.754031ms","query":"SELECT builds.completed FROM builds WHERE builds.id = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.658302322Z","level":"info","source":"atc","message":"atc.build-tracker.track.task-step.initializing","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.667017424Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"80.349597ms","query":"SELECT type, version, payload FROM pipeline_build_events_1 WHERE build_id = $1 ORDER BY event_id ASC OFFSET $2 LIMIT $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.753432330Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query","data":{"duration":"94.728105ms","query":"SELECT w.name, w.version, w.addr, w.state, w.baggageclaim_url, w.certs_path, w.http_proxy_url, w.https_proxy_url, w.no_proxy, w.active_containers, w.active_volumes, w.resource_types, w.platform, w.tags, t.name, w.team_id, w.start_time, w.expires, w.ephemeral FROM workers w LEFT JOIN teams t ON w.team_id = t.id JOIN containers c ON c.worker_name = w.name WHERE (c.build_id = $1 AND c.plan_id = $2 AND c.team_id = $3)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.781716195Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query","data":{"duration":"28.012567ms","query":"SELECT w.name, w.version, w.addr, w.state, w.baggageclaim_url, w.certs_path, w.http_proxy_url, w.https_proxy_url, w.no_proxy, w.active_containers, w.active_volumes, w.resource_types, w.platform, w.tags, t.name, w.team_id, w.start_time, w.expires, w.ephemeral FROM workers w LEFT JOIN teams t ON w.team_id = t.id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.827895995Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"160.757681ms","query":"SELECT builds.completed FROM builds WHERE builds.id = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.842199321Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query","data":{"duration":"45.044097ms","query":"SELECT worker_name, COUNT(*) FROM containers WHERE build_id IS NOT NULL GROUP BY worker_name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.893052433Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"64.908217ms","query":"SELECT type, version, payload FROM pipeline_build_events_1 WHERE build_id = $1 ORDER BY event_id ASC OFFSET $2 LIMIT $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.951501369Z","level":"debug","source":"atc","message":"atc.log-conn.atc.pipelines.radar.scan-resource.interval-runner.found-created-container-in-db.query-row","data":{"duration":"108.494107ms","query":"SELECT id, handle, worker_name, hijacked, discontinued, state, meta_type, meta_step_name, meta_attempt, meta_working_directory, meta_process_user, meta_pipeline_id, meta_job_id, meta_build_id, meta_pipeline_name, meta_job_name, meta_build_name FROM containers WHERE (worker_name = $1 AND build_id = $2 AND plan_id = $3 AND team_id = $4)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:16.951612935Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.creating-container-in-db","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:17.200955838Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.created-creating-container-in-db","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:17.325729703Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.fetching-image","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:17.416051279Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-creating-container-in-db.query-row","data":{"duration":"90.069851ms","query":"SELECT id, handle, worker_name, hijacked, discontinued, state, meta_type, meta_step_name, meta_attempt, meta_working_directory, meta_process_user, meta_pipeline_id, meta_job_id, meta_build_id, meta_pipeline_name, meta_job_name, meta_build_name FROM containers WHERE (worker_name = $1 AND image_check_container_id = $2 AND team_id = $3)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:17.416126800Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.creating-container-in-db","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:17.625160750Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.created-creating-container-in-db","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:17.721667412Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.fetching-image","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:18.052078347Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query-row","data":{"duration":"144.522106ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.worker_base_resource_type_id = $1 AND v.worker_name = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:18.156362321Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-volume-for-base-resource-type.found-created-volume","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1.1","step-name":"print","volume":"3d39e04d-6229-4173-7af6-81239aaa68c5"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:18.290846937Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query-row","data":{"duration":"134.147293ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.path = $2 AND v.team_id = $3 AND v.worker_name = $4","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:18.479612450Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-cow-volume-for-container.created-creating-volume","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1.2","step-name":"print","volume":"76abf60b-d3e2-4b79-7895-300eb9d7b1f1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:18.552497110Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-cow-volume-for-container.acquire.acquired","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","id":[4,83],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1.2.1","step-name":"print","volume":"76abf60b-d3e2-4b79-7895-300eb9d7b1f1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:18.668046517Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-cow-volume-for-container.creating-real-volume","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1.2","step-name":"print","volume":"76abf60b-d3e2-4b79-7895-300eb9d7b1f1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.066126243Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.exec","data":{"duration":"101.720885ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.066186994Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-cow-volume-for-container.created","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1.2","step-name":"print","volume":"76abf60b-d3e2-4b79-7895-300eb9d7b1f1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.159666713Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-cow-volume-for-container.release.released","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","id":[4,83],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1.2.4","step-name":"print","volume":"76abf60b-d3e2-4b79-7895-300eb9d7b1f1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.209002751Z","level":"debug","source":"atc","message":"atc.pipelines.scheduler.tick.lock.acquire.acquired","data":{"id":[2,1],"pipeline":"simple","session":"17.2.236.1.1","team":"main"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.293349084Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query-row","data":{"duration":"133.260966ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.path = $2 AND v.team_id = $3 AND v.worker_name = $4","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.337962281Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.exec","data":{"duration":"128.680278ms","query":"UPDATE pipelines SET last_scheduled = now() WHERE id = $1 AND now() - last_scheduled \u003e ($2 || ' SECONDS')::INTERVAL","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.379951284Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query-row","data":{"duration":"86.337094ms","query":"INSERT INTO volumes (worker_name,handle,container_id,path,team_id) VALUES ($1,$2,$3,$4,$5) RETURNING id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.380073770Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-volume-for-container.created-creating-volume","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1.3","step-name":"print","volume":"802bf436-3a62-4cfa-6f65-609e75d864ba"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.437638900Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query-row","data":{"duration":"99.506681ms","query":"SELECT cache_index FROM pipelines WHERE id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.476611982Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-volume-for-container.acquire.acquired","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","id":[4,84],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1.3.1","step-name":"print","volume":"802bf436-3a62-4cfa-6f65-609e75d864ba"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.518421479Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query","data":{"duration":"80.595103ms","query":"SELECT v.id, v.check_order, r.id, o.build_id, b.job_id FROM build_resource_config_version_outputs o JOIN builds b ON b.id = o.build_id JOIN resource_config_versions v ON v.version_md5 = o.version_md5 JOIN resources r ON r.id = o.resource_id WHERE r.resource_config_scope_id = v.resource_config_scope_id AND (r.id, v.version_md5) NOT IN (SELECT resource_id, version_md5 from resource_disabled_versions) AND v.check_order \u003c\u003e $1 AND b.status = $2 AND r.pipeline_id = $3","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.571533020Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-volume-for-container.creating-real-volume","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1.3","step-name":"print","volume":"802bf436-3a62-4cfa-6f65-609e75d864ba"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.619165138Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query","data":{"duration":"100.586222ms","query":"SELECT v.id, v.check_order, r.id, i.build_id, i.name, b.job_id, b.status = 'succeeded' FROM build_resource_config_version_inputs i JOIN builds b ON b.id = i.build_id JOIN resource_config_versions v ON v.version_md5 = i.version_md5 JOIN resources r ON r.id = i.resource_id WHERE r.resource_config_scope_id = v.resource_config_scope_id AND (r.id, v.version_md5) NOT IN (SELECT resource_id, version_md5 from resource_disabled_versions) AND v.check_order \u003c\u003e $1 AND r.pipeline_id = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.711880234Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query","data":{"duration":"92.457874ms","query":"SELECT v.id, v.check_order, r.id FROM resource_config_versions v JOIN resources r ON r.resource_config_scope_id = v.resource_config_scope_id LEFT JOIN resource_disabled_versions d ON d.resource_id = r.id AND d.version_md5 = v.version_md5 WHERE v.check_order \u003c\u003e $1 AND d.resource_id IS NULL AND d.version_md5 IS NULL AND r.pipeline_id = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.819991521Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query","data":{"duration":"107.813525ms","query":"SELECT j.name, j.id FROM jobs j WHERE j.pipeline_id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:19.964760855Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query","data":{"duration":"144.611786ms","query":"SELECT r.name, r.id FROM resources r WHERE r.pipeline_id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.097251880Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query-row","data":{"duration":"132.071515ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id WHERE p.id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.098622119Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.exec","data":{"duration":"131.941851ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.098827360Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-volume-for-container.created","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1.3","step-name":"print","volume":"802bf436-3a62-4cfa-6f65-609e75d864ba"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.164739832Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query","data":{"duration":"67.119627ms","query":"SELECT r.id, r.name, r.type, r.config, r.check_error, rs.last_check_start_time, rs.last_check_end_time, r.pipeline_id, r.nonce, r.resource_config_id, r.resource_config_scope_id, p.name, t.name, rs.check_error, rp.version, rp.comment_text FROM resources r JOIN pipelines p ON p.id = r.pipeline_id JOIN teams t ON t.id = p.team_id LEFT JOIN resource_config_scopes rs ON r.resource_config_scope_id = rs.id LEFT JOIN resource_pins rp ON rp.resource_id = r.id WHERE r.active = $1 AND r.pipeline_id = $2 ORDER BY r.name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.168785916Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-volume-for-container.release.released","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","id":[4,84],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1.3.4","step-name":"print","volume":"802bf436-3a62-4cfa-6f65-609e75d864ba"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.168886249Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.worker-certs-volume.find-or-create.finding-worker-resource-certs","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1.1.1","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.168927722Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.worker-certs-volume.find-or-create.worker-resource-certs-not-found","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1.1.1","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.168964310Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.creating-garden-container","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.232686216Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.creating-garden-container.query","data":{"duration":"67.40259ms","query":"SELECT j.id, j.name, j.config, j.paused, j.first_logged_build_id, j.pipeline_id, p.name, p.team_id, t.name, j.nonce, j.tags, j.has_new_inputs FROM jobs j, pipelines p LEFT JOIN teams t ON p.team_id = t.id WHERE j.pipeline_id = p.id AND active = $1 AND pipeline_id = $2 ORDER BY j.id ASC","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.270944360Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.creating-garden-container.query","data":{"duration":"37.168385ms","query":"SELECT r.id, r.name, r.type, r.config, rcv.version, r.nonce, r.check_error, ro.check_error FROM resource_types r LEFT JOIN resource_configs c ON c.id = r.resource_config_id LEFT JOIN resource_config_scopes ro ON ro.resource_config_id = c.id LEFT JOIN LATERAL ( SELECT rcv.* FROM resource_config_versions rcv WHERE rcv.resource_config_scope_id = ro.id AND rcv.check_order != 0 ORDER BY rcv.check_order DESC LIMIT 1 ) AS rcv ON true WHERE r.active = $1 AND r.pipeline_id = $2 ORDER BY r.name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.459208404Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.created-container-in-garden","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.556898526Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-garden.exec","data":{"duration":"97.275587ms","query":"UPDATE containers SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.556971620Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.created-container-in-db","data":{"build":8,"container":"3f4f1546-9228-435f-7454-9dad0e463184","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.1","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.665265121Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"108.083966ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.state = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.746049577Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"80.795435ms","query":"SELECT b.id, b.name, b.job_id, b.team_id, b.status, b.manually_triggered, b.scheduled, b.schema, b.private_plan, b.public_plan, b.create_time, b.start_time, b.end_time, b.reap_time, j.name, b.pipeline_id, p.name, t.name, b.nonce, b.drained, b.aborted, b.completed FROM builds b LEFT OUTER JOIN jobs j ON b.job_id = j.id LEFT OUTER JOIN pipelines p ON b.pipeline_id = p.id LEFT OUTER JOIN teams t ON b.team_id = t.id WHERE b.pipeline_id = $1 AND b.status = $2 AND j.active = $3 ORDER BY b.id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.781522028Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"114.965984ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.handle = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.844710967Z","level":"debug","source":"atc","message":"atc.pipelines.scheduler.tick.lock.release.released","data":{"id":[2,1],"pipeline":"simple","session":"17.2.236.1.2","team":"main"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:20.988138133Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"97.325923ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.handle = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:21.233215086Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"61.408238ms","query":"SELECT w.name, w.version, w.addr, w.state, w.baggageclaim_url, w.certs_path, w.http_proxy_url, w.https_proxy_url, w.no_proxy, w.active_containers, w.active_volumes, w.resource_types, w.platform, w.tags, t.name, w.team_id, w.start_time, w.expires, w.ephemeral FROM workers w LEFT JOIN teams t ON w.team_id = t.id WHERE w.name = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:21.320718937Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"52.831771ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:23.029790688Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"84.581522ms","query":"INSERT INTO build_image_resource_caches (resource_cache_id,build_id) VALUES ($1,$2)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:23.199381394Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"169.158483ms","query":"SELECT wbrt.id, wbrt.version FROM worker_base_resource_types wbrt LEFT JOIN base_resource_types brt ON brt.id = wbrt.base_resource_type_id LEFT JOIN workers w ON w.name = wbrt.worker_name WHERE brt.name = $1 AND wbrt.worker_name = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:23.259698299Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"60.138393ms","query":"SELECT id FROM worker_resource_caches WHERE resource_cache_id = $1 AND worker_base_resource_type_id = $2 FOR SHARE","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:23.349211431Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"89.283227ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.worker_name = $1 AND v.worker_resource_cache_id = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:23.562964722Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"103.916826ms","query":"SELECT metadata FROM resource_caches WHERE id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:23.563521982Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.init-image.found-initialized-versioned-source","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"metadata":[{"name":"image","value":"sha256:db8ee"}],"pipeline":"simple","session":"18.239.4.1.2","step-name":"print","version":{"digest":"sha256:9f1003c480699be56815db0f8146ad2e22efea85129b5b5983d0e0fb52d9ab70"}}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:23.830597195Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"118.542443ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.path = $2 AND v.team_id = $3 AND v.worker_name = $4","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:23.905429490Z","level":"debug","source":"atc","message":"atc.report-volumes-for-worker.handles-info","data":{"handles-count":38,"name":"deedae84fd4b","session":"7097"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:23.939996968Z","level":"debug","source":"atc","message":"atc.build-tracker.track.start","data":{"session":"18.240"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:23.961204893Z","level":"debug","source":"atc","message":"atc.report-containers-for-worker.handles-info","data":{"name":"deedae84fd4b","num-handles":9,"session":"7099"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:23.974375077Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"34.808337ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.047975240Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"107.53877ms","query":"SELECT b.id, b.name, b.job_id, b.team_id, b.status, b.manually_triggered, b.scheduled, b.schema, b.private_plan, b.public_plan, b.create_time, b.start_time, b.end_time, b.reap_time, j.name, b.pipeline_id, p.name, t.name, b.nonce, b.drained, b.aborted, b.completed FROM builds b LEFT OUTER JOIN jobs j ON b.job_id = j.id LEFT OUTER JOIN pipelines p ON b.pipeline_id = p.id LEFT OUTER JOIN teams t ON b.team_id = t.id WHERE b.status = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.048981160Z","level":"debug","source":"atc","message":"atc.build-tracker.track.done","data":{"session":"18.240"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.049285060Z","level":"debug","source":"atc","message":"atc.build-tracker.track.lock.acquire.not-acquired-already-held-locally","data":{"build":8,"build_id":8,"id":[1,8],"job":"test","pipeline":"simple","session":"18.240.1.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.049482903Z","level":"debug","source":"atc","message":"atc.build-tracker.track.build-already-tracked","data":{"build":8,"job":"test","pipeline":"simple","session":"18.240"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.049787661Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.create-cow-volume.find-or-create-cow-volume-for-container.created-creating-volume","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.2.1","step-name":"print","volume":"ef12f780-db09-4680-7478-c08934f74969"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.054046293Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"148.286525ms","query":"UPDATE volumes SET missing_since = $1 WHERE (missing_since IS NOT NULL AND handle IN ($2,$3,$4,$5,$6,$7,$8,$9,$10,$11,$12,$13,$14,$15,$16,$17,$18,$19,$20,$21,$22,$23,$24,$25,$26,$27,$28,$29,$30,$31,$32,$33,$34,$35,$36,$37,$38,$39))","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.106767768Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"145.449591ms","query":"UPDATE containers SET missing_since = $1 WHERE (missing_since IS NOT NULL AND handle IN ($2,$3,$4,$5,$6,$7,$8,$9,$10))","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.152743866Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"98.459643ms","query":"SELECT handle FROM volumes WHERE missing_since IS NULL AND worker_name = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.210436618Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.create-cow-volume.find-or-create-cow-volume-for-container.acquire.acquired","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","id":[4,85],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.2.1.1","step-name":"print","volume":"ef12f780-db09-4680-7478-c08934f74969"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.239953613Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"133.047051ms","query":"SELECT handle FROM containers WHERE missing_since IS NULL AND worker_name = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.285715831Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"132.507601ms","query":"UPDATE volumes SET missing_since = now() WHERE (handle IN ($1) AND state \u003c\u003e $2)","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.335094023Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.create-cow-volume.find-or-create-cow-volume-for-container.creating-real-volume","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.2.1","step-name":"print","volume":"ef12f780-db09-4680-7478-c08934f74969"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.343799812Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"103.678218ms","query":"UPDATE containers SET missing_since = now() WHERE (handle IN ($1,$2,$3,$4) AND state \u003c\u003e $5)","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.424878024Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.exec","data":{"duration":"138.707354ms","query":"DELETE FROM volumes WHERE (worker_name = $1 AND handle NOT IN ($2,$3,$4,$5,$6,$7,$8,$9,$10,$11,$12,$13,$14,$15,$16,$17,$18,$19,$20,$21,$22,$23,$24,$25,$26,$27,$28,$29,$30,$31,$32,$33,$34,$35,$36,$37,$38,$39) AND state = $40)","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.425158240Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.successfully-report-volumes","data":{"command":"report-volumes","remote":"172.23.0.4:47834","session":"319.1.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.436841872Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.exec","data":{"duration":"92.86318ms","query":"DELETE FROM containers WHERE (worker_name = $1 AND handle NOT IN ($2,$3,$4,$5,$6,$7,$8,$9,$10) AND state = $11)","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.437177817Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.successfully-report-containers","data":{"command":"report-containers","remote":"172.23.0.4:47832","session":"318.1.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.531871036Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.heartbeat.start","data":{"command":"forward-worker","remote":"172.23.0.4:46444","session":"305.3.1.6","worker-address":"127.0.0.1:40011","worker-platform":"linux","worker-tags":""}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.632855627Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"64.02026ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.633052693Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.create-cow-volume.find-or-create-cow-volume-for-container.created","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.2.1","step-name":"print","volume":"ef12f780-db09-4680-7478-c08934f74969"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.747869849Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.create-cow-volume.find-or-create-cow-volume-for-container.release.released","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","id":[4,85],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.2.1.4","step-name":"print","volume":"ef12f780-db09-4680-7478-c08934f74969"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.840222314Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"91.24665ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.path = $2 AND v.team_id = $3 AND v.worker_name = $4","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.952576627Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"112.16221ms","query":"INSERT INTO volumes (worker_name,handle,container_id,path,team_id) VALUES ($1,$2,$3,$4,$5) RETURNING id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:24.952688425Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.created-creating-volume","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.3","step-name":"print","volume":"beed00bc-1f92-4a58-6743-c3b27baf4fa6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.029870333Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.acquire.acquired","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","id":[4,86],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.3.1","step-name":"print","volume":"beed00bc-1f92-4a58-6743-c3b27baf4fa6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.082207363Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"97.851557ms","query":"SELECT handle FROM volumes WHERE state = $1 AND worker_name = $2","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.082597796Z","level":"debug","source":"atc","message":"atc.list-destroying-volumes.volumes-to-destroy","data":{"count":0,"session":"7107","worker":"deedae84fd4b"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.083198017Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.successfully-sweep-volumes","data":{"command":"sweep-volumes","remote":"172.23.0.4:47852","session":"320.1.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.140764914Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.creating-real-volume","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.3","step-name":"print","volume":"beed00bc-1f92-4a58-6743-c3b27baf4fa6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.186702761Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"109.763107ms","query":"SELECT handle FROM containers WHERE discontinued = $1 AND state = $2 AND worker_name = $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.186829178Z","level":"debug","source":"atc","message":"atc.list-destroying-containers.containers-to-destroy","data":{"count":5,"session":"7109","worker":"deedae84fd4b"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.187127276Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.successfully-sweep-containers","data":{"command":"sweep-containers","remote":"172.23.0.4:47854","session":"321.1.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.281055536Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"135.503144ms","query":"SELECT active_tasks FROM workers WHERE name = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.358057102Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"76.831036ms","query":"SELECT active_tasks FROM workers WHERE name = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.358889411Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.heartbeat.done","data":{"command":"forward-worker","remote":"172.23.0.4:46444","session":"305.3.1.6","worker-address":"127.0.0.1:40011","worker-platform":"linux","worker-tags":""}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.594624870Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"104.472494ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.594745736Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.created","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.3","step-name":"print","volume":"beed00bc-1f92-4a58-6743-c3b27baf4fa6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.672682913Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.release.released","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","id":[4,86],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.3.4","step-name":"print","volume":"beed00bc-1f92-4a58-6743-c3b27baf4fa6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.773356952Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"100.408944ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.path = $2 AND v.team_id = $3 AND v.worker_name = $4","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.873211939Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"99.207469ms","query":"INSERT INTO volumes (worker_name,handle,container_id,path,team_id) VALUES ($1,$2,$3,$4,$5) RETURNING id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.873414542Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.created-creating-volume","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.4","step-name":"print","volume":"8842f9af-efcd-4710-4ac6-8d16377adc9a"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:25.950943077Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.acquire.acquired","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","id":[4,87],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.4.1","step-name":"print","volume":"8842f9af-efcd-4710-4ac6-8d16377adc9a"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:26.070439073Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.creating-real-volume","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.4","step-name":"print","volume":"8842f9af-efcd-4710-4ac6-8d16377adc9a"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:26.312419595Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"41.990739ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:26.538785054Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"136.523683ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:26.538879115Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.created","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.4","step-name":"print","volume":"8842f9af-efcd-4710-4ac6-8d16377adc9a"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:26.666272179Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.release.released","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","id":[4,87],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4.4.4","step-name":"print","volume":"8842f9af-efcd-4710-4ac6-8d16377adc9a"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:26.666382070Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.creating-garden-container","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:27.053829778Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.created-container-in-garden","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:27.125019197Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-garden.exec","data":{"duration":"70.716913ms","query":"UPDATE containers SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:27.125095208Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.created-container-in-db","data":{"build":8,"container":"ea7d24f4-1e59-4254-6900-571e6daf4e86","job":"test","job-id":1,"pipeline":"simple","session":"18.239.4","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:27.239181113Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"113.835526ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.state = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:27.307986332Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query-row","data":{"duration":"68.458133ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.handle = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:27.498841841Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query-row","data":{"duration":"90.339994ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.handle = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:27.714123198Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query-row","data":{"duration":"127.014054ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.handle = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:28.149077926Z","level":"info","source":"atc","message":"atc.build-tracker.track.task-step.spawning","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:28.318897303Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.starting","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:28.381545640Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"74.013274ms","query":"SELECT builds.completed FROM builds WHERE builds.id = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:28.495142095Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"113.399008ms","query":"SELECT type, version, payload FROM pipeline_build_events_1 WHERE build_id = $1 ORDER BY event_id ASC OFFSET $2 LIMIT $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:28.719944781Z","level":"info","source":"atc","message":"atc.build-tracker.track.task-step.attached","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.001672472Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"88.034373ms","query":"SELECT builds.completed FROM builds WHERE builds.id = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.097280385Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"95.521293ms","query":"SELECT type, version, payload FROM pipeline_build_events_1 WHERE build_id = $1 ORDER BY event_id ASC OFFSET $2 LIMIT $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.299800167Z","level":"info","source":"atc","message":"atc.build-tracker.track.task-step.finished","data":{"build":8,"exit-status":127,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.299948479Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.registering-outputs","data":{"build":8,"job":"test","job-id":1,"outputs":null,"pipeline":"simple","session":"18.239.4","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.300025972Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.initializing-caches","data":{"build":8,"caches":null,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.4","step-name":"print"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.346953928Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"64.792451ms","query":"SELECT builds.completed FROM builds WHERE builds.id = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.448140426Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"100.893023ms","query":"SELECT type, version, payload FROM pipeline_build_events_1 WHERE build_id = $1 ORDER BY event_id ASC OFFSET $2 LIMIT $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.529235834Z","level":"info","source":"atc","message":"atc.build-tracker.track.task-step.initializing","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.636172970Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"106.744026ms","query":"SELECT w.name, w.version, w.addr, w.state, w.baggageclaim_url, w.certs_path, w.http_proxy_url, w.https_proxy_url, w.no_proxy, w.active_containers, w.active_volumes, w.resource_types, w.platform, w.tags, t.name, w.team_id, w.start_time, w.expires, w.ephemeral FROM workers w LEFT JOIN teams t ON w.team_id = t.id JOIN containers c ON c.worker_name = w.name WHERE (c.build_id = $1 AND c.plan_id = $2 AND c.team_id = $3)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.646907734Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"115.595964ms","query":"SELECT builds.completed FROM builds WHERE builds.id = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.694513917Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"58.208736ms","query":"SELECT w.name, w.version, w.addr, w.state, w.baggageclaim_url, w.certs_path, w.http_proxy_url, w.https_proxy_url, w.no_proxy, w.active_containers, w.active_volumes, w.resource_types, w.platform, w.tags, t.name, w.team_id, w.start_time, w.expires, w.ephemeral FROM workers w LEFT JOIN teams t ON w.team_id = t.id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.783690353Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"86.827471ms","query":"SELECT worker_name, COUNT(*) FROM containers WHERE build_id IS NOT NULL GROUP BY worker_name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.790990640Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"143.988085ms","query":"SELECT type, version, payload FROM pipeline_build_events_1 WHERE build_id = $1 ORDER BY event_id ASC OFFSET $2 LIMIT $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.933931002Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query-row","data":{"duration":"149.964303ms","query":"SELECT id, handle, worker_name, hijacked, discontinued, state, meta_type, meta_step_name, meta_attempt, meta_working_directory, meta_process_user, meta_pipeline_id, meta_job_id, meta_build_id, meta_pipeline_name, meta_job_name, meta_build_name FROM containers WHERE (worker_name = $1 AND build_id = $2 AND plan_id = $3 AND team_id = $4)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:29.934011133Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.creating-container-in-db","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:30.114004867Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.created-creating-container-in-db","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:30.200207358Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.fetching-image","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:30.294299974Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-creating-container-in-db.query-row","data":{"duration":"93.906852ms","query":"SELECT id, handle, worker_name, hijacked, discontinued, state, meta_type, meta_step_name, meta_attempt, meta_working_directory, meta_process_user, meta_pipeline_id, meta_job_id, meta_build_id, meta_pipeline_name, meta_job_name, meta_build_name FROM containers WHERE (worker_name = $1 AND image_check_container_id = $2 AND team_id = $3)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:30.294366746Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.creating-container-in-db","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:30.477905643Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.created-creating-container-in-db","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:30.619611567Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.fetching-image","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:30.894416956Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query-row","data":{"duration":"83.563394ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.worker_base_resource_type_id = $1 AND v.worker_name = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:30.915541638Z","level":"debug","source":"atc","message":"atc.pipelines.scheduler.tick.lock.acquire.acquired","data":{"id":[2,1],"pipeline":"simple","session":"17.2.237.1.1","team":"main"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:30.999734740Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-volume-for-base-resource-type.found-created-volume","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1.1","step-name":"alert","volume":"3d39e04d-6229-4173-7af6-81239aaa68c5"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:31.024568654Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.exec","data":{"duration":"108.944309ms","query":"UPDATE pipelines SET last_scheduled = now() WHERE id = $1 AND now() - last_scheduled \u003e ($2 || ' SECONDS')::INTERVAL","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:31.120027465Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query-row","data":{"duration":"120.072115ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.path = $2 AND v.team_id = $3 AND v.worker_name = $4","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:31.131626100Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query-row","data":{"duration":"106.936183ms","query":"SELECT cache_index FROM pipelines WHERE id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:31.241398217Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query-row","data":{"duration":"109.599216ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id WHERE p.id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:31.337589389Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query","data":{"duration":"95.999339ms","query":"SELECT r.id, r.name, r.type, r.config, r.check_error, rs.last_check_start_time, rs.last_check_end_time, r.pipeline_id, r.nonce, r.resource_config_id, r.resource_config_scope_id, p.name, t.name, rs.check_error, rp.version, rp.comment_text FROM resources r JOIN pipelines p ON p.id = r.pipeline_id JOIN teams t ON t.id = p.team_id LEFT JOIN resource_config_scopes rs ON r.resource_config_scope_id = rs.id LEFT JOIN resource_pins rp ON rp.resource_id = r.id WHERE r.active = $1 AND r.pipeline_id = $2 ORDER BY r.name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:31.345323311Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-cow-volume-for-container.created-creating-volume","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1.2","step-name":"alert","volume":"f602d760-c709-4fc7-7cc1-12a8613d69d6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:31.445780615Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-cow-volume-for-container.acquire.acquired","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","id":[4,88],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1.2.1","step-name":"alert","volume":"f602d760-c709-4fc7-7cc1-12a8613d69d6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:31.450806730Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query","data":{"duration":"112.917204ms","query":"SELECT j.id, j.name, j.config, j.paused, j.first_logged_build_id, j.pipeline_id, p.name, p.team_id, t.name, j.nonce, j.tags, j.has_new_inputs FROM jobs j, pipelines p LEFT JOIN teams t ON p.team_id = t.id WHERE j.pipeline_id = p.id AND active = $1 AND pipeline_id = $2 ORDER BY j.id ASC","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:31.548125149Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-cow-volume-for-container.creating-real-volume","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1.2","step-name":"alert","volume":"f602d760-c709-4fc7-7cc1-12a8613d69d6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:31.594010129Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query","data":{"duration":"142.322651ms","query":"SELECT r.id, r.name, r.type, r.config, rcv.version, r.nonce, r.check_error, ro.check_error FROM resource_types r LEFT JOIN resource_configs c ON c.id = r.resource_config_id LEFT JOIN resource_config_scopes ro ON ro.resource_config_id = c.id LEFT JOIN LATERAL ( SELECT rcv.* FROM resource_config_versions rcv WHERE rcv.resource_config_scope_id = ro.id AND rcv.check_order != 0 ORDER BY rcv.check_order DESC LIMIT 1 ) AS rcv ON true WHERE r.active = $1 AND r.pipeline_id = $2 ORDER BY r.name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:31.979937138Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.exec","data":{"duration":"105.563049ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:31.979996217Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-cow-volume-for-container.created","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1.2","step-name":"alert","volume":"f602d760-c709-4fc7-7cc1-12a8613d69d6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:32.103750755Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-cow-volume-for-container.release.released","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","id":[4,88],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1.2.4","step-name":"alert","volume":"f602d760-c709-4fc7-7cc1-12a8613d69d6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:32.142119159Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query","data":{"duration":"99.181807ms","query":"SELECT b.id, b.name, b.job_id, b.team_id, b.status, b.manually_triggered, b.scheduled, b.schema, b.private_plan, b.public_plan, b.create_time, b.start_time, b.end_time, b.reap_time, j.name, b.pipeline_id, p.name, t.name, b.nonce, b.drained, b.aborted, b.completed FROM builds b LEFT OUTER JOIN jobs j ON b.job_id = j.id LEFT OUTER JOIN pipelines p ON b.pipeline_id = p.id LEFT OUTER JOIN teams t ON b.team_id = t.id WHERE b.pipeline_id = $1 AND b.status = $2 AND j.active = $3 ORDER BY b.id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:32.142284353Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"51.861692ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:32.220137239Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query-row","data":{"duration":"116.171086ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.path = $2 AND v.team_id = $3 AND v.worker_name = $4","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:32.242274147Z","level":"debug","source":"atc","message":"atc.pipelines.scheduler.tick.lock.release.released","data":{"id":[2,1],"pipeline":"simple","session":"17.2.237.1.2","team":"main"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:32.306735279Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.query-row","data":{"duration":"86.358464ms","query":"INSERT INTO volumes (worker_name,handle,container_id,path,team_id) VALUES ($1,$2,$3,$4,$5) RETURNING id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:32.306809639Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-volume-for-container.created-creating-volume","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1.3","step-name":"alert","volume":"5c825929-4c16-42ff-5cd8-91d965bc4e14"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:32.376875763Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-volume-for-container.acquire.acquired","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","id":[4,89],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1.3.1","step-name":"alert","volume":"5c825929-4c16-42ff-5cd8-91d965bc4e14"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:32.489103262Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-volume-for-container.creating-real-volume","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1.3","step-name":"alert","volume":"5c825929-4c16-42ff-5cd8-91d965bc4e14"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.000389289Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-creating-container-in-db.exec","data":{"duration":"111.529467ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.000449497Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-volume-for-container.created","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1.3","step-name":"alert","volume":"5c825929-4c16-42ff-5cd8-91d965bc4e14"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.078343912Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.find-or-create-volume-for-container.release.released","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","id":[4,89],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1.3.4","step-name":"alert","volume":"5c825929-4c16-42ff-5cd8-91d965bc4e14"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.078448917Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.worker-certs-volume.find-or-create.finding-worker-resource-certs","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1.1.1","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.078483252Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.worker-certs-volume.find-or-create.worker-resource-certs-not-found","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1.1.1","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.078529949Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.creating-garden-container","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.314571982Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.created-container-in-garden","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.446677997Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-garden.exec","data":{"duration":"131.947042ms","query":"UPDATE containers SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.446734521Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.created-container-in-db","data":{"build":8,"container":"8fc1af5a-9027-478b-5274-e1fa48992996","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.1","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.548155193Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"101.237024ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.state = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.690753693Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"142.378882ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.handle = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.856382456Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"39.668299ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.handle = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.940046621Z","level":"debug","source":"atc","message":"atc.build-tracker.track.start","data":{"session":"18.241"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:33.977560032Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"38.153685ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.011462800Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"68.160968ms","query":"SELECT w.name, w.version, w.addr, w.state, w.baggageclaim_url, w.certs_path, w.http_proxy_url, w.https_proxy_url, w.no_proxy, w.active_containers, w.active_volumes, w.resource_types, w.platform, w.tags, t.name, w.team_id, w.start_time, w.expires, w.ephemeral FROM workers w LEFT JOIN teams t ON w.team_id = t.id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.017109992Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"76.950555ms","query":"SELECT b.id, b.name, b.job_id, b.team_id, b.status, b.manually_triggered, b.scheduled, b.schema, b.private_plan, b.public_plan, b.create_time, b.start_time, b.end_time, b.reap_time, j.name, b.pipeline_id, p.name, t.name, b.nonce, b.drained, b.aborted, b.completed FROM builds b LEFT OUTER JOIN jobs j ON b.job_id = j.id LEFT OUTER JOIN pipelines p ON b.pipeline_id = p.id LEFT OUTER JOIN teams t ON b.team_id = t.id WHERE b.status = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.017914254Z","level":"debug","source":"atc","message":"atc.build-tracker.track.done","data":{"session":"18.241"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.027561139Z","level":"debug","source":"atc","message":"atc.collector.tick.acquire.acquired","data":{"id":[3,-824445620],"session":"20.80.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.027663395Z","level":"debug","source":"atc","message":"atc.collector.tick.build-collector.start","data":{"session":"20.80.2"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.074178350Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"128.286943ms","query":"SELECT r.id, r.name, r.type, r.config, rcv.version, r.nonce, r.check_error, ro.check_error FROM resource_types r LEFT JOIN resource_configs c ON c.id = r.resource_config_id LEFT JOIN resource_config_scopes ro ON ro.resource_config_id = c.id LEFT JOIN LATERAL ( SELECT rcv.* FROM resource_config_versions rcv WHERE rcv.resource_config_scope_id = ro.id AND rcv.check_order != 0 ORDER BY rcv.check_order DESC LIMIT 1 ) AS rcv ON true WHERE r.active = $1 AND r.pipeline_id = $2 ORDER BY r.name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.100611239Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"82.122911ms","query":"SELECT worker_name, COUNT(*) FROM containers WHERE build_id IS NOT NULL GROUP BY worker_name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.110240440Z","level":"debug","source":"atc","message":"atc.build-log-collector.tick.acquire.acquired","data":{"id":[3,997540461],"session":"22.80.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.110373529Z","level":"debug","source":"atc","message":"atc.build-log-collector.tick.build-reaper.start","data":{"session":"22.80.2"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.110467658Z","level":"debug","source":"atc","message":"atc.build-tracker.track.lock.acquire.not-acquired-already-held-locally","data":{"build":8,"build_id":8,"id":[1,8],"job":"test","pipeline":"simple","session":"18.241.1.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.110543272Z","level":"debug","source":"atc","message":"atc.build-tracker.track.build-already-tracked","data":{"build":8,"job":"test","pipeline":"simple","session":"18.241"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.111416598Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"83.571311ms","query":"UPDATE builds b SET interceptible = $1 WHERE completed = $2 AND interceptible = $3 AND (job_id IS NOT NULL OR now() - end_time \u003e '300 seconds'::interval) AND (NOT EXISTS (SELECT 1 FROM jobs j WHERE j.latest_completed_build_id = b.id) OR status = $4)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.111495883Z","level":"debug","source":"atc","message":"atc.collector.tick.build-collector.done","data":{"session":"20.80.2"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.111530450Z","level":"debug","source":"atc","message":"atc.collector.tick.worker-collector.start","data":{"session":"20.80.3"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.161363614Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"76.947263ms","query":"SELECT w.name, w.version, w.addr, w.state, w.baggageclaim_url, w.certs_path, w.http_proxy_url, w.https_proxy_url, w.no_proxy, w.active_containers, w.active_volumes, w.resource_types, w.platform, w.tags, t.name, w.team_id, w.start_time, w.expires, w.ephemeral FROM workers w LEFT JOIN teams t ON w.team_id = t.id WHERE w.name = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.168521841Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"56.872355ms","query":"DELETE FROM workers WHERE ephemeral = $1 AND expires \u003c NOW() RETURNING name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.169016748Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"58.537004ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.174677106Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"100.352602ms","query":"SELECT r.id, r.name, r.type, r.config, r.check_error, rs.last_check_start_time, rs.last_check_end_time, r.pipeline_id, r.nonce, r.resource_config_id, r.resource_config_scope_id, p.name, t.name, rs.check_error, rp.version, rp.comment_text FROM resources r JOIN pipelines p ON p.id = r.pipeline_id JOIN teams t ON t.id = p.team_id LEFT JOIN resource_config_scopes rs ON r.resource_config_scope_id = rs.id LEFT JOIN resource_pins rp ON rp.resource_id = r.id WHERE r.active = $1 AND r.pipeline_id = $2 ORDER BY r.name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.295828230Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"126.47659ms","query":"SELECT j.id, j.name, j.config, j.paused, j.first_logged_build_id, j.pipeline_id, p.name, p.team_id, t.name, j.nonce, j.tags, j.has_new_inputs FROM jobs j, pipelines p LEFT JOIN teams t ON p.team_id = t.id WHERE j.pipeline_id = p.id AND active = $1 AND pipeline_id = $2 ORDER BY j.id ASC","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.297409179Z","level":"debug","source":"atc","message":"atc.build-log-collector.tick.build-reaper.done","data":{"session":"22.80.2"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.323646976Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"154.846027ms","query":"UPDATE workers SET expires = $1, state = $2 WHERE state = $3 AND expires \u003c NOW() RETURNING name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.401276442Z","level":"debug","source":"atc","message":"atc.build-log-collector.tick.release.released","data":{"id":[3,997540461],"session":"22.80.3"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.447172488Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"123.109948ms","query":"DELETE FROM workers WHERE state = $1 AND name NOT IN (SELECT DISTINCT w.name FROM builds b JOIN containers c ON b.id = c.build_id JOIN workers w ON w.name = c.worker_name LEFT JOIN jobs j ON j.id = b.job_id WHERE b.completed = $2 AND (j.interruptible = $3 OR b.job_id IS NULL)) RETURNING name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.534269905Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"86.803581ms","query":"UPDATE workers SET state = $1, addr = $2, baggageclaim_url = $3 WHERE state = $4 AND name NOT IN (SELECT DISTINCT w.name FROM builds b JOIN containers c ON b.id = c.build_id JOIN workers w ON w.name = c.worker_name LEFT JOIN jobs j ON j.id = b.job_id WHERE b.completed = $5 AND (j.interruptible = $6 OR b.job_id IS NULL)) RETURNING name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.564166428Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"29.640283ms","query":"SELECT name, state FROM workers","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.564268330Z","level":"debug","source":"atc","message":"atc.collector.tick.worker-collector.done","data":{"session":"20.80.3"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.564304855Z","level":"debug","source":"atc","message":"atc.collector.tick.resource-cache-use-collector.start","data":{"session":"20.80.4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.623904748Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"59.496286ms","query":"DELETE FROM build_image_resource_caches birc USING builds b WHERE birc.build_id = b.id AND ((now() - b.end_time) \u003e '24 HOURS'::INTERVAL) AND job_id IS NULL","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.682178214Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"58.136418ms","query":"DELETE FROM resource_cache_uses rcu USING builds b WHERE (rcu.build_id = b.id AND b.interceptible = false)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.682429247Z","level":"debug","source":"atc","message":"atc.collector.tick.resource-cache-use-collector.done","data":{"session":"20.80.4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.682561915Z","level":"debug","source":"atc","message":"atc.collector.tick.resource-config-collector.start","data":{"session":"20.80.5"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.746292117Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"63.296785ms","query":"DELETE FROM resource_configs WHERE id NOT IN (SELECT resource_config_id FROM resource_config_check_sessions UNION SELECT resource_config_id FROM resource_caches UNION SELECT resource_config_id FROM resources WHERE resource_config_id IS NOT NULL UNION SELECT resource_config_id FROM resource_types WHERE resource_config_id IS NOT NULL)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.746556057Z","level":"debug","source":"atc","message":"atc.collector.tick.resource-config-collector.done","data":{"session":"20.80.5"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.746688305Z","level":"debug","source":"atc","message":"atc.collector.tick.resource-cache-collector.start","data":{"session":"20.80.6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.836113060Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"88.663121ms","query":"DELETE FROM resource_caches WHERE id NOT IN (SELECT resource_cache_id FROM resource_cache_uses UNION SELECT resource_cache_id FROM resource_configs WHERE resource_cache_id IS NOT NULL UNION SELECT resource_cache_id FROM build_image_resource_caches UNION SELECT r_cache.id FROM next_build_inputs nbi JOIN resource_config_versions rcv ON rcv.id = nbi.resource_config_version_id JOIN resource_config_scopes rs ON rs.id = rcv.resource_config_scope_id JOIN resource_caches r_cache ON r_cache.resource_config_id = rs.resource_config_id AND r_cache.version = rcv.version JOIN jobs j ON nbi.job_id = j.id JOIN pipelines p ON j.pipeline_id = p.id WHERE p.paused = false) RETURNING id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.836322522Z","level":"debug","source":"atc","message":"atc.collector.tick.resource-cache-collector.done","data":{"session":"20.80.6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.836398098Z","level":"debug","source":"atc","message":"atc.collector.tick.resource-config-check-session-collector.start","data":{"session":"20.80.7"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.890383404Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"53.820114ms","query":"DELETE FROM resource_config_check_sessions WHERE expires_at \u003c NOW()","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.935377588Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"44.763592ms","query":"DELETE FROM resource_config_check_sessions WHERE id NOT IN (SELECT rccs.id FROM resource_config_check_sessions rccs JOIN resource_configs rc ON rccs.resource_config_id = rc.id JOIN resources r ON r.resource_config_id = rc.id JOIN pipelines p ON p.id = r.pipeline_id WHERE r.active AND NOT p.paused UNION SELECT rccs.id FROM resource_config_check_sessions rccs JOIN resource_configs rc ON rccs.resource_config_id = rc.id JOIN resource_types rt ON rt.resource_config_id = rc.id JOIN pipelines p ON p.id = rt.pipeline_id WHERE rt.active AND NOT p.paused)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.935578915Z","level":"debug","source":"atc","message":"atc.collector.tick.resource-config-check-session-collector.done","data":{"session":"20.80.7"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.935707291Z","level":"debug","source":"atc","message":"atc.collector.tick.artifact-collector.start","data":{"session":"20.80.8"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.989151323Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"53.232215ms","query":"DELETE FROM worker_artifacts WHERE created_at \u003c NOW() - interval '12 hours'","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.989226093Z","level":"debug","source":"atc","message":"atc.collector.tick.artifact-collector.done","data":{"session":"20.80.8"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:34.989261072Z","level":"debug","source":"atc","message":"atc.collector.tick.container-collector.start","data":{"session":"20.80.9"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.114801541Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"125.263864ms","query":"SELECT c.id, c.handle, c.worker_name, c.hijacked, c.discontinued, c.state, c.meta_type, c.meta_step_name, c.meta_attempt, c.meta_working_directory, c.meta_process_user, c.meta_pipeline_id, c.meta_job_id, c.meta_build_id, c.meta_pipeline_name, c.meta_job_name, c.meta_build_name FROM containers c LEFT JOIN builds b ON b.id = c.build_id LEFT JOIN containers icc ON icc.id = c.image_check_container_id LEFT JOIN containers igc ON igc.id = c.image_get_container_id WHERE (c.build_id IS NULL AND c.image_check_container_id IS NULL AND c.image_get_container_id IS NULL AND c.resource_config_check_session_id IS NULL OR (c.build_id IS NOT NULL AND b.interceptible = $1) OR (c.image_check_container_id IS NOT NULL AND icc.state \u003c\u003e $2) OR (c.image_get_container_id IS NOT NULL AND igc.state \u003c\u003e $3))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.115107898Z","level":"debug","source":"atc","message":"atc.collector.tick.container-collector.orphaned-containers.found-orphaned-containers-for-deletion","data":{"created-containers-num":1,"creating-containers-num":0,"destroying-containers-num":5,"session":"20.80.9.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.115351061Z","level":"debug","source":"atc","message":"atc.collector.tick.container-collector.orphaned-containers.found-created-containers-for-deletion","data":{"num-containers":1,"session":"20.80.9.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.238599207Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"121.592179ms","query":"DELETE FROM containers WHERE containers.state = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.240365692Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"124.268756ms","query":"UPDATE containers SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4)) RETURNING discontinued","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.369632484Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"130.561585ms","query":"DELETE FROM containers WHERE (state IN ($1,$2) AND NOW() - missing_since \u003e $3)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.369808132Z","level":"debug","source":"atc","message":"atc.collector.tick.container-collector.done","data":{"session":"20.80.9"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.369912176Z","level":"debug","source":"atc","message":"atc.collector.tick.volume-collector.start","data":{"session":"20.80.10"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.504255815Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"134.034385ms","query":"DELETE FROM volumes WHERE id IN (SELECT v.id FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.state = $1)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.613812355Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query","data":{"duration":"109.068261ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id IS NULL AND v.worker_artifact_id IS NULL AND v.worker_base_resource_type_id IS NULL AND v.worker_resource_cache_id IS NULL AND v.worker_resource_certs_id IS NULL AND v.worker_task_cache_id IS NULL AND v.state = $1 AND (w.state = $2 OR w.state = $3 OR w.state = $4)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.614335356Z","level":"debug","source":"atc","message":"atc.collector.tick.volume-collector.mark-volumes.found-orphaned-volumes","data":{"destroying":6,"session":"20.80.10.2"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.717939848Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"103.034564ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.801804724Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"83.243572ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:35.914338097Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"112.211872ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.057867690Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"94.395719ms","query":"INSERT INTO build_image_resource_caches (resource_cache_id,build_id) VALUES ($1,$2)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.059031492Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"144.130313ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.134962466Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"76.903807ms","query":"SELECT wbrt.id, wbrt.version FROM worker_base_resource_types wbrt LEFT JOIN base_resource_types brt ON brt.id = wbrt.base_resource_type_id LEFT JOIN workers w ON w.name = wbrt.worker_name WHERE brt.name = $1 AND wbrt.worker_name = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.146655572Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"87.515228ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.215427561Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"68.564075ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.216739313Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"81.277601ms","query":"SELECT id FROM worker_resource_caches WHERE resource_cache_id = $1 AND worker_base_resource_type_id = $2 FOR SHARE","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.290591439Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"73.39317ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.worker_name = $1 AND v.worker_resource_cache_id = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.348009750Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"131.919166ms","query":"DELETE FROM volumes WHERE (state IN ($1,$2) AND NOW() - missing_since \u003e $3)","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.348070548Z","level":"debug","source":"atc","message":"atc.collector.tick.volume-collector.done","data":{"session":"20.80.10"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.363334471Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"90.929955ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.472558804Z","level":"debug","source":"atc","message":"atc.collector.tick.release.released","data":{"id":[3,-824445620],"session":"20.80.11"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.556801355Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"136.853364ms","query":"SELECT metadata FROM resource_caches WHERE id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.556901926Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.image.init-image.found-initialized-versioned-source","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"metadata":[{"name":"image","value":"sha256:db8ee"}],"pipeline":"simple","session":"18.239.5.1.2","step-name":"alert","version":{"digest":"sha256:9f1003c480699be56815db0f8146ad2e22efea85129b5b5983d0e0fb52d9ab70"}}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.724439389Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"51.615501ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.path = $2 AND v.team_id = $3 AND v.worker_name = $4","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:36.878227028Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.create-cow-volume.find-or-create-cow-volume-for-container.created-creating-volume","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.2.1","step-name":"alert","volume":"5699d719-8f77-4436-7e32-3a6a3cb257a8"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:37.004942786Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.create-cow-volume.find-or-create-cow-volume-for-container.acquire.acquired","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","id":[4,90],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.2.1.1","step-name":"alert","volume":"5699d719-8f77-4436-7e32-3a6a3cb257a8"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:37.129008611Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.create-cow-volume.find-or-create-cow-volume-for-container.creating-real-volume","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.2.1","step-name":"alert","volume":"5699d719-8f77-4436-7e32-3a6a3cb257a8"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:37.506999519Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"87.069257ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:37.507068372Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.create-cow-volume.find-or-create-cow-volume-for-container.created","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.2.1","step-name":"alert","volume":"5699d719-8f77-4436-7e32-3a6a3cb257a8"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:37.599029038Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.create-cow-volume.find-or-create-cow-volume-for-container.release.released","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","id":[4,90],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.2.1.4","step-name":"alert","volume":"5699d719-8f77-4436-7e32-3a6a3cb257a8"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:37.710312854Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"109.661111ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.path = $2 AND v.team_id = $3 AND v.worker_name = $4","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:37.758327909Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"47.851671ms","query":"INSERT INTO volumes (worker_name,handle,container_id,path,team_id) VALUES ($1,$2,$3,$4,$5) RETURNING id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:37.758434555Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.created-creating-volume","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.3","step-name":"alert","volume":"0fd537ed-a968-458f-521e-bfae53ee1b58"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:37.842508482Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.acquire.acquired","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","id":[4,91],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.3.1","step-name":"alert","volume":"0fd537ed-a968-458f-521e-bfae53ee1b58"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:37.937174812Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.creating-real-volume","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.3","step-name":"alert","volume":"0fd537ed-a968-458f-521e-bfae53ee1b58"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:38.431345777Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"136.581773ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:38.431428286Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.created","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.3","step-name":"alert","volume":"0fd537ed-a968-458f-521e-bfae53ee1b58"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:38.535735335Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.release.released","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","id":[4,91],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.3.4","step-name":"alert","volume":"0fd537ed-a968-458f-521e-bfae53ee1b58"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:38.624122127Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"87.973549ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.path = $2 AND v.team_id = $3 AND v.worker_name = $4","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:38.733583616Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.query-row","data":{"duration":"109.253692ms","query":"INSERT INTO volumes (worker_name,handle,container_id,path,team_id) VALUES ($1,$2,$3,$4,$5) RETURNING id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:38.733715610Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.created-creating-volume","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.4","step-name":"alert","volume":"87f66a23-d705-4df2-677e-94d9731d1501"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:38.816562164Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.acquire.acquired","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","id":[4,92],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.4.1","step-name":"alert","volume":"87f66a23-d705-4df2-677e-94d9731d1501"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:38.927505734Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.creating-real-volume","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.4","step-name":"alert","volume":"87f66a23-d705-4df2-677e-94d9731d1501"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:39.429087746Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.image.created-container-in-db.exec","data":{"duration":"134.328487ms","query":"UPDATE volumes SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:39.429146735Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.created","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.4","step-name":"alert","volume":"87f66a23-d705-4df2-677e-94d9731d1501"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:39.529934398Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.find-or-create-volume-for-container.release.released","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","id":[4,92],"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5.4.4","step-name":"alert","volume":"87f66a23-d705-4df2-677e-94d9731d1501"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:39.530082541Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.creating-garden-container","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:39.889797587Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.created-container-in-garden","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:40.051504793Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-garden.exec","data":{"duration":"161.458988ms","query":"UPDATE containers SET state = $1 WHERE (id = $2 AND (state = $3 OR state = $4))","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:40.051572864Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.created-container-in-db","data":{"build":8,"container":"240f3519-5ad2-4c41-465f-55b253c2d0d5","job":"test","job-id":1,"pipeline":"simple","session":"18.239.5","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:40.131489977Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"79.74025ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.container_id = $1 AND v.state = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:40.260080713Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query-row","data":{"duration":"128.329116ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.handle = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:40.474039582Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query-row","data":{"duration":"98.712113ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.handle = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:40.649413704Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query-row","data":{"duration":"84.253124ms","query":"SELECT v.id, v.handle, v.state, w.name, v.path, c.handle, pv.handle, v.team_id, wrc.resource_cache_id, v.worker_base_resource_type_id, v.worker_task_cache_id, v.worker_resource_certs_id, v.worker_artifact_id, case when v.worker_base_resource_type_id is not NULL then 'resource-type' when v.worker_resource_cache_id is not NULL then 'resource' when v.container_id is not NULL then 'container' when v.worker_task_cache_id is not NULL then 'task-cache' when v.worker_resource_certs_id is not NULL then 'resource-certs' when v.worker_artifact_id is not NULL then 'artifact' else 'unknown' end FROM volumes v LEFT JOIN workers w ON v.worker_name = w.name LEFT JOIN containers c ON v.container_id = c.id LEFT JOIN volumes pv ON v.parent_id = pv.id LEFT JOIN worker_resource_caches wrc ON wrc.id = v.worker_resource_cache_id WHERE v.handle = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:41.107099114Z","level":"info","source":"atc","message":"atc.build-tracker.track.task-step.spawning","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:41.325063301Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"32.732284ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:41.371975882Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.starting","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:41.506300481Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"131.591878ms","query":"SELECT builds.completed FROM builds WHERE builds.id = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:41.563651543Z","level":"info","source":"atc","message":"atc.build-tracker.track.task-step.attached","data":{"build":8,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:41.642574857Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"136.110983ms","query":"SELECT type, version, payload FROM pipeline_build_events_1 WHERE build_id = $1 ORDER BY event_id ASC OFFSET $2 LIMIT $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:41.851604467Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"115.154681ms","query":"SELECT builds.completed FROM builds WHERE builds.id = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:41.955437783Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"103.748532ms","query":"SELECT type, version, payload FROM pipeline_build_events_1 WHERE build_id = $1 ORDER BY event_id ASC OFFSET $2 LIMIT $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.049870502Z","level":"info","source":"atc","message":"atc.build-tracker.track.task-step.finished","data":{"build":8,"exit-status":0,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.049943053Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.registering-outputs","data":{"build":8,"job":"test","job-id":1,"outputs":null,"pipeline":"simple","session":"18.239.5","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.049982829Z","level":"debug","source":"atc","message":"atc.build-tracker.track.task-step.initializing-caches","data":{"build":8,"caches":null,"job":"test","job-id":1,"pipeline":"simple","session":"18.239.5","step-name":"alert"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.162946060Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"100.814712ms","query":"SELECT builds.completed FROM builds WHERE builds.id = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.278038247Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"114.999648ms","query":"SELECT type, version, payload FROM pipeline_build_events_1 WHERE build_id = $1 ORDER BY event_id ASC OFFSET $2 LIMIT $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.321114832Z","level":"debug","source":"atc","message":"atc.pipelines.scheduler.tick.lock.acquire.acquired","data":{"id":[2,1],"pipeline":"simple","session":"17.2.238.1.1","team":"main"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.673611304Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.exec","data":{"duration":"352.394135ms","query":"UPDATE pipelines SET last_scheduled = now() WHERE id = $1 AND now() - last_scheduled \u003e ($2 || ' SECONDS')::INTERVAL","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.760453329Z","level":"info","source":"atc","message":"atc.build-tracker.track.finish.failed","data":{"build":8,"job":"test","pipeline":"simple","session":"18.239.6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.762097718Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query-row","data":{"duration":"88.306841ms","query":"SELECT cache_index FROM pipelines WHERE id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.841461732Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query-row","data":{"duration":"80.656683ms","query":"SELECT b.id, b.name, b.job_id, b.team_id, b.status, b.manually_triggered, b.scheduled, b.schema, b.private_plan, b.public_plan, b.create_time, b.start_time, b.end_time, b.reap_time, j.name, b.pipeline_id, p.name, t.name, b.nonce, b.drained, b.aborted, b.completed FROM builds b LEFT OUTER JOIN jobs j ON b.job_id = j.id LEFT OUTER JOIN pipelines p ON b.pipeline_id = p.id LEFT OUTER JOIN teams t ON b.team_id = t.id WHERE b.id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.886463629Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"116.421655ms","query":"SELECT builds.completed FROM builds WHERE builds.id = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.891732264Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"129.316973ms","query":"SELECT v.id, v.check_order, r.id, o.build_id, b.job_id FROM build_resource_config_version_outputs o JOIN builds b ON b.id = o.build_id JOIN resource_config_versions v ON v.version_md5 = o.version_md5 JOIN resources r ON r.id = o.resource_id WHERE r.resource_config_scope_id = v.resource_config_scope_id AND (r.id, v.version_md5) NOT IN (SELECT resource_id, version_md5 from resource_disabled_versions) AND v.check_order \u003c\u003e $1 AND b.status = $2 AND r.pipeline_id = $3","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.956042193Z","level":"debug","source":"atc","message":"atc.build-tracker.track.lock.release.released","data":{"build":8,"build_id":8,"id":[1,8],"job":"test","pipeline":"simple","session":"18.239.1.2"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:42.974463682Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"87.883308ms","query":"SELECT type, version, payload FROM pipeline_build_events_1 WHERE build_id = $1 ORDER BY event_id ASC OFFSET $2 LIMIT $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:43.006871484Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"114.690445ms","query":"SELECT v.id, v.check_order, r.id, i.build_id, i.name, b.job_id, b.status = 'succeeded' FROM build_resource_config_version_inputs i JOIN builds b ON b.id = i.build_id JOIN resource_config_versions v ON v.version_md5 = i.version_md5 JOIN resources r ON r.id = i.resource_id WHERE r.resource_config_scope_id = v.resource_config_scope_id AND (r.id, v.version_md5) NOT IN (SELECT resource_id, version_md5 from resource_disabled_versions) AND v.check_order \u003c\u003e $1 AND r.pipeline_id = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:43.078690666Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"71.573117ms","query":"SELECT v.id, v.check_order, r.id FROM resource_config_versions v JOIN resources r ON r.resource_config_scope_id = v.resource_config_scope_id LEFT JOIN resource_disabled_versions d ON d.resource_id = r.id AND d.version_md5 = v.version_md5 WHERE v.check_order \u003c\u003e $1 AND d.resource_id IS NULL AND d.version_md5 IS NULL AND r.pipeline_id = $2","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:43.172620267Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"93.568352ms","query":"SELECT j.name, j.id FROM jobs j WHERE j.pipeline_id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:43.266796480Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"94.043336ms","query":"SELECT r.name, r.id FROM resources r WHERE r.pipeline_id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:43.416053683Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query-row","data":{"duration":"149.002247ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id WHERE p.id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:43.510817576Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"94.569628ms","query":"SELECT r.id, r.name, r.type, r.config, r.check_error, rs.last_check_start_time, rs.last_check_end_time, r.pipeline_id, r.nonce, r.resource_config_id, r.resource_config_scope_id, p.name, t.name, rs.check_error, rp.version, rp.comment_text FROM resources r JOIN pipelines p ON p.id = r.pipeline_id JOIN teams t ON t.id = p.team_id LEFT JOIN resource_config_scopes rs ON r.resource_config_scope_id = rs.id LEFT JOIN resource_pins rp ON rp.resource_id = r.id WHERE r.active = $1 AND r.pipeline_id = $2 ORDER BY r.name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:43.615015864Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"103.992089ms","query":"SELECT j.id, j.name, j.config, j.paused, j.first_logged_build_id, j.pipeline_id, p.name, p.team_id, t.name, j.nonce, j.tags, j.has_new_inputs FROM jobs j, pipelines p LEFT JOIN teams t ON p.team_id = t.id WHERE j.pipeline_id = p.id AND active = $1 AND pipeline_id = $2 ORDER BY j.id ASC","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:43.726392038Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"108.860774ms","query":"SELECT r.id, r.name, r.type, r.config, rcv.version, r.nonce, r.check_error, ro.check_error FROM resource_types r LEFT JOIN resource_configs c ON c.id = r.resource_config_id LEFT JOIN resource_config_scopes ro ON ro.resource_config_id = c.id LEFT JOIN LATERAL ( SELECT rcv.* FROM resource_config_versions rcv WHERE rcv.resource_config_scope_id = ro.id AND rcv.check_order != 0 ORDER BY rcv.check_order DESC LIMIT 1 ) AS rcv ON true WHERE r.active = $1 AND r.pipeline_id = $2 ORDER BY r.name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:43.940021853Z","level":"debug","source":"atc","message":"atc.build-tracker.track.start","data":{"session":"18.242"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:43.964720436Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"25.309072ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:44.055820035Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"115.574963ms","query":"SELECT b.id, b.name, b.job_id, b.team_id, b.status, b.manually_triggered, b.scheduled, b.schema, b.private_plan, b.public_plan, b.create_time, b.start_time, b.end_time, b.reap_time, j.name, b.pipeline_id, p.name, t.name, b.nonce, b.drained, b.aborted, b.completed FROM builds b LEFT OUTER JOIN jobs j ON b.job_id = j.id LEFT OUTER JOIN pipelines p ON b.pipeline_id = p.id LEFT OUTER JOIN teams t ON b.team_id = t.id WHERE b.status = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:44.055894615Z","level":"debug","source":"atc","message":"atc.build-tracker.track.done","data":{"session":"18.242"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:44.343568240Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"112.346724ms","query":"SELECT b.id, b.name, b.job_id, b.team_id, b.status, b.manually_triggered, b.scheduled, b.schema, b.private_plan, b.public_plan, b.create_time, b.start_time, b.end_time, b.reap_time, j.name, b.pipeline_id, p.name, t.name, b.nonce, b.drained, b.aborted, b.completed FROM builds b LEFT OUTER JOIN jobs j ON b.job_id = j.id LEFT OUTER JOIN pipelines p ON b.pipeline_id = p.id LEFT OUTER JOIN teams t ON b.team_id = t.id WHERE b.pipeline_id = $1 AND b.status = $2 AND j.active = $3 ORDER BY b.id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:44.488314742Z","level":"debug","source":"atc","message":"atc.pipelines.scheduler.tick.lock.release.released","data":{"id":[2,1],"pipeline":"simple","session":"17.2.238.1.2","team":"main"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:46.319870095Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"66.978055ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:51.056687998Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.start","data":{"command":"retire-worker","remote":"172.23.0.4:48134","session":"322.1.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:51.117788157Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"59.625196ms","query":"SELECT w.name, w.version, w.addr, w.state, w.baggageclaim_url, w.certs_path, w.http_proxy_url, w.https_proxy_url, w.no_proxy, w.active_containers, w.active_volumes, w.resource_types, w.platform, w.tags, t.name, w.team_id, w.start_time, w.expires, w.ephemeral FROM workers w LEFT JOIN teams t ON w.team_id = t.id WHERE w.name = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:51.209075802Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.exec","data":{"duration":"90.873844ms","query":"UPDATE workers SET state = $1 WHERE name = $2","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:51.209667678Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.end","data":{"command":"retire-worker","remote":"172.23.0.4:48134","session":"322.1.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:51.295896623Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"47.680536ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:53.804281037Z","level":"debug","source":"atc","message":"atc.report-containers-for-worker.handles-info","data":{"name":"deedae84fd4b","num-handles":7,"session":"7137"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:53.897862248Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"93.456316ms","query":"UPDATE containers SET missing_since = $1 WHERE (missing_since IS NOT NULL AND handle IN ($2,$3,$4,$5,$6,$7,$8))","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:53.939987099Z","level":"debug","source":"atc","message":"atc.build-tracker.track.start","data":{"session":"18.243"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:53.945049496Z","level":"debug","source":"atc","message":"atc.report-volumes-for-worker.handles-info","data":{"handles-count":46,"name":"deedae84fd4b","session":"7139"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:53.951810345Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"53.827169ms","query":"SELECT handle FROM containers WHERE missing_since IS NULL AND worker_name = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.005603627Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"66.215975ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.052462738Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"100.482311ms","query":"UPDATE containers SET missing_since = now() WHERE (handle IN ($1,$2,$3,$4,$5) AND state \u003c\u003e $6)","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.067883444Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"122.678058ms","query":"UPDATE volumes SET missing_since = $1 WHERE (missing_since IS NOT NULL AND handle IN ($2,$3,$4,$5,$6,$7,$8,$9,$10,$11,$12,$13,$14,$15,$16,$17,$18,$19,$20,$21,$22,$23,$24,$25,$26,$27,$28,$29,$30,$31,$32,$33,$34,$35,$36,$37,$38,$39,$40,$41,$42,$43,$44,$45,$46,$47))","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.069211852Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"129.090224ms","query":"SELECT b.id, b.name, b.job_id, b.team_id, b.status, b.manually_triggered, b.scheduled, b.schema, b.private_plan, b.public_plan, b.create_time, b.start_time, b.end_time, b.reap_time, j.name, b.pipeline_id, p.name, t.name, b.nonce, b.drained, b.aborted, b.completed FROM builds b LEFT OUTER JOIN jobs j ON b.job_id = j.id LEFT OUTER JOIN pipelines p ON b.pipeline_id = p.id LEFT OUTER JOIN teams t ON b.team_id = t.id WHERE b.status = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.069268589Z","level":"debug","source":"atc","message":"atc.build-tracker.track.done","data":{"session":"18.243"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.173808258Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"105.800456ms","query":"SELECT handle FROM volumes WHERE missing_since IS NULL AND worker_name = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.186903402Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.exec","data":{"duration":"134.288785ms","query":"DELETE FROM containers WHERE (worker_name = $1 AND handle NOT IN ($2,$3,$4,$5,$6,$7,$8) AND state = $9)","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.187123984Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.successfully-report-containers","data":{"command":"report-containers","remote":"172.23.0.4:48140","session":"324.1.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.287268891Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"113.25787ms","query":"UPDATE volumes SET missing_since = now() WHERE ((1=0) AND state \u003c\u003e $1)","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.383972859Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.exec","data":{"duration":"96.524854ms","query":"DELETE FROM volumes WHERE (worker_name = $1 AND handle NOT IN ($2,$3,$4,$5,$6,$7,$8,$9,$10,$11,$12,$13,$14,$15,$16,$17,$18,$19,$20,$21,$22,$23,$24,$25,$26,$27,$28,$29,$30,$31,$32,$33,$34,$35,$36,$37,$38,$39,$40,$41,$42,$43,$44,$45,$46,$47) AND state = $48)","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.384213026Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.successfully-report-volumes","data":{"command":"report-volumes","remote":"172.23.0.4:48138","session":"323.1.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.566356126Z","level":"debug","source":"atc","message":"atc.pipelines.scheduler.tick.lock.acquire.acquired","data":{"id":[2,1],"pipeline":"simple","session":"17.2.239.1.1","team":"main"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.634003376Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.exec","data":{"duration":"67.54466ms","query":"UPDATE pipelines SET last_scheduled = now() WHERE id = $1 AND now() - last_scheduled \u003e ($2 || ' SECONDS')::INTERVAL","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.717389294Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query-row","data":{"duration":"83.269466ms","query":"SELECT cache_index FROM pipelines WHERE id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.781650723Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"66.505009ms","query":"SELECT handle FROM containers WHERE discontinued = $1 AND state = $2 AND worker_name = $3","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.781729488Z","level":"debug","source":"atc","message":"atc.list-destroying-containers.containers-to-destroy","data":{"count":1,"session":"7143","worker":"deedae84fd4b"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.781991812Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.successfully-sweep-containers","data":{"command":"sweep-containers","remote":"172.23.0.4:48142","session":"325.1.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.810272299Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query-row","data":{"duration":"92.754527ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id WHERE p.id = $1","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.868731590Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"102.231286ms","query":"SELECT handle FROM volumes WHERE state = $1 AND worker_name = $2","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.868812119Z","level":"debug","source":"atc","message":"atc.found-orphaned-volumes","data":{"destroying":6}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.868859809Z","level":"debug","source":"atc","message":"atc.list-destroying-volumes.volumes-to-destroy","data":{"count":6,"session":"7145","worker":"deedae84fd4b"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.869225033Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.successfully-sweep-volumes","data":{"command":"sweep-volumes","remote":"172.23.0.4:48144","session":"326.1.1"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:54.963830075Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"153.338107ms","query":"SELECT r.id, r.name, r.type, r.config, r.check_error, rs.last_check_start_time, rs.last_check_end_time, r.pipeline_id, r.nonce, r.resource_config_id, r.resource_config_scope_id, p.name, t.name, rs.check_error, rp.version, rp.comment_text FROM resources r JOIN pipelines p ON p.id = r.pipeline_id JOIN teams t ON t.id = p.team_id LEFT JOIN resource_config_scopes rs ON r.resource_config_scope_id = rs.id LEFT JOIN resource_pins rp ON rp.resource_id = r.id WHERE r.active = $1 AND r.pipeline_id = $2 ORDER BY r.name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:55.068497538Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"104.443844ms","query":"SELECT j.id, j.name, j.config, j.paused, j.first_logged_build_id, j.pipeline_id, p.name, p.team_id, t.name, j.nonce, j.tags, j.has_new_inputs FROM jobs j, pipelines p LEFT JOIN teams t ON p.team_id = t.id WHERE j.pipeline_id = p.id AND active = $1 AND pipeline_id = $2 ORDER BY j.id ASC","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:55.136114014Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"66.909073ms","query":"SELECT r.id, r.name, r.type, r.config, rcv.version, r.nonce, r.check_error, ro.check_error FROM resource_types r LEFT JOIN resource_configs c ON c.id = r.resource_config_id LEFT JOIN resource_config_scopes ro ON ro.resource_config_id = c.id LEFT JOIN LATERAL ( SELECT rcv.* FROM resource_config_versions rcv WHERE rcv.resource_config_scope_id = ro.id AND rcv.check_order != 0 ORDER BY rcv.check_order DESC LIMIT 1 ) AS rcv ON true WHERE r.active = $1 AND r.pipeline_id = $2 ORDER BY r.name","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:55.359123229Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.heartbeat.start","data":{"command":"forward-worker","remote":"172.23.0.4:46444","session":"305.3.1.7","worker-address":"127.0.0.1:40011","worker-platform":"linux","worker-tags":""}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:55.830690945Z","level":"debug","source":"atc","message":"atc.log-conn.atc.build-tracker.track.task-step.created-container-in-db.query","data":{"duration":"83.053119ms","query":"SELECT b.id, b.name, b.job_id, b.team_id, b.status, b.manually_triggered, b.scheduled, b.schema, b.private_plan, b.public_plan, b.create_time, b.start_time, b.end_time, b.reap_time, j.name, b.pipeline_id, p.name, t.name, b.nonce, b.drained, b.aborted, b.completed FROM builds b LEFT OUTER JOIN jobs j ON b.job_id = j.id LEFT OUTER JOIN pipelines p ON b.pipeline_id = p.id LEFT OUTER JOIN teams t ON b.team_id = t.id WHERE b.pipeline_id = $1 AND b.status = $2 AND j.active = $3 ORDER BY b.id","session":"6"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:55.908817174Z","level":"debug","source":"atc","message":"atc.pipelines.scheduler.tick.lock.release.released","data":{"id":[2,1],"pipeline":"simple","session":"17.2.239.1.2","team":"main"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:56.076740944Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"115.664479ms","query":"SELECT active_tasks FROM workers WHERE name = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:56.139878123Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query-row","data":{"duration":"62.986477ms","query":"SELECT active_tasks FROM workers WHERE name = $1","session":"4"}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:56.140568869Z","level":"info","source":"tsa","message":"tsa.connection.channel.command.heartbeat.done","data":{"command":"forward-worker","remote":"172.23.0.4:46444","session":"305.3.1.7","worker-address":"127.0.0.1:40011","worker-platform":"linux","worker-tags":""}} | |
| [36mweb_1_da9eccd0ef92 |[0m {"timestamp":"2019-08-23T16:36:56.304705954Z","level":"debug","source":"atc","message":"atc.log-conn.atc.check-resource.found-created-container-in-db.query","data":{"duration":"38.253813ms","query":"SELECT p.id, p.name, p.groups, p.version, p.team_id, t.name, p.paused, p.public FROM pipelines p LEFT JOIN teams t ON p.team_id = t.id ORDER BY ordering","session":"4"}} |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment