[06:24:49.138] New invocation is queued and will start shortly
[06:24:51.778] Starting the invocation (attempt 1)
[06:24:51.820] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:24:51.820] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:24:51.820] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053840902023703088",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9f93113646fbdb60994ce7940d27451c8e2ac448\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053840902023703088",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/9f93113646fbdb60994ce7940d27451c8e2ac448",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9f93113646fbdb60994ce7940d27451c8e2ac448",
"gitiles_ref:refs/heads/master"
]
}
[06:24:53.100] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581747891958370",
"id": "8888410838400833696",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9f93113646fbdb60994ce7940d27451c8e2ac448\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581747892722118",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168621",
"builder:Linux Builder (dbg)",
"buildset:commit/git/9f93113646fbdb60994ce7940d27451c8e2ac448",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9f93113646fbdb60994ce7940d27451c8e2ac448",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053840902023703088",
"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/8888410838400833696/+/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": "1581747892722246",
"url": "https://ci.chromium.org/b/8888410838400833696",
"utcnow_ts": "1581747893090736"
}
}
[06:24:53.100] Task URL: https://ci.chromium.org/b/8888410838400833696
[06:24:53.100] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:2:0) after 1m0s
[06:25:55.387] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:2:0)
[06:25:55.387] Timer tick, asking Buildbucket for the build status
[06:25:55.488] Build 8888410838400833696: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:25:55.488] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:3:0) after 1m0s
[06:26:56.013] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:3:0)
[06:26:56.013] Timer tick, asking Buildbucket for the build status
[06:26:56.304] Build 8888410838400833696: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:26:56.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:4:0) after 1m0s
[06:27:56.385] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:4:0)
[06:27:56.385] Timer tick, asking Buildbucket for the build status
[06:27:56.506] Build 8888410838400833696: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:27:56.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:5:0) after 1m0s
[06:28:56.713] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:5:0)
[06:28:56.713] Timer tick, asking Buildbucket for the build status
[06:28:56.838] Build 8888410838400833696: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:28:56.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:6:0) after 1m0s
[06:29:11.403] Received PubSub notification, asking Buildbucket for the build status
[06:29:11.482] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:56.911] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:6:0)
[06:29:56.911] Timer tick, asking Buildbucket for the build status
[06:29:57.040] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:57.040] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:8:0) after 1m0s
[06:30:57.181] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:8:0)
[06:30:57.181] Timer tick, asking Buildbucket for the build status
[06:30:57.282] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:57.282] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:9:0) after 1m0s
[06:31:57.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:9:0)
[06:31:57.379] Timer tick, asking Buildbucket for the build status
[06:31:57.537] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:57.537] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:10:0) after 1m0s
[06:32:57.733] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:10:0)
[06:32:57.733] Timer tick, asking Buildbucket for the build status
[06:32:57.833] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:57.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:11:0) after 1m0s
[06:33:57.929] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:11:0)
[06:33:57.929] Timer tick, asking Buildbucket for the build status
[06:33:58.167] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:58.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:12:0) after 1m0s
[06:34:58.218] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:12:0)
[06:34:58.218] Timer tick, asking Buildbucket for the build status
[06:34:58.482] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:58.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:13:0) after 1m0s
[06:35:58.503] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:13:0)
[06:35:58.503] Timer tick, asking Buildbucket for the build status
[06:35:58.999] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:58.999] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:14:0) after 1m0s
[06:36:59.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:14:0)
[06:36:59.005] Timer tick, asking Buildbucket for the build status
[06:36:59.372] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:59.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:15:0) after 1m0s
[06:37:59.364] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:15:0)
[06:37:59.364] Timer tick, asking Buildbucket for the build status
[06:37:59.451] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:59.451] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:16:0) after 1m0s
[06:38:59.503] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:16:0)
[06:38:59.503] Timer tick, asking Buildbucket for the build status
[06:38:59.608] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:59.608] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:17:0) after 1m0s
[06:39:59.595] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:17:0)
[06:39:59.595] Timer tick, asking Buildbucket for the build status
[06:39:59.699] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:59.699] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:18:0) after 1m0s
[06:40:59.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:18:0)
[06:40:59.718] Timer tick, asking Buildbucket for the build status
[06:40:59.867] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:59.867] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:19:0) after 1m0s
[06:41:59.892] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:19:0)
[06:41:59.892] Timer tick, asking Buildbucket for the build status
[06:42:00.143] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:00.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:20:0) after 1m0s
[06:43:00.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:20:0)
[06:43:00.159] Timer tick, asking Buildbucket for the build status
[06:43:00.348] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:00.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:21:0) after 1m0s
[06:44:00.350] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:21:0)
[06:44:00.350] Timer tick, asking Buildbucket for the build status
[06:44:00.486] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:00.486] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:22:0) after 1m0s
[06:45:00.517] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:22:0)
[06:45:00.517] Timer tick, asking Buildbucket for the build status
[06:45:00.715] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:00.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:23:0) after 1m0s
[06:46:00.762] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:23:0)
[06:46:00.762] Timer tick, asking Buildbucket for the build status
[06:46:00.981] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:00.981] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:24:0) after 1m0s
[06:47:01.001] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:24:0)
[06:47:01.001] Timer tick, asking Buildbucket for the build status
[06:47:01.116] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:01.116] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:25:0) after 1m0s
[06:48:01.177] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:25:0)
[06:48:01.177] Timer tick, asking Buildbucket for the build status
[06:48:01.362] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:01.362] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:26:0) after 1m0s
[06:49:01.414] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:26:0)
[06:49:01.414] Timer tick, asking Buildbucket for the build status
[06:49:01.670] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:01.670] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:27:0) after 1m0s
[06:50:01.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:27:0)
[06:50:01.691] Timer tick, asking Buildbucket for the build status
[06:50:01.849] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:01.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:28:0) after 1m0s
[06:51:01.883] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:28:0)
[06:51:01.883] Timer tick, asking Buildbucket for the build status
[06:51:02.059] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:02.059] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:29:0) after 1m0s
[06:52:02.090] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:29:0)
[06:52:02.090] Timer tick, asking Buildbucket for the build status
[06:52:02.300] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:02.300] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:30:0) after 1m0s
[06:53:02.558] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:30:0)
[06:53:02.558] Timer tick, asking Buildbucket for the build status
[06:53:02.709] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:02.709] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:31:0) after 1m0s
[06:54:02.824] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:31:0)
[06:54:02.824] Timer tick, asking Buildbucket for the build status
[06:54:03.076] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:03.076] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:32:0) after 1m0s
[06:55:03.156] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:32:0)
[06:55:03.156] Timer tick, asking Buildbucket for the build status
[06:55:03.373] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:03.373] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:33:0) after 1m0s
[06:56:03.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:33:0)
[06:56:03.618] Timer tick, asking Buildbucket for the build status
[06:56:03.919] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:03.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:34:0) after 1m0s
[06:57:04.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:34:0)
[06:57:04.086] Timer tick, asking Buildbucket for the build status
[06:57:04.190] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:04.190] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:35:0) after 1m0s
[06:58:04.280] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:35:0)
[06:58:04.280] Timer tick, asking Buildbucket for the build status
[06:58:04.535] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:04.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:36:0) after 1m0s
[06:59:04.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:36:0)
[06:59:04.586] Timer tick, asking Buildbucket for the build status
[06:59:04.806] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:04.806] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:37:0) after 1m0s
[07:00:04.778] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:37:0)
[07:00:04.778] Timer tick, asking Buildbucket for the build status
[07:00:05.948] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:05.948] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:38:0) after 1m0s
[07:01:06.528] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:38:0)
[07:01:06.528] Timer tick, asking Buildbucket for the build status
[07:01:07.804] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:07.804] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:39:0) after 1m0s
[07:02:07.982] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:39:0)
[07:02:07.982] Timer tick, asking Buildbucket for the build status
[07:02:08.172] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:08.172] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:40:0) after 1m0s
[07:03:08.314] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:40:0)
[07:03:08.314] Timer tick, asking Buildbucket for the build status
[07:03:08.485] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:08.486] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:41:0) after 1m0s
[07:04:08.734] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:41:0)
[07:04:08.734] Timer tick, asking Buildbucket for the build status
[07:04:09.053] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:09.053] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:42:0) after 1m0s
[07:05:09.080] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:42:0)
[07:05:09.080] Timer tick, asking Buildbucket for the build status
[07:05:09.168] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:09.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:43:0) after 1m0s
[07:06:09.414] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:43:0)
[07:06:09.414] Timer tick, asking Buildbucket for the build status
[07:06:09.716] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:09.716] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:44:0) after 1m0s
[07:07:09.885] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:44:0)
[07:07:09.885] Timer tick, asking Buildbucket for the build status
[07:07:10.361] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:10.361] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:45:0) after 1m0s
[07:08:10.537] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:45:0)
[07:08:10.537] Timer tick, asking Buildbucket for the build status
[07:08:10.656] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:10.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:46:0) after 1m0s
[07:09:10.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:46:0)
[07:09:10.900] Timer tick, asking Buildbucket for the build status
[07:09:11.132] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:11.132] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:47:0) after 1m0s
[07:10:11.333] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:47:0)
[07:10:11.333] Timer tick, asking Buildbucket for the build status
[07:10:11.539] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:11.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:48:0) after 1m0s
[07:11:11.578] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:48:0)
[07:11:11.578] Timer tick, asking Buildbucket for the build status
[07:11:11.857] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:11.857] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:49:0) after 1m0s
[07:12:12.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:49:0)
[07:12:12.059] Timer tick, asking Buildbucket for the build status
[07:12:12.273] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:12.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:50:0) after 1m0s
[07:13:12.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:50:0)
[07:13:12.343] Timer tick, asking Buildbucket for the build status
[07:13:12.646] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:12.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:51:0) after 1m0s
[07:14:12.766] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:51:0)
[07:14:12.766] Timer tick, asking Buildbucket for the build status
[07:14:12.881] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:12.883] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:52:0) after 1m0s
[07:15:12.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:52:0)
[07:15:12.918] Timer tick, asking Buildbucket for the build status
[07:15:14.102] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:14.102] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:53:0) after 1m0s
[07:16:14.150] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:53:0)
[07:16:14.150] Timer tick, asking Buildbucket for the build status
[07:16:14.301] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:14.301] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:54:0) after 1m0s
[07:17:14.373] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:54:0)
[07:17:14.373] Timer tick, asking Buildbucket for the build status
[07:17:14.665] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:14.665] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:55:0) after 1m0s
[07:18:14.843] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:55:0)
[07:18:14.843] Timer tick, asking Buildbucket for the build status
[07:18:15.152] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:15.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:56:0) after 1m0s
[07:19:15.314] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:56:0)
[07:19:15.314] Timer tick, asking Buildbucket for the build status
[07:19:16.367] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:16.367] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:57:0) after 1m0s
[07:20:16.438] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:57:0)
[07:20:16.438] Timer tick, asking Buildbucket for the build status
[07:20:16.533] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:16.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:58:0) after 1m0s
[07:21:16.569] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:58:0)
[07:21:16.569] Timer tick, asking Buildbucket for the build status
[07:21:16.740] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:16.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:59:0) after 1m0s
[07:22:17.654] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:59:0)
[07:22:17.654] Timer tick, asking Buildbucket for the build status
[07:22:17.729] Build 8888410838400833696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:17.729] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053840902023703088:60:0) after 1m0s
[07:22:59.492] Received PubSub notification, asking Buildbucket for the build status
[07:22:59.572] Build 8888410838400833696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:22:59.572] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581751378654614",
"created_by": "project:chromium",
"created_ts": "1581747891958370",
"id": "8888410838400833696",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9f93113646fbdb60994ce7940d27451c8e2ac448\"}}",
"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-central1-b-8-59-sdca\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168621, \"got_angle_revision\": \"12a36dd9ea4af84964e025320372556c39e60c5f\", \"got_dawn_revision\": \"215b5370bc4fa9dc5eb0004a0a21b9245296d04b\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"9f93113646fbdb60994ce7940d27451c8e2ac448\", \"got_revision_cp\": \"refs/heads/master@{#741718}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"348f07b40c2bb540b7fdc03765fe17f687ff91f5\", \"got_v8_revision\": \"d8c8ba85588daae682c61abd952ffa37cb16c26b\", \"got_v8_revision_cp\": \"refs/heads/8.2.85@{#1}\", \"got_webrtc_revision\": \"966bcc4bd5cc7aee5963e784ddf0caf668b2d6e6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30527}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9f93113646fbdb60994ce7940d27451c8e2ac448\", \"swarm_hashes_refs/heads/master(at){#741718}_without_patch\": {\"accessibility_unittests\": \"d70104a2c6e29c33d56ab7f10d2b8cfb5ccf0308\", \"angle_unittests\": \"a870e0cebd9c9c71f43f0939934f935ed23e4f18\", \"app_shell_unittests\": \"48360ca2cba4e0c4ef27d05653eaf5734c11cbc8\", \"aura_unittests\": \"17632ad998d8ee37f0f8688c0df8e5d74c5e83da\", \"base_unittests\": \"8eb1eb8ed9a765d7e089a49026a7408755c4c57f\", \"base_util_unittests\": \"247f2b6d2d1a520255b8a462ea858437da74837c\", \"blink_common_unittests\": \"8ecdb40c4b1cb38024de69721affab0a69767705\", \"blink_fuzzer_unittests\": \"723fb9f324451999e06d37a5463f4dc38f333cd9\", \"blink_heap_unittests\": \"fd2bb63853e313ddfeae78be594bf9f11d01d420\", \"blink_platform_unittests\": \"5e47cdd4aa97092e0edfb7447de327ba4a354e06\", \"blink_python_tests\": \"fc9c936ef12f297cada07e3596f74c3f0650fe98\", \"blink_unittests\": \"02f366c9c6ca6b47a094279a2017e128862ef082\", \"blink_web_tests\": \"08ad85acf92f7a00df112cc63c6689e070f378b1\", \"boringssl_crypto_tests\": \"9927cc1e99d1487b4ad37293c25e076ff7dfb475\", \"boringssl_ssl_tests\": \"ca8450965b384932401dd32fb1a196c1b8f2b90a\", \"browser_tests\": \"611ddf201292b205cc8cd3b5a1d44ed42de68859\", \"cacheinvalidation_unittests\": \"615294346db7f37481d645ada108521ba69a3225\", \"capture_unittests\": \"6da21d6a02b29b371f9548e6e39870821e976a82\", \"cast_unittests\": \"8921b7bf51d3dcf7d158979d205e9fdf6bd93465\", \"cc_unittests\": \"534cb5cef9a18604440ed3eafe1833d4a44f0cdf\", \"chrome_app_unittests\": \"0557ff2f2f9b53afc5e800af728e9dcd5047e214\", \"chromedriver_unittests\": \"b300f46d2d9cd2cbaf85deceea3c5d827cd0d2a2\", \"components_browsertests\": \"86fee9432d1a66f2fe8cc7a37bba8e577554b658\", \"components_unittests\": \"3a4c11622ac6481cc955e80e2434a5394e45f28b\", \"compositor_unittests\": \"f04fc1a6f90c4f165d0a3dd2fb51bf9369009f35\", \"content_browsertests\": \"3dbd9f14ba43d2a5a31f1ac0914206d4faa62675\", \"content_shell_crash_test\": \"2b538b468f9ce9db3b166ba270b62b62375e50cc\", \"content_unittests\": \"a309111d44ecc0699663d2896c47e1ce8b2930fd\", \"crashpad_tests\": \"74b7448adf864686aa5ad81e0e2497db9295d792\", \"cronet_tests\": \"60232fcfb09112c6fa36024bb44bfa684a3b2651\", \"cronet_unittests\": \"a0496db5ce24531c18f1dd69af27271d9af2c2bb\", \"crypto_unittests\": \"df8d2afb2c55ec3ac22f88365f9e38b2b1578b07\", \"dbus_unittests\": \"3bcdbd2351dd00b87692f6e6150f8bcb2cdbef74\", \"device_unittests\": \"d9bdf10a0c59e8f61fa9a9a402ed17f9fb1e3544\", \"display_unittests\": \"e7deb446c88001155902e0f015c23b84c33a4570\", \"events_unittests\": \"9ce52b9861e5c3015b20ba74250e88eeb023b693\", \"extensions_browsertests\": \"283dd50539e701aee68cd9451b5a72cab89e5fc0\", \"extensions_unittests\": \"d0ae26003ae6ed642ae90aa3c107c54f0ecad8b7\", \"filesystem_service_unittests\": \"e95918791b8dd508398bda1de160c6ad624e4843\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"2696d9b7d97d7d1ca019e276e9b82f814f6e2ef4\", \"gfx_unittests\": \"ea758c65a1ca86da1007236b98b25615f119be05\", \"gin_unittests\": \"e579c22018f90826952b54e94940601f0be496e8\", \"google_apis_unittests\": \"168d34c46be20740e851dd164ac8254d97dcd5af\", \"gpu_unittests\": \"6ff5c48b78d2762f5424ffd599db61f73b1a1edf\", \"grit_python_unittests\": \"314a136826c68f2b20f2d52ddf6f5e567311beb1\", \"gwp_asan_unittests\": \"124524fe994b7cf83203041ef7d85f9b2523d970\", \"headless_browsertests\": \"6a7d7523077f7558809d3fd78919f76e223bbc1e\", \"headless_unittests\": \"236eb74ffa65a2fa35455d69fb105a9620d449d9\", \"interactive_ui_tests\": \"ba0c252611e2b6b0e1b0a43048e0d8d2026060cc\", \"ipc_tests\": \"c3ae3f81196c5ca243b04ddd8ab94d94949d35e2\", \"jingle_unittests\": \"cc9f30d45430d09284e39ba99c011b48ccd9c74f\", \"latency_unittests\": \"2708ffeefc9a519af3c5c270225130561621edb5\", \"libjingle_xmpp_unittests\": \"d548ae016dec854d620ada57e917df0c5f3c8b46\", \"media_blink_unittests\": \"172033caa2df55c419f7f495fa2dc4dcde9822d8\", \"media_service_unittests\": \"95dfacd3cb5c50bbc8fb24c5f1be1139b9c82d84\", \"media_unittests\": \"95f4b2b5d6e7e3e668d015d26e92f80111904666\", \"message_center_unittests\": \"d2ba34cfef81649eb02b65bcf2e482fac5d9e4d8\", \"metrics_python_tests\": \"356c310f65550be9987153365523bb6de7aaed3e\", \"midi_unittests\": \"602b03c886e8c1eb8af7fd073a7451b3abb0014e\", \"mojo_core_unittests\": \"5555951f1e9cc8037fb4c89c2acaba5eadcc3994\", \"mojo_unittests\": \"949ef6e759210a7b6da8dc7e55230099eb12ee1c\", \"nacl_helper_nonsfi_unittests\": \"780544e37262fae2e103f68611af5ed37b01fe39\", \"nacl_loader_unittests\": \"a37f9156649e9724f8fa5c9605534b5c15d6f347\", \"native_theme_unittests\": \"147964c8ae3aca3106fdf2cbfeee6e6227bcd373\", \"net_unittests\": \"9c84c7ebc4c272b96990715a4774197b9c9df6b0\", \"pdf_unittests\": \"ddf7531eeaccfeaae045fe81ef00b96e2b742d5a\", \"perfetto_unittests\": \"a707f0e2a15ea16ae8de794aba09c768447d3f40\", \"ppapi_unittests\": \"ee86538dbfa168399582a310a3285e478635668c\", \"printing_unittests\": \"860954dc91f967e5a2d9a3f05371968dc396c21a\", \"remoting_unittests\": \"4dd488c4dfc97b2140f0a255a59e3ca6479c197e\", \"sandbox_linux_unittests\": \"2f14b75e6fc90c0eaaff009c54bd8e45911915ad\", \"service_manager_unittests\": \"68c7d3eb2d4a9e8220663b1169e2236231fbbb5f\", \"services_unittests\": \"ef6fe994eea5742c8ebe818e8afc23e087055cfa\", \"shell_dialogs_unittests\": \"12b0923ba265797ddbc135821e3e555e8d1bb920\", \"skia_unittests\": \"f744ebd92a96b3cb45e3b91fd1a8b32502e67221\", \"snapshot_unittests\": \"02746f5ac2f1d6d1d0325665305eb950f0decc97\", \"sql_unittests\": \"004333257e2f8309f6b70416273ba520965cfe8e\", \"storage_unittests\": \"c253bf1366387d87190bf32162fb63294eb4277e\", \"sync_integration_tests\": \"911c34655b27453ed2e1dd4c3af39fa3325e0b93\", \"telemetry_gpu_unittests\": \"546b51149920e9fd40dcb27efd02c42d865c32ac\", \"telemetry_perf_unittests\": \"80fd934066f64a950f2a484b3a52eabe25740c43\", \"telemetry_unittests\": \"18a55152c744991979b64ab0694385c9f3a92fb6\", \"traffic_annotation_auditor_unittests\": \"4a97d93f399f3637f41143ffd0e8401d7126e9e7\", \"ui_base_unittests\": \"399134a43626ce8050b5e9d5763f2daf5e0fbf6f\", \"ui_touch_selection_unittests\": \"582af28b62f539ac91b13c7db8a5198f4c3e1824\", \"unit_tests\": \"24b65df7906e532aeb37b502deea1033f76817b5\", \"url_unittests\": \"0afbd11e9906b7444a9383e662e319e0f718f68d\", \"views_perftests\": \"7980d7c731e0f4f8e55bdff97d66944f0eb0b1f9\", \"views_unittests\": \"1483e402dd9d34d8416697ba1ed7bf62f510ef3b\", \"viz_unittests\": \"2e02388604ffdb57601b200fb9b3ef1ed8ee2b14\", \"vr_common_unittests\": \"b20295d8321faba0637c38017d790a4326c2c3ef\", \"vr_pixeltests\": \"882d3947e93205c6a73ad0fe9796a0e4a6600dc0\", \"webdriver_wpt_tests\": \"24135427ff6c001d504904b06577e306ce0a6c32\", \"weblayer_browsertests\": \"a6db2c6ca4732ab1273616677144f1a2ea4d09b7\", \"wm_unittests\": \"d1403d328adc666942cf4ffd0bd16016ce8a3008\", \"wtf_unittests\": \"88d7bc3827636331ef1d12395e9691f4e6b78c2e\", \"xr_browser_tests\": \"006d8cd69c852affd025ffa38a2e7348c7f445a4\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_497278b5819f10111ab639898a894ad19d361b24d185228ca986cf767de7a7ef_v2\", \"builder_662609c3a18ad850d61ba5f58749e60478bc4d9d576475e0c738a1c9e2d8d890_v2\", \"builder_84395cd86c4615c28c38755187d54d1bbe2dcea268d302d74191d04ce3a055d0_v2\", \"builder_86798dabf94f8387a70bc61df85c682f30176fee5c17d25bf53551dac0cf2cf7_v2\", \"builder_93a7e775fd8f3ff9ef99e2aeb686615c0006e7587b0f2c5be32706e8df4f86a4_v2\", \"builder_957c0f1a1c74c3ada6665996f2ff614223d5da32e2cc2316ff7cc4c9c4beae5c_v2\", \"builder_c38832248eaa59248ebe0e5d5ae62a6bc955f53f6a99a9c0e5cded93d679f9bc_v2\", \"builder_ced67bd9e4a31c2a429bce6c01367a471355a6320e5e7684f07304ef1e327147_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-central1-b-8-59-sdca\"], \"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\": [\"4815-21a95a1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581748150684292",
"status": "COMPLETED",
"status_changed_ts": "1581751378950818",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168621",
"builder:Linux Builder (dbg)",
"buildset:commit/git/9f93113646fbdb60994ce7940d27451c8e2ac448",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9f93113646fbdb60994ce7940d27451c8e2ac448",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053840902023703088",
"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/8888410838400833696/+/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:4a6056fddd069b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1581751378954725",
"url": "https://ci.chromium.org/b/8888410838400833696",
"utcnow_ts": "1581751379560454"
}
}
[07:22:59.572] Invocation finished in 58m10.452847284s with status SUCCEEDED