[15:07:50.088] New invocation is queued and will start shortly
[15:07:51.199] Starting the invocation (attempt 1)
[15:07:51.231] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:07:51.231] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:07:51.231] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9042392778946534816",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4bb2f54b83de846080b06f45603bc30a3a90c099\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9042392778946534816",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/4bb2f54b83de846080b06f45603bc30a3a90c099",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4bb2f54b83de846080b06f45603bc30a3a90c099",
"gitiles_ref:refs/heads/master"
]
}
[15:07:51.966] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1592665671314048",
"id": "8876962716994370992",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4bb2f54b83de846080b06f45603bc30a3a90c099\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1592665671797947",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/17410",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/4bb2f54b83de846080b06f45603bc30a3a90c099",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4bb2f54b83de846080b06f45603bc30a3a90c099",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9042392778946534816",
"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/8876962716994370992/+/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": "1592665671798113",
"url": "https://ci.chromium.org/b/8876962716994370992",
"utcnow_ts": "1592665671953529"
}
}
[15:07:51.966] Task URL: https://ci.chromium.org/b/8876962716994370992
[15:07:51.966] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:2:0) after 1m0s
[15:08:52.112] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:2:0)
[15:08:52.112] Timer tick, asking Buildbucket for the build status
[15:08:52.738] Build 8876962716994370992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:08:52.738] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:3:0) after 1m0s
[15:08:54.401] Received PubSub notification, asking Buildbucket for the build status
[15:08:54.719] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:52.819] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:3:0)
[15:09:52.819] Timer tick, asking Buildbucket for the build status
[15:09:52.950] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:52.950] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:5:0) after 1m0s
[15:10:52.977] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:5:0)
[15:10:52.977] Timer tick, asking Buildbucket for the build status
[15:10:53.331] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:53.331] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:6:0) after 1m0s
[15:11:53.361] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:6:0)
[15:11:53.361] Timer tick, asking Buildbucket for the build status
[15:11:53.524] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:53.524] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:7:0) after 1m0s
[15:12:53.542] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:7:0)
[15:12:53.543] Timer tick, asking Buildbucket for the build status
[15:12:54.063] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:54.063] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:8:0) after 1m0s
[15:13:54.091] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:8:0)
[15:13:54.091] Timer tick, asking Buildbucket for the build status
[15:13:54.447] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:54.447] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:9:0) after 1m0s
[15:14:54.468] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:9:0)
[15:14:54.468] Timer tick, asking Buildbucket for the build status
[15:14:54.654] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:54.654] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:10:0) after 1m0s
[15:15:54.670] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:10:0)
[15:15:54.670] Timer tick, asking Buildbucket for the build status
[15:15:54.815] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:54.815] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:11:0) after 1m0s
[15:16:54.885] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:11:0)
[15:16:54.885] Timer tick, asking Buildbucket for the build status
[15:16:55.166] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:55.166] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:12:0) after 1m0s
[15:17:55.229] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:12:0)
[15:17:55.229] Timer tick, asking Buildbucket for the build status
[15:17:55.861] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:55.861] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:13:0) after 1m0s
[15:18:55.981] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:13:0)
[15:18:55.981] Timer tick, asking Buildbucket for the build status
[15:18:56.165] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:56.165] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:14:0) after 1m0s
[15:19:56.252] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:14:0)
[15:19:56.252] Timer tick, asking Buildbucket for the build status
[15:19:56.374] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:56.374] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:15:0) after 1m0s
[15:20:56.399] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:15:0)
[15:20:56.399] Timer tick, asking Buildbucket for the build status
[15:20:56.538] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:56.538] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:16:0) after 1m0s
[15:21:56.557] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:16:0)
[15:21:56.557] Timer tick, asking Buildbucket for the build status
[15:21:56.738] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:56.738] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:17:0) after 1m0s
[15:22:56.772] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:17:0)
[15:22:56.772] Timer tick, asking Buildbucket for the build status
[15:22:57.052] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:57.052] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:18:0) after 1m0s
[15:23:57.077] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:18:0)
[15:23:57.077] Timer tick, asking Buildbucket for the build status
[15:23:57.256] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:57.256] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:19:0) after 1m0s
[15:24:57.277] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:19:0)
[15:24:57.277] Timer tick, asking Buildbucket for the build status
[15:24:57.392] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:57.392] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:20:0) after 1m0s
[15:25:57.410] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:20:0)
[15:25:57.410] Timer tick, asking Buildbucket for the build status
[15:25:57.639] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:57.639] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:21:0) after 1m0s
[15:26:57.772] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:21:0)
[15:26:57.772] Timer tick, asking Buildbucket for the build status
[15:26:57.924] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:57.924] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:22:0) after 1m0s
[15:27:58.121] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:22:0)
[15:27:58.121] Timer tick, asking Buildbucket for the build status
[15:27:58.305] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:58.305] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:23:0) after 1m0s
[15:28:58.509] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:23:0)
[15:28:58.509] Timer tick, asking Buildbucket for the build status
[15:28:58.635] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:58.635] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:24:0) after 1m0s
[15:29:58.956] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:24:0)
[15:29:58.956] Timer tick, asking Buildbucket for the build status
[15:29:59.082] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:59.082] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:25:0) after 1m0s
[15:30:59.180] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:25:0)
[15:30:59.180] Timer tick, asking Buildbucket for the build status
[15:30:59.383] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:59.383] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:26:0) after 1m0s
[15:31:59.380] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:26:0)
[15:31:59.380] Timer tick, asking Buildbucket for the build status
[15:31:59.524] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:59.524] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:27:0) after 1m0s
[15:32:59.760] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:27:0)
[15:32:59.760] Timer tick, asking Buildbucket for the build status
[15:32:59.897] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:59.897] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:28:0) after 1m0s
[15:34:00.140] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:28:0)
[15:34:00.140] Timer tick, asking Buildbucket for the build status
[15:34:00.486] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:00.486] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:29:0) after 1m0s
[15:35:01.015] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:29:0)
[15:35:01.015] Timer tick, asking Buildbucket for the build status
[15:35:01.164] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:01.164] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:30:0) after 1m0s
[15:36:01.568] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:30:0)
[15:36:01.568] Timer tick, asking Buildbucket for the build status
[15:36:01.712] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:01.712] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:31:0) after 1m0s
[15:37:01.769] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:31:0)
[15:37:01.769] Timer tick, asking Buildbucket for the build status
[15:37:01.939] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:01.940] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:32:0) after 1m0s
[15:38:01.970] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:32:0)
[15:38:01.970] Timer tick, asking Buildbucket for the build status
[15:38:02.121] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:02.121] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:33:0) after 1m0s
[15:39:02.186] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:33:0)
[15:39:02.186] Timer tick, asking Buildbucket for the build status
[15:39:02.300] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:02.300] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:34:0) after 1m0s
[15:40:03.133] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:34:0)
[15:40:03.133] Timer tick, asking Buildbucket for the build status
[15:40:04.686] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:04.686] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:35:0) after 1m0s
[15:41:04.819] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:35:0)
[15:41:04.819] Timer tick, asking Buildbucket for the build status
[15:41:04.962] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:04.962] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:36:0) after 1m0s
[15:42:05.358] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:36:0)
[15:42:05.358] Timer tick, asking Buildbucket for the build status
[15:42:05.676] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:05.676] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:37:0) after 1m0s
[15:43:05.794] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:37:0)
[15:43:05.794] Timer tick, asking Buildbucket for the build status
[15:43:07.111] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:07.111] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:38:0) after 1m0s
[15:44:07.279] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:38:0)
[15:44:07.279] Timer tick, asking Buildbucket for the build status
[15:44:07.503] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:07.503] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:39:0) after 1m0s
[15:45:07.677] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:39:0)
[15:45:07.677] Timer tick, asking Buildbucket for the build status
[15:45:07.784] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:07.784] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:40:0) after 1m0s
[15:46:07.968] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:40:0)
[15:46:07.968] Timer tick, asking Buildbucket for the build status
[15:46:08.158] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:08.158] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:41:0) after 1m0s
[15:47:08.260] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:41:0)
[15:47:08.260] Timer tick, asking Buildbucket for the build status
[15:47:08.652] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:08.652] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:42:0) after 1m0s
[15:48:08.797] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:42:0)
[15:48:08.797] Timer tick, asking Buildbucket for the build status
[15:48:09.285] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:09.285] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:43:0) after 1m0s
[15:49:09.305] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:43:0)
[15:49:09.305] Timer tick, asking Buildbucket for the build status
[15:49:09.525] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:09.525] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:44:0) after 1m0s
[15:50:09.555] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:44:0)
[15:50:09.555] Timer tick, asking Buildbucket for the build status
[15:50:09.714] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:09.714] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:45:0) after 1m0s
[15:51:09.732] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:45:0)
[15:51:09.732] Timer tick, asking Buildbucket for the build status
[15:51:09.877] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:09.877] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:46:0) after 1m0s
[15:52:09.896] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:46:0)
[15:52:09.896] Timer tick, asking Buildbucket for the build status
[15:52:10.169] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:10.169] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:47:0) after 1m0s
[15:53:10.186] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:47:0)
[15:53:10.186] Timer tick, asking Buildbucket for the build status
[15:53:10.370] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:10.370] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:48:0) after 1m0s
[15:54:10.425] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:48:0)
[15:54:10.425] Timer tick, asking Buildbucket for the build status
[15:54:10.581] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:10.581] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:49:0) after 1m0s
[15:55:10.603] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:49:0)
[15:55:10.603] Timer tick, asking Buildbucket for the build status
[15:55:11.422] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:11.422] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:50:0) after 1m0s
[15:56:11.458] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:50:0)
[15:56:11.458] Timer tick, asking Buildbucket for the build status
[15:56:11.923] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:11.923] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:51:0) after 1m0s
[15:57:11.951] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:51:0)
[15:57:11.951] Timer tick, asking Buildbucket for the build status
[15:57:12.114] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:12.114] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:52:0) after 1m0s
[15:58:12.136] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:52:0)
[15:58:12.136] Timer tick, asking Buildbucket for the build status
[15:58:12.369] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:12.369] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:53:0) after 1m0s
[15:59:12.390] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:53:0)
[15:59:12.390] Timer tick, asking Buildbucket for the build status
[15:59:12.965] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:12.965] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:54:0) after 1m0s
[16:00:13.082] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:54:0)
[16:00:13.082] Timer tick, asking Buildbucket for the build status
[16:00:13.480] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:13.480] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:55:0) after 1m0s
[16:01:13.500] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:55:0)
[16:01:13.500] Timer tick, asking Buildbucket for the build status
[16:01:13.660] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:13.660] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:56:0) after 1m0s
[16:02:13.707] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:56:0)
[16:02:13.707] Timer tick, asking Buildbucket for the build status
[16:02:14.837] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:14.837] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:57:0) after 1m0s
[16:03:14.945] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:57:0)
[16:03:14.945] Timer tick, asking Buildbucket for the build status
[16:03:15.161] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:15.161] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:58:0) after 1m0s
[16:04:15.237] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:58:0)
[16:04:15.237] Timer tick, asking Buildbucket for the build status
[16:04:15.349] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:15.349] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:59:0) after 1m0s
[16:05:15.394] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:59:0)
[16:05:15.394] Timer tick, asking Buildbucket for the build status
[16:05:15.690] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:15.690] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:60:0) after 1m0s
[16:06:15.713] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:60:0)
[16:06:15.713] Timer tick, asking Buildbucket for the build status
[16:06:15.835] Build 8876962716994370992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:15.835] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9042392778946534816:61:0) after 1m0s
[16:06:16.475] Received PubSub notification, asking Buildbucket for the build status
[16:06:16.608] Build 8876962716994370992: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:06:16.608] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1592669175343308",
"created_by": "project:chromium",
"created_ts": "1592665671314048",
"id": "8876962716994370992",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4bb2f54b83de846080b06f45603bc30a3a90c099\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"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-us-central1-b-32-3-4k45\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux (dbg)\", \"buildnumber\": 17410, \"got_angle_revision\": \"b772a955e70cee70b22c2170351476c7f865d577\", \"got_dawn_revision\": \"250f26229b27df7f19e013ca304843a666eb9f25\", \"got_nacl_revision\": \"87616f7ce2ea8383efd3731960c15f4ba5852d93\", \"got_revision\": \"4bb2f54b83de846080b06f45603bc30a3a90c099\", \"got_revision_cp\": \"refs/heads/master@{#780584}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"ae1007957f096d015a1d48697a75c78d75d2f7b2\", \"got_v8_revision\": \"92ef92314762433b372528e3590aa3dde31cb94f\", \"got_v8_revision_cp\": \"refs/heads/8.5.188@{#1}\", \"got_webrtc_revision\": \"7a82467d0db0d61f466a1da54b94f6a136726a3c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31546}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4bb2f54b83de846080b06f45603bc30a3a90c099\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-32-3-4k45\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5218-123b763\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1592665733497264",
"status": "COMPLETED",
"status_changed_ts": "1592669175958810",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/17410",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/4bb2f54b83de846080b06f45603bc30a3a90c099",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4bb2f54b83de846080b06f45603bc30a3a90c099",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9042392778946534816",
"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/8876962716994370992/+/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:4ceb171320704210",
"user_agent:luci-scheduler"
],
"updated_ts": "1592669175966152",
"url": "https://ci.chromium.org/b/8876962716994370992",
"utcnow_ts": "1592669176596559"
}
}
[16:06:16.608] Invocation finished in 58m26.539499308s with status SUCCEEDED