[02:34:33.933] New invocation is queued and will start shortly
[02:34:35.003] Starting the invocation (attempt 1)
[02:34:35.042] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:34:35.042] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:34:35.042] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054761357977027520",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5fc05e2346e535d21125aa0c2c791df3542b9579\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054761357977027520",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/5fc05e2346e535d21125aa0c2c791df3542b9579",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5fc05e2346e535d21125aa0c2c791df3542b9579",
"gitiles_ref:refs/heads/master"
]
}
[02:34:35.793] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580870075178560",
"id": "8889331296008445824",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5fc05e2346e535d21125aa0c2c791df3542b9579\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580870075627541",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168238",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5fc05e2346e535d21125aa0c2c791df3542b9579",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5fc05e2346e535d21125aa0c2c791df3542b9579",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054761357977027520",
"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/8889331296008445824/+/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": "1580870075627639",
"url": "https://ci.chromium.org/b/8889331296008445824",
"utcnow_ts": "1580870075769495"
}
}
[02:34:35.793] Task URL: https://ci.chromium.org/b/8889331296008445824
[02:34:35.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:2:0) after 1m0s
[02:35:35.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:2:0)
[02:35:35.852] Timer tick, asking Buildbucket for the build status
[02:35:38.102] Build 8889331296008445824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:38.102] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:3:0) after 1m0s
[02:36:38.126] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:3:0)
[02:36:38.126] Timer tick, asking Buildbucket for the build status
[02:36:38.612] Build 8889331296008445824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:38.612] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:4:0) after 1m0s
[02:37:38.694] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:4:0)
[02:37:38.694] Timer tick, asking Buildbucket for the build status
[02:37:39.446] Build 8889331296008445824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:39.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:5:0) after 1m0s
[02:38:39.502] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:5:0)
[02:38:39.502] Timer tick, asking Buildbucket for the build status
[02:38:40.592] Build 8889331296008445824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:40.592] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:6:0) after 1m0s
[02:39:34.684] Received PubSub notification, asking Buildbucket for the build status
[02:39:34.824] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:40.641] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:6:0)
[02:39:40.641] Timer tick, asking Buildbucket for the build status
[02:39:41.484] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:41.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:8:0) after 1m0s
[02:40:41.527] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:8:0)
[02:40:41.527] Timer tick, asking Buildbucket for the build status
[02:40:42.071] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:42.071] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:9:0) after 1m0s
[02:41:42.142] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:9:0)
[02:41:42.142] Timer tick, asking Buildbucket for the build status
[02:41:42.305] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:42.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:10:0) after 1m0s
[02:42:42.380] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:10:0)
[02:42:42.380] Timer tick, asking Buildbucket for the build status
[02:42:43.018] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:43.018] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:11:0) after 1m0s
[02:43:43.112] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:11:0)
[02:43:43.112] Timer tick, asking Buildbucket for the build status
[02:43:43.359] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:43.359] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:12:0) after 1m0s
[02:44:43.385] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:12:0)
[02:44:43.385] Timer tick, asking Buildbucket for the build status
[02:44:43.607] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:43.608] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:13:0) after 1m0s
[02:45:43.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:13:0)
[02:45:43.593] Timer tick, asking Buildbucket for the build status
[02:45:43.934] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:43.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:14:0) after 1m0s
[02:46:43.991] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:14:0)
[02:46:43.991] Timer tick, asking Buildbucket for the build status
[02:46:44.250] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:44.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:15:0) after 1m0s
[02:47:44.248] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:15:0)
[02:47:44.248] Timer tick, asking Buildbucket for the build status
[02:47:45.536] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:45.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:16:0) after 1m0s
[02:48:45.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:16:0)
[02:48:45.606] Timer tick, asking Buildbucket for the build status
[02:48:45.864] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:45.864] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:17:0) after 1m0s
[02:49:45.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:17:0)
[02:49:45.953] Timer tick, asking Buildbucket for the build status
[02:49:46.129] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:46.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:18:0) after 1m0s
[02:50:46.233] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:18:0)
[02:50:46.233] Timer tick, asking Buildbucket for the build status
[02:50:46.418] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:46.418] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:19:0) after 1m0s
[02:51:46.450] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:19:0)
[02:51:46.450] Timer tick, asking Buildbucket for the build status
[02:51:46.716] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:46.716] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:20:0) after 1m0s
[02:52:46.715] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:20:0)
[02:52:46.715] Timer tick, asking Buildbucket for the build status
[02:52:47.029] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:47.029] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:21:0) after 1m0s
[02:53:47.071] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:21:0)
[02:53:47.071] Timer tick, asking Buildbucket for the build status
[02:53:48.202] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:48.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:22:0) after 1m0s
[02:54:48.220] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:22:0)
[02:54:48.220] Timer tick, asking Buildbucket for the build status
[02:54:48.454] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:48.454] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:23:0) after 1m0s
[02:55:48.619] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:23:0)
[02:55:48.619] Timer tick, asking Buildbucket for the build status
[02:55:48.769] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:48.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:24:0) after 1m0s
[02:56:48.932] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:24:0)
[02:56:48.932] Timer tick, asking Buildbucket for the build status
[02:56:49.182] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:49.182] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:25:0) after 1m0s
[02:57:49.337] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:25:0)
[02:57:49.337] Timer tick, asking Buildbucket for the build status
[02:57:50.076] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:50.076] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:26:0) after 1m0s
[02:58:50.176] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:26:0)
[02:58:50.176] Timer tick, asking Buildbucket for the build status
[02:58:51.605] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:51.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:27:0) after 1m0s
[02:59:51.663] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:27:0)
[02:59:51.663] Timer tick, asking Buildbucket for the build status
[02:59:52.069] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:52.069] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:28:0) after 1m0s
[03:00:52.183] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:28:0)
[03:00:52.183] Timer tick, asking Buildbucket for the build status
[03:00:52.475] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:52.475] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:29:0) after 1m0s
[03:01:52.609] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:29:0)
[03:01:52.609] Timer tick, asking Buildbucket for the build status
[03:01:52.827] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:52.827] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:30:0) after 1m0s
[03:02:52.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:30:0)
[03:02:52.900] Timer tick, asking Buildbucket for the build status
[03:02:53.324] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:53.324] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:31:0) after 1m0s
[03:03:53.366] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:31:0)
[03:03:53.366] Timer tick, asking Buildbucket for the build status
[03:03:53.786] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:53.786] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:32:0) after 1m0s
[03:04:53.807] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:32:0)
[03:04:53.807] Timer tick, asking Buildbucket for the build status
[03:04:54.013] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:54.013] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:33:0) after 1m0s
[03:05:54.089] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:33:0)
[03:05:54.089] Timer tick, asking Buildbucket for the build status
[03:05:54.530] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:54.530] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:34:0) after 1m0s
[03:06:54.640] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:34:0)
[03:06:54.640] Timer tick, asking Buildbucket for the build status
[03:06:55.514] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:55.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:35:0) after 1m0s
[03:07:55.663] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:35:0)
[03:07:55.663] Timer tick, asking Buildbucket for the build status
[03:07:55.919] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:55.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:36:0) after 1m0s
[03:08:55.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:36:0)
[03:08:55.943] Timer tick, asking Buildbucket for the build status
[03:08:56.178] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:56.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:37:0) after 1m0s
[03:09:56.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:37:0)
[03:09:56.205] Timer tick, asking Buildbucket for the build status
[03:10:05.397] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:05.397] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:38:0) after 1m0s
[03:11:05.559] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:38:0)
[03:11:05.559] Timer tick, asking Buildbucket for the build status
[03:11:05.975] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:05.975] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:39:0) after 1m0s
[03:12:06.162] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:39:0)
[03:12:06.162] Timer tick, asking Buildbucket for the build status
[03:12:06.698] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:06.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:40:0) after 1m0s
[03:13:06.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:40:0)
[03:13:06.915] Timer tick, asking Buildbucket for the build status
[03:13:07.348] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:07.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:41:0) after 1m0s
[03:14:07.613] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:41:0)
[03:14:07.613] Timer tick, asking Buildbucket for the build status
[03:14:07.821] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:07.821] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:42:0) after 1m0s
[03:15:07.869] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:42:0)
[03:15:07.869] Timer tick, asking Buildbucket for the build status
[03:15:08.434] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:08.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:43:0) after 1m0s
[03:16:08.528] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:43:0)
[03:16:08.528] Timer tick, asking Buildbucket for the build status
[03:16:10.926] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:10.926] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:44:0) after 1m0s
[03:17:10.988] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:44:0)
[03:17:10.988] Timer tick, asking Buildbucket for the build status
[03:17:11.256] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:11.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:45:0) after 1m0s
[03:18:11.310] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:45:0)
[03:18:11.310] Timer tick, asking Buildbucket for the build status
[03:18:11.593] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:11.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:46:0) after 1m0s
[03:19:11.676] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:46:0)
[03:19:11.676] Timer tick, asking Buildbucket for the build status
[03:19:11.894] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:11.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:47:0) after 1m0s
[03:20:11.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:47:0)
[03:20:11.966] Timer tick, asking Buildbucket for the build status
[03:20:12.982] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:12.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:48:0) after 1m0s
[03:21:13.071] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:48:0)
[03:21:13.071] Timer tick, asking Buildbucket for the build status
[03:21:14.217] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:14.217] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:49:0) after 1m0s
[03:22:14.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:49:0)
[03:22:14.205] Timer tick, asking Buildbucket for the build status
[03:22:15.041] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:15.041] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:50:0) after 1m0s
[03:23:15.149] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:50:0)
[03:23:15.149] Timer tick, asking Buildbucket for the build status
[03:23:15.556] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:15.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:51:0) after 1m0s
[03:24:15.547] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:51:0)
[03:24:15.547] Timer tick, asking Buildbucket for the build status
[03:24:15.910] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:15.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:52:0) after 1m0s
[03:25:16.025] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:52:0)
[03:25:16.025] Timer tick, asking Buildbucket for the build status
[03:25:16.787] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:16.787] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:53:0) after 1m0s
[03:26:16.843] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:53:0)
[03:26:16.843] Timer tick, asking Buildbucket for the build status
[03:26:17.150] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:17.150] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:54:0) after 1m0s
[03:27:17.209] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:54:0)
[03:27:17.209] Timer tick, asking Buildbucket for the build status
[03:27:17.544] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:17.544] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:55:0) after 1m0s
[03:28:17.613] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:55:0)
[03:28:17.613] Timer tick, asking Buildbucket for the build status
[03:28:18.002] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:18.002] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:56:0) after 1m0s
[03:29:17.988] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:56:0)
[03:29:17.988] Timer tick, asking Buildbucket for the build status
[03:29:18.195] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:18.195] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:57:0) after 1m0s
[03:30:18.262] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:57:0)
[03:30:18.262] Timer tick, asking Buildbucket for the build status
[03:30:18.517] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:18.517] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:58:0) after 1m0s
[03:31:18.571] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:58:0)
[03:31:18.571] Timer tick, asking Buildbucket for the build status
[03:31:19.094] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:19.094] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:59:0) after 1m0s
[03:32:19.116] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:59:0)
[03:32:19.116] Timer tick, asking Buildbucket for the build status
[03:32:19.829] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:19.829] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:60:0) after 1m0s
[03:33:19.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:60:0)
[03:33:19.802] Timer tick, asking Buildbucket for the build status
[03:33:20.372] Build 8889331296008445824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:20.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054761357977027520:61:0) after 1m0s
[03:34:09.980] Received PubSub notification, asking Buildbucket for the build status
[03:34:11.374] Build 8889331296008445824: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:34:11.374] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580873649080352",
"created_by": "project:chromium",
"created_ts": "1580870075178560",
"id": "8889331296008445824",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5fc05e2346e535d21125aa0c2c791df3542b9579\"}}",
"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-76-mprw\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168238, \"got_angle_revision\": \"d8c4b5fb80d417343b52273515e046a37eda7138\", \"got_dawn_revision\": \"47b15048a1fab6c360dc1594764506224655752b\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"5fc05e2346e535d21125aa0c2c791df3542b9579\", \"got_revision_cp\": \"refs/heads/master@{#738413}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"59a29e3c4f030bc4a835a8cb8ebdefaa1f58b74d\", \"got_v8_revision\": \"4460b2d2f5aa0f77cfd10a6c2301a95ad08aa9a5\", \"got_v8_revision_cp\": \"refs/heads/8.2.8@{#1}\", \"got_webrtc_revision\": \"0f6bcd18b2ef109850c6430fa0d474688571da5f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30455}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5fc05e2346e535d21125aa0c2c791df3542b9579\", \"swarm_hashes_refs/heads/master(at){#738413}_without_patch\": {\"accessibility_unittests\": \"909bd17f8b2f1f9d2c8a3276c8e82b488ec4e4bf\", \"angle_unittests\": \"b6267a6088aa32cef9cdb7abbaf881e03095fe8c\", \"app_shell_unittests\": \"122fb3200022385c0b7e8ea03388064b0d515ac0\", \"aura_unittests\": \"06b64db7ee88b1c7b138b2def5bae4971eccfdbe\", \"base_unittests\": \"a29af277288587a8797dfa0db01345b3d8a9f959\", \"base_util_unittests\": \"8818a9773a30bd7c33cdec37a93518b2382f934c\", \"blink_common_unittests\": \"64e4c7bb9bb5e4627b7dcc0a1c56a4720ab9b9cb\", \"blink_fuzzer_unittests\": \"07fb927ed8a551687a79ed07e5379e6866200289\", \"blink_heap_unittests\": \"a96a3250d46d457d55b6355a66e311cc5720c098\", \"blink_platform_unittests\": \"e4c6dfef2e83ed2d46a56774233734aaa06917ba\", \"blink_python_tests\": \"c61fe259e89d0bce1e53a4bb5f518d62b4a77c4a\", \"blink_unittests\": \"7ce73764c95624c18e8f7ffc014deb69932972e2\", \"blink_web_tests\": \"9da4a37a64a97c8dd462d0e89ca30f8e7eb1b1a9\", \"boringssl_crypto_tests\": \"647acdbbf37dcb1c568c903868bcd7d855eb8d3d\", \"boringssl_ssl_tests\": \"2d20c1e4e0accd9c244ab60965347348f2ba0707\", \"browser_tests\": \"4ef2acaf5e4dd0de81a08861f75dbc270cafbbb5\", \"cacheinvalidation_unittests\": \"fb78258f28fc3710799ce5f9fc563eff7a407fe6\", \"capture_unittests\": \"c65f4fd40da54954dd2230de348ec9bfd980fcbd\", \"cast_unittests\": \"1427df5ee5ca58b342bf9252ffb9092b41db28a8\", \"cc_unittests\": \"97920df90e283b89bdcc422b1da16a8c52b46143\", \"chrome_app_unittests\": \"9bd6df84c18d7321e90802e700c36927f487b551\", \"chromedriver_unittests\": \"d919dd1ccecb8c71e8d5ee87d5f46eef6161a6b1\", \"components_browsertests\": \"0e418faa5c3a51d81c2f426dda21fe28996944eb\", \"components_unittests\": \"96a4ef411f9b4f1e809fdbce8f32d6640fcbfc38\", \"compositor_unittests\": \"9326ff2f1d2a4be4eaca11b66d960aedde56f56c\", \"content_browsertests\": \"cc0badaa795b2958e3b3a8294f468a0106090ed9\", \"content_shell_crash_test\": \"ed45f593ff344652ee6662ec91106cfc768dcb34\", \"content_unittests\": \"7181550da7e97824b0d8ed1428cf5ec74539baec\", \"crashpad_tests\": \"0af122a680e0eff26626a05740cfdd0e49ba9701\", \"cronet_tests\": \"7f0218e638d1650780cf1a632870eaf20c7ed7a0\", \"cronet_unittests\": \"991cfabacdcf9bfb5bdcedfdfddcda56a14f0d45\", \"crypto_unittests\": \"71311b088be815360ef6cc6ea5930edb34e2864b\", \"dbus_unittests\": \"873e817e19dbdc5f49bf65aaf57c76570748dbc7\", \"device_unittests\": \"5017d3a0286c960d8fb809c8e1006e8b285a753a\", \"display_unittests\": \"77126d035ab0e1445b0f7f193cd561b405f678be\", \"events_unittests\": \"6aca479ce94b0a6ebe303eac16e733af27ea39e3\", \"extensions_browsertests\": \"935ffb7f2a91d058ff8da7aede70c85e36180e8d\", \"extensions_unittests\": \"9ebd98d041642996db3ae27536e63301ff6987e2\", \"filesystem_service_unittests\": \"b0a591725b30861059f66c29bec20ea10ffabe20\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"c63a26ecf69ab9ca6a8305547ddca501b1945dc7\", \"gfx_unittests\": \"7abb1de26efb8ab4713f4611981c2eaf79435c35\", \"gin_unittests\": \"c61de02267715647bd180f3f4569bee0096ef33c\", \"google_apis_unittests\": \"5ccc92fee472ecd0737e185315d0d28b742c5087\", \"gpu_unittests\": \"2ada24332b720b914015bf652da168ba81e0fa05\", \"grit_python_unittests\": \"a906de1211aa1e6d0b6deb8a9a162d6998fb6336\", \"gwp_asan_unittests\": \"d2d57150bbed91c14f6cd4b17df0b9b078449849\", \"headless_browsertests\": \"88db48ff18d82452e5d8d843c5237b8fe3f5f44d\", \"headless_unittests\": \"7716abb5bbf7c56ac612234d367a1f694df3fb11\", \"interactive_ui_tests\": \"eb6fb57a32034653427c6a06554cf1ffb073bb79\", \"ipc_tests\": \"cfe36b2091665f65012b28c0be60dfcd4f53ae9b\", \"jingle_unittests\": \"64b6c09a62f1b33376675535a7079223054fd5da\", \"latency_unittests\": \"1b40675bd1b4227317f8af26abe47285acaa6b72\", \"libjingle_xmpp_unittests\": \"b21105dfc49bfddbe16ed3253a504d764e5c0117\", \"media_blink_unittests\": \"9dd2b91a81af440298f5455e4f2c548b4c4e0b11\", \"media_service_unittests\": \"f983dafeb56920436bc84935627783fa337509ed\", \"media_unittests\": \"e4a6238579d2526e34f6134df092d86c0a2161ed\", \"message_center_unittests\": \"3ccbb739374a258158e2272db291c60a4d4bed2d\", \"metrics_python_tests\": \"6ffd47aa0207eaec005f17ca3d9ad7e20fe57bf2\", \"midi_unittests\": \"7e3f5c99666bf77e63aec67ff345b1bafbc81bd8\", \"mojo_core_unittests\": \"cea2055bc973787f0b09972f913f38b422a730ee\", \"mojo_unittests\": \"da5fa93ca1be955993a695bcce2d4614b27c9e1b\", \"nacl_helper_nonsfi_unittests\": \"e41c27281be29d84e9ff3af4423bbc0a75c448f2\", \"nacl_loader_unittests\": \"a5a09c6362ccb3cdf26a7eabde881eebad4e98e7\", \"native_theme_unittests\": \"78a45f7594ff012d0ed377eeacce8be5a9915d9b\", \"net_unittests\": \"2c5a3efa42b5f0933a2bb730e6e48f1694672e65\", \"pdf_unittests\": \"2309f8a241b2fc9c91a62738d6e281a4446bd7e3\", \"perfetto_unittests\": \"5e25f8e87928db75e8b7bc6b9f1d06a79e2433ea\", \"ppapi_unittests\": \"3b06d54317b791c3e4b8d3cb71c7db06e9a6f90d\", \"printing_unittests\": \"854f792edeb3e2d936f8fd77bb4f64b80fbda6fd\", \"remoting_unittests\": \"7289d693603f9eba7b93c2043cbab9c90aff2886\", \"sandbox_linux_unittests\": \"f077ad62754d18572a2ee9ce2d3d872a65c6e5a6\", \"service_manager_unittests\": \"5f85cbf865fbb9c39c8d50795231f0cabb77770f\", \"services_unittests\": \"d55bd3a44068214fe65741c5ff7cd909b7ddb6e9\", \"shell_dialogs_unittests\": \"c559c37ce4c3a648cc1870eb50ed2e3baf7b505a\", \"skia_unittests\": \"970e72409bf262cdcd45240e1f2e9e7371d02da3\", \"snapshot_unittests\": \"f290f4671f808d706169e1528e9c1fd7a230f0cf\", \"sql_unittests\": \"a3b4ee81735b81745ef66d3f2cdb4792e51234d2\", \"storage_unittests\": \"4644f3bfaa57b825f601e976619ebbf5fc9b221b\", \"sync_integration_tests\": \"6e99d36a43f92fa4485a0b56aafa817e11cf044d\", \"telemetry_gpu_unittests\": \"c1f57660435661fcb8e5c7d4964b1429853cf545\", \"telemetry_perf_unittests\": \"6ce77a7ff1fdd9387f9518c4845574f7c3782f38\", \"telemetry_unittests\": \"b25d7eb604baa8f415474fcc6a2d047badb00d7b\", \"traffic_annotation_auditor_unittests\": \"a64efba7506677f55710f63ccb9d10a260be97de\", \"ui_base_unittests\": \"3c6323a786905425178729757f8d8b7d2d40b35c\", \"ui_touch_selection_unittests\": \"594589ebea544b76f922480da44e70d9445589ff\", \"unit_tests\": \"2f88c4e9507e6b1e1c9ef9ebf136096a6597aeb9\", \"url_unittests\": \"2035252b66fdc3f8e161f54fbb61ecf28f0b621e\", \"views_perftests\": \"ca935bc98899538f04d0da5b2f70e81165e5ad60\", \"views_unittests\": \"ea45770f9672d726891d135f33a543525fc79048\", \"viz_unittests\": \"49d7724850eada7991a397740a6e205a3522d4a2\", \"vr_common_unittests\": \"42312cc9386db48fe8f1adbeb4e249f3a9dae029\", \"vr_pixeltests\": \"898b7067510aec064fb6b766910510c08b8c5645\", \"webdriver_wpt_tests\": \"9c8a6c2d3bff0db1cb91efd42b4e2a082d7545e8\", \"weblayer_browsertests\": \"f4adc44562074074ef995ef01f1eae1ea80b2971\", \"wm_unittests\": \"7d18a2f72c5287e952dd9e3cd0709cca1a322c6b\", \"wtf_unittests\": \"43966aecb6ac23de51aa7ef81baea7b6920562de\", \"xr_browser_tests\": \"91e743f96ee31a14c2e736527bb301c8e909bd6a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2dd6a1af21400b3785138e4c8a94108d5525f829439b094d94efcf967177507f_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_v2\", \"builder_f5b4454e99a04aaa5ba743b59cc70de19e20bebedef65e50e3b90534a8d6a5e4_v2\", \"builder_fee9fca6ae81433eeb1d42efab0a28c36a6729b3802c0b4c1b597e1d52e72868_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-76-mprw\"], \"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\": [\"4809-bb2013c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580870373927103",
"status": "COMPLETED",
"status_changed_ts": "1580873649498810",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168238",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5fc05e2346e535d21125aa0c2c791df3542b9579",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5fc05e2346e535d21125aa0c2c791df3542b9579",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054761357977027520",
"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/8889331296008445824/+/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:4a2c048dc975c010",
"user_agent:luci-scheduler"
],
"updated_ts": "1580873649539743",
"url": "https://ci.chromium.org/b/8889331296008445824",
"utcnow_ts": "1580873651365126"
}
}
[03:34:11.374] Invocation finished in 59m37.456825386s with status SUCCEEDED