[09:24:00.149] New invocation is queued and will start shortly
[09:24:01.301] Starting the invocation (attempt 1)
[09:24:01.332] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:24:01.332] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:24:01.333] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9055279180231067856",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"561b3a08640909d54cc95d53e63e0c77a8268d88\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9055279180231067856",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/561b3a08640909d54cc95d53e63e0c77a8268d88",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/561b3a08640909d54cc95d53e63e0c77a8268d88",
"gitiles_ref:refs/heads/master"
]
}
[09:24:02.141] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580376241519401",
"id": "8889849118131010912",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"561b3a08640909d54cc95d53e63e0c77a8268d88\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580376241995205",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168023",
"builder:Linux Builder (dbg)",
"buildset:commit/git/561b3a08640909d54cc95d53e63e0c77a8268d88",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/561b3a08640909d54cc95d53e63e0c77a8268d88",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055279180231067856",
"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/8889849118131010912/+/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": "1580376241995326",
"url": "https://ci.chromium.org/b/8889849118131010912",
"utcnow_ts": "1580376242128869"
}
}
[09:24:02.141] Task URL: https://ci.chromium.org/b/8889849118131010912
[09:24:02.141] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:2:0) after 1m0s
[09:25:02.877] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:2:0)
[09:25:02.877] Timer tick, asking Buildbucket for the build status
[09:25:03.193] Build 8889849118131010912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:25:03.193] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:3:0) after 1m0s
[09:26:03.307] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:3:0)
[09:26:03.307] Timer tick, asking Buildbucket for the build status
[09:26:04.361] Build 8889849118131010912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:26:04.361] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:4:0) after 1m0s
[09:27:04.384] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:4:0)
[09:27:04.384] Timer tick, asking Buildbucket for the build status
[09:27:04.577] Build 8889849118131010912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:27:04.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:5:0) after 1m0s
[09:28:04.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:5:0)
[09:28:04.596] Timer tick, asking Buildbucket for the build status
[09:28:04.959] Build 8889849118131010912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:28:04.959] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:6:0) after 1m0s
[09:28:07.019] Received PubSub notification, asking Buildbucket for the build status
[09:28:07.469] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:04.968] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:6:0)
[09:29:04.968] Timer tick, asking Buildbucket for the build status
[09:29:05.782] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:05.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:8:0) after 1m0s
[09:30:05.840] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:8:0)
[09:30:05.840] Timer tick, asking Buildbucket for the build status
[09:30:06.305] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:06.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:9:0) after 1m0s
[09:31:06.363] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:9:0)
[09:31:06.363] Timer tick, asking Buildbucket for the build status
[09:31:06.549] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:06.549] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:10:0) after 1m0s
[09:32:06.576] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:10:0)
[09:32:06.576] Timer tick, asking Buildbucket for the build status
[09:32:07.457] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:07.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:11:0) after 1m0s
[09:33:07.510] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:11:0)
[09:33:07.510] Timer tick, asking Buildbucket for the build status
[09:33:08.458] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:08.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:12:0) after 1m0s
[09:34:08.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:12:0)
[09:34:08.480] Timer tick, asking Buildbucket for the build status
[09:34:08.669] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:08.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:13:0) after 1m0s
[09:35:08.856] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:13:0)
[09:35:08.856] Timer tick, asking Buildbucket for the build status
[09:35:09.415] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:09.415] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:14:0) after 1m0s
[09:36:09.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:14:0)
[09:36:09.497] Timer tick, asking Buildbucket for the build status
[09:36:10.113] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:10.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:15:0) after 1m0s
[09:37:10.238] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:15:0)
[09:37:10.238] Timer tick, asking Buildbucket for the build status
[09:37:10.544] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:10.544] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:16:0) after 1m0s
[09:38:10.567] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:16:0)
[09:38:10.567] Timer tick, asking Buildbucket for the build status
[09:38:10.802] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:10.802] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:17:0) after 1m0s
[09:39:10.897] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:17:0)
[09:39:10.897] Timer tick, asking Buildbucket for the build status
[09:39:11.101] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:11.101] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:18:0) after 1m0s
[09:40:11.154] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:18:0)
[09:40:11.154] Timer tick, asking Buildbucket for the build status
[09:40:11.360] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:11.360] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:19:0) after 1m0s
[09:41:11.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:19:0)
[09:41:11.383] Timer tick, asking Buildbucket for the build status
[09:41:11.549] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:11.549] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:20:0) after 1m0s
[09:42:11.603] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:20:0)
[09:42:11.603] Timer tick, asking Buildbucket for the build status
[09:42:11.860] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:11.860] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:21:0) after 1m0s
[09:43:11.868] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:21:0)
[09:43:11.868] Timer tick, asking Buildbucket for the build status
[09:43:12.063] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:12.063] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:22:0) after 1m0s
[09:44:12.222] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:22:0)
[09:44:12.222] Timer tick, asking Buildbucket for the build status
[09:44:12.541] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:12.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:23:0) after 1m0s
[09:45:12.537] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:23:0)
[09:45:12.537] Timer tick, asking Buildbucket for the build status
[09:45:12.760] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:12.760] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:24:0) after 1m0s
[09:46:12.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:24:0)
[09:46:12.785] Timer tick, asking Buildbucket for the build status
[09:46:13.261] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:13.261] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:25:0) after 1m0s
[09:47:13.298] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:25:0)
[09:47:13.298] Timer tick, asking Buildbucket for the build status
[09:47:13.767] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:13.767] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:26:0) after 1m0s
[09:48:13.808] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:26:0)
[09:48:13.808] Timer tick, asking Buildbucket for the build status
[09:48:13.971] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:13.971] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:27:0) after 1m0s
[09:49:13.998] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:27:0)
[09:49:13.998] Timer tick, asking Buildbucket for the build status
[09:49:14.852] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:14.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:28:0) after 1m0s
[09:50:14.877] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:28:0)
[09:50:14.877] Timer tick, asking Buildbucket for the build status
[09:50:15.211] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:15.211] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:29:0) after 1m0s
[09:51:15.248] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:29:0)
[09:51:15.248] Timer tick, asking Buildbucket for the build status
[09:51:15.427] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:15.427] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:30:0) after 1m0s
[09:52:15.491] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:30:0)
[09:52:15.491] Timer tick, asking Buildbucket for the build status
[09:52:15.665] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:15.665] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:31:0) after 1m0s
[09:53:15.868] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:31:0)
[09:53:15.868] Timer tick, asking Buildbucket for the build status
[09:53:16.032] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:16.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:32:0) after 1m0s
[09:54:16.094] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:32:0)
[09:54:16.094] Timer tick, asking Buildbucket for the build status
[09:54:16.686] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:16.686] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:33:0) after 1m0s
[09:55:16.761] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:33:0)
[09:55:16.761] Timer tick, asking Buildbucket for the build status
[09:55:16.928] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:16.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:34:0) after 1m0s
[09:56:16.952] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:34:0)
[09:56:16.952] Timer tick, asking Buildbucket for the build status
[09:56:17.109] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:17.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:35:0) after 1m0s
[09:57:17.155] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:35:0)
[09:57:17.155] Timer tick, asking Buildbucket for the build status
[09:57:17.391] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:17.391] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:36:0) after 1m0s
[09:58:17.430] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:36:0)
[09:58:17.430] Timer tick, asking Buildbucket for the build status
[09:58:17.636] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:17.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:37:0) after 1m0s
[09:59:17.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:37:0)
[09:59:17.656] Timer tick, asking Buildbucket for the build status
[09:59:17.815] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:17.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:38:0) after 1m0s
[10:00:17.856] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:38:0)
[10:00:17.856] Timer tick, asking Buildbucket for the build status
[10:00:17.987] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:17.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:39:0) after 1m0s
[10:01:17.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:39:0)
[10:01:17.989] Timer tick, asking Buildbucket for the build status
[10:01:18.175] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:18.175] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:40:0) after 1m0s
[10:02:18.271] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:40:0)
[10:02:18.271] Timer tick, asking Buildbucket for the build status
[10:02:18.498] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:18.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:41:0) after 1m0s
[10:03:18.604] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:41:0)
[10:03:18.604] Timer tick, asking Buildbucket for the build status
[10:03:18.850] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:18.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:42:0) after 1m0s
[10:04:18.948] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:42:0)
[10:04:18.948] Timer tick, asking Buildbucket for the build status
[10:04:19.818] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:19.818] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:43:0) after 1m0s
[10:05:19.909] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:43:0)
[10:05:19.909] Timer tick, asking Buildbucket for the build status
[10:05:20.126] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:20.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:44:0) after 1m0s
[10:06:20.192] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:44:0)
[10:06:20.192] Timer tick, asking Buildbucket for the build status
[10:06:20.462] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:20.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:45:0) after 1m0s
[10:07:20.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:45:0)
[10:07:20.462] Timer tick, asking Buildbucket for the build status
[10:07:20.694] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:20.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:46:0) after 1m0s
[10:08:20.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:46:0)
[10:08:20.720] Timer tick, asking Buildbucket for the build status
[10:08:20.921] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:20.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:47:0) after 1m0s
[10:09:21.050] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:47:0)
[10:09:21.050] Timer tick, asking Buildbucket for the build status
[10:09:21.644] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:21.644] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:48:0) after 1m0s
[10:10:21.703] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:48:0)
[10:10:21.703] Timer tick, asking Buildbucket for the build status
[10:10:21.938] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:21.938] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:49:0) after 1m0s
[10:11:22.073] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:49:0)
[10:11:22.073] Timer tick, asking Buildbucket for the build status
[10:11:22.929] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:22.929] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:50:0) after 1m0s
[10:12:22.928] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:50:0)
[10:12:22.928] Timer tick, asking Buildbucket for the build status
[10:12:23.113] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:23.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:51:0) after 1m0s
[10:13:23.140] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:51:0)
[10:13:23.140] Timer tick, asking Buildbucket for the build status
[10:13:23.436] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:23.436] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:52:0) after 1m0s
[10:14:23.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:52:0)
[10:14:23.508] Timer tick, asking Buildbucket for the build status
[10:14:23.635] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:23.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:53:0) after 1m0s
[10:15:23.615] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:53:0)
[10:15:23.615] Timer tick, asking Buildbucket for the build status
[10:15:24.032] Build 8889849118131010912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:24.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055279180231067856:54:0) after 1m0s
[10:15:56.580] Received PubSub notification, asking Buildbucket for the build status
[10:15:56.719] Build 8889849118131010912: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:15:56.719] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580379355582710",
"created_by": "project:chromium",
"created_ts": "1580376241519401",
"id": "8889849118131010912",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"561b3a08640909d54cc95d53e63e0c77a8268d88\"}}",
"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-82-5tge\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168023, \"got_angle_revision\": \"ee07cb317579dfda40dabb5d6d9c209e0e9e0643\", \"got_dawn_revision\": \"48e7da791f1496ea836f8d92d1af1b120eee1d6a\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"561b3a08640909d54cc95d53e63e0c77a8268d88\", \"got_revision_cp\": \"refs/heads/master@{#736774}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"430def835f9f85d52f4a96db9b715cd9a7403c9c\", \"got_v8_revision\": \"62aeb04515990c92d82af9f4843d4cdb4085a9a5\", \"got_v8_revision_cp\": \"refs/heads/8.1.310@{#1}\", \"got_webrtc_revision\": \"1cf15bfe55a3b9c007e9810d13ec210628b64e86\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30416}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"561b3a08640909d54cc95d53e63e0c77a8268d88\", \"swarm_hashes_refs/heads/master(at){#736774}_without_patch\": {\"accessibility_unittests\": \"c2819635abf4bda4965624c12f5157b4c99500c4\", \"angle_unittests\": \"9be462b9bb736c4ec73fe99ac69d59394bd61963\", \"app_shell_unittests\": \"4d21f4d7b8df66195acf484d53852455a68dc159\", \"aura_unittests\": \"e3ce9fd4ea2302c2ec218ec7ec93b67340506a0f\", \"base_unittests\": \"a89334ca9f2095fe509e2a9a28fccc421699a4a0\", \"base_util_unittests\": \"6f5ddad0f09980f9404a65136d56c3f55925f8a6\", \"blink_common_unittests\": \"130e57bbc75123d2ff18810b45cc04c333423516\", \"blink_fuzzer_unittests\": \"38f4ce1695195d017677a16af31ced3a54455361\", \"blink_heap_unittests\": \"3b6f2a966fe49a3c233da63a6cf53f48f3e58608\", \"blink_platform_unittests\": \"1e55a65ff975a79f4d8354379ad3488da24958f7\", \"blink_python_tests\": \"97f0cefb192c1b9e8177f654dd57b8359da11840\", \"blink_unittests\": \"c0db66a49917a467a2fd893638224390b078391b\", \"blink_web_tests\": \"577e466d970d3529848efb5eb71da8db9e5e6a50\", \"boringssl_crypto_tests\": \"fe88eaeb443f9d011f54b722c5a9851fd0ca680e\", \"boringssl_ssl_tests\": \"25c4024ea5e4cd176c49e07b900f21503081a216\", \"browser_tests\": \"f27b85fcddac61a2e78aaf1a1d33641ea9907163\", \"cacheinvalidation_unittests\": \"a141d4db18c35516aeee2ff19f5823fb5aed8cfb\", \"capture_unittests\": \"6974a8d018eedf7c2a2901efd25d98d312f60208\", \"cast_unittests\": \"840e92e4faa72e455d89b08c057383738740bbf9\", \"cc_unittests\": \"e26fda00d5191b24661396a7224596fc25fb4330\", \"chrome_app_unittests\": \"dbbc16f50fc1c594605d9ca8f0af019071abd315\", \"chromedriver_unittests\": \"05285aba8387950e4648ba60b9d13e14c791f6ae\", \"components_browsertests\": \"7a9b535c894182988dc901039b7a7fc3a23b629a\", \"components_unittests\": \"9259f20f8724cb4a5b3d1e464ac44555e54ec842\", \"compositor_unittests\": \"463cfac28466808aaca28e8e664f3844f09cd0a4\", \"content_browsertests\": \"8e13131c9f401cd602ae03b6f082da8d3f6dad06\", \"content_shell_crash_test\": \"f3dac8535a4e2d4b0b464d959797548ad2c1f11a\", \"content_unittests\": \"0473c27104045ea78911f4d6dccd9a8af246ee28\", \"crashpad_tests\": \"e6e63363dc3a1a5e5dcfd37f6e956679e8f38fe3\", \"cronet_tests\": \"60736d3524dabceea68a41bf72c5bdc1bd0defdb\", \"cronet_unittests\": \"de551e7a7c9d675a74838940b88e0c1999576720\", \"crypto_unittests\": \"996ce6eff6ebfdad9dec29a5ef695d0caa6be324\", \"dbus_unittests\": \"047147107de20f9dcbacaaba41624087f0e334b7\", \"device_unittests\": \"3809dc311f2520b04d53d830b92322c1a705188f\", \"display_unittests\": \"da31bc99fe478df05a7e4774914cec3b6f2938a0\", \"events_unittests\": \"cdb33135b497805679691094e68389c9e89e02e4\", \"extensions_browsertests\": \"497d93a262b0d6d6cb6a67bf30bda56fa2baaca3\", \"extensions_unittests\": \"aa27b17b3025b698e817f17ecf75eae26231c6c4\", \"filesystem_service_unittests\": \"70ae05028fc8cf5005ebd1be66c6623de01db49e\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"81bb944d19b2ee73225d09e9e23e4b12e6fae1f3\", \"gfx_unittests\": \"1527ade86324d1af36d8ee38ee7995094c3f701f\", \"gin_unittests\": \"a9633fa63f8b505495dbf750e822687be9cbcd7f\", \"google_apis_unittests\": \"78397954d41eab36b1b87ac776a3544c0bfc0a92\", \"gpu_unittests\": \"966c60c547509c7a198d9ed618b0072065519623\", \"grit_python_unittests\": \"896da2265579af982b0c06ff8662522b696fea99\", \"gwp_asan_unittests\": \"302577f81dc9d22b34efa866a8740bf1f4bfc098\", \"headless_browsertests\": \"2a3223d1dd9daf67d4e4f02108e7d13b4b054aba\", \"headless_unittests\": \"7ad562bb8ff0b4600ac7646b1680d4c64a0ead1a\", \"interactive_ui_tests\": \"9d9596b14f44eb9e894f368c9dd1ca986ef82530\", \"ipc_tests\": \"a466aa4a30994980369a7ee64283a3aba35aecd9\", \"jingle_unittests\": \"c5298a832aee85b85f9b16850f402150e96b23a3\", \"latency_unittests\": \"667c0cf2f26d32f267206b03962605684a2505a8\", \"libjingle_xmpp_unittests\": \"3f021f1a04da8d798acb6c7680d772e5d0f69951\", \"media_blink_unittests\": \"f35b706b9d057624a414f1ca92f85242ef0f59ff\", \"media_service_unittests\": \"afab5ccc6429811cd6cc982965768a48b1a67b44\", \"media_unittests\": \"21a8a6168858d8e9105fd7b9ea8ee13b2d17dca2\", \"message_center_unittests\": \"cec7089bd953db05dc32a5266104c6962ab1326d\", \"metrics_python_tests\": \"a23e842484d697c4281bc41ab9d147c74915e45e\", \"midi_unittests\": \"0ec9b7e1c715264968dc25393e40a31e529425e7\", \"mojo_core_unittests\": \"3619272c2e1e4cfed1b0a1ad16a3da8323768cd4\", \"mojo_unittests\": \"3f1b52fa9322e49b7f0b250da158bf27d0539e40\", \"nacl_helper_nonsfi_unittests\": \"49f85ee001d1f82e99d56578f95878f99041c5b3\", \"nacl_loader_unittests\": \"cfcf96ca4a08c99dc251c1c77ee00ab5f6b012b6\", \"native_theme_unittests\": \"82c015cab55c836ca7048d38c59b45a5b447e906\", \"net_unittests\": \"6c7d4cc1cb9058df2905dfe2f7ae087d8583816d\", \"pdf_unittests\": \"015586f3e6871d58a182b4ce69557986bddab18c\", \"perfetto_unittests\": \"dad04b08d9b8579bed93c51930639382436ad84e\", \"ppapi_unittests\": \"460379e4e11f4d535890c032d51e7197ef42c062\", \"printing_unittests\": \"12b2603d876ed036ba715b3644c290096f25519a\", \"remoting_unittests\": \"8ecb1cb753baa89896454e4c19853d9d108c0a53\", \"sandbox_linux_unittests\": \"fd64e0daf3cefddedb9a9348472cb4d8591feb71\", \"service_manager_unittests\": \"dac386d4b0b26c241e9eafa68e2c068fdf28bb4e\", \"services_unittests\": \"0b3f98cf72942acb7135e1cce1cfd51b87aa118e\", \"shell_dialogs_unittests\": \"2f3e717180b0ce2053a4d7e6eff6e52e7a8cff94\", \"skia_unittests\": \"10483cf7de78e76badd7f2c44b22d8f486244f46\", \"snapshot_unittests\": \"6041a43d50558f327a6aefc79f0248915f616199\", \"sql_unittests\": \"7d0d157bd95f6bde7276fe965e92e7ea40fb6d05\", \"storage_unittests\": \"942fe6876b5c0937d39a3616820c2a283d6794a9\", \"sync_integration_tests\": \"e1777ee11b1727c5f80231cd93ea8028aa1ce916\", \"telemetry_gpu_unittests\": \"cd7e18138e3e3a1753a429eb5a75f6e64d92f79b\", \"telemetry_perf_unittests\": \"4c37208220e428d3b06ec3b3a806fdb92c6fc174\", \"telemetry_unittests\": \"fee421961f9852473b6780f7b41bfd4625f499b9\", \"traffic_annotation_auditor_unittests\": \"0713a1def99bae12d325aff74b54a85a8d191766\", \"ui_base_unittests\": \"56f83281e33697f5d1f8dbf5b315d6785e4f58d4\", \"ui_touch_selection_unittests\": \"221c77a49f260727049c07de97976949bab11888\", \"unit_tests\": \"4199eeb7dda779fe86586c4d186f601738357149\", \"url_unittests\": \"716dff108d7524afcb285f3937d89b083c18d324\", \"views_perftests\": \"f66612de501d0241c8a096e3757d563a44b01e40\", \"views_unittests\": \"7f2a72e0aeca2ddc69c60722f89f4ef571ada84a\", \"viz_unittests\": \"f912793e0e223079c04549bbe41eb963c3758565\", \"vr_common_unittests\": \"57626b661f623e2865cc43ca016acfc45d68c82b\", \"vr_pixeltests\": \"b5739591125f1521bacc8c8388abef13a778f90e\", \"webdriver_wpt_tests\": \"229e3cf18da4870ab39e870effe8f2bed7464bde\", \"weblayer_browsertests\": \"821e6514e537a7bbc990caae000dbdbabf4eb388\", \"wm_unittests\": \"143053484b317ed4750d86193b775e4a6af1afe6\", \"wtf_unittests\": \"b858dc99fc7092ad666b7f13b7debc43e0bfd16e\", \"xr_browser_tests\": \"875dc80b6e6135c4bf22d78dac73f216fb7abad4\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2dd6a1af21400b3785138e4c8a94108d5525f829439b094d94efcf967177507f_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-82-5tge\"], \"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\": [\"4804-5c544e0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580376485623475",
"status": "COMPLETED",
"status_changed_ts": "1580379356158443",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168023",
"builder:Linux Builder (dbg)",
"buildset:commit/git/561b3a08640909d54cc95d53e63e0c77a8268d88",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/561b3a08640909d54cc95d53e63e0c77a8268d88",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055279180231067856",
"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/8889849118131010912/+/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:4a0e95411eb8c910",
"user_agent:luci-scheduler"
],
"updated_ts": "1580379356177661",
"url": "https://ci.chromium.org/b/8889849118131010912",
"utcnow_ts": "1580379356711633"
}
}
[10:15:56.719] Invocation finished in 51m56.586704422s with status SUCCEEDED