[16:26:19.228] New invocation is queued and will start shortly
[16:26:20.494] Starting the invocation (attempt 1)
[16:26:20.540] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:26:20.540] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:26:20.540] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053984252752529760",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"202a62ca05edcd7684cc2a742dfffd37091309c6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053984252752529760",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/202a62ca05edcd7684cc2a742dfffd37091309c6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/202a62ca05edcd7684cc2a742dfffd37091309c6",
"gitiles_ref:refs/heads/master"
]
}
[16:26:21.338] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581611180684190",
"id": "8888554190561422832",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"202a62ca05edcd7684cc2a742dfffd37091309c6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581611181202068",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168562",
"builder:Linux Builder (dbg)",
"buildset:commit/git/202a62ca05edcd7684cc2a742dfffd37091309c6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/202a62ca05edcd7684cc2a742dfffd37091309c6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053984252752529760",
"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/8888554190561422832/+/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": "1581611181202206",
"url": "https://ci.chromium.org/b/8888554190561422832",
"utcnow_ts": "1581611181327752"
}
}
[16:26:21.338] Task URL: https://ci.chromium.org/b/8888554190561422832
[16:26:21.338] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:2:0) after 1m0s
[16:27:21.367] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:2:0)
[16:27:21.367] Timer tick, asking Buildbucket for the build status
[16:27:21.866] Build 8888554190561422832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:27:21.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:3:0) after 1m0s
[16:28:21.940] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:3:0)
[16:28:21.940] Timer tick, asking Buildbucket for the build status
[16:28:22.393] Build 8888554190561422832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:28:22.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:4:0) after 1m0s
[16:29:22.451] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:4:0)
[16:29:22.451] Timer tick, asking Buildbucket for the build status
[16:29:22.576] Build 8888554190561422832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:29:22.576] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:5:0) after 1m0s
[16:30:22.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:5:0)
[16:30:22.628] Timer tick, asking Buildbucket for the build status
[16:30:22.753] Build 8888554190561422832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:30:22.753] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:6:0) after 1m0s
[16:31:21.428] Received PubSub notification, asking Buildbucket for the build status
[16:31:21.553] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:22.787] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:6:0)
[16:31:22.787] Timer tick, asking Buildbucket for the build status
[16:31:23.488] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:23.488] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:8:0) after 1m0s
[16:32:23.478] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:8:0)
[16:32:23.478] Timer tick, asking Buildbucket for the build status
[16:32:23.755] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:23.755] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:9:0) after 1m0s
[16:33:23.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:9:0)
[16:33:23.805] Timer tick, asking Buildbucket for the build status
[16:33:24.582] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:24.582] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:10:0) after 1m0s
[16:34:24.704] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:10:0)
[16:34:24.704] Timer tick, asking Buildbucket for the build status
[16:34:25.153] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:25.153] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:11:0) after 1m0s
[16:35:25.246] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:11:0)
[16:35:25.246] Timer tick, asking Buildbucket for the build status
[16:35:25.386] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:25.386] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:12:0) after 1m0s
[16:36:25.430] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:12:0)
[16:36:25.430] Timer tick, asking Buildbucket for the build status
[16:36:25.800] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:25.800] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:13:0) after 1m0s
[16:37:25.821] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:13:0)
[16:37:25.821] Timer tick, asking Buildbucket for the build status
[16:37:25.978] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:25.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:14:0) after 1m0s
[16:38:26.042] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:14:0)
[16:38:26.042] Timer tick, asking Buildbucket for the build status
[16:38:26.178] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:26.179] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:15:0) after 1m0s
[16:39:26.271] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:15:0)
[16:39:26.271] Timer tick, asking Buildbucket for the build status
[16:39:26.462] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:26.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:16:0) after 1m0s
[16:40:26.488] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:16:0)
[16:40:26.488] Timer tick, asking Buildbucket for the build status
[16:40:26.700] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:26.700] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:17:0) after 1m0s
[16:41:26.702] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:17:0)
[16:41:26.702] Timer tick, asking Buildbucket for the build status
[16:41:27.477] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:27.477] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:18:0) after 1m0s
[16:42:27.523] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:18:0)
[16:42:27.523] Timer tick, asking Buildbucket for the build status
[16:42:28.526] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:28.526] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:19:0) after 1m0s
[16:43:28.514] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:19:0)
[16:43:28.514] Timer tick, asking Buildbucket for the build status
[16:43:28.683] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:28.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:20:0) after 1m0s
[16:44:28.745] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:20:0)
[16:44:28.745] Timer tick, asking Buildbucket for the build status
[16:44:28.944] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:28.944] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:21:0) after 1m0s
[16:45:28.973] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:21:0)
[16:45:28.973] Timer tick, asking Buildbucket for the build status
[16:45:29.648] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:29.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:22:0) after 1m0s
[16:46:29.669] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:22:0)
[16:46:29.669] Timer tick, asking Buildbucket for the build status
[16:46:29.859] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:29.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:23:0) after 1m0s
[16:47:29.931] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:23:0)
[16:47:29.931] Timer tick, asking Buildbucket for the build status
[16:47:30.118] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:30.118] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:24:0) after 1m0s
[16:48:30.268] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:24:0)
[16:48:30.268] Timer tick, asking Buildbucket for the build status
[16:48:30.508] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:30.508] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:25:0) after 1m0s
[16:49:30.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:25:0)
[16:49:30.511] Timer tick, asking Buildbucket for the build status
[16:49:31.068] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:31.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:26:0) after 1m0s
[16:50:31.049] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:26:0)
[16:50:31.049] Timer tick, asking Buildbucket for the build status
[16:50:32.160] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:32.160] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:27:0) after 1m0s
[16:51:32.276] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:27:0)
[16:51:32.276] Timer tick, asking Buildbucket for the build status
[16:51:32.451] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:32.451] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:28:0) after 1m0s
[16:52:33.317] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:28:0)
[16:52:33.317] Timer tick, asking Buildbucket for the build status
[16:52:33.468] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:33.468] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:29:0) after 1m0s
[16:53:33.449] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:29:0)
[16:53:33.449] Timer tick, asking Buildbucket for the build status
[16:53:33.606] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:33.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:30:0) after 1m0s
[16:54:33.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:30:0)
[16:54:33.618] Timer tick, asking Buildbucket for the build status
[16:54:33.773] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:33.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:31:0) after 1m0s
[16:55:33.790] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:31:0)
[16:55:33.790] Timer tick, asking Buildbucket for the build status
[16:55:34.226] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:34.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:32:0) after 1m0s
[16:56:34.295] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:32:0)
[16:56:34.295] Timer tick, asking Buildbucket for the build status
[16:56:35.019] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:35.019] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:33:0) after 1m0s
[16:57:35.066] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:33:0)
[16:57:35.066] Timer tick, asking Buildbucket for the build status
[16:57:35.436] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:35.436] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:34:0) after 1m0s
[16:58:35.469] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:34:0)
[16:58:35.469] Timer tick, asking Buildbucket for the build status
[16:58:35.662] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:35.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:35:0) after 1m0s
[16:59:35.690] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:35:0)
[16:59:35.690] Timer tick, asking Buildbucket for the build status
[16:59:35.859] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:35.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:36:0) after 1m0s
[17:00:35.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:36:0)
[17:00:35.900] Timer tick, asking Buildbucket for the build status
[17:00:36.123] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:36.123] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:37:0) after 1m0s
[17:01:36.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:37:0)
[17:01:36.205] Timer tick, asking Buildbucket for the build status
[17:01:36.411] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:36.411] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:38:0) after 1m0s
[17:02:36.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:38:0)
[17:02:36.481] Timer tick, asking Buildbucket for the build status
[17:02:36.751] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:36.751] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:39:0) after 1m0s
[17:03:36.843] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:39:0)
[17:03:36.843] Timer tick, asking Buildbucket for the build status
[17:03:37.261] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:37.261] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:40:0) after 1m0s
[17:04:37.302] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:40:0)
[17:04:37.302] Timer tick, asking Buildbucket for the build status
[17:04:38.075] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:38.075] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:41:0) after 1m0s
[17:05:38.154] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:41:0)
[17:05:38.154] Timer tick, asking Buildbucket for the build status
[17:05:38.508] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:38.508] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:42:0) after 1m0s
[17:06:38.634] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:42:0)
[17:06:38.634] Timer tick, asking Buildbucket for the build status
[17:06:38.965] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:38.965] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:43:0) after 1m0s
[17:07:39.031] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:43:0)
[17:07:39.031] Timer tick, asking Buildbucket for the build status
[17:07:39.215] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:39.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:44:0) after 1m0s
[17:08:39.281] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:44:0)
[17:08:39.281] Timer tick, asking Buildbucket for the build status
[17:08:39.775] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:39.776] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:45:0) after 1m0s
[17:09:39.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:45:0)
[17:09:39.866] Timer tick, asking Buildbucket for the build status
[17:09:40.574] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:40.574] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:46:0) after 1m0s
[17:10:40.672] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:46:0)
[17:10:40.672] Timer tick, asking Buildbucket for the build status
[17:10:41.295] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:41.295] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:47:0) after 1m0s
[17:11:41.342] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:47:0)
[17:11:41.342] Timer tick, asking Buildbucket for the build status
[17:11:43.695] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:43.695] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:48:0) after 1m0s
[17:12:43.927] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:48:0)
[17:12:43.927] Timer tick, asking Buildbucket for the build status
[17:12:44.387] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:44.387] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:49:0) after 1m0s
[17:13:44.561] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:49:0)
[17:13:44.561] Timer tick, asking Buildbucket for the build status
[17:13:44.689] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:44.689] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:50:0) after 1m0s
[17:14:44.841] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:50:0)
[17:14:44.841] Timer tick, asking Buildbucket for the build status
[17:14:45.661] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:45.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:51:0) after 1m0s
[17:15:45.712] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:51:0)
[17:15:45.712] Timer tick, asking Buildbucket for the build status
[17:15:45.861] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:45.861] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:52:0) after 1m0s
[17:16:45.974] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:52:0)
[17:16:45.974] Timer tick, asking Buildbucket for the build status
[17:16:46.148] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:46.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:53:0) after 1m0s
[17:17:46.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:53:0)
[17:17:46.205] Timer tick, asking Buildbucket for the build status
[17:17:46.431] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:46.431] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:54:0) after 1m0s
[17:18:46.853] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:54:0)
[17:18:46.853] Timer tick, asking Buildbucket for the build status
[17:18:47.015] Build 8888554190561422832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:47.015] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053984252752529760:55:0) after 1m0s
[17:19:32.718] Received PubSub notification, asking Buildbucket for the build status
[17:19:33.272] Build 8888554190561422832: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:19:33.272] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581614347731949",
"created_by": "project:chromium",
"created_ts": "1581611180684190",
"id": "8888554190561422832",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"202a62ca05edcd7684cc2a742dfffd37091309c6\"}}",
"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-0-zi0w\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168562, \"got_angle_revision\": \"77a5100d9a9a87d25e3aa2f66656cb47718b1afa\", \"got_dawn_revision\": \"2fd618192985122aafb494ac5e9a39f572ef46fc\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"202a62ca05edcd7684cc2a742dfffd37091309c6\", \"got_revision_cp\": \"refs/heads/master@{#741077}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"29cc245860746cb9ec174eba4b5f50bc8c6e790d\", \"got_v8_revision\": \"f63b3f364bf8823496fe6b3134780e7d2996a644\", \"got_v8_revision_cp\": \"refs/heads/8.2.68@{#1}\", \"got_webrtc_revision\": \"b28e57e72580b0269cecf91754b4c3a1937e0712\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30511}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"202a62ca05edcd7684cc2a742dfffd37091309c6\", \"swarm_hashes_refs/heads/master(at){#741077}_without_patch\": {\"accessibility_unittests\": \"042af145f809a607af1dd563b71517dc841d35b6\", \"angle_unittests\": \"0ec6e1595c1506ea6164358ee1e5759d937ec25f\", \"app_shell_unittests\": \"30f19cf6007c491cee3e091ae0a795aceb393740\", \"aura_unittests\": \"1129116f17146bab27eb9692a834384739c756cf\", \"base_unittests\": \"cd70d6144e3238b8047ce9058a2938f01fb1271c\", \"base_util_unittests\": \"e17668784acbfa58b724b1c3f35cf3c631b506e7\", \"blink_common_unittests\": \"90badc42f9a3827efd8d3b98e774a1ac7532b28d\", \"blink_fuzzer_unittests\": \"f7b602ab168864a79fe2201756b537d8825043d5\", \"blink_heap_unittests\": \"c5cd4909ccdc905e2a169c6269278d29af704134\", \"blink_platform_unittests\": \"7ea0f851b63b07ad49e1fb2f7af5cc4d1b87b9ee\", \"blink_python_tests\": \"68f398edd92a311c3e6a7f7e03194ee24f83ed61\", \"blink_unittests\": \"6efc4385125712d274042af8b637516702cbd741\", \"blink_web_tests\": \"4f7150e6f0156c00f74dd5065fc1a44c293e9b2a\", \"boringssl_crypto_tests\": \"b87a055c98d697df135e48b0c23327df3468e283\", \"boringssl_ssl_tests\": \"98d469f6f38158412495b377357c0d4ac7d11c97\", \"browser_tests\": \"74f0dc2b3d82cad546fc39ae79579e63103ca9a0\", \"cacheinvalidation_unittests\": \"63312ec567db8c629f743fe998eddfda58183bcb\", \"capture_unittests\": \"5c3f35e022826d51d7e579cf3ff3a47d17b1c15b\", \"cast_unittests\": \"88aaa757c7ee4e71dfb7793fc614942ee9b75c2e\", \"cc_unittests\": \"b6245eb8d6b29541bd73390e90353e6d795d66ee\", \"chrome_app_unittests\": \"a729448e3c066ae22df1db2473cc857855c5e08b\", \"chromedriver_unittests\": \"d6f224264fa53770f28bb3edcd9babfe299c7c45\", \"components_browsertests\": \"1d9bed14f89d9263bcaade48e36720db1d50f293\", \"components_unittests\": \"fed7c48ef95c5b4a67a1ff1064bdc65204f02082\", \"compositor_unittests\": \"6460ed6902c48e5f4f0ce059208f678415664e68\", \"content_browsertests\": \"b7240eeedcd03d9fb03ea39a16512eb65bb66734\", \"content_shell_crash_test\": \"4e208e1649b8d9703e3472e78fa8df1f7c2f65ee\", \"content_unittests\": \"30754b6db9647c5bf5bcd119806381c6f8a9d547\", \"crashpad_tests\": \"e12d70617f5414d5734e54cf7cf7e76a1c138961\", \"cronet_tests\": \"79b573ab7e61fa56e861a64675addb7c62b649bb\", \"cronet_unittests\": \"43c209b02b32f917f5fa3c6b0733c8981fbe00e3\", \"crypto_unittests\": \"1924ae41beaaf91a8b7d97335bdd1bbe1751d70e\", \"dbus_unittests\": \"bab92fd98e2699ee4ede963aac0c36b8ee990add\", \"device_unittests\": \"2b733801d548ced6b9a46ef792dc90f3f7cb8ec9\", \"display_unittests\": \"285e2b249048476c096b0c6aaa08f7ba477357f9\", \"events_unittests\": \"f9728a579fc3f378178f0903fe557bf21a584019\", \"extensions_browsertests\": \"b72ae127b646232c493fb63fbfdf6f6cafb75f46\", \"extensions_unittests\": \"d79b9461aa794d8aeae5914038cf050142cbe569\", \"filesystem_service_unittests\": \"c8651e8a37b5c3cdb2c131085bf84931d3c0986e\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"b8956e3c2b88ad6ed639b3d8a1cfdce09cc4462f\", \"gfx_unittests\": \"3e9fa3d14127aa5973f44fd5a9cf894d4a0823ac\", \"gin_unittests\": \"9681bcfd0e48d55ed3ba53cfbc6f828b91a32ca3\", \"google_apis_unittests\": \"add092ac67c77f4fd6dbd91b527b9c28e41ff5c7\", \"gpu_unittests\": \"bc68c2db046bbc82cea77a66158307b008daa39a\", \"grit_python_unittests\": \"07488c096d20bb2a4e6d9280813fca6afbee6dc0\", \"gwp_asan_unittests\": \"fab8b58188083ab0d7ea803f315934d46c509def\", \"headless_browsertests\": \"f3e2655aa813ec97c1ca7ccb3fe8254b272e03ba\", \"headless_unittests\": \"a20868a105ce7f268d73f1d41952d9d9c7b0f9f2\", \"interactive_ui_tests\": \"1eb4e1da169dee7bb85bc9be3a73c846fb4742c2\", \"ipc_tests\": \"42bd12968a12421c9338b44a46e8bbc906b7b20c\", \"jingle_unittests\": \"7f5199128299d42b1eb04be28d30f8027ee9a615\", \"latency_unittests\": \"1e72bad98770dc34687333f93b624db85688e14a\", \"libjingle_xmpp_unittests\": \"fd168c0bfaa9496104d6466582127e28b145bd8d\", \"media_blink_unittests\": \"35e9fe375a02b9810c540e796198d103d8162e9b\", \"media_service_unittests\": \"b7b97be03b271454d1545cde4075eed8e581b909\", \"media_unittests\": \"a652760e043b8bd6af0ec4fc855e7df289763c0b\", \"message_center_unittests\": \"52e3cd887c350849c7a5f1834d0c1f51fc2646c1\", \"metrics_python_tests\": \"fcc2762a1a7050e6a6310e87491e30be346bca3a\", \"midi_unittests\": \"220f68d93f7faf1c351dc0de8c9e177d8b4c6ecb\", \"mojo_core_unittests\": \"37d934fe119ab7e718daeca692ae912773e6bf51\", \"mojo_unittests\": \"789d72fc2684b82384a4ce3ebc78bfc1e6de10f3\", \"nacl_helper_nonsfi_unittests\": \"9caed98f05189c3cb88d280dc5643b58514812f1\", \"nacl_loader_unittests\": \"68c4fbd6624a1cf5dc923e1ff7568b28782bcb84\", \"native_theme_unittests\": \"02e880d598e47b4abb3a868934260020db9a56b8\", \"net_unittests\": \"392cf02973270e6de4de42e98c47677bf4e4107d\", \"pdf_unittests\": \"fd3e775b4cb8434c4d9e3320639056d0a75e6e71\", \"perfetto_unittests\": \"77cdb112db5164e51b2b852925d844e0eb6dfea6\", \"ppapi_unittests\": \"f60c75628cd94bda72a5c34ebf34e1ed84f559ef\", \"printing_unittests\": \"6e9eaf441a376ff3917ad352e9047fa954805f8f\", \"remoting_unittests\": \"fa1714f9bb450c5344db6b954a7034a424d23f8d\", \"sandbox_linux_unittests\": \"c954623bc4b652987c727c1e551e31948f025985\", \"service_manager_unittests\": \"340269a49a4aa840c016c1f91ae968c7a8dd88d6\", \"services_unittests\": \"af9bd5b710c7d74b9fded8ba17ef844fa2772994\", \"shell_dialogs_unittests\": \"d0ba21892c35351223cad32a6e0f7ddd446fe93e\", \"skia_unittests\": \"6c2dfba5b8467c4ddfd9c128d186a978fad56d38\", \"snapshot_unittests\": \"4d68b29f300b315dc14e11c4171336074520ecb8\", \"sql_unittests\": \"8b93b52ade2ec6ac586908db0d2057e13fc6e8ae\", \"storage_unittests\": \"7abca217e80d1fc72369d3f405acae5879044ef4\", \"sync_integration_tests\": \"4e03ddb9b0219e52bcae088f039b99adef4a077b\", \"telemetry_gpu_unittests\": \"66b56ca1932a07361a6199542936a38ce610d02e\", \"telemetry_perf_unittests\": \"3c8e34dbd703780ec8611a2585f962feb78e2cec\", \"telemetry_unittests\": \"67363f1497babdcdc021dacdeb046bde16fcbeee\", \"traffic_annotation_auditor_unittests\": \"a88862794e0672dce1e4f5f9039cfdd5ce635821\", \"ui_base_unittests\": \"6b1ac2404f447d573f41c481f1b793b4f406e476\", \"ui_touch_selection_unittests\": \"d27783f80f1a04af25357b7a1883f8f1607f8783\", \"unit_tests\": \"54687b80de01ff74e64a4b52495b14658958c5b8\", \"url_unittests\": \"824719361fa5bd4f352953d4e1866ef4e9a69901\", \"views_perftests\": \"b89db95a4d2dba5be3b15426362b72483c2e0baf\", \"views_unittests\": \"1ab90d891d68d93945f03cb3e80a451324103e72\", \"viz_unittests\": \"6ea7f86c8a9046cb477b82aa329150529e3c83bd\", \"vr_common_unittests\": \"23128b80089d7915c656c63a7519a85edd523c43\", \"vr_pixeltests\": \"f403c62e57e365050ae3054ab269f83f89fc1e99\", \"webdriver_wpt_tests\": \"75c2374cafeabc59dd7f8ad55a3f3744281af2a1\", \"weblayer_browsertests\": \"dd6ea233fe67bbfb6a4aaffff617563cdff22f0e\", \"wm_unittests\": \"ca4de77b69374e2e33e3bf5fd1cf2e59a9a04210\", \"wtf_unittests\": \"eb9903a41bb7076287ee9ec00cd46744e8cc4fd9\", \"xr_browser_tests\": \"7d54d2fc37a3277e3af23562bc01e9f71b680b73\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1de787806eca61f7210aef74433a5aa46707bd25b3e4e26a6b86243e6768278e_v2\", \"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_61faa0d0c1c079ecf670d8cfa4f78ad3b1687a5534233c7377462c3ee6819708_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-0-zi0w\"], \"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-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581611467073344",
"status": "COMPLETED",
"status_changed_ts": "1581614372478619",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168562",
"builder:Linux Builder (dbg)",
"buildset:commit/git/202a62ca05edcd7684cc2a742dfffd37091309c6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/202a62ca05edcd7684cc2a742dfffd37091309c6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053984252752529760",
"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/8888554190561422832/+/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:4a5830ed792bc610",
"user_agent:luci-scheduler"
],
"updated_ts": "1581614372482082",
"url": "https://ci.chromium.org/b/8888554190561422832",
"utcnow_ts": "1581614373264718"
}
}
[17:19:33.272] Invocation finished in 53m14.061407798s with status SUCCEEDED