[04:08:12.250] New invocation is queued and will start shortly
[04:08:13.405] Starting the invocation (attempt 1)
[04:08:13.439] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:08:13.439] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:08:13.439] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9068797996539938000",
"parameters_json": "{\"builder_name\":\"Deterministic Linux\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"a082dcf30010bd8856cc738306a6e8156b1e6aeb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux",
"scheduler_invocation_id:9068797996539938000",
"scheduler_job_id:chromium/Deterministic Linux",
"user_agent:luci-scheduler",
"buildset:commit/git/a082dcf30010bd8856cc738306a6e8156b1e6aeb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a082dcf30010bd8856cc738306a6e8156b1e6aeb",
"gitiles_ref:refs/heads/master"
]
}
[04:08:14.286] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1567483693682950",
"id": "8903367934372312736",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a082dcf30010bd8856cc738306a6e8156b1e6aeb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1567483694160311",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/24588",
"builder:Deterministic Linux",
"buildset:commit/git/a082dcf30010bd8856cc738306a6e8156b1e6aeb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a082dcf30010bd8856cc738306a6e8156b1e6aeb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068797996539938000",
"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/8903367934372312736/+/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": "1567483694160476",
"url": "https://ci.chromium.org/b/8903367934372312736",
"utcnow_ts": "1567483694278537"
}
}
[04:08:14.286] Task URL: https://ci.chromium.org/b/8903367934372312736
[04:08:14.286] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:2:0) after 1m0s
[04:09:14.399] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:2:0)
[04:09:14.399] Timer tick, asking Buildbucket for the build status
[04:09:14.594] Build 8903367934372312736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:14.594] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:3:0) after 1m0s
[04:10:14.676] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:3:0)
[04:10:14.676] Timer tick, asking Buildbucket for the build status
[04:10:15.542] Build 8903367934372312736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:15.542] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:4:0) after 1m0s
[04:11:15.722] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:4:0)
[04:11:15.722] Timer tick, asking Buildbucket for the build status
[04:11:16.496] Build 8903367934372312736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:16.496] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:5:0) after 1m0s
[04:12:16.517] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:5:0)
[04:12:16.517] Timer tick, asking Buildbucket for the build status
[04:12:17.446] Build 8903367934372312736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:17.446] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:6:0) after 1m0s
[04:13:17.480] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:6:0)
[04:13:17.480] Timer tick, asking Buildbucket for the build status
[04:13:18.103] Build 8903367934372312736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:13:18.103] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:7:0) after 1m0s
[04:13:22.832] Received PubSub notification, asking Buildbucket for the build status
[04:13:23.005] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:18.135] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:7:0)
[04:14:18.135] Timer tick, asking Buildbucket for the build status
[04:14:18.419] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:18.419] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:9:0) after 1m0s
[04:15:18.398] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:9:0)
[04:15:18.398] Timer tick, asking Buildbucket for the build status
[04:15:18.587] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:18.587] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:10:0) after 1m0s
[04:16:18.630] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:10:0)
[04:16:18.630] Timer tick, asking Buildbucket for the build status
[04:16:21.953] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:21.953] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:11:0) after 1m0s
[04:17:22.096] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:11:0)
[04:17:22.096] Timer tick, asking Buildbucket for the build status
[04:17:22.308] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:22.308] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:12:0) after 1m0s
[04:18:22.431] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:12:0)
[04:18:22.431] Timer tick, asking Buildbucket for the build status
[04:18:23.055] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:23.055] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:13:0) after 1m0s
[04:19:23.092] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:13:0)
[04:19:23.092] Timer tick, asking Buildbucket for the build status
[04:19:23.790] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:23.790] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:14:0) after 1m0s
[04:20:23.823] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:14:0)
[04:20:23.823] Timer tick, asking Buildbucket for the build status
[04:20:24.281] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:24.281] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:15:0) after 1m0s
[04:21:24.337] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:15:0)
[04:21:24.337] Timer tick, asking Buildbucket for the build status
[04:21:24.939] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:24.939] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:16:0) after 1m0s
[04:22:25.003] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:16:0)
[04:22:25.003] Timer tick, asking Buildbucket for the build status
[04:22:25.316] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:25.316] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:17:0) after 1m0s
[04:23:25.340] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:17:0)
[04:23:25.340] Timer tick, asking Buildbucket for the build status
[04:23:25.602] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:25.602] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:18:0) after 1m0s
[04:24:25.604] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:18:0)
[04:24:25.604] Timer tick, asking Buildbucket for the build status
[04:24:26.346] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:26.346] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:19:0) after 1m0s
[04:25:26.434] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:19:0)
[04:25:26.434] Timer tick, asking Buildbucket for the build status
[04:25:26.671] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:26.671] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:20:0) after 1m0s
[04:26:26.692] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:20:0)
[04:26:26.692] Timer tick, asking Buildbucket for the build status
[04:26:26.956] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:26.956] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:21:0) after 1m0s
[04:27:26.980] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:21:0)
[04:27:26.980] Timer tick, asking Buildbucket for the build status
[04:27:27.203] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:27.203] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:22:0) after 1m0s
[04:28:27.294] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:22:0)
[04:28:27.294] Timer tick, asking Buildbucket for the build status
[04:28:28.405] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:28.405] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:23:0) after 1m0s
[04:29:28.448] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:23:0)
[04:29:28.448] Timer tick, asking Buildbucket for the build status
[04:29:28.634] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:28.635] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:24:0) after 1m0s
[04:30:28.671] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:24:0)
[04:30:28.671] Timer tick, asking Buildbucket for the build status
[04:30:28.846] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:28.846] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:25:0) after 1m0s
[04:31:28.871] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:25:0)
[04:31:28.871] Timer tick, asking Buildbucket for the build status
[04:31:29.103] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:29.103] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:26:0) after 1m0s
[04:32:29.150] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:26:0)
[04:32:29.150] Timer tick, asking Buildbucket for the build status
[04:32:30.078] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:30.078] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:27:0) after 1m0s
[04:33:30.102] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:27:0)
[04:33:30.102] Timer tick, asking Buildbucket for the build status
[04:33:30.382] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:30.382] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:28:0) after 1m0s
[04:34:30.420] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:28:0)
[04:34:30.420] Timer tick, asking Buildbucket for the build status
[04:34:30.581] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:30.581] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:29:0) after 1m0s
[04:35:30.658] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:29:0)
[04:35:30.658] Timer tick, asking Buildbucket for the build status
[04:35:31.125] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:31.125] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:30:0) after 1m0s
[04:36:31.256] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:30:0)
[04:36:31.256] Timer tick, asking Buildbucket for the build status
[04:36:31.548] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:31.548] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:31:0) after 1m0s
[04:37:31.550] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:31:0)
[04:37:31.550] Timer tick, asking Buildbucket for the build status
[04:37:32.108] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:32.108] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:32:0) after 1m0s
[04:38:32.333] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:32:0)
[04:38:32.333] Timer tick, asking Buildbucket for the build status
[04:38:33.341] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:33.341] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:33:0) after 1m0s
[04:39:33.399] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:33:0)
[04:39:33.399] Timer tick, asking Buildbucket for the build status
[04:39:34.717] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:34.718] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:34:0) after 1m0s
[04:40:34.737] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:34:0)
[04:40:34.737] Timer tick, asking Buildbucket for the build status
[04:40:35.945] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:35.945] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:35:0) after 1m0s
[04:41:36.068] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:35:0)
[04:41:36.068] Timer tick, asking Buildbucket for the build status
[04:41:36.295] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:36.295] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:36:0) after 1m0s
[04:42:36.328] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:36:0)
[04:42:36.328] Timer tick, asking Buildbucket for the build status
[04:42:36.563] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:36.563] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:37:0) after 1m0s
[04:43:36.701] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:37:0)
[04:43:36.701] Timer tick, asking Buildbucket for the build status
[04:43:36.915] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:36.915] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:38:0) after 1m0s
[04:44:36.977] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:38:0)
[04:44:36.977] Timer tick, asking Buildbucket for the build status
[04:44:37.627] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:37.627] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:39:0) after 1m0s
[04:45:37.684] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:39:0)
[04:45:37.684] Timer tick, asking Buildbucket for the build status
[04:45:37.931] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:37.931] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:40:0) after 1m0s
[04:46:37.952] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:40:0)
[04:46:37.952] Timer tick, asking Buildbucket for the build status
[04:46:38.320] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:38.320] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:41:0) after 1m0s
[04:47:38.391] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:41:0)
[04:47:38.391] Timer tick, asking Buildbucket for the build status
[04:47:38.663] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:38.663] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:42:0) after 1m0s
[04:48:38.649] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:42:0)
[04:48:38.649] Timer tick, asking Buildbucket for the build status
[04:48:38.866] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:38.866] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:43:0) after 1m0s
[04:49:38.888] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:43:0)
[04:49:38.888] Timer tick, asking Buildbucket for the build status
[04:49:40.366] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:40.366] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:44:0) after 1m0s
[04:50:40.367] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:44:0)
[04:50:40.367] Timer tick, asking Buildbucket for the build status
[04:50:40.861] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:40.861] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:45:0) after 1m0s
[04:51:40.943] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:45:0)
[04:51:40.943] Timer tick, asking Buildbucket for the build status
[04:51:41.141] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:41.141] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:46:0) after 1m0s
[04:52:41.241] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:46:0)
[04:52:41.241] Timer tick, asking Buildbucket for the build status
[04:52:41.454] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:41.454] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:47:0) after 1m0s
[04:53:41.488] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:47:0)
[04:53:41.488] Timer tick, asking Buildbucket for the build status
[04:53:42.806] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:42.806] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:48:0) after 1m0s
[04:54:42.936] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:48:0)
[04:54:42.936] Timer tick, asking Buildbucket for the build status
[04:54:43.234] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:43.234] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:49:0) after 1m0s
[04:55:43.472] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:49:0)
[04:55:43.472] Timer tick, asking Buildbucket for the build status
[04:55:43.677] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:43.677] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:50:0) after 1m0s
[04:56:43.701] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:50:0)
[04:56:43.701] Timer tick, asking Buildbucket for the build status
[04:56:44.124] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:44.124] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:51:0) after 1m0s
[04:57:44.258] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:51:0)
[04:57:44.258] Timer tick, asking Buildbucket for the build status
[04:57:44.771] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:44.771] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:52:0) after 1m0s
[04:58:44.873] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:52:0)
[04:58:44.873] Timer tick, asking Buildbucket for the build status
[04:58:45.048] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:45.048] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:53:0) after 1m0s
[04:59:45.133] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:53:0)
[04:59:45.133] Timer tick, asking Buildbucket for the build status
[04:59:45.329] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:45.329] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:54:0) after 1m0s
[05:00:45.405] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:54:0)
[05:00:45.405] Timer tick, asking Buildbucket for the build status
[05:00:46.248] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:46.248] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:55:0) after 1m0s
[05:01:46.257] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:55:0)
[05:01:46.257] Timer tick, asking Buildbucket for the build status
[05:01:46.474] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:46.474] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:56:0) after 1m0s
[05:02:46.554] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:56:0)
[05:02:46.555] Timer tick, asking Buildbucket for the build status
[05:02:46.759] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:46.759] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:57:0) after 1m0s
[05:03:46.790] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:57:0)
[05:03:46.790] Timer tick, asking Buildbucket for the build status
[05:03:53.586] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:53.586] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:58:0) after 1m0s
[05:04:53.720] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:58:0)
[05:04:53.720] Timer tick, asking Buildbucket for the build status
[05:04:53.888] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:53.888] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:59:0) after 1m0s
[05:05:53.966] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:59:0)
[05:05:53.966] Timer tick, asking Buildbucket for the build status
[05:05:54.133] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:54.133] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:60:0) after 1m0s
[05:06:54.245] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:60:0)
[05:06:54.245] Timer tick, asking Buildbucket for the build status
[05:06:55.321] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:55.321] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:61:0) after 1m0s
[05:07:55.386] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:61:0)
[05:07:55.386] Timer tick, asking Buildbucket for the build status
[05:07:56.469] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:56.469] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:62:0) after 1m0s
[05:08:56.552] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:62:0)
[05:08:56.552] Timer tick, asking Buildbucket for the build status
[05:08:56.806] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:56.806] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:63:0) after 1m0s
[05:09:56.933] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:63:0)
[05:09:56.933] Timer tick, asking Buildbucket for the build status
[05:09:57.171] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:57.171] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:64:0) after 1m0s
[05:10:57.564] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:64:0)
[05:10:57.564] Timer tick, asking Buildbucket for the build status
[05:10:57.793] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:57.793] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:65:0) after 1m0s
[05:11:58.213] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:65:0)
[05:11:58.213] Timer tick, asking Buildbucket for the build status
[05:12:05.470] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:05.470] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:66:0) after 1m0s
[05:13:05.664] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:66:0)
[05:13:05.664] Timer tick, asking Buildbucket for the build status
[05:13:05.926] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:05.926] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:67:0) after 1m0s
[05:14:07.296] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:67:0)
[05:14:07.296] Timer tick, asking Buildbucket for the build status
[05:14:07.470] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:07.470] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:68:0) after 1m0s
[05:15:07.579] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:68:0)
[05:15:07.579] Timer tick, asking Buildbucket for the build status
[05:15:07.734] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:07.734] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:69:0) after 1m0s
[05:16:07.911] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:69:0)
[05:16:07.911] Timer tick, asking Buildbucket for the build status
[05:16:08.070] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:08.070] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:70:0) after 1m0s
[05:17:08.240] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:70:0)
[05:17:08.240] Timer tick, asking Buildbucket for the build status
[05:17:08.734] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:08.734] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:71:0) after 1m0s
[05:18:08.899] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:71:0)
[05:18:08.899] Timer tick, asking Buildbucket for the build status
[05:18:09.185] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:09.185] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:72:0) after 1m0s
[05:19:09.358] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:72:0)
[05:19:09.358] Timer tick, asking Buildbucket for the build status
[05:19:09.607] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:09.607] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:73:0) after 1m0s
[05:20:09.698] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:73:0)
[05:20:09.698] Timer tick, asking Buildbucket for the build status
[05:20:10.052] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:10.052] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:74:0) after 1m0s
[05:21:10.349] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:74:0)
[05:21:10.349] Timer tick, asking Buildbucket for the build status
[05:21:10.735] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:10.735] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:75:0) after 1m0s
[05:22:10.932] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:75:0)
[05:22:10.932] Timer tick, asking Buildbucket for the build status
[05:22:11.226] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:11.226] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:76:0) after 1m0s
[05:23:11.555] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:76:0)
[05:23:11.555] Timer tick, asking Buildbucket for the build status
[05:23:12.300] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:12.300] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:77:0) after 1m0s
[05:24:12.537] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:77:0)
[05:24:12.537] Timer tick, asking Buildbucket for the build status
[05:24:13.720] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:13.720] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:78:0) after 1m0s
[05:25:13.820] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:78:0)
[05:25:13.820] Timer tick, asking Buildbucket for the build status
[05:25:14.661] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:14.661] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:79:0) after 1m0s
[05:26:14.827] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:79:0)
[05:26:14.827] Timer tick, asking Buildbucket for the build status
[05:26:15.167] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:15.167] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:80:0) after 1m0s
[05:27:15.179] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:80:0)
[05:27:15.179] Timer tick, asking Buildbucket for the build status
[05:27:15.460] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:15.460] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:81:0) after 1m0s
[05:28:15.488] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:81:0)
[05:28:15.488] Timer tick, asking Buildbucket for the build status
[05:28:15.856] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:15.856] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:82:0) after 1m0s
[05:29:15.883] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:82:0)
[05:29:15.883] Timer tick, asking Buildbucket for the build status
[05:29:16.153] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:16.153] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:83:0) after 1m0s
[05:30:16.258] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:83:0)
[05:30:16.258] Timer tick, asking Buildbucket for the build status
[05:30:16.587] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:16.587] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:84:0) after 1m0s
[05:31:16.657] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:84:0)
[05:31:16.657] Timer tick, asking Buildbucket for the build status
[05:31:17.700] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:17.700] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:85:0) after 1m0s
[05:32:17.786] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:85:0)
[05:32:17.786] Timer tick, asking Buildbucket for the build status
[05:32:20.264] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:20.264] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:86:0) after 1m0s
[05:33:20.453] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:86:0)
[05:33:20.453] Timer tick, asking Buildbucket for the build status
[05:33:20.798] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:20.798] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:87:0) after 1m0s
[05:34:20.887] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:87:0)
[05:34:20.887] Timer tick, asking Buildbucket for the build status
[05:34:21.114] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:21.114] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:88:0) after 1m0s
[05:35:21.100] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:88:0)
[05:35:21.100] Timer tick, asking Buildbucket for the build status
[05:35:21.519] Build 8903367934372312736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:21.519] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9068797996539938000:89:0) after 1m0s
[05:35:51.642] Received PubSub notification, asking Buildbucket for the build status
[05:35:52.064] Build 8903367934372312736: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:35:52.064] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1567488950365740",
"created_by": "project:chromium",
"created_ts": "1567483693682950",
"id": "8903367934372312736",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a082dcf30010bd8856cc738306a6e8156b1e6aeb\"}}",
"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-east1-d-8-55-aoj7\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux\", \"buildnumber\": 24588, \"got_angle_revision\": \"a3c7c2ff6a90a864751bdcd023979a32fae2bda7\", \"got_dawn_revision\": \"179d7b28a36a5c042f16857ac7a45466c519b536\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"a082dcf30010bd8856cc738306a6e8156b1e6aeb\", \"got_revision_cp\": \"refs/heads/master@{#692547}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"b2ea4d05947ae3eaa20c1fa9c53f8565cbc1d3b9\", \"got_v8_revision_cp\": \"refs/heads/7.8.251@{#1}\", \"got_webrtc_revision\": \"a77a1f910ba33f89041a344d6962d2e3bb6cda20\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29041}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a082dcf30010bd8856cc738306a6e8156b1e6aeb\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_836dfd76661dd6ce0283c27a20a4f1b22697b2b6477701844f76cebb302cb05b_v2\", \"builder_9e49cd32b549f6319d13cc4336b62d992f414a35b4dbe85977fe32b22cc6c851_v2\", \"builder_b624744545b53f30069a9e0f9d655dd80cb0688eb6f8753c5d4d1f02e2478691_v2\", \"builder_b75dae3e256987d276902f5f82eb7d6f9dd38c954d991608166704214caef6db_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-east1-d-8-55-aoj7\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"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\": [\"4397-8786906\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1567484001541644",
"status": "COMPLETED",
"status_changed_ts": "1567488951349693",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/24588",
"builder:Deterministic Linux",
"buildset:commit/git/a082dcf30010bd8856cc738306a6e8156b1e6aeb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a082dcf30010bd8856cc738306a6e8156b1e6aeb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068797996539938000",
"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/8903367934372312736/+/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:470e209625a4b510",
"user_agent:luci-scheduler"
],
"updated_ts": "1567488951354563",
"url": "https://ci.chromium.org/b/8903367934372312736",
"utcnow_ts": "1567488952052617"
}
}
[05:35:52.064] Invocation finished in 1h27m39.832758557s with status SUCCEEDED