[19:04:47.164] New invocation is queued and will start shortly
[19:04:49.743] Starting the invocation (attempt 1)
[19:04:49.791] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:04:49.792] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:04:49.792] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9056148610157650416",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"88d96aba7a2740d116093fa4cc79ab5e89d068db\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9056148610157650416",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/88d96aba7a2740d116093fa4cc79ab5e89d068db",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/88d96aba7a2740d116093fa4cc79ab5e89d068db",
"gitiles_ref:refs/heads/master"
]
}
[19:04:50.668] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579547090092106",
"id": "8890718546418356224",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"88d96aba7a2740d116093fa4cc79ab5e89d068db\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579547090477008",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167603",
"builder:Linux Builder (dbg)",
"buildset:commit/git/88d96aba7a2740d116093fa4cc79ab5e89d068db",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/88d96aba7a2740d116093fa4cc79ab5e89d068db",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056148610157650416",
"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/8890718546418356224/+/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": "1579547090477131",
"url": "https://ci.chromium.org/b/8890718546418356224",
"utcnow_ts": "1579547090656980"
}
}
[19:04:50.668] Task URL: https://ci.chromium.org/b/8890718546418356224
[19:04:50.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:2:0) after 1m0s
[19:05:50.716] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:2:0)
[19:05:50.716] Timer tick, asking Buildbucket for the build status
[19:05:50.962] Build 8890718546418356224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:50.962] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:3:0) after 1m0s
[19:06:51.026] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:3:0)
[19:06:51.026] Timer tick, asking Buildbucket for the build status
[19:06:51.183] Build 8890718546418356224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:06:51.183] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:4:0) after 1m0s
[19:07:51.291] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:4:0)
[19:07:51.291] Timer tick, asking Buildbucket for the build status
[19:07:51.899] Build 8890718546418356224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:07:51.899] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:5:0) after 1m0s
[19:08:52.117] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:5:0)
[19:08:52.117] Timer tick, asking Buildbucket for the build status
[19:08:53.151] Build 8890718546418356224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:08:53.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:6:0) after 1m0s
[19:09:11.836] Received PubSub notification, asking Buildbucket for the build status
[19:09:11.992] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:53.173] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:6:0)
[19:09:53.173] Timer tick, asking Buildbucket for the build status
[19:09:54.542] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:54.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:8:0) after 1m0s
[19:10:54.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:8:0)
[19:10:54.562] Timer tick, asking Buildbucket for the build status
[19:10:54.748] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:54.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:9:0) after 1m0s
[19:11:54.765] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:9:0)
[19:11:54.765] Timer tick, asking Buildbucket for the build status
[19:11:54.927] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:54.927] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:10:0) after 1m0s
[19:12:54.979] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:10:0)
[19:12:54.979] Timer tick, asking Buildbucket for the build status
[19:12:55.511] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:55.511] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:11:0) after 1m0s
[19:13:55.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:11:0)
[19:13:55.565] Timer tick, asking Buildbucket for the build status
[19:13:55.696] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:55.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:12:0) after 1m0s
[19:14:55.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:12:0)
[19:14:55.720] Timer tick, asking Buildbucket for the build status
[19:14:55.939] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:55.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:13:0) after 1m0s
[19:15:55.975] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:13:0)
[19:15:55.975] Timer tick, asking Buildbucket for the build status
[19:15:56.104] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:56.104] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:14:0) after 1m0s
[19:16:56.156] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:14:0)
[19:16:56.156] Timer tick, asking Buildbucket for the build status
[19:16:57.179] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:57.179] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:15:0) after 1m0s
[19:17:57.218] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:15:0)
[19:17:57.218] Timer tick, asking Buildbucket for the build status
[19:17:57.428] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:57.428] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:16:0) after 1m0s
[19:18:57.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:16:0)
[19:18:57.562] Timer tick, asking Buildbucket for the build status
[19:18:57.812] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:57.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:17:0) after 1m0s
[19:19:57.860] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:17:0)
[19:19:57.860] Timer tick, asking Buildbucket for the build status
[19:19:58.258] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:58.258] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:18:0) after 1m0s
[19:20:58.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:18:0)
[19:20:58.282] Timer tick, asking Buildbucket for the build status
[19:21:00.095] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:00.095] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:19:0) after 1m0s
[19:22:00.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:19:0)
[19:22:00.166] Timer tick, asking Buildbucket for the build status
[19:22:00.311] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:00.311] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:20:0) after 1m0s
[19:23:00.759] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:20:0)
[19:23:00.759] Timer tick, asking Buildbucket for the build status
[19:23:00.972] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:00.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:21:0) after 1m0s
[19:24:01.011] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:21:0)
[19:24:01.011] Timer tick, asking Buildbucket for the build status
[19:24:01.563] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:01.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:22:0) after 1m0s
[19:25:01.625] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:22:0)
[19:25:01.625] Timer tick, asking Buildbucket for the build status
[19:25:01.821] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:01.821] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:23:0) after 1m0s
[19:26:02.073] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:23:0)
[19:26:02.073] Timer tick, asking Buildbucket for the build status
[19:26:02.342] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:02.342] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:24:0) after 1m0s
[19:27:02.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:24:0)
[19:27:02.433] Timer tick, asking Buildbucket for the build status
[19:27:02.752] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:02.752] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:25:0) after 1m0s
[19:28:03.682] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:25:0)
[19:28:03.682] Timer tick, asking Buildbucket for the build status
[19:28:03.817] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:03.817] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:26:0) after 1m0s
[19:29:03.857] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:26:0)
[19:29:03.857] Timer tick, asking Buildbucket for the build status
[19:29:04.142] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:04.142] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:27:0) after 1m0s
[19:30:04.241] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:27:0)
[19:30:04.241] Timer tick, asking Buildbucket for the build status
[19:30:04.694] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:04.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:28:0) after 1m0s
[19:31:04.764] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:28:0)
[19:31:04.764] Timer tick, asking Buildbucket for the build status
[19:31:05.447] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:05.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:29:0) after 1m0s
[19:32:05.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:29:0)
[19:32:05.540] Timer tick, asking Buildbucket for the build status
[19:32:05.868] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:05.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:30:0) after 1m0s
[19:33:05.923] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:30:0)
[19:33:05.923] Timer tick, asking Buildbucket for the build status
[19:33:06.149] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:06.149] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:31:0) after 1m0s
[19:34:06.167] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:31:0)
[19:34:06.167] Timer tick, asking Buildbucket for the build status
[19:34:06.382] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:06.382] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:32:0) after 1m0s
[19:35:06.411] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:32:0)
[19:35:06.411] Timer tick, asking Buildbucket for the build status
[19:35:07.008] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:07.008] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:33:0) after 1m0s
[19:36:07.067] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:33:0)
[19:36:07.067] Timer tick, asking Buildbucket for the build status
[19:36:07.408] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:07.408] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:34:0) after 1m0s
[19:37:07.482] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:34:0)
[19:37:07.482] Timer tick, asking Buildbucket for the build status
[19:37:07.666] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:07.666] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:35:0) after 1m0s
[19:38:07.692] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:35:0)
[19:38:07.692] Timer tick, asking Buildbucket for the build status
[19:38:08.214] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:08.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:36:0) after 1m0s
[19:39:08.271] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:36:0)
[19:39:08.271] Timer tick, asking Buildbucket for the build status
[19:39:08.472] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:08.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:37:0) after 1m0s
[19:40:08.500] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:37:0)
[19:40:08.500] Timer tick, asking Buildbucket for the build status
[19:40:08.677] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:08.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:38:0) after 1m0s
[19:41:08.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:38:0)
[19:41:08.701] Timer tick, asking Buildbucket for the build status
[19:41:08.947] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:08.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:39:0) after 1m0s
[19:42:09.028] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:39:0)
[19:42:09.028] Timer tick, asking Buildbucket for the build status
[19:42:09.231] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:09.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:40:0) after 1m0s
[19:43:09.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:40:0)
[19:43:09.223] Timer tick, asking Buildbucket for the build status
[19:43:09.353] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:09.353] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:41:0) after 1m0s
[19:44:09.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:41:0)
[19:44:09.379] Timer tick, asking Buildbucket for the build status
[19:44:09.535] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:09.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:42:0) after 1m0s
[19:45:09.688] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:42:0)
[19:45:09.688] Timer tick, asking Buildbucket for the build status
[19:45:09.908] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:09.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:43:0) after 1m0s
[19:46:09.928] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:43:0)
[19:46:09.928] Timer tick, asking Buildbucket for the build status
[19:46:10.148] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:10.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:44:0) after 1m0s
[19:47:10.180] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:44:0)
[19:47:10.181] Timer tick, asking Buildbucket for the build status
[19:47:10.360] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:10.360] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:45:0) after 1m0s
[19:48:10.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:45:0)
[19:48:10.381] Timer tick, asking Buildbucket for the build status
[19:48:10.573] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:10.573] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:46:0) after 1m0s
[19:49:10.648] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:46:0)
[19:49:10.648] Timer tick, asking Buildbucket for the build status
[19:49:10.793] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:10.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:47:0) after 1m0s
[19:50:10.808] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:47:0)
[19:50:10.808] Timer tick, asking Buildbucket for the build status
[19:50:11.005] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:11.005] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:48:0) after 1m0s
[19:51:11.055] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:48:0)
[19:51:11.056] Timer tick, asking Buildbucket for the build status
[19:51:11.189] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:11.190] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:49:0) after 1m0s
[19:52:11.336] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:49:0)
[19:52:11.336] Timer tick, asking Buildbucket for the build status
[19:52:11.843] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:11.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:50:0) after 1m0s
[19:53:11.929] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:50:0)
[19:53:11.929] Timer tick, asking Buildbucket for the build status
[19:53:12.336] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:12.336] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:51:0) after 1m0s
[19:54:12.396] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:51:0)
[19:54:12.396] Timer tick, asking Buildbucket for the build status
[19:54:12.541] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:12.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:52:0) after 1m0s
[19:55:12.549] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:52:0)
[19:55:12.549] Timer tick, asking Buildbucket for the build status
[19:55:12.943] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:12.943] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:53:0) after 1m0s
[19:56:12.992] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:53:0)
[19:56:12.992] Timer tick, asking Buildbucket for the build status
[19:56:13.162] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:13.162] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:54:0) after 1m0s
[19:57:13.190] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:54:0)
[19:57:13.190] Timer tick, asking Buildbucket for the build status
[19:57:13.758] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:13.758] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:55:0) after 1m0s
[19:58:13.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:55:0)
[19:58:13.775] Timer tick, asking Buildbucket for the build status
[19:58:13.960] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:13.960] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:56:0) after 1m0s
[19:59:14.052] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:56:0)
[19:59:14.052] Timer tick, asking Buildbucket for the build status
[19:59:14.338] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:14.338] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:57:0) after 1m0s
[20:00:14.513] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:57:0)
[20:00:14.513] Timer tick, asking Buildbucket for the build status
[20:00:14.694] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:14.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:58:0) after 1m0s
[20:01:14.788] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:58:0)
[20:01:14.788] Timer tick, asking Buildbucket for the build status
[20:01:15.090] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:15.090] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:59:0) after 1m0s
[20:02:15.235] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:59:0)
[20:02:15.235] Timer tick, asking Buildbucket for the build status
[20:02:15.681] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:15.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:60:0) after 1m0s
[20:03:15.731] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:60:0)
[20:03:15.731] Timer tick, asking Buildbucket for the build status
[20:03:15.929] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:15.929] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:61:0) after 1m0s
[20:04:16.115] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:61:0)
[20:04:16.115] Timer tick, asking Buildbucket for the build status
[20:04:17.822] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:17.822] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:62:0) after 1m0s
[20:05:17.980] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:62:0)
[20:05:17.980] Timer tick, asking Buildbucket for the build status
[20:05:18.146] Build 8890718546418356224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:18.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056148610157650416:63:0) after 1m0s
[20:05:31.702] Received PubSub notification, asking Buildbucket for the build status
[20:05:32.625] Build 8890718546418356224: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:05:32.625] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579550730777951",
"created_by": "project:chromium",
"created_ts": "1579547090092106",
"id": "8890718546418356224",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"88d96aba7a2740d116093fa4cc79ab5e89d068db\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-85-xub6\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167603, \"got_angle_revision\": \"0c10c9522a3b9fdce133bbf292c791989f240155\", \"got_dawn_revision\": \"d8f8c29bb25a5a1658da37aae05b9b4cd4353640\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"88d96aba7a2740d116093fa4cc79ab5e89d068db\", \"got_revision_cp\": \"refs/heads/master@{#733424}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"11cb891a01a2b47cc2135ef19ca18046662476e0\", \"got_v8_revision\": \"ebf1fafecf21a24b80552abb1bf17efb63b9a659\", \"got_v8_revision_cp\": \"refs/heads/8.1.227@{#1}\", \"got_webrtc_revision\": \"61380c09e2412eaab23c0b046991efc5a732b438\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30323}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"88d96aba7a2740d116093fa4cc79ab5e89d068db\", \"swarm_hashes_refs/heads/master(at){#733424}_without_patch\": {\"accessibility_unittests\": \"72736d83d37e6d10df472141c892d208c7166cd0\", \"angle_unittests\": \"a95f608f778ed2456c67cfce6817ae087c19a071\", \"app_shell_unittests\": \"8a3929f8d2fd3ef35cea01a66705192e585d9657\", \"aura_unittests\": \"2858b114a76d21343c32541d2039c38dcb24ebac\", \"base_unittests\": \"5cdb137f06e13c5ae90f8f11ab225db6345cfb3c\", \"base_util_unittests\": \"2c476ef8018323cf83cd412cf6c9e7d38723dd33\", \"blink_common_unittests\": \"e9e444d249d37939975a291c153bb9b826a119e0\", \"blink_fuzzer_unittests\": \"145cb766a0c070f64d2044cbe7b6316f25165ce3\", \"blink_heap_unittests\": \"a0b2f079176cda24bf22de2e9759d0375ef3a1e2\", \"blink_platform_unittests\": \"4d51da49009d996fe336d95a46ea5451bcd57c12\", \"blink_python_tests\": \"10ac6fb6d92d18d7bef8f0a4e42c10d300324382\", \"blink_unittests\": \"99fa96d5540079bfbbc5089708aa1029fef95bd8\", \"blink_web_tests\": \"b735f453568fd75ad1d0ba977433740531d013c4\", \"boringssl_crypto_tests\": \"c0261570eab9992dbde72c764192ad2ee4cb6e89\", \"boringssl_ssl_tests\": \"4883fd265ab7eef627385977c36427c3c4245bb0\", \"browser_tests\": \"ac26406cc123ffd4158117d562dda5393f520f08\", \"cacheinvalidation_unittests\": \"55a12d9bc6a6549482244993df5e7e4607a8b4c2\", \"capture_unittests\": \"d145788e0e96e0394f6cd5cb2057e6f3380abf62\", \"cast_unittests\": \"f796c167d31ddff86f8b5a980d7b2910d34ccc37\", \"cc_unittests\": \"7e95b12d38a692ddf3b5e18929b2f067debe3b1f\", \"chrome_app_unittests\": \"1b98718cc16e15815325bd0e6d763528d62130a6\", \"chromedriver_unittests\": \"cab1df8498a1e7871ad8ff23952e95d14695ea66\", \"components_browsertests\": \"7d4ff09a2f8967c7e0e11bfeb6db7c55465c310f\", \"components_unittests\": \"212a62dea5e70c890bb1f17bb93c96400c40196c\", \"compositor_unittests\": \"abd7b981fe3cb4c0ff9f86551b6ba6ab4fecffd1\", \"content_browsertests\": \"0194042b11ac92175f76d714dc0c9a001c4426fa\", \"content_shell_crash_test\": \"5bf9e7530c531503f693ac53d23642974ea49211\", \"content_unittests\": \"9d26cf8c98938d0678179c23b3ed77f1ab138bac\", \"crashpad_tests\": \"f970bded79c3f64390a0e5a33c9e4fa6669a7e55\", \"cronet_tests\": \"bb9bff7230d3331e919a4bfcf0b2636ed9fe64d9\", \"cronet_unittests\": \"89a529dcf94dc51061c6814e39df3b16af5a0a98\", \"crypto_unittests\": \"40baa854f07aead8960f155dc10df3db3721fae4\", \"dbus_unittests\": \"f2b64a851361a937dd0585602c3aeae4a9fe2a5b\", \"device_unittests\": \"bc7090351cfb8c7b46790727e86e7b2eef64ac9a\", \"display_unittests\": \"ca192f1ac2c26be63125a639b6e84e6702aac89e\", \"events_unittests\": \"cb026f4bc5cf7ff0bd6d0f990e0010722d5624d4\", \"extensions_browsertests\": \"fbfd3e5c3a4af802a9ad33a3b7ea7f5f2d138e59\", \"extensions_unittests\": \"1e0cab66b910fc8f45b21640b7ebf510226c39b8\", \"filesystem_service_unittests\": \"450e6457f1ebdb67756ecd02b334d27a782024cc\", \"flatbuffers_unittests\": \"92c174f535dbe8e15f58589d59dc88efc29a10d4\", \"gcm_unit_tests\": \"1133a4366710533319913abdf5c5da283eadc588\", \"gfx_unittests\": \"853a1a0b4cbed60e69e87e6c1f72ca0e36d6c276\", \"gin_unittests\": \"b6f9600a4904706ae2f300e0572084ec3b34e399\", \"google_apis_unittests\": \"348d21558cae960362e7ef9762f428c11a1be4f3\", \"gpu_unittests\": \"b3c841150e1f18c94a2e10cdac10eead22cb6c77\", \"grit_python_unittests\": \"5ba70f2d123ffe8977591ea0b82d6185e7fe166f\", \"gwp_asan_unittests\": \"77ec854313980c4136577d5ddb9e9e004338b5f4\", \"headless_browsertests\": \"c69de0d57d2d01d444d437ca378367fc2e5c463d\", \"headless_unittests\": \"8c61a55803d11d7e2552320d8ed99250266b52a6\", \"interactive_ui_tests\": \"3e3a976f1dd3e161a17c226e8be484edd4dcab20\", \"ipc_tests\": \"35d8a4114162225c469495e059e0643dc5b34b27\", \"jingle_unittests\": \"710187564e8be42933a324006009075640d823b5\", \"latency_unittests\": \"7f16e8567315d2185c6c8c4d429a695c51f4d25f\", \"libjingle_xmpp_unittests\": \"22fd244d5950718e24926b11e91d96e1011becdc\", \"media_blink_unittests\": \"817e999db7c3492a57abfa9f8b7852d2f75bb286\", \"media_service_unittests\": \"0201e7ae2693182dd140f1bdad60d3a9157a1527\", \"media_unittests\": \"5e560b41f75ea4e76711a1c076347e443311c151\", \"message_center_unittests\": \"017a2a95b95c02680ae213658a15adba713c4e8d\", \"metrics_python_tests\": \"f1a05dac527397880fe40926c47360eb26a5ecdb\", \"midi_unittests\": \"3d538bf649e622ad366da387b9bca41d7c143ea1\", \"mojo_core_unittests\": \"2c55f28f4dad5e2d0912274e2f0322589749a41e\", \"mojo_unittests\": \"1d2bf1c058098c3a267fcc8eda8e40c20fcb7893\", \"nacl_helper_nonsfi_unittests\": \"6735c2ba2776675cb36ec2ea164b9893d6cb1a03\", \"nacl_loader_unittests\": \"dc7167508f9f11951c24db331e6a4c094d6b4ecf\", \"native_theme_unittests\": \"cf8c1a8f4a72dd4a8e151480fd02110cf5c11ceb\", \"net_unittests\": \"17b12d358936dcebc789ef316eb86526a786e965\", \"pdf_unittests\": \"1e4496cd83cf5e18339ab1f50270ae28e0b96e88\", \"perfetto_unittests\": \"7246751fd2d5be1690362bd2433ea7dc9877e0d5\", \"ppapi_unittests\": \"d1ed7b46eb04c9db9a43c0932d2e8d9f59ca5ab8\", \"printing_unittests\": \"b0b58bc10dfbd13a4e309340c61f8026d239b69f\", \"remoting_unittests\": \"71d5f9be652856bfead68136f57575505061e39b\", \"sandbox_linux_unittests\": \"cab7a8a5ff5fe8347e9f908612406d1c70d7bfd4\", \"service_manager_unittests\": \"066f570bed34fecafc8c22867a6c9da27d4c2d90\", \"services_unittests\": \"a8accea237e9892c2e5428fdd645e9f46de1ae5e\", \"shell_dialogs_unittests\": \"d2d67ab59c6e08b8409b86a243bc05234c8bf77d\", \"skia_unittests\": \"2a1531c0679f68e3b5f81d9cfa221de88fe09736\", \"snapshot_unittests\": \"5eb5b285e594926c180f2e4a9aee0a4a83621455\", \"sql_unittests\": \"1ac35b1de20cd59dda8012286121764fd9f9dd09\", \"storage_unittests\": \"0a6d5ac0196e7bc00378efe1042b8ab5af56e5de\", \"sync_integration_tests\": \"651557954d09d521c00c73dd20009b5b67e2039e\", \"telemetry_gpu_unittests\": \"6d54fbae5064a23db8d16608bf47a25941cbcf4a\", \"telemetry_perf_unittests\": \"f8a20676a34f62534db94c8a0177b2400bbb0971\", \"telemetry_unittests\": \"d77cccea53db6356bd01009ee8030a30339d4575\", \"traffic_annotation_auditor_unittests\": \"47708cc8d84e3fe764647de88cea3ba789532d4f\", \"ui_base_unittests\": \"08912cb9e79f65fb5fc48ea0533de9ace369c29e\", \"ui_touch_selection_unittests\": \"3c91203a01726f2ed186629ed1cf19e23fe28b3e\", \"unit_tests\": \"c7de7fea75935e5bc24c6777e4fa2e1ccb0244da\", \"url_unittests\": \"889a4588360f89c8f248dd430777deb94ac76a09\", \"views_perftests\": \"4c62886b1915316e86b33716ad6a1869474abf96\", \"views_unittests\": \"c9c585f7d730501ca9920843a8f8e094e96a4fb3\", \"viz_unittests\": \"7be8ef45f2005d04e766cb3f3ec954742317792b\", \"vr_common_unittests\": \"e511cb6ce3810685939e66a8e6691944f17b527f\", \"vr_pixeltests\": \"fdac4ea37a440dac3b84e9fb94feb64cb5c5e62f\", \"webdriver_wpt_tests\": \"05e03c4dd443d1dc3076c1b80884687a5b28d158\", \"weblayer_browsertests\": \"34da5c185d94ce43239fa92f11a71c2f3bb4ff29\", \"wm_unittests\": \"2aa1063a512af62c978004e9d7cf477c12218b2a\", \"wtf_unittests\": \"2c9c9ff453fe010b256e2af955bdbe2e95d0f81b\", \"xr_browser_tests\": \"104ced40a88e20249ebad090a00f6bf683649bc0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1e71b8f30887124a6a932742f4d9c151dda7b5a73771d0a8da8aa2686aa32d51_v2\", \"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_v2\", \"builder_94dc7d486ab1bafc5bfb11010ef18f0cf20e8e07ae86b58c09f57ba294c280ac_v2\", \"builder_cf86ffd4f5ab8cfb78df26f2423c6469d25eebb822f3186294b467cd0c0284c5_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-85-xub6\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"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\": [\"4784-e3296b5\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579547351237274",
"status": "COMPLETED",
"status_changed_ts": "1579550731239972",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167603",
"builder:Linux Builder (dbg)",
"buildset:commit/git/88d96aba7a2740d116093fa4cc79ab5e89d068db",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/88d96aba7a2740d116093fa4cc79ab5e89d068db",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056148610157650416",
"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/8890718546418356224/+/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:49dd29674f320a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1579550731246742",
"url": "https://ci.chromium.org/b/8890718546418356224",
"utcnow_ts": "1579550732555045"
}
}
[20:05:32.625] Invocation finished in 1h0m45.482963581s with status SUCCEEDED