[05:32:54.794] New invocation is queued and will start shortly
[05:32:55.925] Starting the invocation (attempt 1)
[05:32:55.996] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:32:55.996] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:32:55.996] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9069245651940200848",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"acae4d71f9f52a14cc2311ab84cbf17eb95b57bf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9069245651940200848",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/acae4d71f9f52a14cc2311ab84cbf17eb95b57bf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/acae4d71f9f52a14cc2311ab84cbf17eb95b57bf",
"gitiles_ref:refs/heads/master"
]
}
[05:32:56.745] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1567056776178475",
"id": "8903815589820339264",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"acae4d71f9f52a14cc2311ab84cbf17eb95b57bf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1567056776610653",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/10529",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/acae4d71f9f52a14cc2311ab84cbf17eb95b57bf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/acae4d71f9f52a14cc2311ab84cbf17eb95b57bf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069245651940200848",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8903815589820339264/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1567056776610775",
"url": "https://ci.chromium.org/b/8903815589820339264",
"utcnow_ts": "1567056776734437"
}
}
[05:32:56.746] Task URL: https://ci.chromium.org/b/8903815589820339264
[05:32:56.746] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:2:0) after 1m0s
[05:32:58.018] Received PubSub notification, asking Buildbucket for the build status
[05:32:58.340] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:56.796] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:2:0)
[05:33:56.796] Timer tick, asking Buildbucket for the build status
[05:33:58.696] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:58.696] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:4:0) after 1m0s
[05:34:58.834] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:4:0)
[05:34:58.834] Timer tick, asking Buildbucket for the build status
[05:34:59.324] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:59.324] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:5:0) after 1m0s
[05:35:59.422] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:5:0)
[05:35:59.422] Timer tick, asking Buildbucket for the build status
[05:36:00.268] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:00.269] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:6:0) after 1m0s
[05:37:00.363] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:6:0)
[05:37:00.363] Timer tick, asking Buildbucket for the build status
[05:37:00.823] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:00.823] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:7:0) after 1m0s
[05:38:00.852] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:7:0)
[05:38:00.852] Timer tick, asking Buildbucket for the build status
[05:38:01.221] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:01.221] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:8:0) after 1m0s
[05:39:01.385] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:8:0)
[05:39:01.385] Timer tick, asking Buildbucket for the build status
[05:39:01.870] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:01.870] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:9:0) after 1m0s
[05:40:02.007] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:9:0)
[05:40:02.007] Timer tick, asking Buildbucket for the build status
[05:40:02.340] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:02.340] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:10:0) after 1m0s
[05:41:02.420] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:10:0)
[05:41:02.420] Timer tick, asking Buildbucket for the build status
[05:41:02.721] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:02.721] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:11:0) after 1m0s
[05:42:02.881] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:11:0)
[05:42:02.881] Timer tick, asking Buildbucket for the build status
[05:42:03.084] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:03.084] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:12:0) after 1m0s
[05:43:03.164] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:12:0)
[05:43:03.164] Timer tick, asking Buildbucket for the build status
[05:43:03.831] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:03.831] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:13:0) after 1m0s
[05:44:03.862] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:13:0)
[05:44:03.862] Timer tick, asking Buildbucket for the build status
[05:44:04.091] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:04.091] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:14:0) after 1m0s
[05:45:04.261] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:14:0)
[05:45:04.261] Timer tick, asking Buildbucket for the build status
[05:45:04.545] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:04.545] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:15:0) after 1m0s
[05:46:04.517] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:15:0)
[05:46:04.517] Timer tick, asking Buildbucket for the build status
[05:46:04.902] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:04.902] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:16:0) after 1m0s
[05:47:05.650] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:16:0)
[05:47:05.650] Timer tick, asking Buildbucket for the build status
[05:47:05.823] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:05.823] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:17:0) after 1m0s
[05:48:05.820] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:17:0)
[05:48:05.820] Timer tick, asking Buildbucket for the build status
[05:48:08.488] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:08.488] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:18:0) after 1m0s
[05:49:08.583] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:18:0)
[05:49:08.583] Timer tick, asking Buildbucket for the build status
[05:49:08.807] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:08.807] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:19:0) after 1m0s
[05:50:08.846] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:19:0)
[05:50:08.846] Timer tick, asking Buildbucket for the build status
[05:50:09.176] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:09.176] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:20:0) after 1m0s
[05:51:09.200] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:20:0)
[05:51:09.200] Timer tick, asking Buildbucket for the build status
[05:51:09.509] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:09.509] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:21:0) after 1m0s
[05:52:09.538] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:21:0)
[05:52:09.538] Timer tick, asking Buildbucket for the build status
[05:52:09.730] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:09.730] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:22:0) after 1m0s
[05:53:09.795] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:22:0)
[05:53:09.795] Timer tick, asking Buildbucket for the build status
[05:53:10.012] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:10.012] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:23:0) after 1m0s
[05:54:10.064] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:23:0)
[05:54:10.064] Timer tick, asking Buildbucket for the build status
[05:54:11.121] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:11.121] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:24:0) after 1m0s
[05:55:11.149] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:24:0)
[05:55:11.149] Timer tick, asking Buildbucket for the build status
[05:55:11.738] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:11.738] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:25:0) after 1m0s
[05:56:11.836] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:25:0)
[05:56:11.836] Timer tick, asking Buildbucket for the build status
[05:56:12.280] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:12.280] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:26:0) after 1m0s
[05:57:12.311] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:26:0)
[05:57:12.311] Timer tick, asking Buildbucket for the build status
[05:57:13.295] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:13.295] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:27:0) after 1m0s
[05:58:13.361] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:27:0)
[05:58:13.361] Timer tick, asking Buildbucket for the build status
[05:58:13.530] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:13.530] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:28:0) after 1m0s
[05:59:13.592] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:28:0)
[05:59:13.592] Timer tick, asking Buildbucket for the build status
[05:59:14.038] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:14.038] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:29:0) after 1m0s
[06:00:14.056] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:29:0)
[06:00:14.056] Timer tick, asking Buildbucket for the build status
[06:00:14.552] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:14.552] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:30:0) after 1m0s
[06:01:14.595] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:30:0)
[06:01:14.595] Timer tick, asking Buildbucket for the build status
[06:01:17.203] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:17.203] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:31:0) after 1m0s
[06:02:17.341] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:31:0)
[06:02:17.341] Timer tick, asking Buildbucket for the build status
[06:02:17.720] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:17.720] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:32:0) after 1m0s
[06:03:17.764] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:32:0)
[06:03:17.764] Timer tick, asking Buildbucket for the build status
[06:03:22.809] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:22.809] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:33:0) after 1m0s
[06:04:22.874] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:33:0)
[06:04:22.874] Timer tick, asking Buildbucket for the build status
[06:04:23.139] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:23.139] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:34:0) after 1m0s
[06:05:23.165] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:34:0)
[06:05:23.165] Timer tick, asking Buildbucket for the build status
[06:05:23.833] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:23.833] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:35:0) after 1m0s
[06:06:23.993] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:35:0)
[06:06:23.993] Timer tick, asking Buildbucket for the build status
[06:06:24.199] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:24.199] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:36:0) after 1m0s
[06:07:24.237] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:36:0)
[06:07:24.237] Timer tick, asking Buildbucket for the build status
[06:07:24.429] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:24.429] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:37:0) after 1m0s
[06:08:24.459] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:37:0)
[06:08:24.459] Timer tick, asking Buildbucket for the build status
[06:08:25.051] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:25.051] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:38:0) after 1m0s
[06:09:25.110] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:38:0)
[06:09:25.110] Timer tick, asking Buildbucket for the build status
[06:09:25.459] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:25.459] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:39:0) after 1m0s
[06:10:25.497] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:39:0)
[06:10:25.497] Timer tick, asking Buildbucket for the build status
[06:10:26.271] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:26.271] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:40:0) after 1m0s
[06:11:26.320] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:40:0)
[06:11:26.320] Timer tick, asking Buildbucket for the build status
[06:11:26.573] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:26.573] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:41:0) after 1m0s
[06:12:26.586] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:41:0)
[06:12:26.586] Timer tick, asking Buildbucket for the build status
[06:12:27.802] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:27.802] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:42:0) after 1m0s
[06:13:27.826] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:42:0)
[06:13:27.826] Timer tick, asking Buildbucket for the build status
[06:13:28.092] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:28.092] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:43:0) after 1m0s
[06:14:28.162] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:43:0)
[06:14:28.162] Timer tick, asking Buildbucket for the build status
[06:14:28.395] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:28.395] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:44:0) after 1m0s
[06:15:28.451] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:44:0)
[06:15:28.451] Timer tick, asking Buildbucket for the build status
[06:15:29.333] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:29.333] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:45:0) after 1m0s
[06:16:29.546] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:45:0)
[06:16:29.546] Timer tick, asking Buildbucket for the build status
[06:16:29.728] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:29.728] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:46:0) after 1m0s
[06:17:29.756] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:46:0)
[06:17:29.756] Timer tick, asking Buildbucket for the build status
[06:17:30.546] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:30.546] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:47:0) after 1m0s
[06:18:30.863] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:47:0)
[06:18:30.863] Timer tick, asking Buildbucket for the build status
[06:18:31.030] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:31.030] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:48:0) after 1m0s
[06:19:31.087] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:48:0)
[06:19:31.087] Timer tick, asking Buildbucket for the build status
[06:19:31.522] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:31.522] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:49:0) after 1m0s
[06:20:31.619] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:49:0)
[06:20:31.619] Timer tick, asking Buildbucket for the build status
[06:20:32.368] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:32.368] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:50:0) after 1m0s
[06:21:32.422] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:50:0)
[06:21:32.422] Timer tick, asking Buildbucket for the build status
[06:21:32.679] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:32.679] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:51:0) after 1m0s
[06:22:32.720] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:51:0)
[06:22:32.720] Timer tick, asking Buildbucket for the build status
[06:22:33.334] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:33.334] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:52:0) after 1m0s
[06:23:33.345] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:52:0)
[06:23:33.345] Timer tick, asking Buildbucket for the build status
[06:23:34.411] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:34.411] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:53:0) after 1m0s
[06:24:34.492] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:53:0)
[06:24:34.493] Timer tick, asking Buildbucket for the build status
[06:24:34.736] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:34.736] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:54:0) after 1m0s
[06:25:34.771] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:54:0)
[06:25:34.771] Timer tick, asking Buildbucket for the build status
[06:25:35.060] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:35.060] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:55:0) after 1m0s
[06:26:35.125] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:55:0)
[06:26:35.125] Timer tick, asking Buildbucket for the build status
[06:26:35.366] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:35.366] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:56:0) after 1m0s
[06:27:35.451] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:56:0)
[06:27:35.451] Timer tick, asking Buildbucket for the build status
[06:27:35.639] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:35.639] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:57:0) after 1m0s
[06:28:35.692] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:57:0)
[06:28:35.692] Timer tick, asking Buildbucket for the build status
[06:28:36.163] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:36.163] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:58:0) after 1m0s
[06:29:36.228] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:58:0)
[06:29:36.228] Timer tick, asking Buildbucket for the build status
[06:29:36.594] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:36.594] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:59:0) after 1m0s
[06:30:36.625] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:59:0)
[06:30:36.625] Timer tick, asking Buildbucket for the build status
[06:30:36.755] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:36.755] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:60:0) after 1m0s
[06:31:36.751] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:60:0)
[06:31:36.751] Timer tick, asking Buildbucket for the build status
[06:31:36.977] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:36.977] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:61:0) after 1m0s
[06:32:37.028] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:61:0)
[06:32:37.028] Timer tick, asking Buildbucket for the build status
[06:32:37.537] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:37.537] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:62:0) after 1m0s
[06:33:37.563] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:62:0)
[06:33:37.563] Timer tick, asking Buildbucket for the build status
[06:33:38.305] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:38.305] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:63:0) after 1m0s
[06:34:38.344] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:63:0)
[06:34:38.344] Timer tick, asking Buildbucket for the build status
[06:34:39.426] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:39.426] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:64:0) after 1m0s
[06:35:39.474] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:64:0)
[06:35:39.474] Timer tick, asking Buildbucket for the build status
[06:35:40.230] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:40.230] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:65:0) after 1m0s
[06:36:40.295] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:65:0)
[06:36:40.295] Timer tick, asking Buildbucket for the build status
[06:36:40.446] Build 8903815589820339264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:40.446] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9069245651940200848:66:0) after 1m0s
[06:36:58.749] Received PubSub notification, asking Buildbucket for the build status
[06:36:59.013] Build 8903815589820339264: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:36:59.013] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1567060618157590",
"created_by": "project:chromium",
"created_ts": "1567056776178475",
"id": "8903815589820339264",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"acae4d71f9f52a14cc2311ab84cbf17eb95b57bf\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-32-14-4ddt\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux (dbg)\", \"buildnumber\": 10529, \"got_angle_revision\": \"5d27a69616018803cd2b2b98cd9006f5b2cb994f\", \"got_dawn_revision\": \"ca0eac314b807170eb60d0cc2f7c97362477058f\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"acae4d71f9f52a14cc2311ab84cbf17eb95b57bf\", \"got_revision_cp\": \"refs/heads/master@{#691493}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"5c64cbb4ac8b7a3ef9a755e7d499a98decfb964c\", \"got_v8_revision_cp\": \"refs/heads/7.8.219@{#1}\", \"got_webrtc_revision\": \"703ea953f0f0c352de47166625db5b4459951ce3\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28993}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"acae4d71f9f52a14cc2311ab84cbf17eb95b57bf\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-32-14-4ddt\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4397-8786906\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1567056777285494",
"status": "COMPLETED",
"status_changed_ts": "1567060618427612",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/10529",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/acae4d71f9f52a14cc2311ab84cbf17eb95b57bf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/acae4d71f9f52a14cc2311ab84cbf17eb95b57bf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069245651940200848",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8903815589820339264/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:46f4ae56decab410",
"user_agent:luci-scheduler"
],
"updated_ts": "1567060618430769",
"url": "https://ci.chromium.org/b/8903815589820339264",
"utcnow_ts": "1567060619002647"
}
}
[06:36:59.013] Invocation finished in 1h4m4.239927746s with status SUCCEEDED