[16:20:42.115] New invocation is queued and will start shortly
[16:20:43.191] Starting the invocation (attempt 1)
[16:20:43.276] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:20:43.276] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:20:43.276] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054890575904458992",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"501061ccbb7b9fab6dad77babecf12e4ae9f7890\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054890575904458992",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/501061ccbb7b9fab6dad77babecf12e4ae9f7890",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/501061ccbb7b9fab6dad77babecf12e4ae9f7890",
"gitiles_ref:refs/heads/master"
]
}
[16:20:45.186] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580746843961032",
"id": "8889460513305517664",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"501061ccbb7b9fab6dad77babecf12e4ae9f7890\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580746844825609",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168183",
"builder:Linux Builder (dbg)",
"buildset:commit/git/501061ccbb7b9fab6dad77babecf12e4ae9f7890",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/501061ccbb7b9fab6dad77babecf12e4ae9f7890",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054890575904458992",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8889460513305517664/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1580746844825927",
"url": "https://ci.chromium.org/b/8889460513305517664",
"utcnow_ts": "1580746845173052"
}
}
[16:20:45.186] Task URL: https://ci.chromium.org/b/8889460513305517664
[16:20:45.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:2:0) after 1m0s
[16:20:48.536] Received PubSub notification, asking Buildbucket for the build status
[16:20:49.105] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:45.227] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:2:0)
[16:21:45.227] Timer tick, asking Buildbucket for the build status
[16:21:45.402] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:45.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:4:0) after 1m0s
[16:22:45.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:4:0)
[16:22:45.435] Timer tick, asking Buildbucket for the build status
[16:22:45.603] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:45.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:5:0) after 1m0s
[16:23:45.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:5:0)
[16:23:45.943] Timer tick, asking Buildbucket for the build status
[16:23:46.542] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:46.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:6:0) after 1m0s
[16:24:46.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:6:0)
[16:24:46.562] Timer tick, asking Buildbucket for the build status
[16:24:46.960] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:46.960] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:7:0) after 1m0s
[16:25:46.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:7:0)
[16:25:46.955] Timer tick, asking Buildbucket for the build status
[16:25:47.193] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:47.193] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:8:0) after 1m0s
[16:26:47.345] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:8:0)
[16:26:47.345] Timer tick, asking Buildbucket for the build status
[16:26:47.778] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:47.778] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:9:0) after 1m0s
[16:27:47.870] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:9:0)
[16:27:47.870] Timer tick, asking Buildbucket for the build status
[16:27:48.139] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:48.139] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:10:0) after 1m0s
[16:28:48.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:10:0)
[16:28:48.205] Timer tick, asking Buildbucket for the build status
[16:28:48.602] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:48.602] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:11:0) after 1m0s
[16:29:48.666] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:11:0)
[16:29:48.666] Timer tick, asking Buildbucket for the build status
[16:29:48.858] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:48.858] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:12:0) after 1m0s
[16:30:48.888] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:12:0)
[16:30:48.888] Timer tick, asking Buildbucket for the build status
[16:30:51.310] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:51.310] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:13:0) after 1m0s
[16:31:51.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:13:0)
[16:31:51.286] Timer tick, asking Buildbucket for the build status
[16:31:51.447] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:51.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:14:0) after 1m0s
[16:32:51.474] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:14:0)
[16:32:51.474] Timer tick, asking Buildbucket for the build status
[16:32:51.759] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:51.759] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:15:0) after 1m0s
[16:33:51.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:15:0)
[16:33:51.866] Timer tick, asking Buildbucket for the build status
[16:33:52.109] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:52.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:16:0) after 1m0s
[16:34:52.189] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:16:0)
[16:34:52.189] Timer tick, asking Buildbucket for the build status
[16:34:52.885] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:52.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:17:0) after 1m0s
[16:35:52.957] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:17:0)
[16:35:52.957] Timer tick, asking Buildbucket for the build status
[16:35:53.125] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:53.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:18:0) after 1m0s
[16:36:53.195] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:18:0)
[16:36:53.195] Timer tick, asking Buildbucket for the build status
[16:36:53.564] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:53.564] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:19:0) after 1m0s
[16:37:53.564] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:19:0)
[16:37:53.564] Timer tick, asking Buildbucket for the build status
[16:37:53.699] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:53.699] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:20:0) after 1m0s
[16:38:53.724] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:20:0)
[16:38:53.724] Timer tick, asking Buildbucket for the build status
[16:38:53.975] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:53.975] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:21:0) after 1m0s
[16:39:53.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:21:0)
[16:39:53.994] Timer tick, asking Buildbucket for the build status
[16:39:54.172] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:54.172] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:22:0) after 1m0s
[16:40:54.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:22:0)
[16:40:54.259] Timer tick, asking Buildbucket for the build status
[16:40:54.842] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:54.842] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:23:0) after 1m0s
[16:41:54.873] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:23:0)
[16:41:54.873] Timer tick, asking Buildbucket for the build status
[16:41:55.654] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:55.654] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:24:0) after 1m0s
[16:42:55.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:24:0)
[16:42:55.656] Timer tick, asking Buildbucket for the build status
[16:42:55.837] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:55.837] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:25:0) after 1m0s
[16:43:55.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:25:0)
[16:43:55.844] Timer tick, asking Buildbucket for the build status
[16:43:56.041] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:56.041] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:26:0) after 1m0s
[16:44:56.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:26:0)
[16:44:56.064] Timer tick, asking Buildbucket for the build status
[16:44:56.814] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:56.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:27:0) after 1m0s
[16:45:56.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:27:0)
[16:45:56.949] Timer tick, asking Buildbucket for the build status
[16:45:57.165] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:57.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:28:0) after 1m0s
[16:46:57.249] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:28:0)
[16:46:57.249] Timer tick, asking Buildbucket for the build status
[16:46:57.902] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:57.902] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:29:0) after 1m0s
[16:47:58.016] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:29:0)
[16:47:58.016] Timer tick, asking Buildbucket for the build status
[16:47:58.322] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:58.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:30:0) after 1m0s
[16:48:58.360] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:30:0)
[16:48:58.360] Timer tick, asking Buildbucket for the build status
[16:48:58.482] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:58.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:31:0) after 1m0s
[16:49:58.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:31:0)
[16:49:58.593] Timer tick, asking Buildbucket for the build status
[16:49:58.973] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:58.973] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:32:0) after 1m0s
[16:50:58.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:32:0)
[16:50:58.989] Timer tick, asking Buildbucket for the build status
[16:50:59.375] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:59.375] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:33:0) after 1m0s
[16:51:59.460] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:33:0)
[16:51:59.460] Timer tick, asking Buildbucket for the build status
[16:51:59.589] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:59.589] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:34:0) after 1m0s
[16:52:59.674] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:34:0)
[16:52:59.674] Timer tick, asking Buildbucket for the build status
[16:53:00.081] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:00.081] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:35:0) after 1m0s
[16:54:00.179] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:35:0)
[16:54:00.179] Timer tick, asking Buildbucket for the build status
[16:54:00.491] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:00.491] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:36:0) after 1m0s
[16:55:00.609] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:36:0)
[16:55:00.609] Timer tick, asking Buildbucket for the build status
[16:55:00.741] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:00.741] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:37:0) after 1m0s
[16:56:00.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:37:0)
[16:56:00.816] Timer tick, asking Buildbucket for the build status
[16:56:01.589] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:01.589] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:38:0) after 1m0s
[16:57:01.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:38:0)
[16:57:01.691] Timer tick, asking Buildbucket for the build status
[16:57:01.952] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:01.952] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:39:0) after 1m0s
[16:58:02.061] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:39:0)
[16:58:02.061] Timer tick, asking Buildbucket for the build status
[16:58:02.262] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:02.262] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:40:0) after 1m0s
[16:59:02.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:40:0)
[16:59:02.289] Timer tick, asking Buildbucket for the build status
[16:59:02.517] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:02.517] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:41:0) after 1m0s
[17:00:02.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:41:0)
[17:00:02.606] Timer tick, asking Buildbucket for the build status
[17:00:02.755] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:02.755] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:42:0) after 1m0s
[17:01:02.873] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:42:0)
[17:01:02.873] Timer tick, asking Buildbucket for the build status
[17:01:03.072] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:03.072] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:43:0) after 1m0s
[17:02:03.130] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:43:0)
[17:02:03.130] Timer tick, asking Buildbucket for the build status
[17:02:03.409] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:03.409] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:44:0) after 1m0s
[17:03:03.501] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:44:0)
[17:03:03.501] Timer tick, asking Buildbucket for the build status
[17:03:04.706] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:04.706] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:45:0) after 1m0s
[17:04:04.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:45:0)
[17:04:04.866] Timer tick, asking Buildbucket for the build status
[17:04:05.135] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:05.135] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:46:0) after 1m0s
[17:05:05.190] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:46:0)
[17:05:05.190] Timer tick, asking Buildbucket for the build status
[17:05:05.388] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:05.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:47:0) after 1m0s
[17:06:05.404] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:47:0)
[17:06:05.404] Timer tick, asking Buildbucket for the build status
[17:06:05.594] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:05.594] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:48:0) after 1m0s
[17:07:05.604] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:48:0)
[17:07:05.604] Timer tick, asking Buildbucket for the build status
[17:07:05.840] Build 8889460513305517664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:05.840] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054890575904458992:49:0) after 1m0s
[17:07:10.009] Received PubSub notification, asking Buildbucket for the build status
[17:07:10.197] Build 8889460513305517664: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:07:10.197] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580749628762043",
"created_by": "project:chromium",
"created_ts": "1580746843961032",
"id": "8889460513305517664",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"501061ccbb7b9fab6dad77babecf12e4ae9f7890\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-96-nza5\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168183, \"got_angle_revision\": \"326c900efd2810e3678559bbe13b4a40befcdf43\", \"got_dawn_revision\": \"43a0a815b8146d7ce596e281267ccd6565e9619e\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"501061ccbb7b9fab6dad77babecf12e4ae9f7890\", \"got_revision_cp\": \"refs/heads/master@{#737792}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"41a7302e9c62c207e5ac5bcce40f16a1e418c42b\", \"got_v8_revision\": \"352de2b8cce0e8a3a242257feecb5acffd3fe428\", \"got_v8_revision_cp\": \"refs/heads/8.2.2@{#1}\", \"got_webrtc_revision\": \"f2be3eff26fd39848c8504016d001421248cec1b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30446}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"501061ccbb7b9fab6dad77babecf12e4ae9f7890\", \"swarm_hashes_refs/heads/master(at){#737792}_without_patch\": {\"accessibility_unittests\": \"04b7ee3ece4e4a8cb9440ac9158de89cb0812231\", \"angle_unittests\": \"9a0d9cbdd072db3ac858cee67a0aca97daeff7a8\", \"app_shell_unittests\": \"f1d93fbd4a3ebaecf706a8431213c022cc9fa249\", \"aura_unittests\": \"84e6d66ab96115ffd399af7517fa4ff0da7a7263\", \"base_unittests\": \"1323b7a7d6a197b64314810eb635df2d21e11eea\", \"base_util_unittests\": \"9022bfa98f98bf7300810839fd3ec0a81bd2ac05\", \"blink_common_unittests\": \"4f7e5d952e18873542b5beff38a74ec4f7356648\", \"blink_fuzzer_unittests\": \"1a39bcf4aeaca2bdad3c97887b71fb7da20c7b5d\", \"blink_heap_unittests\": \"c1d4e1853adfcd264b270cc7b67b040b5f91a6ac\", \"blink_platform_unittests\": \"8e06851c903c440717fd82a02bf519d4dd43b6be\", \"blink_python_tests\": \"2af8109c8dad73f3daa8703a45fd8243befd6ed4\", \"blink_unittests\": \"60963ad8b57a9c958f6495b137980c38f2dd6d7b\", \"blink_web_tests\": \"4c19af7dbb764a93a2006e97e8c5cc091e215dbf\", \"boringssl_crypto_tests\": \"0d9dd90676b9539c4dc1801aac1ed80ab978d819\", \"boringssl_ssl_tests\": \"5df2b3e0fdfe24ac749053ef378c004f986baa25\", \"browser_tests\": \"a224e1903e8189573f1fcf744633233784b72689\", \"cacheinvalidation_unittests\": \"8d0b5f02126ffa6f44160bd54e46ea6d602eada8\", \"capture_unittests\": \"16e7b6deb693f9981a7d39a1c7a4541758cc301d\", \"cast_unittests\": \"71fb40ddb157da394a47fe12fa03e962514442bf\", \"cc_unittests\": \"d67e8972fd45663335dcebce35fccd08c0a82ced\", \"chrome_app_unittests\": \"b93655c3c2d66a41e172b9e42269b85f99a45dcc\", \"chromedriver_unittests\": \"8bbd7133c3fa388c58419ba33e38aed3c8dd5ece\", \"components_browsertests\": \"49b0804c99d16227f3d105625c2db81138df806f\", \"components_unittests\": \"8adc09960b670998bd50b07c709fab3d51afcdbd\", \"compositor_unittests\": \"1c731f774a0c5f3ad215477937e7d60e36e1d558\", \"content_browsertests\": \"ff32a1d4ce21805cce6ef539bdd4a4f1b6247df8\", \"content_shell_crash_test\": \"6e11ec5e115d70896bcb55b75c6a9991c71f7459\", \"content_unittests\": \"0ee6f2df54bef025da53ae354ba619ae462c23b9\", \"crashpad_tests\": \"fe0c94c0bd26f75d965362aebd39b702c2543367\", \"cronet_tests\": \"fbf63491fff71d063d92f299d48038814a00fe72\", \"cronet_unittests\": \"e99cd36280eabc7ce46b8648d8e0b5963368f454\", \"crypto_unittests\": \"5b58dcbb57ca90cc5f6c6a510a6b35390d90aa47\", \"dbus_unittests\": \"06a4761005fb2fef3af2c38e8b37d3a8ec654ff5\", \"device_unittests\": \"c39eb3af41af9372e5b12a126a30fd10927d32f2\", \"display_unittests\": \"c31d4d3fd257898f8b138fbf51544a13d63075cb\", \"events_unittests\": \"0f809c2d5c1196a8832593ffedd4704e5107440a\", \"extensions_browsertests\": \"4e3e5dc7ae2021076089c4028bfe7ef0f901ca1d\", \"extensions_unittests\": \"2921446aacb413ad466327786911dc63749bb575\", \"filesystem_service_unittests\": \"85dbc7d89e141de871fad932a32d7326553486a7\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"47594b09c838b37e336e506351ceb14df9db99f1\", \"gfx_unittests\": \"8e02305c8f16077d9e1a44cd8684cdef4c8d0ac9\", \"gin_unittests\": \"ba7bb4f10b86ef367b2d027077c79400239e30a1\", \"google_apis_unittests\": \"40fec9303efbcbff8ff7963317724b1bff21d580\", \"gpu_unittests\": \"2afc0bf16bb224636fc6b9923e3e0e254e498caa\", \"grit_python_unittests\": \"a906de1211aa1e6d0b6deb8a9a162d6998fb6336\", \"gwp_asan_unittests\": \"11fe733cf7f1e55378ca969b1c0dd94988e7e7ec\", \"headless_browsertests\": \"106055e4c8028f0d6b9afa6da3c35fc4d6df91f5\", \"headless_unittests\": \"85bf4b48c3803a4aaf747b95ea1f5d922514586e\", \"interactive_ui_tests\": \"51474a0c3f95185a68e815e4191e6a660b4ecb15\", \"ipc_tests\": \"261db51c5cce4445aeb6269b0e9aebb6b8354f0e\", \"jingle_unittests\": \"e626e26388c003b619bb076371c593f3f69c0f28\", \"latency_unittests\": \"76318d2265ac3a390b24151130cab1023c2af0b9\", \"libjingle_xmpp_unittests\": \"7e441979f060642182e4aeb70b7d40004029ab04\", \"media_blink_unittests\": \"c6d1088775abf077841d263444ce97f59e432164\", \"media_service_unittests\": \"bb609b83348275c9bc6cd685c4d1756f77b77cf7\", \"media_unittests\": \"430089826c9126ddb32df9183f4e29a311897b17\", \"message_center_unittests\": \"de06fe94d64b9b4389b20c0aa3dd68f3a62b9ddb\", \"metrics_python_tests\": \"20f9e3a4a2c761867c74314a680ecf1ca008f4b1\", \"midi_unittests\": \"cc5c8e8080bc0d2808f85e856654a6c77da02e8b\", \"mojo_core_unittests\": \"6be1b0f140bb4788ccc2009dde4fb7ded90b2755\", \"mojo_unittests\": \"3873dd0904da45f56e1dceeb76afc3d211fbe43d\", \"nacl_helper_nonsfi_unittests\": \"86225d9a92754e3a64befa7ebec3562a6853aab1\", \"nacl_loader_unittests\": \"20c57c00264e98321dd89e9586d5b6ce516b30a9\", \"native_theme_unittests\": \"48251a4005989ad6ec154d41e95e69a1f3b32885\", \"net_unittests\": \"0918391056c8f3878530a8e8dced2d719657478d\", \"pdf_unittests\": \"d8924cd65472ccf9465582924ddb391b9714f3c2\", \"perfetto_unittests\": \"1d4deff11ffde23f5f9121c424cae97756589e94\", \"ppapi_unittests\": \"132a6edca6750766823cc543859c7128b7c921a7\", \"printing_unittests\": \"18e86f87ba2216322a8b78f0824941d5d3805bfe\", \"remoting_unittests\": \"c8f04e0480672cb2046b54d052761dd8b128e412\", \"sandbox_linux_unittests\": \"b64d7cad44dbf7819ab806ba9ca1f924a2098aad\", \"service_manager_unittests\": \"1820521d3faf87c646b9688e0a9eb5ed2c0508de\", \"services_unittests\": \"5326ea5f369ce56e00e0e38cd9e097af36f7f8f8\", \"shell_dialogs_unittests\": \"de48746ce4b441d77bf76f9454dea9677f7f6037\", \"skia_unittests\": \"febaf07d5d1343275dedf1fa919ca740d37cbab0\", \"snapshot_unittests\": \"3792ab2e13d99eeb7abae635afdee26025a04b4e\", \"sql_unittests\": \"fb593617ee2909e169fbf88d82104e4ae2555ce3\", \"storage_unittests\": \"4477fe700c81b89a7e7650b3ef802b5e727fe441\", \"sync_integration_tests\": \"2988422a57c270e37f6300b6f4485ba3436c2cf0\", \"telemetry_gpu_unittests\": \"5e4deec6c2e6e6d53d4674c89f9bd38e0f6b9312\", \"telemetry_perf_unittests\": \"cdf4bae0226b86abb115353d59944347a5afcec8\", \"telemetry_unittests\": \"58ed8db2eea87f1027221db3151234fc7d237946\", \"traffic_annotation_auditor_unittests\": \"00ec4cd0f63540624426dcbadc180bb271bf7cfe\", \"ui_base_unittests\": \"2998092b9f268b9463799ea4c533a642524b1ba2\", \"ui_touch_selection_unittests\": \"6564e9cfd4c1d47993d9ff8066ff118b84321cdb\", \"unit_tests\": \"97941b17786e2edbffb760045aca7802b9559abe\", \"url_unittests\": \"3e11e31ae02dd6cd84abcf14185c145286babe68\", \"views_perftests\": \"332374696dd488e4ca4bd6dbeafbaf84a50caf29\", \"views_unittests\": \"7b1b82ac0eb7e1db821a93b0a91a3757db16f04f\", \"viz_unittests\": \"566590bb8f5d2adef31c3ebb0270c38a070f8642\", \"vr_common_unittests\": \"e5302b00429177bdf570ddde88c403a2136a09da\", \"vr_pixeltests\": \"a383b72f65b45cff8bddf8dbbf26837d7501d6ab\", \"webdriver_wpt_tests\": \"7aca897d7f48fbfe9b79026c47e010ae114bdc83\", \"weblayer_browsertests\": \"24feb091f817a1cf386b2f76acea9f7f2718a9c6\", \"wm_unittests\": \"d7fa4b8a7981d487aae61dd76491d432ff0613f3\", \"wtf_unittests\": \"e3b0f944795ff1aeccbb90116ee9f746769b2c9e\", \"xr_browser_tests\": \"66894c85fee694c929036e0c9d1b10e6d16a609d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3e75b41c9bcf12a22209c03b338cce1fafa12941d3074ed1e1ed2450e6b71594_v2\", \"builder_3f81ed4451c975c2dad8aeab96873eead95b88de9f86596d05edcfd1a5ff7d01_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_bf333895421fefa78318d8049b60faf5c230ceed823f417948b15e1b2d972d6c_v2\", \"builder_dbb86507a6c3fadd4cf4e1827f5f960c6207016e30a949100a00998d3583e722_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-96-nza5\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4807-99a4c10\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580746847930825",
"status": "COMPLETED",
"status_changed_ts": "1580749629312037",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168183",
"builder:Linux Builder (dbg)",
"buildset:commit/git/501061ccbb7b9fab6dad77babecf12e4ae9f7890",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/501061ccbb7b9fab6dad77babecf12e4ae9f7890",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054890575904458992",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8889460513305517664/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4a24ac3464697610",
"user_agent:luci-scheduler"
],
"updated_ts": "1580749629320036",
"url": "https://ci.chromium.org/b/8889460513305517664",
"utcnow_ts": "1580749630169901"
}
}
[17:07:10.197] Invocation finished in 46m28.099121415s with status SUCCEEDED