[09:51:12.231] New invocation is queued and will start shortly
[09:51:13.472] Starting the invocation (attempt 1)
[09:51:13.654] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:51:13.654] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:51:13.654] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9065514926082651296",
"parameters_json": "{\"builder_name\":\"Deterministic Linux\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2ea6736e91fb7a35f291bf1791aecb5e1333a017\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux",
"scheduler_invocation_id:9065514926082651296",
"scheduler_job_id:chromium/Deterministic Linux",
"user_agent:luci-scheduler",
"buildset:commit/git/2ea6736e91fb7a35f291bf1791aecb5e1333a017",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2ea6736e91fb7a35f291bf1791aecb5e1333a017",
"gitiles_ref:refs/heads/master"
]
}
[09:51:14.930] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1570614674043233",
"id": "8900084863509413984",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2ea6736e91fb7a35f291bf1791aecb5e1333a017\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1570614674606379",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/25114",
"builder:Deterministic Linux",
"buildset:commit/git/2ea6736e91fb7a35f291bf1791aecb5e1333a017",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2ea6736e91fb7a35f291bf1791aecb5e1333a017",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9065514926082651296",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8900084863509413984/+/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": "1570614674606499",
"url": "https://ci.chromium.org/b/8900084863509413984",
"utcnow_ts": "1570614674909461"
}
}
[09:51:14.930] Task URL: https://ci.chromium.org/b/8900084863509413984
[09:51:14.930] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:2:0) after 1m0s
[09:52:15.026] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:2:0)
[09:52:15.026] Timer tick, asking Buildbucket for the build status
[09:52:15.235] Build 8900084863509413984: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:52:15.235] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:3:0) after 1m0s
[09:53:15.263] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:3:0)
[09:53:15.263] Timer tick, asking Buildbucket for the build status
[09:53:15.503] Build 8900084863509413984: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:53:15.503] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:4:0) after 1m0s
[09:53:59.843] Received PubSub notification, asking Buildbucket for the build status
[09:53:59.990] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:15.526] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:4:0)
[09:54:15.526] Timer tick, asking Buildbucket for the build status
[09:54:15.793] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:15.793] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:6:0) after 1m0s
[09:55:15.822] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:6:0)
[09:55:15.822] Timer tick, asking Buildbucket for the build status
[09:55:16.043] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:16.043] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:7:0) after 1m0s
[09:56:16.191] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:7:0)
[09:56:16.191] Timer tick, asking Buildbucket for the build status
[09:56:18.720] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:18.720] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:8:0) after 1m0s
[09:57:18.762] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:8:0)
[09:57:18.762] Timer tick, asking Buildbucket for the build status
[09:57:19.186] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:19.186] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:9:0) after 1m0s
[09:58:19.260] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:9:0)
[09:58:19.260] Timer tick, asking Buildbucket for the build status
[09:58:19.444] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:19.444] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:10:0) after 1m0s
[09:59:19.469] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:10:0)
[09:59:19.469] Timer tick, asking Buildbucket for the build status
[09:59:19.796] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:19.796] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:11:0) after 1m0s
[10:00:20.724] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:11:0)
[10:00:20.724] Timer tick, asking Buildbucket for the build status
[10:00:21.606] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:21.606] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:12:0) after 1m0s
[10:01:21.735] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:12:0)
[10:01:21.735] Timer tick, asking Buildbucket for the build status
[10:01:22.305] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:22.305] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:13:0) after 1m0s
[10:02:22.467] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:13:0)
[10:02:22.468] Timer tick, asking Buildbucket for the build status
[10:02:25.713] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:25.713] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:14:0) after 1m0s
[10:03:25.862] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:14:0)
[10:03:25.862] Timer tick, asking Buildbucket for the build status
[10:03:26.110] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:26.110] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:15:0) after 1m0s
[10:04:26.152] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:15:0)
[10:04:26.152] Timer tick, asking Buildbucket for the build status
[10:04:26.784] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:26.784] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:16:0) after 1m0s
[10:05:26.865] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:16:0)
[10:05:26.865] Timer tick, asking Buildbucket for the build status
[10:05:30.868] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:30.868] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:17:0) after 1m0s
[10:06:30.952] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:17:0)
[10:06:30.952] Timer tick, asking Buildbucket for the build status
[10:06:31.746] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:31.746] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:18:0) after 1m0s
[10:07:31.846] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:18:0)
[10:07:31.846] Timer tick, asking Buildbucket for the build status
[10:07:34.060] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:34.060] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:19:0) after 1m0s
[10:08:34.134] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:19:0)
[10:08:34.134] Timer tick, asking Buildbucket for the build status
[10:08:34.872] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:34.872] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:20:0) after 1m0s
[10:09:34.970] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:20:0)
[10:09:34.970] Timer tick, asking Buildbucket for the build status
[10:09:35.423] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:35.423] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:21:0) after 1m0s
[10:10:35.559] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:21:0)
[10:10:35.559] Timer tick, asking Buildbucket for the build status
[10:10:35.777] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:35.777] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:22:0) after 1m0s
[10:11:35.819] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:22:0)
[10:11:35.819] Timer tick, asking Buildbucket for the build status
[10:11:36.112] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:36.112] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:23:0) after 1m0s
[10:12:36.168] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:23:0)
[10:12:36.168] Timer tick, asking Buildbucket for the build status
[10:12:36.361] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:36.361] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:24:0) after 1m0s
[10:13:36.405] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:24:0)
[10:13:36.405] Timer tick, asking Buildbucket for the build status
[10:13:36.561] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:36.562] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:25:0) after 1m0s
[10:14:36.627] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:25:0)
[10:14:36.627] Timer tick, asking Buildbucket for the build status
[10:14:36.869] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:36.869] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:26:0) after 1m0s
[10:15:36.968] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:26:0)
[10:15:36.968] Timer tick, asking Buildbucket for the build status
[10:15:37.503] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:37.503] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:27:0) after 1m0s
[10:16:37.567] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:27:0)
[10:16:37.567] Timer tick, asking Buildbucket for the build status
[10:16:37.899] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:37.899] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:28:0) after 1m0s
[10:17:37.935] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:28:0)
[10:17:37.935] Timer tick, asking Buildbucket for the build status
[10:17:38.350] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:38.350] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:29:0) after 1m0s
[10:18:38.404] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:29:0)
[10:18:38.404] Timer tick, asking Buildbucket for the build status
[10:18:38.849] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:38.849] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:30:0) after 1m0s
[10:19:38.870] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:30:0)
[10:19:38.870] Timer tick, asking Buildbucket for the build status
[10:19:39.094] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:39.094] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:31:0) after 1m0s
[10:20:39.275] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:31:0)
[10:20:39.275] Timer tick, asking Buildbucket for the build status
[10:20:40.710] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:40.710] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:32:0) after 1m0s
[10:21:40.877] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:32:0)
[10:21:40.877] Timer tick, asking Buildbucket for the build status
[10:21:41.297] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:41.297] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:33:0) after 1m0s
[10:22:41.545] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:33:0)
[10:22:41.545] Timer tick, asking Buildbucket for the build status
[10:22:42.313] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:42.313] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:34:0) after 1m0s
[10:23:42.359] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:34:0)
[10:23:42.359] Timer tick, asking Buildbucket for the build status
[10:23:42.593] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:42.593] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:35:0) after 1m0s
[10:24:42.745] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:35:0)
[10:24:42.745] Timer tick, asking Buildbucket for the build status
[10:24:42.978] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:42.978] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:36:0) after 1m0s
[10:25:43.080] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:36:0)
[10:25:43.080] Timer tick, asking Buildbucket for the build status
[10:25:44.124] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:44.124] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:37:0) after 1m0s
[10:26:44.164] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:37:0)
[10:26:44.164] Timer tick, asking Buildbucket for the build status
[10:26:44.442] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:44.442] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:38:0) after 1m0s
[10:27:44.498] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:38:0)
[10:27:44.498] Timer tick, asking Buildbucket for the build status
[10:27:44.742] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:44.742] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:39:0) after 1m0s
[10:28:44.968] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:39:0)
[10:28:44.968] Timer tick, asking Buildbucket for the build status
[10:28:46.054] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:46.054] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:40:0) after 1m0s
[10:29:46.190] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:40:0)
[10:29:46.190] Timer tick, asking Buildbucket for the build status
[10:29:46.532] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:46.532] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:41:0) after 1m0s
[10:30:46.552] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:41:0)
[10:30:46.552] Timer tick, asking Buildbucket for the build status
[10:30:46.956] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:46.956] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:42:0) after 1m0s
[10:31:47.011] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:42:0)
[10:31:47.011] Timer tick, asking Buildbucket for the build status
[10:31:47.221] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:47.221] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:43:0) after 1m0s
[10:32:47.259] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:43:0)
[10:32:47.259] Timer tick, asking Buildbucket for the build status
[10:32:47.515] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:47.515] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:44:0) after 1m0s
[10:33:47.560] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:44:0)
[10:33:47.560] Timer tick, asking Buildbucket for the build status
[10:33:47.820] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:47.820] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:45:0) after 1m0s
[10:34:47.915] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:45:0)
[10:34:47.915] Timer tick, asking Buildbucket for the build status
[10:34:48.034] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:48.034] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:46:0) after 1m0s
[10:35:48.252] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:46:0)
[10:35:48.252] Timer tick, asking Buildbucket for the build status
[10:35:48.438] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:48.438] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:47:0) after 1m0s
[10:36:48.561] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:47:0)
[10:36:48.561] Timer tick, asking Buildbucket for the build status
[10:36:48.811] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:48.811] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:48:0) after 1m0s
[10:37:48.871] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:48:0)
[10:37:48.871] Timer tick, asking Buildbucket for the build status
[10:37:49.838] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:49.838] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:49:0) after 1m0s
[10:38:49.981] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:49:0)
[10:38:49.981] Timer tick, asking Buildbucket for the build status
[10:38:50.172] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:50.172] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:50:0) after 1m0s
[10:39:50.223] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:50:0)
[10:39:50.223] Timer tick, asking Buildbucket for the build status
[10:39:50.737] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:50.737] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:51:0) after 1m0s
[10:40:50.791] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:51:0)
[10:40:50.791] Timer tick, asking Buildbucket for the build status
[10:40:51.103] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:51.103] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:52:0) after 1m0s
[10:41:51.352] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:52:0)
[10:41:51.352] Timer tick, asking Buildbucket for the build status
[10:41:52.104] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:52.104] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:53:0) after 1m0s
[10:42:52.132] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:53:0)
[10:42:52.132] Timer tick, asking Buildbucket for the build status
[10:42:52.367] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:52.367] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:54:0) after 1m0s
[10:43:52.437] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:54:0)
[10:43:52.437] Timer tick, asking Buildbucket for the build status
[10:43:54.826] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:54.826] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:55:0) after 1m0s
[10:44:55.086] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:55:0)
[10:44:55.086] Timer tick, asking Buildbucket for the build status
[10:44:55.708] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:55.708] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:56:0) after 1m0s
[10:45:55.735] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:56:0)
[10:45:55.735] Timer tick, asking Buildbucket for the build status
[10:45:56.042] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:56.042] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:57:0) after 1m0s
[10:46:56.305] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:57:0)
[10:46:56.305] Timer tick, asking Buildbucket for the build status
[10:46:56.575] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:56.575] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:58:0) after 1m0s
[10:47:56.736] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:58:0)
[10:47:56.736] Timer tick, asking Buildbucket for the build status
[10:47:57.009] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:57.009] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:59:0) after 1m0s
[10:48:57.064] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:59:0)
[10:48:57.064] Timer tick, asking Buildbucket for the build status
[10:48:57.260] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:57.260] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:60:0) after 1m0s
[10:49:57.293] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:60:0)
[10:49:57.293] Timer tick, asking Buildbucket for the build status
[10:49:57.566] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:57.566] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:61:0) after 1m0s
[10:50:57.717] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:61:0)
[10:50:57.717] Timer tick, asking Buildbucket for the build status
[10:51:00.257] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:00.257] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:62:0) after 1m0s
[10:52:00.490] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:62:0)
[10:52:00.490] Timer tick, asking Buildbucket for the build status
[10:52:02.458] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:02.458] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:63:0) after 1m0s
[10:53:02.638] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:63:0)
[10:53:02.638] Timer tick, asking Buildbucket for the build status
[10:53:03.543] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:03.543] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:64:0) after 1m0s
[10:54:03.579] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:64:0)
[10:54:03.579] Timer tick, asking Buildbucket for the build status
[10:54:03.787] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:03.787] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:65:0) after 1m0s
[10:55:03.896] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:65:0)
[10:55:03.896] Timer tick, asking Buildbucket for the build status
[10:55:04.623] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:04.623] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:66:0) after 1m0s
[10:56:04.703] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:66:0)
[10:56:04.703] Timer tick, asking Buildbucket for the build status
[10:56:04.903] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:04.903] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:67:0) after 1m0s
[10:57:04.951] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:67:0)
[10:57:04.951] Timer tick, asking Buildbucket for the build status
[10:57:05.576] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:05.576] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:68:0) after 1m0s
[10:58:05.696] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:68:0)
[10:58:05.696] Timer tick, asking Buildbucket for the build status
[10:58:05.935] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:05.935] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:69:0) after 1m0s
[10:59:06.109] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:69:0)
[10:59:06.109] Timer tick, asking Buildbucket for the build status
[10:59:06.278] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:06.278] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:70:0) after 1m0s
[11:00:06.539] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:70:0)
[11:00:06.539] Timer tick, asking Buildbucket for the build status
[11:00:07.666] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:07.666] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:71:0) after 1m0s
[11:01:07.724] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:71:0)
[11:01:07.724] Timer tick, asking Buildbucket for the build status
[11:01:08.461] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:08.461] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:72:0) after 1m0s
[11:02:08.501] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:72:0)
[11:02:08.501] Timer tick, asking Buildbucket for the build status
[11:02:08.660] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:08.660] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:73:0) after 1m0s
[11:03:08.829] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:73:0)
[11:03:08.829] Timer tick, asking Buildbucket for the build status
[11:03:09.453] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:09.453] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:74:0) after 1m0s
[11:04:09.792] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:74:0)
[11:04:09.792] Timer tick, asking Buildbucket for the build status
[11:04:09.990] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:09.990] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:75:0) after 1m0s
[11:05:10.264] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:75:0)
[11:05:10.264] Timer tick, asking Buildbucket for the build status
[11:05:10.507] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:10.507] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:76:0) after 1m0s
[11:06:10.570] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:76:0)
[11:06:10.570] Timer tick, asking Buildbucket for the build status
[11:06:11.133] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:11.133] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:77:0) after 1m0s
[11:07:11.166] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:77:0)
[11:07:11.166] Timer tick, asking Buildbucket for the build status
[11:07:11.551] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:11.551] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:78:0) after 1m0s
[11:08:11.770] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:78:0)
[11:08:11.770] Timer tick, asking Buildbucket for the build status
[11:08:12.366] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:12.366] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:79:0) after 1m0s
[11:09:12.582] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:79:0)
[11:09:12.582] Timer tick, asking Buildbucket for the build status
[11:09:12.769] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:12.769] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:80:0) after 1m0s
[11:10:12.835] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:80:0)
[11:10:12.835] Timer tick, asking Buildbucket for the build status
[11:10:13.959] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:13.959] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:81:0) after 1m0s
[11:11:14.223] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:81:0)
[11:11:14.223] Timer tick, asking Buildbucket for the build status
[11:11:14.869] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:14.869] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:82:0) after 1m0s
[11:12:14.963] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:82:0)
[11:12:14.963] Timer tick, asking Buildbucket for the build status
[11:12:17.969] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:17.969] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:83:0) after 1m0s
[11:13:18.473] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:83:0)
[11:13:18.473] Timer tick, asking Buildbucket for the build status
[11:13:19.358] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:19.358] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:84:0) after 1m0s
[11:14:19.422] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:84:0)
[11:14:19.422] Timer tick, asking Buildbucket for the build status
[11:14:20.121] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:20.121] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:85:0) after 1m0s
[11:15:20.164] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:85:0)
[11:15:20.164] Timer tick, asking Buildbucket for the build status
[11:15:20.517] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:20.517] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:86:0) after 1m0s
[11:16:20.650] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:86:0)
[11:16:20.650] Timer tick, asking Buildbucket for the build status
[11:16:21.045] Build 8900084863509413984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:21.045] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9065514926082651296:87:0) after 1m0s
[11:16:39.076] Received PubSub notification, asking Buildbucket for the build status
[11:16:39.315] Build 8900084863509413984: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:16:39.315] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1570619797814538",
"created_by": "project:chromium",
"created_ts": "1570614674043233",
"id": "8900084863509413984",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2ea6736e91fb7a35f291bf1791aecb5e1333a017\"}}",
"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-us-central1-b-8-117-kbwm\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux\", \"buildnumber\": 25114, \"got_angle_revision\": \"8e7d9d6c2326a36ff57335ceeb41eb86cb639a7f\", \"got_dawn_revision\": \"c932f3309322b833cf13556a408fab75fa51c77d\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"2ea6736e91fb7a35f291bf1791aecb5e1333a017\", \"got_revision_cp\": \"refs/heads/master@{#704123}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_v8_revision\": \"6092f6bca4f1ceb990563e4254449c22adc3f786\", \"got_v8_revision_cp\": \"refs/heads/7.9.241@{#1}\", \"got_webrtc_revision\": \"e1092c0bc8b8a2865da7ce6284ec19d189f71e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29411}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2ea6736e91fb7a35f291bf1791aecb5e1333a017\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_78902ab2d70d1593a97658cf8910c0e117cf7950e257117b8c551c6fe1cfb403_v2\", \"builder_a27b44a1648dd298adda85735042ac3d0ffdcfd6d22ed3e792b03614ce2ee968_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-117-kbwm\"], \"image\": [\"chrome-xenial-19090600-ed917d8233f\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4494-2b7cf0c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1570614837971619",
"status": "COMPLETED",
"status_changed_ts": "1570619798620936",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/25114",
"builder:Deterministic Linux",
"buildset:commit/git/2ea6736e91fb7a35f291bf1791aecb5e1333a017",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2ea6736e91fb7a35f291bf1791aecb5e1333a017",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9065514926082651296",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8900084863509413984/+/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:47c8bfe26028ef10",
"user_agent:luci-scheduler"
],
"updated_ts": "1570619798626883",
"url": "https://ci.chromium.org/b/8900084863509413984",
"utcnow_ts": "1570619799301486"
}
}
[11:16:39.315] Invocation finished in 1h25m27.109827658s with status SUCCEEDED