[10:16:52.512] New invocation is queued and will start shortly
[10:16:53.609] Starting the invocation (attempt 1)
[10:16:53.661] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:16:53.662] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:16:53.662] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059262120289700704",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4502703ea440187aa3eb6f11326bc663f7974bf6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059262120289700704",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/4502703ea440187aa3eb6f11326bc663f7974bf6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4502703ea440187aa3eb6f11326bc663f7974bf6",
"gitiles_ref:refs/heads/master"
]
}
[10:16:54.567] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576577813848898",
"id": "8893832058224327664",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4502703ea440187aa3eb6f11326bc663f7974bf6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576577814394715",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166201",
"builder:Linux Builder (dbg)",
"buildset:commit/git/4502703ea440187aa3eb6f11326bc663f7974bf6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4502703ea440187aa3eb6f11326bc663f7974bf6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059262120289700704",
"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/8893832058224327664/+/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": "1576577814395001",
"url": "https://ci.chromium.org/b/8893832058224327664",
"utcnow_ts": "1576577814545575"
}
}
[10:16:54.567] Task URL: https://ci.chromium.org/b/8893832058224327664
[10:16:54.567] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:2:0) after 1m0s
[10:17:54.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:2:0)
[10:17:54.614] Timer tick, asking Buildbucket for the build status
[10:17:54.977] Build 8893832058224327664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:17:54.977] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:3:0) after 1m0s
[10:18:55.018] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:3:0)
[10:18:55.018] Timer tick, asking Buildbucket for the build status
[10:18:55.463] Build 8893832058224327664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:18:55.463] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:4:0) after 1m0s
[10:19:55.573] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:4:0)
[10:19:55.573] Timer tick, asking Buildbucket for the build status
[10:19:56.295] Build 8893832058224327664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:19:56.295] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:5:0) after 1m0s
[10:20:56.325] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:5:0)
[10:20:56.325] Timer tick, asking Buildbucket for the build status
[10:20:56.544] Build 8893832058224327664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:20:56.544] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:6:0) after 1m0s
[10:21:56.568] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:6:0)
[10:21:56.568] Timer tick, asking Buildbucket for the build status
[10:21:57.025] Build 8893832058224327664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:21:57.025] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:7:0) after 1m0s
[10:22:03.652] Received PubSub notification, asking Buildbucket for the build status
[10:22:03.851] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:57.046] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:7:0)
[10:22:57.046] Timer tick, asking Buildbucket for the build status
[10:22:57.323] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:57.323] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:9:0) after 1m0s
[10:23:57.348] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:9:0)
[10:23:57.348] Timer tick, asking Buildbucket for the build status
[10:23:58.681] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:58.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:10:0) after 1m0s
[10:24:58.709] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:10:0)
[10:24:58.709] Timer tick, asking Buildbucket for the build status
[10:24:59.402] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:59.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:11:0) after 1m0s
[10:25:59.530] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:11:0)
[10:25:59.530] Timer tick, asking Buildbucket for the build status
[10:25:59.811] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:59.811] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:12:0) after 1m0s
[10:26:59.822] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:12:0)
[10:26:59.822] Timer tick, asking Buildbucket for the build status
[10:27:00.150] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:00.150] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:13:0) after 1m0s
[10:28:00.208] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:13:0)
[10:28:00.208] Timer tick, asking Buildbucket for the build status
[10:28:00.452] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:00.452] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:14:0) after 1m0s
[10:29:00.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:14:0)
[10:29:00.553] Timer tick, asking Buildbucket for the build status
[10:29:00.987] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:00.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:15:0) after 1m0s
[10:30:01.020] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:15:0)
[10:30:01.020] Timer tick, asking Buildbucket for the build status
[10:30:01.474] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:01.474] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:16:0) after 1m0s
[10:31:01.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:16:0)
[10:31:01.552] Timer tick, asking Buildbucket for the build status
[10:31:01.761] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:01.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:17:0) after 1m0s
[10:32:01.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:17:0)
[10:32:01.785] Timer tick, asking Buildbucket for the build status
[10:32:01.990] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:01.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:18:0) after 1m0s
[10:33:02.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:18:0)
[10:33:02.033] Timer tick, asking Buildbucket for the build status
[10:33:02.986] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:02.986] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:19:0) after 1m0s
[10:34:03.073] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:19:0)
[10:34:03.073] Timer tick, asking Buildbucket for the build status
[10:34:04.645] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:04.645] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:20:0) after 1m0s
[10:35:04.702] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:20:0)
[10:35:04.704] Timer tick, asking Buildbucket for the build status
[10:35:05.435] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:05.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:21:0) after 1m0s
[10:36:05.469] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:21:0)
[10:36:05.469] Timer tick, asking Buildbucket for the build status
[10:36:06.778] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:06.778] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:22:0) after 1m0s
[10:37:06.798] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:22:0)
[10:37:06.798] Timer tick, asking Buildbucket for the build status
[10:37:07.614] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:07.614] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:23:0) after 1m0s
[10:38:07.677] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:23:0)
[10:38:07.677] Timer tick, asking Buildbucket for the build status
[10:38:07.882] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:07.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:24:0) after 1m0s
[10:39:07.952] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:24:0)
[10:39:07.952] Timer tick, asking Buildbucket for the build status
[10:39:08.148] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:08.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:25:0) after 1m0s
[10:40:08.202] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:25:0)
[10:40:08.202] Timer tick, asking Buildbucket for the build status
[10:40:08.372] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:08.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:26:0) after 1m0s
[10:41:08.409] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:26:0)
[10:41:08.409] Timer tick, asking Buildbucket for the build status
[10:41:08.678] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:08.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:27:0) after 1m0s
[10:42:08.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:27:0)
[10:42:08.656] Timer tick, asking Buildbucket for the build status
[10:42:08.819] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:08.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:28:0) after 1m0s
[10:43:08.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:28:0)
[10:43:08.852] Timer tick, asking Buildbucket for the build status
[10:43:09.123] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:09.123] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:29:0) after 1m0s
[10:44:09.187] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:29:0)
[10:44:09.187] Timer tick, asking Buildbucket for the build status
[10:44:09.365] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:09.365] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:30:0) after 1m0s
[10:45:09.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:30:0)
[10:45:09.395] Timer tick, asking Buildbucket for the build status
[10:45:09.999] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:09.999] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:31:0) after 1m0s
[10:46:09.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:31:0)
[10:46:09.981] Timer tick, asking Buildbucket for the build status
[10:46:10.306] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:10.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:32:0) after 1m0s
[10:47:10.299] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:32:0)
[10:47:10.299] Timer tick, asking Buildbucket for the build status
[10:47:10.781] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:10.781] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:33:0) after 1m0s
[10:48:10.817] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:33:0)
[10:48:10.817] Timer tick, asking Buildbucket for the build status
[10:48:10.987] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:10.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:34:0) after 1m0s
[10:49:10.992] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:34:0)
[10:49:10.992] Timer tick, asking Buildbucket for the build status
[10:49:11.282] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:11.282] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:35:0) after 1m0s
[10:50:11.308] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:35:0)
[10:50:11.308] Timer tick, asking Buildbucket for the build status
[10:50:11.733] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:11.733] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:36:0) after 1m0s
[10:51:11.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:36:0)
[10:51:11.786] Timer tick, asking Buildbucket for the build status
[10:51:11.945] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:11.945] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:37:0) after 1m0s
[10:52:12.071] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:37:0)
[10:52:12.071] Timer tick, asking Buildbucket for the build status
[10:52:12.288] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:12.288] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:38:0) after 1m0s
[10:53:12.357] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:38:0)
[10:53:12.357] Timer tick, asking Buildbucket for the build status
[10:53:12.612] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:12.612] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:39:0) after 1m0s
[10:54:12.655] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:39:0)
[10:54:12.655] Timer tick, asking Buildbucket for the build status
[10:54:12.925] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:12.925] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:40:0) after 1m0s
[10:55:12.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:40:0)
[10:55:12.949] Timer tick, asking Buildbucket for the build status
[10:55:13.140] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:13.140] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:41:0) after 1m0s
[10:56:13.137] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:41:0)
[10:56:13.137] Timer tick, asking Buildbucket for the build status
[10:56:13.356] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:13.356] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:42:0) after 1m0s
[10:57:13.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:42:0)
[10:57:13.381] Timer tick, asking Buildbucket for the build status
[10:57:13.506] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:13.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:43:0) after 1m0s
[10:58:13.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:43:0)
[10:58:13.535] Timer tick, asking Buildbucket for the build status
[10:58:14.496] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:14.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:44:0) after 1m0s
[10:59:14.577] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:44:0)
[10:59:14.577] Timer tick, asking Buildbucket for the build status
[10:59:14.943] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:14.943] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:45:0) after 1m0s
[11:00:15.006] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:45:0)
[11:00:15.006] Timer tick, asking Buildbucket for the build status
[11:00:15.442] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:15.442] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:46:0) after 1m0s
[11:01:15.471] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:46:0)
[11:01:15.471] Timer tick, asking Buildbucket for the build status
[11:01:16.472] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:16.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:47:0) after 1m0s
[11:02:16.490] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:47:0)
[11:02:16.490] Timer tick, asking Buildbucket for the build status
[11:02:17.085] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:17.085] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:48:0) after 1m0s
[11:03:17.136] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:48:0)
[11:03:17.136] Timer tick, asking Buildbucket for the build status
[11:03:17.377] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:17.377] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:49:0) after 1m0s
[11:04:17.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:49:0)
[11:04:17.435] Timer tick, asking Buildbucket for the build status
[11:04:17.822] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:17.822] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:50:0) after 1m0s
[11:05:17.906] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:50:0)
[11:05:17.906] Timer tick, asking Buildbucket for the build status
[11:05:18.434] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:18.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:51:0) after 1m0s
[11:06:18.443] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:51:0)
[11:06:18.443] Timer tick, asking Buildbucket for the build status
[11:06:18.858] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:18.858] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:52:0) after 1m0s
[11:07:18.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:52:0)
[11:07:18.915] Timer tick, asking Buildbucket for the build status
[11:07:19.166] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:19.166] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:53:0) after 1m0s
[11:08:19.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:53:0)
[11:08:19.226] Timer tick, asking Buildbucket for the build status
[11:08:19.334] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:19.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:54:0) after 1m0s
[11:09:19.356] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:54:0)
[11:09:19.356] Timer tick, asking Buildbucket for the build status
[11:09:19.651] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:19.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:55:0) after 1m0s
[11:10:19.679] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:55:0)
[11:10:19.679] Timer tick, asking Buildbucket for the build status
[11:10:19.923] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:19.923] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:56:0) after 1m0s
[11:11:19.945] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:56:0)
[11:11:19.945] Timer tick, asking Buildbucket for the build status
[11:11:20.317] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:20.317] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:57:0) after 1m0s
[11:12:20.384] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:57:0)
[11:12:20.384] Timer tick, asking Buildbucket for the build status
[11:12:20.696] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:20.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:58:0) after 1m0s
[11:13:20.854] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:58:0)
[11:13:20.854] Timer tick, asking Buildbucket for the build status
[11:13:21.066] Build 8893832058224327664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:21.066] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059262120289700704:59:0) after 1m0s
[11:14:16.979] Received PubSub notification, asking Buildbucket for the build status
[11:14:17.155] Build 8893832058224327664: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:14:17.155] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576581256402153",
"created_by": "project:chromium",
"created_ts": "1576577813848898",
"id": "8893832058224327664",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4502703ea440187aa3eb6f11326bc663f7974bf6\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-94-5nae\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166201, \"got_angle_revision\": \"7587588595901010a7b8d8683d06796ae420edb8\", \"got_dawn_revision\": \"1bbbe8f52da580bd245af733e068eaabacf72731\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"4502703ea440187aa3eb6f11326bc663f7974bf6\", \"got_revision_cp\": \"refs/heads/master@{#725468}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"fbbfeb700295d1fe1ec6419a93594e5e1540ea32\", \"got_v8_revision\": \"29760046ff9e33dec7fa39cf2cf77577c836b525\", \"got_v8_revision_cp\": \"refs/heads/8.1.45@{#1}\", \"got_webrtc_revision\": \"3a8df884d1691260c93da86ce864f8c890e9d155\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30103}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4502703ea440187aa3eb6f11326bc663f7974bf6\", \"swarm_hashes_refs/heads/master(at){#725468}_without_patch\": {\"accessibility_unittests\": \"21782884801cfcc7b0f1b79f310c0c51afc9afdd\", \"angle_unittests\": \"eb27a8405f5a6f2873f82bd755d7f2861e6df9e1\", \"app_shell_unittests\": \"9c2421526b15515836b1162a437d5ada787bd715\", \"aura_unittests\": \"65c14c73b0aee53adf2b3a490b5bfd6ac67ed82b\", \"base_unittests\": \"b3352c745073fcc007a28715e3c7badb9767f979\", \"base_util_unittests\": \"d63537b581dbdb1a4e700d94c4732b5af7458dfa\", \"blink_common_unittests\": \"82c97481d9642a29c6deba4addf7e2fcd6377113\", \"blink_fuzzer_unittests\": \"f9279b25f6d9ed3a18d1ca784eac83ea0a5bd73e\", \"blink_heap_unittests\": \"ce6a2692bd15ba6a933e775ecef3082ade7df9ef\", \"blink_platform_unittests\": \"6a71bab13ba6bc4ffccb50e662e1dc0b9831dab6\", \"blink_python_tests\": \"67f91c35168dc06160ab24eb9e9e925b981ad95a\", \"blink_unittests\": \"329d988688e1fd5e75bbc66289e8d947ebc38d3e\", \"blink_web_tests\": \"3e67e80f7cb33159b8293136f8ab1bb46bac934c\", \"boringssl_crypto_tests\": \"2b3438fae1f5284809a2e15e686fb9ea0ab43ef7\", \"boringssl_ssl_tests\": \"4419018ff4c026bf8aacc01ac79419b72cd84f25\", \"browser_tests\": \"52b303f9eb2e4918c14f454d24ff2753aba5f255\", \"cacheinvalidation_unittests\": \"4684c5482aeb8ee957277a0d31462af78a23cba2\", \"capture_unittests\": \"035067a0ee8235b10a85eb7438975ca8c3a78a6f\", \"cast_unittests\": \"22f2c32a404dc24eafe375e0af089b8192654d50\", \"cc_unittests\": \"d6edcec52971a7b553ae25f0ba5f0c28a5551fbd\", \"chrome_app_unittests\": \"aa089a7dd2af8c67de41643a8ce0fae4eb599dae\", \"chromedriver_unittests\": \"70995835310888fb197ec8f0c74537f8c348e82d\", \"components_browsertests\": \"028e64c194ff1061574fad58880a26444b633983\", \"components_unittests\": \"f90bf53eeac4540649f64d19e1d8ce283a4647c2\", \"compositor_unittests\": \"8b06d4b8928a629c890ccf5d370e95f1ba21b7bd\", \"content_browsertests\": \"f07510927c9a5c52d9f9548a7451a08518f3ac2d\", \"content_shell_crash_test\": \"01f24d859052afc0070eaa7f0d67e2925ee3ac6f\", \"content_unittests\": \"bb3b61d99f2b39e1ea470349f392926b8c7dac3b\", \"crashpad_tests\": \"ccf664b07e088fc054e8ab166b2e4048ff18552b\", \"cronet_tests\": \"f6e733bf811d1d47f821bb84116ecb15375a7395\", \"cronet_unittests\": \"048b086fd45332b26babda3ecbf39beb37fc2202\", \"crypto_unittests\": \"d676d6e57641766941de1a2f10dc6dc0e985b7bc\", \"dbus_unittests\": \"0e1c6879ddc0b9216303ae0fc9750275dac6bab3\", \"device_unittests\": \"13711b58eb8ad856b21afbba58a1e54ce49f96af\", \"devtools_lint_check\": \"e89cdebf802c0af0e411b0a4ddf755d863dae4d7\", \"devtools_type_check\": \"5c636320feb36915018f7e89bbd583cbbdb55b0b\", \"display_unittests\": \"890a04c579bf9b6f236abe5aa56501a4107ff4f1\", \"events_unittests\": \"79de111f8e0ca5ad01f39827e0e99ea776dd912a\", \"extensions_browsertests\": \"7dc63c22f254a46fde2d678cd1dae3fb3b6e84c5\", \"extensions_unittests\": \"531f068dc79141d29eecb10ef9d99a1b2157b718\", \"filesystem_service_unittests\": \"0542bc850280ecdab0b452586d431912493db214\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"c580761b154df3f0899a3a9c08a96c977682b525\", \"gfx_unittests\": \"2144fc38faead3bfc42b4085ff72769c29628e53\", \"gin_unittests\": \"af38ca47ac4ec383ebd287441a0565ac5d1caa8b\", \"google_apis_unittests\": \"c67aeac723399129041d19189362099cd505b607\", \"gpu_unittests\": \"532590cabc822fa52936ff20129809c3f925e2ad\", \"grit_python_unittests\": \"25dff9ef6327c596609d376409bc1d0e605b6d43\", \"gwp_asan_unittests\": \"ca32531f1781ed40df41a978c4eca4e257257e01\", \"headless_browsertests\": \"bb35c288a4ebe2fbcfc6947b6389a7000e24b732\", \"headless_unittests\": \"813b6b8cd2f28cad9d60b714968de2e577d8d029\", \"interactive_ui_tests\": \"b5f677161f57cbf7d9c9b446fb532367ce32ed20\", \"ipc_tests\": \"d438ed1937241e7cd72022d2153d1f85e7cba321\", \"jingle_unittests\": \"0565cae4a84bd29a53367ef2305b83efbe0e96ac\", \"latency_unittests\": \"2891074eced628a0bb67494c7dc0310abedfadbd\", \"libjingle_xmpp_unittests\": \"c7b57851a661b45adaff04e49abad85e9f6bf971\", \"media_blink_unittests\": \"832a857bc15f74af8eb17e93007fb82e1910ff55\", \"media_service_unittests\": \"4e01cdb20540f10fc820c6461228c059a4eeced8\", \"media_unittests\": \"bc1ef389c696e60db69f414c9893abb76134eac0\", \"message_center_unittests\": \"764f34c1b0704d56d0e284b6ee983869d5ca3d2a\", \"metrics_python_tests\": \"7bf4044d1527b6682637abb9684685c6e503ca1a\", \"midi_unittests\": \"fd49b31d066938b305a06fbaf176159292b37649\", \"mojo_core_unittests\": \"6346723486534d5be796cba288ec1655698c12b0\", \"mojo_unittests\": \"eeff3b75c4cab4a00ed0539f01215447d16d74a6\", \"nacl_helper_nonsfi_unittests\": \"3d8558f43cc7a071137e0d48cadb0c46a3efb997\", \"nacl_loader_unittests\": \"92a0f32c51275dbf7bbfb53369be2712be0952d6\", \"native_theme_unittests\": \"9ef31f8e212c5ba27809d7b49273dcc791052759\", \"net_unittests\": \"c33149c554166b4b48abdc1af9e64e1b96efd989\", \"pdf_unittests\": \"50bd1a46fff07e6b08afd47fc7e132b128305982\", \"perfetto_unittests\": \"bd0ab448552d6b6f0b52e9ec79db5c955abe9faa\", \"ppapi_unittests\": \"c1848c6287d7c79f69da6b40646b69bc669905d6\", \"printing_unittests\": \"fd1caf2b01b6aed2a8d55b0010721543affd0dee\", \"remoting_unittests\": \"02c5fc8764bcad588b152942caf630bc472ac779\", \"sandbox_linux_unittests\": \"9540f64c28a57ebd84975d3d85627fc765150c7f\", \"service_manager_unittests\": \"d42af5ba2ff785aae11f3836d85e820f555d87cf\", \"services_unittests\": \"a379faef6469a2187300bc7ca6d62b3ccff059b2\", \"shell_dialogs_unittests\": \"b7eb8d7249c7743e6e3e4956125ab551c34ed5c0\", \"skia_unittests\": \"dadd4464a06b76d08d015575d4ba91ec2efd687f\", \"snapshot_unittests\": \"0d5afb9e0688633d2eb9a6ff8700be02c5526deb\", \"sql_unittests\": \"a4b4d24587de267a5fdc4e62a1ccc64031543ddf\", \"storage_unittests\": \"fb17d4eba1f90ecb04a27ad708ba9e40e514110c\", \"sync_integration_tests\": \"20ff6adbb2c84a9b6ef616c0cd8c5f9a7fc663ad\", \"telemetry_gpu_unittests\": \"854037e01efd17bc08cbe25b13337fb3d3fe6360\", \"telemetry_perf_unittests\": \"37aa6ad45dc3687ad41bb98ea17093805954f047\", \"telemetry_unittests\": \"8ae2c6439385fd622e62cbc1d2f5397ad7d5e81a\", \"traffic_annotation_auditor_unittests\": \"e1130ba14fc095873142e910be078a36342b31c1\", \"ui_base_unittests\": \"dc285bcc011d6372f51a6c9eceeb22df9fe7dd73\", \"ui_touch_selection_unittests\": \"cd8011484a9342016273965ff636aae0436c90e9\", \"unit_tests\": \"b50eab6f0ec18997b86d80cc5f883201807573db\", \"url_unittests\": \"9f956c7b19d856c269e848e2dcfe98234447047d\", \"views_perftests\": \"551e39d24473fa88fb4a309ed7589c6c8c69df7d\", \"views_unittests\": \"27d543022b710dab2df55d9ebf04e1079967ed82\", \"viz_unittests\": \"1f896aa6cc9f27c78b2b634fc9b07f1fa11d5083\", \"vr_common_unittests\": \"738502914c6210216d550d425079d2558a0dea45\", \"vr_pixeltests\": \"85ccfa5da1d5f00f68630519cd3b51c70e9100f7\", \"webdriver_wpt_tests\": \"88af5b453fdbd613dbbeca33bbbd5c1c3cf8d95c\", \"weblayer_browsertests\": \"5c9ed6012cc90f0d721f25678b794c7206555e4d\", \"wm_unittests\": \"77cc3dfc69824733351f0a61728595f482ee76a7\", \"wtf_unittests\": \"4b7b9adbf383c37a159db929d3e191135d548579\", \"xr_browser_tests\": \"6b3490e32e8ba5a4b91f76ca6eaabeec50080a5a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2b25d58305a6755482534765a65f2521477c19af0731994631a8cf25b9f3b571_v2\", \"builder_3f701152d865ce3ec8feb15f973214ad980aeace5cbda24477548a965269fca6_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_9ccb21220795a1d1972030c6b5d9b4569472a28790e1458f6676edb8e4495e8f_v2\", \"builder_bf60de3a2a061c3c4c67ad9afdde23d2342e42cdda6e8a4003b52312ca24fd40_v2\", \"builder_d78b729059d6d7166c1c60b6c63641496a1541bf700cb46d40203a280149f161_v2\", \"builder_f5610824250c57fc28679fbc8606252c3dbaca731fb7f2a4bebf1e0fc7f48a3a_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-94-5nae\"], \"image\": [\"chrome-xenial-19120800-09d8782d9a1\"], \"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\": [\"4730-f2fc813\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576578121580439",
"status": "COMPLETED",
"status_changed_ts": "1576581256697025",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166201",
"builder:Linux Builder (dbg)",
"buildset:commit/git/4502703ea440187aa3eb6f11326bc663f7974bf6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4502703ea440187aa3eb6f11326bc663f7974bf6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059262120289700704",
"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/8893832058224327664/+/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:492c2e0b8bd89210",
"user_agent:luci-scheduler"
],
"updated_ts": "1576581256702924",
"url": "https://ci.chromium.org/b/8893832058224327664",
"utcnow_ts": "1576581257136788"
}
}
[11:14:17.155] Invocation finished in 57m24.659126611s with status SUCCEEDED