[03:39:02.212] New invocation is queued and will start shortly
[03:39:03.415] Starting the invocation (attempt 1)
[03:39:03.464] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:39:03.465] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:39:03.465] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9109387272532429792",
"parameters_json": "{\"builder_name\":\"Deterministic Linux\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"dc54c7827dbb6e65a5f9b5c78183a6f951ad226b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux",
"scheduler_invocation_id:9109387272532429792",
"scheduler_job_id:chromium/Deterministic Linux",
"user_agent:luci-scheduler",
"buildset:commit/git/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b",
"gitiles_ref:refs/heads/master"
]
}
[03:39:06.573] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1528774743548032",
"id": "8943957210467795936",
"lease_expiration_ts": "1528810745556783",
"lease_key": "1510276368",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"dc54c7827dbb6e65a5f9b5c78183a6f951ad226b\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1528774746472141",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/14234",
"builder:Deterministic Linux",
"buildset:commit/git/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9109387272532429792",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_dimension:builder:Deterministic Linux",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Deterministic Linux/14234",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8943957210467795936",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:5d96bc6fe62ccf6bc1c65a31374778e7a60b3980",
"swarming_tag:builder:Deterministic Linux",
"swarming_tag:buildset:commit/git/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8943957210467795936/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9109387272532429792",
"swarming_tag:scheduler_job_id:chromium/Deterministic Linux",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e0ae4e28f9c2d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1528774746472206",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Deterministic Linux/14234",
"utcnow_ts": "1528774746563939"
}
}
[03:39:06.573] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Deterministic Linux/14234
[03:39:06.573] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:2:0) after 1m0s
[03:39:08.671] Received PubSub notification, asking Buildbucket for the build status
[03:39:08.707] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:06.704] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:2:0)
[03:40:06.704] Timer tick, asking Buildbucket for the build status
[03:40:06.740] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:06.740] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:4:0) after 1m0s
[03:41:06.835] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:4:0)
[03:41:06.835] Timer tick, asking Buildbucket for the build status
[03:41:06.964] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:06.964] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:5:0) after 1m0s
[03:42:06.982] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:5:0)
[03:42:06.982] Timer tick, asking Buildbucket for the build status
[03:42:07.059] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:07.059] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:6:0) after 1m0s
[03:43:07.134] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:6:0)
[03:43:07.134] Timer tick, asking Buildbucket for the build status
[03:43:07.181] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:07.181] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:7:0) after 1m0s
[03:44:07.313] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:7:0)
[03:44:07.313] Timer tick, asking Buildbucket for the build status
[03:44:07.390] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:07.390] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:8:0) after 1m0s
[03:45:07.517] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:8:0)
[03:45:07.517] Timer tick, asking Buildbucket for the build status
[03:45:07.576] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:07.576] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:9:0) after 1m0s
[03:46:07.700] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:9:0)
[03:46:07.701] Timer tick, asking Buildbucket for the build status
[03:46:07.806] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:07.806] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:10:0) after 1m0s
[03:47:07.846] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:10:0)
[03:47:07.846] Timer tick, asking Buildbucket for the build status
[03:47:08.340] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:08.340] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:11:0) after 1m0s
[03:48:08.371] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:11:0)
[03:48:08.371] Timer tick, asking Buildbucket for the build status
[03:48:08.665] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:08.665] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:12:0) after 1m0s
[03:49:08.683] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:12:0)
[03:49:08.683] Timer tick, asking Buildbucket for the build status
[03:49:08.743] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:08.743] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:13:0) after 1m0s
[03:50:08.775] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:13:0)
[03:50:08.775] Timer tick, asking Buildbucket for the build status
[03:50:08.837] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:08.837] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:14:0) after 1m0s
[03:51:08.892] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:14:0)
[03:51:08.892] Timer tick, asking Buildbucket for the build status
[03:51:08.943] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:08.943] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:15:0) after 1m0s
[03:52:08.965] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:15:0)
[03:52:08.965] Timer tick, asking Buildbucket for the build status
[03:52:09.039] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:09.039] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:16:0) after 1m0s
[03:53:09.118] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:16:0)
[03:53:09.118] Timer tick, asking Buildbucket for the build status
[03:53:09.288] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:09.288] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:17:0) after 1m0s
[03:54:09.414] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:17:0)
[03:54:09.414] Timer tick, asking Buildbucket for the build status
[03:54:09.560] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:09.560] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:18:0) after 1m0s
[03:55:09.595] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:18:0)
[03:55:09.595] Timer tick, asking Buildbucket for the build status
[03:55:09.738] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:09.738] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:19:0) after 1m0s
[03:56:09.759] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:19:0)
[03:56:09.759] Timer tick, asking Buildbucket for the build status
[03:56:09.791] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:09.791] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:20:0) after 1m0s
[03:57:09.812] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:20:0)
[03:57:09.812] Timer tick, asking Buildbucket for the build status
[03:57:09.889] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:09.889] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:21:0) after 1m0s
[03:58:09.915] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:21:0)
[03:58:09.915] Timer tick, asking Buildbucket for the build status
[03:58:09.959] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:09.959] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:22:0) after 1m0s
[03:59:09.986] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:22:0)
[03:59:09.986] Timer tick, asking Buildbucket for the build status
[03:59:10.044] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:10.044] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:23:0) after 1m0s
[04:00:10.064] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:23:0)
[04:00:10.064] Timer tick, asking Buildbucket for the build status
[04:00:10.166] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:10.166] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:24:0) after 1m0s
[04:01:10.186] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:24:0)
[04:01:10.186] Timer tick, asking Buildbucket for the build status
[04:01:10.269] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:10.269] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:25:0) after 1m0s
[04:02:10.329] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:25:0)
[04:02:10.329] Timer tick, asking Buildbucket for the build status
[04:02:10.558] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:10.558] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:26:0) after 1m0s
[04:03:10.648] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:26:0)
[04:03:10.648] Timer tick, asking Buildbucket for the build status
[04:03:10.701] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:10.701] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:27:0) after 1m0s
[04:04:10.724] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:27:0)
[04:04:10.724] Timer tick, asking Buildbucket for the build status
[04:04:11.271] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:11.271] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:28:0) after 1m0s
[04:05:11.289] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:28:0)
[04:05:11.289] Timer tick, asking Buildbucket for the build status
[04:05:11.402] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:11.402] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:29:0) after 1m0s
[04:06:11.423] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:29:0)
[04:06:11.423] Timer tick, asking Buildbucket for the build status
[04:06:11.469] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:11.469] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:30:0) after 1m0s
[04:07:11.489] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:30:0)
[04:07:11.489] Timer tick, asking Buildbucket for the build status
[04:07:11.564] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:11.564] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:31:0) after 1m0s
[04:08:11.664] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:31:0)
[04:08:11.664] Timer tick, asking Buildbucket for the build status
[04:08:11.809] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:11.809] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:32:0) after 1m0s
[04:09:11.828] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:32:0)
[04:09:11.828] Timer tick, asking Buildbucket for the build status
[04:09:11.919] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:11.919] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:33:0) after 1m0s
[04:10:11.939] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:33:0)
[04:10:11.939] Timer tick, asking Buildbucket for the build status
[04:10:12.001] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:12.001] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:34:0) after 1m0s
[04:11:12.020] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:34:0)
[04:11:12.020] Timer tick, asking Buildbucket for the build status
[04:11:12.084] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:12.084] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:35:0) after 1m0s
[04:12:12.102] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:35:0)
[04:12:12.102] Timer tick, asking Buildbucket for the build status
[04:12:12.355] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:12.355] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:36:0) after 1m0s
[04:13:12.374] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:36:0)
[04:13:12.374] Timer tick, asking Buildbucket for the build status
[04:13:12.427] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:12.427] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:37:0) after 1m0s
[04:14:12.449] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:37:0)
[04:14:12.449] Timer tick, asking Buildbucket for the build status
[04:14:12.573] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:12.573] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:38:0) after 1m0s
[04:15:12.591] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:38:0)
[04:15:12.591] Timer tick, asking Buildbucket for the build status
[04:15:12.764] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:12.764] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:39:0) after 1m0s
[04:16:12.785] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:39:0)
[04:16:12.785] Timer tick, asking Buildbucket for the build status
[04:16:12.930] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:12.930] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:40:0) after 1m0s
[04:17:12.948] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:40:0)
[04:17:12.948] Timer tick, asking Buildbucket for the build status
[04:17:13.697] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:13.697] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:41:0) after 1m0s
[04:18:13.714] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:41:0)
[04:18:13.714] Timer tick, asking Buildbucket for the build status
[04:18:13.852] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:13.852] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:42:0) after 1m0s
[04:19:13.872] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:42:0)
[04:19:13.872] Timer tick, asking Buildbucket for the build status
[04:19:13.920] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:13.920] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:43:0) after 1m0s
[04:20:13.940] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:43:0)
[04:20:13.940] Timer tick, asking Buildbucket for the build status
[04:20:14.079] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:14.079] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:44:0) after 1m0s
[04:21:14.098] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:44:0)
[04:21:14.098] Timer tick, asking Buildbucket for the build status
[04:21:14.133] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:14.133] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:45:0) after 1m0s
[04:22:14.188] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:45:0)
[04:22:14.188] Timer tick, asking Buildbucket for the build status
[04:22:14.449] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:14.449] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:46:0) after 1m0s
[04:23:14.470] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:46:0)
[04:23:14.470] Timer tick, asking Buildbucket for the build status
[04:23:14.575] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:14.575] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:47:0) after 1m0s
[04:24:14.594] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:47:0)
[04:24:14.594] Timer tick, asking Buildbucket for the build status
[04:24:14.954] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:14.954] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:48:0) after 1m0s
[04:25:15.027] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:48:0)
[04:25:15.027] Timer tick, asking Buildbucket for the build status
[04:25:15.083] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:15.083] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:49:0) after 1m0s
[04:26:15.117] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:49:0)
[04:26:15.117] Timer tick, asking Buildbucket for the build status
[04:26:15.166] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:15.166] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:50:0) after 1m0s
[04:27:15.249] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:50:0)
[04:27:15.249] Timer tick, asking Buildbucket for the build status
[04:27:15.357] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:15.357] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:51:0) after 1m0s
[04:28:15.488] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:51:0)
[04:28:15.488] Timer tick, asking Buildbucket for the build status
[04:28:15.525] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:15.525] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:52:0) after 1m0s
[04:29:15.640] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:52:0)
[04:29:15.640] Timer tick, asking Buildbucket for the build status
[04:29:15.719] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:15.719] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:53:0) after 1m0s
[04:30:15.835] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:53:0)
[04:30:15.835] Timer tick, asking Buildbucket for the build status
[04:30:15.915] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:15.915] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:54:0) after 1m0s
[04:31:16.068] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:54:0)
[04:31:16.068] Timer tick, asking Buildbucket for the build status
[04:31:16.125] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:16.125] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:55:0) after 1m0s
[04:32:16.171] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:55:0)
[04:32:16.171] Timer tick, asking Buildbucket for the build status
[04:32:16.247] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:16.247] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:56:0) after 1m0s
[04:33:16.328] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:56:0)
[04:33:16.328] Timer tick, asking Buildbucket for the build status
[04:33:16.446] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:16.446] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:57:0) after 1m0s
[04:34:16.468] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:57:0)
[04:34:16.468] Timer tick, asking Buildbucket for the build status
[04:34:16.516] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:16.516] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:58:0) after 1m0s
[04:35:16.549] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:58:0)
[04:35:16.549] Timer tick, asking Buildbucket for the build status
[04:35:16.579] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:16.579] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:59:0) after 1m0s
[04:36:16.599] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:59:0)
[04:36:16.599] Timer tick, asking Buildbucket for the build status
[04:36:16.629] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:16.629] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:60:0) after 1m0s
[04:37:16.759] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:60:0)
[04:37:16.759] Timer tick, asking Buildbucket for the build status
[04:37:16.794] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:16.794] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:61:0) after 1m0s
[04:38:16.820] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:61:0)
[04:38:16.820] Timer tick, asking Buildbucket for the build status
[04:38:16.855] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:16.855] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:62:0) after 1m0s
[04:39:16.879] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:62:0)
[04:39:16.879] Timer tick, asking Buildbucket for the build status
[04:39:16.931] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:16.931] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:63:0) after 1m0s
[04:40:16.952] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:63:0)
[04:40:16.952] Timer tick, asking Buildbucket for the build status
[04:40:17.108] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:17.108] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:64:0) after 1m0s
[04:41:17.242] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:64:0)
[04:41:17.242] Timer tick, asking Buildbucket for the build status
[04:41:17.319] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:17.319] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:65:0) after 1m0s
[04:42:17.341] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:65:0)
[04:42:17.341] Timer tick, asking Buildbucket for the build status
[04:42:17.457] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:17.457] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:66:0) after 1m0s
[04:43:17.479] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:66:0)
[04:43:17.479] Timer tick, asking Buildbucket for the build status
[04:43:17.513] Build 8943957210467795936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:17.513] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9109387272532429792:67:0) after 1m0s
[04:44:06.545] Received PubSub notification, asking Buildbucket for the build status
[04:44:06.579] Build 8943957210467795936: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:44:06.579] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1528778645321470",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1528774743548032",
"id": "8943957210467795936",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"dc54c7827dbb6e65a5f9b5c78183a6f951ad226b\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"task_result\": {\"cipd_pins\": {\"packages\": [{\"path\": \".\", \"version\": \"e4b90cad477c47ea00ff4dbd6183667bb0f1a82c\", \"package_name\": \"infra/tools/luci/kitchen/linux-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"33c8c36035a927244393af56226158d27f46bec0\", \"package_name\": \"infra/git/linux-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"46c0c897ca0f053799ee41fd148bb7a47232df47\", \"package_name\": \"infra/python/cpython/linux-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"6e5ff69bbe48c38117c44fd735cb7adb32417549\", \"package_name\": \"infra/tools/buildbucket/linux-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"4672ed11a416bb60e9ec6738dab9cc41386971f5\", \"package_name\": \"infra/tools/cloudtail/linux-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"139d6a6663b2cdd9420516c9335d0731dd2c5c58\", \"package_name\": \"infra/tools/git/linux-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"df53a719b65668e3b16ecdb600f29f8c901cd67e\", \"package_name\": \"infra/tools/luci-auth/linux-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"72f5c9ec60e557643b06eac503836fb78359ca06\", \"package_name\": \"infra/tools/luci/git-credential-luci/linux-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"8858bf0d89477076c5765ac90e41664cb0be1899\", \"package_name\": \"infra/tools/luci/vpython-native/linux-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"90aede15bc849a97975bb503f96f3e2518f45c0f\", \"package_name\": \"infra/tools/luci/vpython/linux-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"7ac9ea00b7d1d664e9015d2bbd537bc8cb3bfda4\", \"package_name\": \"infra/tools/prpc/linux-amd64\"}, {\"path\": \"kitchen-checkout\", \"version\": \"1ede0efd07e9dc9d7953eb4655e8f4c5c76867e9\", \"package_name\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}], \"client_package\": {\"version\": \"e891a48eebcd04fee5b054173539b558cbcc027c\", \"package_name\": \"infra/tools/cipd/linux-amd64\"}}, \"run_id\": \"3e0ae4e28f9c2d11\", \"outputs_ref\": {\"isolatedserver\": \"https://isolateserver.appspot.com\", \"namespace\": \"default-gzip\", \"isolated\": \"ea1d117c2eb40084e9e04afec4c3b2c3f719abe6\"}, \"server_versions\": [\"3627-fdb781f\"], \"duration\": 3754.6260871887207, \"completed_ts\": \"2018-06-12T04:44:05.321470\", \"started_ts\": \"2018-06-12T03:39:07.766204\", \"internal_failure\": false, \"exit_code\": \"0\", \"state\": \"COMPLETED\", \"bot_version\": \"6de672d34546e29145e89eca7269c6b5b8f5997655dcfc37bae08a2b8e8ce6d7\", \"tags\": [\"build_address:luci.chromium.ci/Deterministic Linux/14234\", \"buildbucket_bucket:luci.chromium.ci\", \"buildbucket_build_id:8943957210467795936\", \"buildbucket_hostname:cr-buildbucket.appspot.com\", \"buildbucket_template_canary:0\", \"buildbucket_template_revision:5d96bc6fe62ccf6bc1c65a31374778e7a60b3980\", \"builder:Deterministic Linux\", \"buildset:commit/git/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b\", \"cores:8\", \"cpu:x86-64\", \"gitiles_ref:refs/heads/master\", \"log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8943957210467795936/+/annotations\", \"luci_project:chromium\", \"os:Ubuntu-14.04\", \"pool:luci.chromium.ci\", \"priority:30\", \"recipe_name:swarming/deterministic_build\", \"recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\", \"scheduler_invocation_id:9109387272532429792\", \"scheduler_job_id:chromium/Deterministic Linux\", \"service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com\", \"user:None\", \"user_agent:luci-scheduler\", \"vpython:native-python-wrapper\"], \"failure\": false, \"modified_ts\": \"2018-06-12T04:44:05.321470\", \"user\": \"\", \"created_ts\": \"2018-06-12T03:39:04.519827\", \"name\": \"bb-8943957210467795936-chromium-Deterministic Linux\", \"task_id\": \"3e0ae4e28f9c2d10\", \"bot_dimensions\": [{\"value\": [\"Deterministic Linux\"], \"key\": \"builder\"}, {\"value\": [\"builder_26450780b77752b73235b8dcd76e4371340e7d14b7166082470bfa623c3e2d62_v2\", \"git\", \"goma\", \"goma_v2\", \"vpython\"], \"key\": \"caches\"}, {\"value\": [\"8\"], \"key\": \"cores\"}, {\"value\": [\"x86\", \"x86-64\", \"x86-64-Ivy_Bridge_GCE\"], \"key\": \"cpu\"}, {\"value\": [\"none\"], \"key\": \"gpu\"}, {\"value\": [\"swarm591-c4\"], \"key\": \"id\"}, {\"value\": [\"0\"], \"key\": \"inside_docker\"}, {\"value\": [\"0\"], \"key\": \"kvm\"}, {\"value\": [\"n1-standard-8\"], \"key\": \"machine_type\"}, {\"value\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"key\": \"os\"}, {\"value\": [\"luci.chromium.ci\"], \"key\": \"pool\"}, {\"value\": [\"2.7.6\"], \"key\": \"python\"}, {\"value\": [\"3627-fdb781f\"], \"key\": \"server_version\"}, {\"value\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"], \"key\": \"zone\"}], \"try_number\": \"1\", \"current_task_slice\": \"0\", \"costs_usd\": [0.4495766753731694], \"bot_id\": \"swarm591-c4\"}}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"jsonResult\": \"null\"}, \"recipeExitCode\": {\"value\": 0}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8943957210467795936/+/annotations\"}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"82ff2ae08f20942bbb406d107ab42ff8d1d6fff4\", \"got_swarming_client_revision\": \"281c390193ec8c02e60279f8dac1b86ac52fa4be\", \"got_revision\": \"dc54c7827dbb6e65a5f9b5c78183a6f951ad226b\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#23569}\", \"$build/goma\": {\"jobs\": 500}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#566292}\", \"branch\": \"refs/heads/master\", \"revision\": \"dc54c7827dbb6e65a5f9b5c78183a6f951ad226b\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Deterministic Linux\", \"got_webrtc_revision\": \"a46bd4b9c745e057b7a8d502bb9a914958642f4d\", \"mastername\": \"chromium.linux\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1528774743548032, \"tags\": [\"builder:Deterministic Linux\", \"buildset:commit/git/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9109387272532429792\", \"scheduler_job_id:chromium/Deterministic Linux\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8943957210467795936\"}}, \"got_v8_revision\": \"27bacf89077dde85c627cc83accc3ac3f018e16d\", \"got_v8_revision_cp\": \"refs/heads/6.9.119@{#1}\", \"got_angle_revision\": \"af8836282eafd23f36652470cb52b008e906a4e0\", \"buildnumber\": 14234, \"path_config\": \"generic\", \"got_buildtools_revision\": \"6f4dae280c6a542acacd8db281decc5c0b2a9823\", \"bot_id\": \"swarm591-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1528774747766204",
"status": "COMPLETED",
"status_changed_ts": "1528778645942750",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/14234",
"builder:Deterministic Linux",
"buildset:commit/git/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9109387272532429792",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_dimension:builder:Deterministic Linux",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Deterministic Linux/14234",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8943957210467795936",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:5d96bc6fe62ccf6bc1c65a31374778e7a60b3980",
"swarming_tag:builder:Deterministic Linux",
"swarming_tag:buildset:commit/git/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dc54c7827dbb6e65a5f9b5c78183a6f951ad226b",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8943957210467795936/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9109387272532429792",
"swarming_tag:scheduler_job_id:chromium/Deterministic Linux",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e0ae4e28f9c2d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1528778645944028",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Deterministic Linux/14234",
"utcnow_ts": "1528778646567512"
}
}
[04:44:06.579] Invocation finished in 1h5m4.384055664s with status SUCCEEDED