[21:38:29.246] New invocation is queued and will start shortly
[21:38:32.297] Starting the invocation (attempt 1)
[21:38:32.331] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:38:32.331] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:38:32.331] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9075617287883176096",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"549a2d98aaa9ba476dbc7a6b284f269272763faa\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9075617287883176096",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/549a2d98aaa9ba476dbc7a6b284f269272763faa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/549a2d98aaa9ba476dbc7a6b284f269272763faa",
"gitiles_ref:refs/heads/master"
]
}
[21:38:33.105] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1560980312459462",
"id": "8910187223841193584",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"549a2d98aaa9ba476dbc7a6b284f269272763faa\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1560980312875712",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/8583",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/549a2d98aaa9ba476dbc7a6b284f269272763faa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/549a2d98aaa9ba476dbc7a6b284f269272763faa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9075617287883176096",
"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/8910187223841193584/+/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": "1560980312875805",
"url": "https://ci.chromium.org/b/8910187223841193584",
"utcnow_ts": "1560980313095066"
}
}
[21:38:33.105] Task URL: https://ci.chromium.org/b/8910187223841193584
[21:38:33.105] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:2:0) after 1m0s
[21:38:39.073] Received PubSub notification, asking Buildbucket for the build status
[21:38:39.186] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:33.127] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:2:0)
[21:39:33.127] Timer tick, asking Buildbucket for the build status
[21:39:33.349] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:33.349] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:4:0) after 1m0s
[21:40:33.384] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:4:0)
[21:40:33.384] Timer tick, asking Buildbucket for the build status
[21:40:34.125] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:34.125] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:5:0) after 1m0s
[21:41:34.161] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:5:0)
[21:41:34.161] Timer tick, asking Buildbucket for the build status
[21:41:52.323] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:52.323] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:6:0) after 1m0s
[21:42:52.381] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:6:0)
[21:42:52.381] Timer tick, asking Buildbucket for the build status
[21:42:52.517] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:52.518] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:7:0) after 1m0s
[21:43:52.559] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:7:0)
[21:43:52.559] Timer tick, asking Buildbucket for the build status
[21:43:52.696] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:52.696] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:8:0) after 1m0s
[21:44:52.799] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:8:0)
[21:44:52.799] Timer tick, asking Buildbucket for the build status
[21:44:52.921] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:52.921] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:9:0) after 1m0s
[21:45:52.981] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:9:0)
[21:45:52.981] Timer tick, asking Buildbucket for the build status
[21:45:53.111] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:53.111] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:10:0) after 1m0s
[21:46:53.218] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:10:0)
[21:46:53.218] Timer tick, asking Buildbucket for the build status
[21:46:53.549] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:53.549] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:11:0) after 1m0s
[21:47:53.570] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:11:0)
[21:47:53.570] Timer tick, asking Buildbucket for the build status
[21:47:53.709] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:53.709] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:12:0) after 1m0s
[21:48:53.942] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:12:0)
[21:48:53.942] Timer tick, asking Buildbucket for the build status
[21:48:54.130] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:54.130] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:13:0) after 1m0s
[21:49:54.362] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:13:0)
[21:49:54.362] Timer tick, asking Buildbucket for the build status
[21:49:54.755] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:54.755] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:14:0) after 1m0s
[21:50:54.851] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:14:0)
[21:50:54.851] Timer tick, asking Buildbucket for the build status
[21:50:54.979] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:54.979] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:15:0) after 1m0s
[21:51:55.085] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:15:0)
[21:51:55.085] Timer tick, asking Buildbucket for the build status
[21:51:55.264] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:55.264] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:16:0) after 1m0s
[21:52:55.298] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:16:0)
[21:52:55.298] Timer tick, asking Buildbucket for the build status
[21:52:55.416] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:55.416] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:17:0) after 1m0s
[21:53:55.550] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:17:0)
[21:53:55.550] Timer tick, asking Buildbucket for the build status
[21:53:55.658] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:55.658] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:18:0) after 1m0s
[21:54:55.743] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:18:0)
[21:54:55.743] Timer tick, asking Buildbucket for the build status
[21:54:55.946] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:55.946] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:19:0) after 1m0s
[21:55:56.097] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:19:0)
[21:55:56.097] Timer tick, asking Buildbucket for the build status
[21:55:57.718] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:57.718] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:20:0) after 1m0s
[21:56:57.846] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:20:0)
[21:56:57.846] Timer tick, asking Buildbucket for the build status
[21:56:58.856] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:58.856] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:21:0) after 1m0s
[21:57:58.859] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:21:0)
[21:57:58.859] Timer tick, asking Buildbucket for the build status
[21:57:59.518] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:59.518] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:22:0) after 1m0s
[21:58:59.557] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:22:0)
[21:58:59.557] Timer tick, asking Buildbucket for the build status
[21:58:59.646] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:59.646] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:23:0) after 1m0s
[21:59:59.663] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:23:0)
[21:59:59.663] Timer tick, asking Buildbucket for the build status
[22:00:00.425] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:00.425] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:24:0) after 1m0s
[22:01:00.538] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:24:0)
[22:01:00.541] Timer tick, asking Buildbucket for the build status
[22:01:00.966] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:00.966] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:25:0) after 1m0s
[22:02:00.973] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:25:0)
[22:02:00.973] Timer tick, asking Buildbucket for the build status
[22:02:01.198] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:01.198] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:26:0) after 1m0s
[22:03:01.280] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:26:0)
[22:03:01.280] Timer tick, asking Buildbucket for the build status
[22:03:05.250] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:05.250] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:27:0) after 1m0s
[22:04:05.272] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:27:0)
[22:04:05.272] Timer tick, asking Buildbucket for the build status
[22:04:05.780] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:05.780] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:28:0) after 1m0s
[22:05:05.828] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:28:0)
[22:05:05.828] Timer tick, asking Buildbucket for the build status
[22:05:06.109] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:06.109] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:29:0) after 1m0s
[22:06:06.178] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:29:0)
[22:06:06.178] Timer tick, asking Buildbucket for the build status
[22:06:08.292] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:08.292] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:30:0) after 1m0s
[22:07:08.481] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:30:0)
[22:07:08.481] Timer tick, asking Buildbucket for the build status
[22:07:09.212] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:09.212] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:31:0) after 1m0s
[22:08:09.270] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:31:0)
[22:08:09.270] Timer tick, asking Buildbucket for the build status
[22:08:09.480] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:09.480] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:32:0) after 1m0s
[22:09:09.563] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:32:0)
[22:09:09.563] Timer tick, asking Buildbucket for the build status
[22:09:11.451] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:11.451] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:33:0) after 1m0s
[22:10:11.508] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:33:0)
[22:10:11.508] Timer tick, asking Buildbucket for the build status
[22:10:11.625] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:11.625] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:34:0) after 1m0s
[22:11:11.774] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:34:0)
[22:11:11.774] Timer tick, asking Buildbucket for the build status
[22:11:12.225] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:12.225] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:35:0) after 1m0s
[22:12:12.428] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:35:0)
[22:12:12.428] Timer tick, asking Buildbucket for the build status
[22:12:12.668] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:12.668] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:36:0) after 1m0s
[22:13:12.719] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:36:0)
[22:13:12.719] Timer tick, asking Buildbucket for the build status
[22:13:13.182] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:13.182] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:37:0) after 1m0s
[22:14:13.267] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:37:0)
[22:14:13.267] Timer tick, asking Buildbucket for the build status
[22:14:13.545] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:13.545] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:38:0) after 1m0s
[22:15:13.603] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:38:0)
[22:15:13.603] Timer tick, asking Buildbucket for the build status
[22:15:13.854] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:13.854] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:39:0) after 1m0s
[22:16:13.882] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:39:0)
[22:16:13.882] Timer tick, asking Buildbucket for the build status
[22:16:14.062] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:14.062] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:40:0) after 1m0s
[22:17:14.122] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:40:0)
[22:17:14.122] Timer tick, asking Buildbucket for the build status
[22:17:14.628] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:14.628] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:41:0) after 1m0s
[22:18:14.655] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:41:0)
[22:18:14.655] Timer tick, asking Buildbucket for the build status
[22:18:14.773] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:14.773] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:42:0) after 1m0s
[22:19:14.884] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:42:0)
[22:19:14.884] Timer tick, asking Buildbucket for the build status
[22:19:15.522] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:15.522] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:43:0) after 1m0s
[22:20:15.593] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:43:0)
[22:20:15.593] Timer tick, asking Buildbucket for the build status
[22:20:18.317] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:18.317] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:44:0) after 1m0s
[22:21:18.387] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:44:0)
[22:21:18.387] Timer tick, asking Buildbucket for the build status
[22:21:20.192] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:20.192] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:45:0) after 1m0s
[22:22:20.216] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:45:0)
[22:22:20.216] Timer tick, asking Buildbucket for the build status
[22:22:20.582] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:20.582] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:46:0) after 1m0s
[22:23:20.581] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:46:0)
[22:23:20.581] Timer tick, asking Buildbucket for the build status
[22:23:20.907] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:20.907] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:47:0) after 1m0s
[22:24:20.929] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:47:0)
[22:24:20.929] Timer tick, asking Buildbucket for the build status
[22:24:23.438] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:23.438] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:48:0) after 1m0s
[22:25:23.443] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:48:0)
[22:25:23.443] Timer tick, asking Buildbucket for the build status
[22:25:23.539] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:23.539] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:49:0) after 1m0s
[22:26:23.558] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:49:0)
[22:26:23.558] Timer tick, asking Buildbucket for the build status
[22:26:25.213] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:25.213] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:50:0) after 1m0s
[22:27:25.267] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:50:0)
[22:27:25.267] Timer tick, asking Buildbucket for the build status
[22:27:26.442] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:26.442] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:51:0) after 1m0s
[22:28:26.483] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:51:0)
[22:28:26.483] Timer tick, asking Buildbucket for the build status
[22:28:26.589] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:26.589] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:52:0) after 1m0s
[22:29:26.614] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:52:0)
[22:29:26.614] Timer tick, asking Buildbucket for the build status
[22:29:26.724] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:26.724] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:53:0) after 1m0s
[22:30:26.760] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:53:0)
[22:30:26.760] Timer tick, asking Buildbucket for the build status
[22:30:26.894] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:26.894] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:54:0) after 1m0s
[22:31:26.923] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:54:0)
[22:31:26.923] Timer tick, asking Buildbucket for the build status
[22:31:27.153] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:27.153] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:55:0) after 1m0s
[22:32:27.204] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:55:0)
[22:32:27.204] Timer tick, asking Buildbucket for the build status
[22:32:27.357] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:27.357] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:56:0) after 1m0s
[22:33:27.392] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:56:0)
[22:33:27.392] Timer tick, asking Buildbucket for the build status
[22:33:27.576] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:27.576] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:57:0) after 1m0s
[22:34:27.601] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:57:0)
[22:34:27.601] Timer tick, asking Buildbucket for the build status
[22:34:28.225] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:28.225] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:58:0) after 1m0s
[22:35:28.326] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:58:0)
[22:35:28.326] Timer tick, asking Buildbucket for the build status
[22:35:28.764] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:28.764] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:59:0) after 1m0s
[22:36:28.758] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:59:0)
[22:36:28.758] Timer tick, asking Buildbucket for the build status
[22:36:30.112] Build 8910187223841193584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:30.112] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9075617287883176096:60:0) after 1m0s
[22:36:50.946] Received PubSub notification, asking Buildbucket for the build status
[22:36:52.243] Build 8910187223841193584: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:36:52.243] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1560983808815231",
"created_by": "project:chromium",
"created_ts": "1560980312459462",
"id": "8910187223841193584",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"549a2d98aaa9ba476dbc7a6b284f269272763faa\"}}",
"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-trusty-32-6-qxjp\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux (dbg)\", \"buildnumber\": 8583, \"got_angle_revision\": \"c6601ec89b5ee8ce973db0a80da84b1758733c69\", \"got_dawn_revision\": \"92cdeaaf810bee05e4b23e99fc2bededbdfa59d4\", \"got_nacl_revision\": \"c4cf6f0621b94cca4053b19fa01ef329f5663880\", \"got_revision\": \"549a2d98aaa9ba476dbc7a6b284f269272763faa\", \"got_revision_cp\": \"refs/heads/master@{#670659}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"ab5fb228bcaf387cc72dfd93cdc62c1db4867ebf\", \"got_v8_revision_cp\": \"refs/heads/7.7.109@{#1}\", \"got_webrtc_revision\": \"5bf58064f2970420129b7bf24121755772d0b159\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28324}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"549a2d98aaa9ba476dbc7a6b284f269272763faa\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_17d4e9c3562fe440e7ef7dabcb522d5a89809ef05519650a163dd2bc405c0258_v2\", \"builder_3805b8ba4abc77de369f5d4399a0a76b312e2fd8c5f804cd390624b9118dfa64_v2\", \"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-trusty-32-6-qxjp\"], \"image\": [\"chrome-trusty-19041600-95c8804d634\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4339-6285c57\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1560980317265044",
"status": "COMPLETED",
"status_changed_ts": "1560983809364927",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/8583",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/549a2d98aaa9ba476dbc7a6b284f269272763faa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/549a2d98aaa9ba476dbc7a6b284f269272763faa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9075617287883176096",
"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/8910187223841193584/+/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:458a7edc9a367f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1560983809388383",
"url": "https://ci.chromium.org/b/8910187223841193584",
"utcnow_ts": "1560983812227874"
}
}
[22:36:52.243] Invocation finished in 58m23.021939566s with status SUCCEEDED