[08:28:54.137] New invocation is queued and will start shortly
[08:28:55.491] Starting the invocation (attempt 1)
[08:28:55.526] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:28:55.526] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:28:55.527] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9095235909036264480",
"parameters_json": "{\"builder_name\":\"Deterministic Linux\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"ffa5838fee064e345d02b27675ba4d676e78544c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux",
"scheduler_invocation_id:9095235909036264480",
"scheduler_job_id:chromium/Deterministic Linux",
"user_agent:luci-scheduler",
"buildset:commit/git/ffa5838fee064e345d02b27675ba4d676e78544c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ffa5838fee064e345d02b27675ba4d676e78544c",
"gitiles_ref:refs/heads/master"
]
}
[08:28:57.513] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1542270535649360",
"id": "8929805846769235008",
"lease_expiration_ts": "1542389337235327",
"lease_key": "1618582130",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ffa5838fee064e345d02b27675ba4d676e78544c\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1542270537419799",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/17798",
"builder:Deterministic Linux",
"buildset:commit/git/ffa5838fee064e345d02b27675ba4d676e78544c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ffa5838fee064e345d02b27675ba4d676e78544c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9095235909036264480",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_dimension:builder:Deterministic Linux",
"swarming_dimension:caches:builder_78902ab2d70d1593a97658cf8910c0e117cf7950e257117b8c551c6fe1cfb403_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Deterministic Linux/17798",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8929805846769235008",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:1fc149ed7cdb2549b1387665915ec044aea4e328",
"swarming_tag:builder:Deterministic Linux",
"swarming_tag:buildset:commit/git/ffa5838fee064e345d02b27675ba4d676e78544c",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ffa5838fee064e345d02b27675ba4d676e78544c",
"swarming_tag:caches:builder_78902ab2d70d1593a97658cf8910c0e117cf7950e257117b8c551c6fe1cfb403_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8929805846769235008/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9095235909036264480",
"swarming_tag:scheduler_job_id:chromium/Deterministic Linux",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:c8a918d09d83cb6088cdeb21510070a640e20ed3",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:412f4e5489970a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1542270537419882",
"url": "https://ci.chromium.org/b/8929805846769235008",
"utcnow_ts": "1542270537501019"
}
}
[08:28:57.513] Task URL: https://ci.chromium.org/b/8929805846769235008
[08:28:57.513] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:2:0) after 1m0s
[08:28:59.479] Received PubSub notification, asking Buildbucket for the build status
[08:28:59.516] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:57.531] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:2:0)
[08:29:57.531] Timer tick, asking Buildbucket for the build status
[08:29:57.600] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:57.600] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:4:0) after 1m0s
[08:30:57.620] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:4:0)
[08:30:57.620] Timer tick, asking Buildbucket for the build status
[08:30:57.696] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:57.696] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:5:0) after 1m0s
[08:31:57.713] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:5:0)
[08:31:57.713] Timer tick, asking Buildbucket for the build status
[08:31:57.776] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:57.776] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:6:0) after 1m0s
[08:32:57.793] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:6:0)
[08:32:57.793] Timer tick, asking Buildbucket for the build status
[08:32:57.864] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:57.864] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:7:0) after 1m0s
[08:33:57.883] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:7:0)
[08:33:57.883] Timer tick, asking Buildbucket for the build status
[08:33:57.943] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:57.943] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:8:0) after 1m0s
[08:34:58.007] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:8:0)
[08:34:58.007] Timer tick, asking Buildbucket for the build status
[08:34:58.089] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:58.089] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:9:0) after 1m0s
[08:35:58.130] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:9:0)
[08:35:58.130] Timer tick, asking Buildbucket for the build status
[08:35:58.225] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:58.225] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:10:0) after 1m0s
[08:36:58.242] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:10:0)
[08:36:58.242] Timer tick, asking Buildbucket for the build status
[08:36:58.293] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:58.293] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:11:0) after 1m0s
[08:37:58.365] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:11:0)
[08:37:58.365] Timer tick, asking Buildbucket for the build status
[08:37:58.463] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:58.463] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:12:0) after 1m0s
[08:38:58.539] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:12:0)
[08:38:58.539] Timer tick, asking Buildbucket for the build status
[08:38:58.688] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:58.688] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:13:0) after 1m0s
[08:39:58.707] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:13:0)
[08:39:58.707] Timer tick, asking Buildbucket for the build status
[08:39:58.839] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:58.839] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:14:0) after 1m0s
[08:40:58.864] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:14:0)
[08:40:58.864] Timer tick, asking Buildbucket for the build status
[08:40:58.927] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:58.927] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:15:0) after 1m0s
[08:41:58.947] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:15:0)
[08:41:58.947] Timer tick, asking Buildbucket for the build status
[08:41:59.051] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:59.051] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:16:0) after 1m0s
[08:42:59.084] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:16:0)
[08:42:59.084] Timer tick, asking Buildbucket for the build status
[08:42:59.193] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:59.193] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:17:0) after 1m0s
[08:43:59.308] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:17:0)
[08:43:59.308] Timer tick, asking Buildbucket for the build status
[08:43:59.391] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:59.391] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:18:0) after 1m0s
[08:44:59.421] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:18:0)
[08:44:59.421] Timer tick, asking Buildbucket for the build status
[08:44:59.564] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:59.564] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:19:0) after 1m0s
[08:45:59.582] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:19:0)
[08:45:59.582] Timer tick, asking Buildbucket for the build status
[08:45:59.640] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:59.640] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:20:0) after 1m0s
[08:46:59.764] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:20:0)
[08:46:59.764] Timer tick, asking Buildbucket for the build status
[08:46:59.836] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:59.836] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:21:0) after 1m0s
[08:47:59.856] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:21:0)
[08:47:59.856] Timer tick, asking Buildbucket for the build status
[08:47:59.929] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:59.929] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:22:0) after 1m0s
[08:48:59.951] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:22:0)
[08:48:59.951] Timer tick, asking Buildbucket for the build status
[08:49:00.117] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:00.117] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:23:0) after 1m0s
[08:50:00.135] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:23:0)
[08:50:00.135] Timer tick, asking Buildbucket for the build status
[08:50:00.244] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:00.244] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:24:0) after 1m0s
[08:51:00.263] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:24:0)
[08:51:00.263] Timer tick, asking Buildbucket for the build status
[08:51:00.325] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:00.325] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:25:0) after 1m0s
[08:52:00.395] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:25:0)
[08:52:00.395] Timer tick, asking Buildbucket for the build status
[08:52:00.537] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:00.537] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:26:0) after 1m0s
[08:53:00.556] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:26:0)
[08:53:00.556] Timer tick, asking Buildbucket for the build status
[08:53:00.614] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:00.614] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:27:0) after 1m0s
[08:54:00.637] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:27:0)
[08:54:00.637] Timer tick, asking Buildbucket for the build status
[08:54:00.762] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:00.762] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:28:0) after 1m0s
[08:55:00.784] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:28:0)
[08:55:00.784] Timer tick, asking Buildbucket for the build status
[08:55:00.906] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:00.906] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:29:0) after 1m0s
[08:56:00.933] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:29:0)
[08:56:00.933] Timer tick, asking Buildbucket for the build status
[08:56:01.209] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:01.209] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:30:0) after 1m0s
[08:57:01.229] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:30:0)
[08:57:01.229] Timer tick, asking Buildbucket for the build status
[08:57:01.486] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:01.486] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:31:0) after 1m0s
[08:58:01.507] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:31:0)
[08:58:01.507] Timer tick, asking Buildbucket for the build status
[08:58:01.603] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:01.603] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:32:0) after 1m0s
[08:59:01.649] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:32:0)
[08:59:01.649] Timer tick, asking Buildbucket for the build status
[08:59:01.737] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:01.737] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:33:0) after 1m0s
[09:00:01.765] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:33:0)
[09:00:01.765] Timer tick, asking Buildbucket for the build status
[09:00:01.814] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:01.814] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:34:0) after 1m0s
[09:01:01.836] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:34:0)
[09:01:01.836] Timer tick, asking Buildbucket for the build status
[09:01:01.882] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:01.882] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:35:0) after 1m0s
[09:02:01.908] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:35:0)
[09:02:01.908] Timer tick, asking Buildbucket for the build status
[09:02:02.004] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:02.004] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:36:0) after 1m0s
[09:03:02.092] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:36:0)
[09:03:02.092] Timer tick, asking Buildbucket for the build status
[09:03:02.171] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:02.171] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:37:0) after 1m0s
[09:04:02.200] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:37:0)
[09:04:02.200] Timer tick, asking Buildbucket for the build status
[09:04:02.302] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:02.302] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:38:0) after 1m0s
[09:05:02.403] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:38:0)
[09:05:02.403] Timer tick, asking Buildbucket for the build status
[09:05:02.486] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:02.486] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:39:0) after 1m0s
[09:06:02.508] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:39:0)
[09:06:02.508] Timer tick, asking Buildbucket for the build status
[09:06:02.627] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:02.627] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:40:0) after 1m0s
[09:07:02.705] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:40:0)
[09:07:02.705] Timer tick, asking Buildbucket for the build status
[09:07:02.805] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:02.805] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:41:0) after 1m0s
[09:08:02.826] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:41:0)
[09:08:02.826] Timer tick, asking Buildbucket for the build status
[09:08:02.882] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:02.882] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:42:0) after 1m0s
[09:09:02.905] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:42:0)
[09:09:02.905] Timer tick, asking Buildbucket for the build status
[09:09:03.231] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:03.231] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:43:0) after 1m0s
[09:10:03.269] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:43:0)
[09:10:03.269] Timer tick, asking Buildbucket for the build status
[09:10:03.339] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:03.339] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:44:0) after 1m0s
[09:11:03.359] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:44:0)
[09:11:03.359] Timer tick, asking Buildbucket for the build status
[09:11:03.451] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:03.451] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:45:0) after 1m0s
[09:12:03.469] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:45:0)
[09:12:03.469] Timer tick, asking Buildbucket for the build status
[09:12:03.544] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:03.544] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:46:0) after 1m0s
[09:13:03.589] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:46:0)
[09:13:03.589] Timer tick, asking Buildbucket for the build status
[09:13:03.642] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:03.642] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:47:0) after 1m0s
[09:14:03.737] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:47:0)
[09:14:03.737] Timer tick, asking Buildbucket for the build status
[09:14:03.833] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:03.833] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:48:0) after 1m0s
[09:15:03.860] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:48:0)
[09:15:03.860] Timer tick, asking Buildbucket for the build status
[09:15:03.932] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:03.932] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:49:0) after 1m0s
[09:16:03.953] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:49:0)
[09:16:03.953] Timer tick, asking Buildbucket for the build status
[09:16:04.022] Build 8929805846769235008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:04.022] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9095235909036264480:50:0) after 1m0s
[09:16:19.471] Received PubSub notification, asking Buildbucket for the build status
[09:16:19.506] Build 8929805846769235008: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:16:19.506] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1542273378392341",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1542270535649360",
"failure_reason": "BUILD_FAILURE",
"id": "8929805846769235008",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ffa5838fee064e345d02b27675ba4d676e78544c\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure Second build\"}, \"swarming\": {\"bot_dimensions\": {\"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Deterministic Linux\"], \"kvm\": [\"0\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Ivy_Bridge_GCE\"], \"server_version\": [\"3844-124e68f\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"swarm591-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_78902ab2d70d1593a97658cf8910c0e117cf7950e257117b8c551c6fe1cfb403_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"99673869a3cd8731d924bd32fa486feebfdc6c4f\", \"got_swarming_client_revision\": \"f78187ab77127de42555afe0ad410bebde6ac6a5\", \"got_revision\": \"ffa5838fee064e345d02b27675ba4d676e78544c\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"597e1587b4bfc7bf89895c7c879ae58b649da3c5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25646}\", \"$build/goma\": {\"jobs\": 500}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#608294}\", \"branch\": \"refs/heads/master\", \"revision\": \"ffa5838fee064e345d02b27675ba4d676e78544c\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Deterministic Linux\", \"got_webrtc_revision\": \"c25d2344574830a3f73a934ba5b4662e0475cbbb\", \"mastername\": \"chromium.linux\", \"got_angle_revision\": \"f3acb8c133fa825d5f25159eaf4ebf303bfef7a6\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1542270535649360, \"tags\": [\"builder:Deterministic Linux\", \"buildset:commit/git/ffa5838fee064e345d02b27675ba4d676e78544c\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ffa5838fee064e345d02b27675ba4d676e78544c\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9095235909036264480\", \"scheduler_job_id:chromium/Deterministic Linux\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8929805846769235008\"}}, \"got_v8_revision\": \"b0e3bc7d7fd4655b488fd916eea1a3601ab3bede\", \"got_v8_revision_cp\": \"refs/heads/7.2.338@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 17798, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"2bbfaa9e353659e0306383b1ce53c7878ecc1bc7\", \"gpu_perftests\": \"a8bbacac7855fd6f7448b4fde8ac972e450d70c4\", \"telemetry_unittests\": \"13f2925ba595f91de950629ea1c467e96e6e390f\", \"load_library_perf_tests\": \"dbdab6f4c8bd1eeda7e557a1eeef5d8ec77b7632\", \"devtools_closure_compile\": \"8f2c11de6d380efa79d5f9623b2f67007cf69989\", \"chromedriver_py_tests\": \"14aa332729b7a55635f6d226ba7a78fafa78f1c6\", \"mojo_core_unittests\": \"903a0a1f54d992ff1375542b15d066e321768d72\", \"leveldb_service_unittests\": \"2e593a9c8a84b43eeb1720362eb4578e32331ee5\", \"storage_unittests\": \"f9e64718268a0acdb0bef69ff2733770c239c109\", \"jingle_unittests\": \"a067cb972a09cd3792cbb6d9e529d142f739f280\", \"angle_unittests\": \"4ab534658dbd513e123fc451fb11d060ff40b0cc\", \"cast_unittests\": \"47bd7dfe87ad72832042aaab8d23cf9f7ca9bbcd\", \"cc_unittests\": \"01020ae18a6bf5ea22f65e1f3038338c5e922915\", \"blink_common_unittests\": \"be9612a029f0b6fb3b154079f907e1026820164b\", \"crashpad_tests\": \"5e3b2cc180424a5d7bc807a9497a41c244fddf8b\", \"telemetry_perf_tests_without_chrome\": \"28f6cac68242d3ab175241967b8c48041851c48e\", \"metrics_python_tests\": \"e6d38ad9f88b3c09dbca34b5cf7aed24b945a71d\", \"message_center_unittests\": \"0ba59f34d010e9587db0436e144a6c015b0a3c8d\", \"ppapi_unittests\": \"a73cb745c640fd8396a93d1a8041f8f1a4a338fc\", \"extensions_unittests\": \"62c03f9f810896b574e7ab7a5a14859592501ea6\", \"gl_unittests_ozone\": \"856c19aab05f9fe220828131048ba26d5d2736ce\", \"app_shell_unittests\": \"b07c2cad3084c92b5f7c01a26a905485de4f113c\", \"boringssl_ssl_tests\": \"a7c0a70225963c59190b34581011171688f014f9\", \"chrome_app_unittests\": \"4a3adef8f5ed551883ae3ea781ee0f903f3fbf0d\", \"gl_unittests\": \"feea3200fa7110cc9edbfd68455a6e9f3ef185de\", \"gcm_unit_tests\": \"917fd3cc585b448fe5acd8ef5d2057f1bc3c5825\", \"views_unittests\": \"82a883d24abc5ac4afbdf4db7942d41018488d31\", \"content_shell_crash_test\": \"1ff29f93229544b869ade095fc877c570d3db214\", \"dawn_end2end_tests\": \"4c8cc67f5e32ecfa329251ff63abb603bc297a0f\", \"ui_base_unittests\": \"b279b3bc267523635c69781d464973e066f6fbb3\", \"gles2_conform_test\": \"8fbd9ddbd7815075aeecbf50e501c7ecabc4d28e\", \"cronet_unittests\": \"34cc917b01ea473b6506a7e89b7cbb211fcec089\", \"performance_test_suite\": \"fb36431e3343796d702531e7249826eccc629c3f\", \"wm_unittests\": \"9dec6a1b1f62ad643af60731e6e4da5f8d2c863f\", \"boringssl_crypto_tests\": \"0fa1ec5b989dfaac6bca29aa109d78fb18c5bb67\", \"gin_unittests\": \"6a76f7494bd86b88b71526e8242181fb7162c03f\", \"performance_browser_tests\": \"65db906474b084d33b172b73a4d8d6e2f033fcf7\", \"extensions_browsertests\": \"c656effd42865d2a4c1b1e5eb35622fadc50e107\", \"webkit_python_tests\": \"879a5702042801ff1c22a0cfd575256e088dc40c\", \"headless_unittests\": \"4d0957bdc51a122343517d7a1434e0ee7e488abb\", \"webkit_layout_tests\": \"e20e1ed8c9fcb428f586404ca8f99ca809464926\", \"breakpad_unittests\": \"55630f8bb0e2f54bc2890b0671f226f4a49b8630\", \"vr_pixeltests\": \"7899718d111e5bc6d90974281f363016f2080faa\", \"webkit_unit_tests\": \"032fc51106fffb80a65b336fdb6232d4723b6a1e\", \"content_unittests\": \"fa4c895539cd0bd500446500a18ac0bc5cd0bdc6\", \"performance_webview_test_suite\": \"a444832e813412e6fa9042a8e471adb25692637d\", \"wtf_unittests\": \"452eb4b5730ef3cfaaa2acc60844a6508940f2a7\", \"webkit_layout_tests_exparchive\": \"d555c0781ab6c48a81accaff161fe526457b1850\", \"traffic_annotation_auditor_unittests\": \"ddf4ae549cf79a713ec81bc965a8b0cb74e7e78c\", \"libjingle_xmpp_unittests\": \"9814822de8c6e778d6234c50bec7543657787726\", \"remoting_unittests\": \"bfd4e6101d1c7f55ab3a9e51475ef91724ca3fd8\", \"vr_common_perftests\": \"98bb81c677104f3609703a81fcfb017eedbed58b\", \"filesystem_service_unittests\": \"d90fc71f7bcfc472ba437b51901a54a8a5ce9fa1\", \"gl_tests\": \"1a25e318d28e8ad07f4a87d63a828d1c072f0ae3\", \"base_perftests\": \"20ec73befe9e45dc21f876273336879c218fc771\", \"headless_browsertests\": \"4a613daa75d457916c37fad37cc749dc02075205\", \"capture_unittests\": \"07366e4431162b67ecc1d5acff05b66323b6c53b\", \"telemetry_gpu_unittests\": \"83d8e8b5e9f31e892368ec8d28ad65c62ac81643\", \"command_buffer_perftests\": \"543537faba48067c9ddea91a4686c9f7b9cb5320\", \"media_unittests\": \"0b0e062467b4a2f20ca4779a09440f3173430309\", \"gfx_unittests\": \"f8d8f38dc2fcaa463b41f8bf6c2b0af68c34e07d\", \"chromedriver_unittests\": \"76024634bd387aaa0d58a094ff32d3be73df62b4\", \"views_mus_interactive_ui_tests\": \"57861bc9a669f93c68f707ac1bc94ddf2534fe3a\", \"sandbox_linux_unittests\": \"c225e26a84ca8c8f41730256dc46da67de9dbe9a\", \"media_service_unittests\": \"329a22bc54bd1a8ad5b30177bb6a86eda7105f17\", \"compositor_unittests\": \"683f02ef365f76aba37ccf42210753bbfc5df0c3\", \"latency_unittests\": \"7756beaad1cd6c76f6fbcd52bf432114acf2975d\", \"swiftshader_unittests\": \"647bfa35499a92d0266f85bb19e17eb1d0f11e35\", \"telemetry_perf_unittests\": \"226fb0c71a35ce1d13b65126efe34fdbb365ae4e\", \"media_blink_unittests\": \"c103c3a3dc8b52b7c68cb39245e410b3e465042b\", \"views_mus_unittests\": \"28a99e636bc4356f43cfefdfdfd1e504e6c64393\", \"components_unittests\": \"3f068faeaa53c07ea2d6792495900dbc7594822a\", \"snapshot_unittests\": \"3e0451d6ce29c15ae04e7f735c9c2f559ad4b286\", \"accessibility_unittests\": \"058ed38f78b6d8803113eb422948e36c24838173\", \"net_perftests\": \"57ea4fa50306544a85d500aba7cbaa21a0d2a76b\", \"aura_unittests\": \"004a9e43bb0de4a7d721ab9f4612c0aaf01fbbda\", \"services_unittests\": \"e80afaa1bdd8be006a913d9ee3864abd43882486\", \"components_perftests\": \"bd3979951bf9f571f59359ca20bb969ab8fcce33\", \"events_unittests\": \"cf7bb3586e02b103fe3c4873853a91592b3adefa\", \"tracing_perftests\": \"4c53377102d0bb2c9860a498bcf10ac4e39b155a\", \"chromedriver_replay_unittests\": \"80dcdd65faa3e5c2519bd1b938064835370c9584\", \"ui_touch_selection_unittests\": \"4aa1a1aeca60afe40a05b609ee991f0f71c834b3\", \"cacheinvalidation_unittests\": \"473a967e02ef1521b599a64dc86e9923dc61e1d9\", \"viz_unittests\": \"5ecb40ac0ecb24983d7f5f0e74b6b6f2525cbe47\", \"browser_tests\": \"a011f68cf109c704a864a0e5f0790f85fe04d5c6\", \"net_unittests\": \"c77ee0c7bb694a7121a91f1b1be34bc59971cd68\", \"pdf_unittests\": \"262266a140508df93fe1427258ce4068f42cbbe4\", \"dbus_unittests\": \"60d138a27140e59562d483f89dce3c4d1e3953d8\", \"vr_common_unittests\": \"d99ef86a01384d435e54f0ff7b24aab504b8a4bf\", \"gpu_unittests\": \"b0ed9848a0e0f7818e3e43b0daa107f0aabcd7fe\", \"native_theme_unittests\": \"03a2d79955aaa3fb19dd8d468e6fe7e92c6a8e1d\", \"interactive_ui_tests\": \"f1a3b58a26df4a6dd314467fac77300dce6c44e1\", \"mojo_unittests\": \"83477b561e041eda75b3f6fc45921004c7208bf8\", \"url_unittests\": \"f7ce225f69e759a586f2eca929b6b4e3b9784d12\", \"media_perftests\": \"c1c2fc58da79027a4f0e0631b0c9374a28d05a2c\", \"cronet_tests\": \"2f411b5d82fe2d2fd24bb6f92bb99117829f2781\", \"google_apis_unittests\": \"f9abefc2ffe223e1d26773a7ba09f19b249aea84\", \"telemetry_gpu_integration_test\": \"d0b52f79975bfa8661b2d7feaeee920360e89209\", \"blink_heap_unittests\": \"d5114762702e3fa732253a1390e2fd9260f061ff\", \"midi_unittests\": \"51df8c82e23c4b2f029d75b651e94772b77668e5\", \"base_unittests\": \"45392b8cd9450e9a09f4224ab4fb300c15b9b91e\", \"sync_integration_tests\": \"02b721dd3cc7a811ab078876d1bc13fd31bcd1b9\", \"sql_unittests\": \"526740ba89425f098c8bfd24b34a7d05d50c60e6\", \"nacl_helper_nonsfi_unittests\": \"b9e23937e2c55695499aa54dc8ba9949e2c3dff8\", \"blink_fuzzer_unittests\": \"d7291464ad060d07791202196e4f5207bdb31829\", \"zucchini_unittests\": \"b971dc7b7d7370a6eb62c7003158b3c44ce5f97f\", \"ipc_tests\": \"fecff88e5bc97d06104262fab81eeb79b4713bd3\", \"courgette_unittests\": \"3964579acf7f4641cefb95a3b97ee3b01f578ad7\", \"devtools_eslint\": \"8e34bbf9591b8233738aa8f94f8de92fabaaf60e\", \"blink_platform_unittests\": \"5b15c9c8dad053d5607826569457e3a386653422\", \"components_browsertests\": \"ab14c8145c29c4189f14650a63e2dbd134cb3602\", \"views_perftests\": \"ff1b7aba3423337ce50982c53e31247a2c241826\", \"angle_perftests\": \"f7cf1938e5b5c02e494883273eb61028536bfae9\", \"display_unittests\": \"f4861359fdbbd1c04f2a44f2a47a561015afd308\", \"service_manager_unittests\": \"0bdb37f91eecb985054e35e932e4098064868d7e\", \"angle_end2end_tests\": \"24637fa4b7b61161b2d3e478029168742342a36f\", \"skia_unittests\": \"e2d076b59b5fc24d0e2eb9f0171e9c453b9a9411\", \"angle_white_box_tests\": \"ebb73cff0e8e182990b9123c0fc4b47e50a5a347\", \"crypto_unittests\": \"777c5d07cca30b3d4ac9339ebb0f43e21b7c2fef\", \"nacl_loader_unittests\": \"8146df544ccf37deae749ec343f90ea25b1d6b19\", \"unit_tests\": \"d27490123931bc6c24093d4f93d3f20039edc55f\", \"content_browsertests\": \"9a897b109d85302b083facba38a20363da77ee3d\", \"printing_unittests\": \"ba2a05d3ab444c86ea5d5ed465daacacf73b6bd5\", \"device_unittests\": \"61d4017de62ab4534d7198f0c86b42b6a369f74e\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm591-c4\", \"got_buildtools_revision\": \"13a00f110ef910a25763346d6538b60f12845656\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1542270537373132",
"status": "COMPLETED",
"status_changed_ts": "1542273378898719",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/17798",
"builder:Deterministic Linux",
"buildset:commit/git/ffa5838fee064e345d02b27675ba4d676e78544c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ffa5838fee064e345d02b27675ba4d676e78544c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9095235909036264480",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_dimension:builder:Deterministic Linux",
"swarming_dimension:caches:builder_78902ab2d70d1593a97658cf8910c0e117cf7950e257117b8c551c6fe1cfb403_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Deterministic Linux/17798",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8929805846769235008",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:1fc149ed7cdb2549b1387665915ec044aea4e328",
"swarming_tag:builder:Deterministic Linux",
"swarming_tag:buildset:commit/git/ffa5838fee064e345d02b27675ba4d676e78544c",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ffa5838fee064e345d02b27675ba4d676e78544c",
"swarming_tag:caches:builder_78902ab2d70d1593a97658cf8910c0e117cf7950e257117b8c551c6fe1cfb403_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8929805846769235008/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9095235909036264480",
"swarming_tag:scheduler_job_id:chromium/Deterministic Linux",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:c8a918d09d83cb6088cdeb21510070a640e20ed3",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:412f4e5489970a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1542273378900689",
"url": "https://ci.chromium.org/b/8929805846769235008",
"utcnow_ts": "1542273379495034"
}
}
[09:16:19.506] Invocation finished in 47m25.402714776s with status FAILED