[01:07:20.333] New invocation is queued and will start shortly
[01:07:21.485] Starting the invocation (attempt 1)
[01:07:21.537] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:07:21.538] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:07:21.538] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9055219830645258960",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c667fc88e0a250442ca923287145fb4c2325a5ff\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9055219830645258960",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/c667fc88e0a250442ca923287145fb4c2325a5ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c667fc88e0a250442ca923287145fb4c2325a5ff",
"gitiles_ref:refs/heads/master"
]
}
[01:07:22.344] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1580432841696554",
"id": "8889789768544497024",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c667fc88e0a250442ca923287145fb4c2325a5ff\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580432842093837",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168055",
"builder:Linux Builder (dbg)",
"buildset:commit/git/c667fc88e0a250442ca923287145fb4c2325a5ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c667fc88e0a250442ca923287145fb4c2325a5ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055219830645258960",
"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/8889789768544497024/+/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": "1580432842093954",
"url": "https://ci.chromium.org/b/8889789768544497024",
"utcnow_ts": "1580432842317716"
}
}
[01:07:22.344] Task URL: https://ci.chromium.org/b/8889789768544497024
[01:07:22.344] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:2:0) after 1m0s
[01:08:22.515] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:2:0)
[01:08:22.515] Timer tick, asking Buildbucket for the build status
[01:08:24.935] Build 8889789768544497024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:24.935] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:3:0) after 1m0s
[01:09:25.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:3:0)
[01:09:25.033] Timer tick, asking Buildbucket for the build status
[01:09:25.213] Build 8889789768544497024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:25.213] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:4:0) after 1m0s
[01:10:25.243] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:4:0)
[01:10:25.243] Timer tick, asking Buildbucket for the build status
[01:10:25.433] Build 8889789768544497024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:25.433] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:5:0) after 1m0s
[01:11:25.486] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:5:0)
[01:11:25.486] Timer tick, asking Buildbucket for the build status
[01:11:26.412] Build 8889789768544497024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:26.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:6:0) after 1m0s
[01:12:05.570] Received PubSub notification, asking Buildbucket for the build status
[01:12:05.699] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:26.517] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:6:0)
[01:12:26.517] Timer tick, asking Buildbucket for the build status
[01:12:26.738] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:26.738] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:8:0) after 1m0s
[01:13:26.795] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:8:0)
[01:13:26.795] Timer tick, asking Buildbucket for the build status
[01:13:32.231] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:32.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:9:0) after 1m0s
[01:14:32.308] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:9:0)
[01:14:32.308] Timer tick, asking Buildbucket for the build status
[01:14:33.149] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:33.149] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:10:0) after 1m0s
[01:15:33.173] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:10:0)
[01:15:33.173] Timer tick, asking Buildbucket for the build status
[01:15:33.399] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:33.399] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:11:0) after 1m0s
[01:16:33.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:11:0)
[01:16:33.434] Timer tick, asking Buildbucket for the build status
[01:16:33.747] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:33.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:12:0) after 1m0s
[01:17:33.764] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:12:0)
[01:17:33.764] Timer tick, asking Buildbucket for the build status
[01:17:33.977] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:33.977] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:13:0) after 1m0s
[01:18:34.029] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:13:0)
[01:18:34.029] Timer tick, asking Buildbucket for the build status
[01:18:34.632] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:34.632] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:14:0) after 1m0s
[01:19:34.733] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:14:0)
[01:19:34.733] Timer tick, asking Buildbucket for the build status
[01:19:35.331] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:35.331] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:15:0) after 1m0s
[01:20:35.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:15:0)
[01:20:35.341] Timer tick, asking Buildbucket for the build status
[01:20:35.647] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:35.647] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:16:0) after 1m0s
[01:21:35.727] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:16:0)
[01:21:35.727] Timer tick, asking Buildbucket for the build status
[01:21:36.228] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:36.228] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:17:0) after 1m0s
[01:22:36.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:17:0)
[01:22:36.286] Timer tick, asking Buildbucket for the build status
[01:22:36.494] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:36.494] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:18:0) after 1m0s
[01:23:36.518] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:18:0)
[01:23:36.518] Timer tick, asking Buildbucket for the build status
[01:23:36.707] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:36.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:19:0) after 1m0s
[01:24:36.842] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:19:0)
[01:24:36.842] Timer tick, asking Buildbucket for the build status
[01:24:37.086] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:37.086] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:20:0) after 1m0s
[01:25:37.127] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:20:0)
[01:25:37.127] Timer tick, asking Buildbucket for the build status
[01:25:37.683] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:37.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:21:0) after 1m0s
[01:26:37.711] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:21:0)
[01:26:37.711] Timer tick, asking Buildbucket for the build status
[01:26:39.375] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:39.375] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:22:0) after 1m0s
[01:27:39.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:22:0)
[01:27:39.419] Timer tick, asking Buildbucket for the build status
[01:27:39.642] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:39.643] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:23:0) after 1m0s
[01:28:39.642] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:23:0)
[01:28:39.642] Timer tick, asking Buildbucket for the build status
[01:28:40.469] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:40.469] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:24:0) after 1m0s
[01:29:40.597] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:24:0)
[01:29:40.597] Timer tick, asking Buildbucket for the build status
[01:29:41.192] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:41.192] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:25:0) after 1m0s
[01:30:41.239] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:25:0)
[01:30:41.239] Timer tick, asking Buildbucket for the build status
[01:30:41.797] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:41.797] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:26:0) after 1m0s
[01:31:41.871] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:26:0)
[01:31:41.871] Timer tick, asking Buildbucket for the build status
[01:31:42.316] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:42.316] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:27:0) after 1m0s
[01:32:42.352] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:27:0)
[01:32:42.352] Timer tick, asking Buildbucket for the build status
[01:32:42.582] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:42.582] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:28:0) after 1m0s
[01:33:42.642] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:28:0)
[01:33:42.642] Timer tick, asking Buildbucket for the build status
[01:33:43.100] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:43.100] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:29:0) after 1m0s
[01:34:43.119] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:29:0)
[01:34:43.119] Timer tick, asking Buildbucket for the build status
[01:34:43.277] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:43.277] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:30:0) after 1m0s
[01:35:43.258] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:30:0)
[01:35:43.258] Timer tick, asking Buildbucket for the build status
[01:35:43.432] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:43.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:31:0) after 1m0s
[01:36:43.486] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:31:0)
[01:36:43.486] Timer tick, asking Buildbucket for the build status
[01:36:43.668] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:43.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:32:0) after 1m0s
[01:37:43.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:32:0)
[01:37:43.701] Timer tick, asking Buildbucket for the build status
[01:37:44.293] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:44.294] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:33:0) after 1m0s
[01:38:44.321] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:33:0)
[01:38:44.321] Timer tick, asking Buildbucket for the build status
[01:38:44.446] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:44.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:34:0) after 1m0s
[01:39:44.421] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:34:0)
[01:39:44.421] Timer tick, asking Buildbucket for the build status
[01:39:44.775] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:44.775] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:35:0) after 1m0s
[01:40:44.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:35:0)
[01:40:44.852] Timer tick, asking Buildbucket for the build status
[01:40:45.020] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:45.020] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:36:0) after 1m0s
[01:41:45.060] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:36:0)
[01:41:45.060] Timer tick, asking Buildbucket for the build status
[01:41:45.254] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:45.254] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:37:0) after 1m0s
[01:42:45.306] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:37:0)
[01:42:45.306] Timer tick, asking Buildbucket for the build status
[01:42:45.453] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:45.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:38:0) after 1m0s
[01:43:45.489] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:38:0)
[01:43:45.489] Timer tick, asking Buildbucket for the build status
[01:43:46.185] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:46.185] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:39:0) after 1m0s
[01:44:46.215] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:39:0)
[01:44:46.215] Timer tick, asking Buildbucket for the build status
[01:44:46.547] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:46.547] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:40:0) after 1m0s
[01:45:46.533] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:40:0)
[01:45:46.533] Timer tick, asking Buildbucket for the build status
[01:45:46.913] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:46.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:41:0) after 1m0s
[01:46:46.983] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:41:0)
[01:46:46.983] Timer tick, asking Buildbucket for the build status
[01:46:47.642] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:47.642] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:42:0) after 1m0s
[01:47:47.664] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:42:0)
[01:47:47.664] Timer tick, asking Buildbucket for the build status
[01:47:48.448] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:48.448] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:43:0) after 1m0s
[01:48:48.493] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:43:0)
[01:48:48.493] Timer tick, asking Buildbucket for the build status
[01:48:48.760] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:48.760] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:44:0) after 1m0s
[01:49:48.850] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:44:0)
[01:49:48.851] Timer tick, asking Buildbucket for the build status
[01:49:49.113] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:49.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:45:0) after 1m0s
[01:50:49.182] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:45:0)
[01:50:49.182] Timer tick, asking Buildbucket for the build status
[01:50:49.371] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:49.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:46:0) after 1m0s
[01:51:49.397] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:46:0)
[01:51:49.397] Timer tick, asking Buildbucket for the build status
[01:51:50.051] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:50.051] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:47:0) after 1m0s
[01:52:50.140] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:47:0)
[01:52:50.140] Timer tick, asking Buildbucket for the build status
[01:52:50.312] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:50.312] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:48:0) after 1m0s
[01:53:50.422] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:48:0)
[01:53:50.422] Timer tick, asking Buildbucket for the build status
[01:53:51.192] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:51.192] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:49:0) after 1m0s
[01:54:51.237] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:49:0)
[01:54:51.237] Timer tick, asking Buildbucket for the build status
[01:54:51.382] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:51.382] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:50:0) after 1m0s
[01:55:51.461] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:50:0)
[01:55:51.461] Timer tick, asking Buildbucket for the build status
[01:55:51.683] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:51.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:51:0) after 1m0s
[01:56:51.706] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:51:0)
[01:56:51.706] Timer tick, asking Buildbucket for the build status
[01:56:52.013] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:52.013] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:52:0) after 1m0s
[01:57:52.032] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:52:0)
[01:57:52.032] Timer tick, asking Buildbucket for the build status
[01:57:52.301] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:52.301] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:53:0) after 1m0s
[01:58:52.406] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:53:0)
[01:58:52.406] Timer tick, asking Buildbucket for the build status
[01:58:52.655] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:52.655] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:54:0) after 1m0s
[01:59:52.727] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:54:0)
[01:59:52.727] Timer tick, asking Buildbucket for the build status
[01:59:52.919] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:52.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:55:0) after 1m0s
[02:00:52.962] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:55:0)
[02:00:52.962] Timer tick, asking Buildbucket for the build status
[02:00:53.162] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:53.162] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:56:0) after 1m0s
[02:01:53.234] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:56:0)
[02:01:53.234] Timer tick, asking Buildbucket for the build status
[02:01:53.509] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:53.509] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:57:0) after 1m0s
[02:02:53.530] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:57:0)
[02:02:53.530] Timer tick, asking Buildbucket for the build status
[02:02:54.692] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:54.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:58:0) after 1m0s
[02:03:54.699] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:58:0)
[02:03:54.699] Timer tick, asking Buildbucket for the build status
[02:03:54.868] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:54.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:59:0) after 1m0s
[02:04:54.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:59:0)
[02:04:54.949] Timer tick, asking Buildbucket for the build status
[02:04:55.107] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:55.107] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:60:0) after 1m0s
[02:05:55.152] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:60:0)
[02:05:55.152] Timer tick, asking Buildbucket for the build status
[02:05:55.320] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:55.320] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:61:0) after 1m0s
[02:06:55.486] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:61:0)
[02:06:55.486] Timer tick, asking Buildbucket for the build status
[02:06:55.794] Build 8889789768544497024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:55.794] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055219830645258960:62:0) after 1m0s
[02:07:05.972] Received PubSub notification, asking Buildbucket for the build status
[02:07:06.162] Build 8889789768544497024: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:07:06.162] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1580436425053282",
"created_by": "project:chromium",
"created_ts": "1580432841696554",
"id": "8889789768544497024",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c667fc88e0a250442ca923287145fb4c2325a5ff\"}}",
"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-96-nza5\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168055, \"got_angle_revision\": \"c8e486b1df0d1d368640fde8894204b1744834fe\", \"got_dawn_revision\": \"1edaa1d1e18fcf59a86e7d0b6ce4812029c5dc20\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"c667fc88e0a250442ca923287145fb4c2325a5ff\", \"got_revision_cp\": \"refs/heads/master@{#737147}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"79b36b640692e3b4561642c06ec36a9e02a1636c\", \"got_v8_revision\": \"a4dcd39d521d14c4b1cac020812e44ee04a7f244\", \"got_v8_revision_cp\": \"refs/heads/8.1.307@{#1}\", \"got_webrtc_revision\": \"be99ee8f17f93e06c81e3deb4897dfa8253d3211\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30432}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c667fc88e0a250442ca923287145fb4c2325a5ff\", \"swarm_hashes_refs/heads/master(at){#737147}_without_patch\": {\"accessibility_unittests\": \"83b0c5933a0ce09fdf88d46e9eadb78cee519325\", \"angle_unittests\": \"b1514e27731db69d877182ec0838c002b4a50966\", \"app_shell_unittests\": \"8cb0791222b84af37f44b05d7cd89180f3c3cd24\", \"aura_unittests\": \"fc2d38db667c6ce419cb6ae89afd00aee276ec95\", \"base_unittests\": \"610959b8105b2bd7a5352cf1ba36e99d278ebb81\", \"base_util_unittests\": \"6d07741eee0daa7bc88b1fd7d60e8618de7061fd\", \"blink_common_unittests\": \"cdb85c884d7ac55f2ab9e771f380a3cb9716c742\", \"blink_fuzzer_unittests\": \"9ffbfb67bba83c029e9d8879dcf97f9dbef0cbf2\", \"blink_heap_unittests\": \"329dcc5937f0c4f587d5dcfe00beab9e972b4951\", \"blink_platform_unittests\": \"75e815b143d9ba1f826b1352dfceb0d4ffdd435e\", \"blink_python_tests\": \"ce32dbbaa26df4d941259bd790f93cb51e2f6e12\", \"blink_unittests\": \"7fc56ade07592e8ce930f91060fa756b1fb50b71\", \"blink_web_tests\": \"2b921f364759e328b06b07f670526b6563421fcb\", \"boringssl_crypto_tests\": \"3222ca278d13285ced5be197a3f453f26b7044ef\", \"boringssl_ssl_tests\": \"29f6572b6f92b307ee82125f2cc34ecd9cea1670\", \"browser_tests\": \"7103dbe90b6d5bc45af8091e45468760762b63df\", \"cacheinvalidation_unittests\": \"fada50e0dc05a3ae7f1d1e1eb2bc1f468a03712b\", \"capture_unittests\": \"a0280b2c218f0e8ec26d564f2c48a0b02eca4850\", \"cast_unittests\": \"38190d1a7e317b56f43d33815220ddd55009fe12\", \"cc_unittests\": \"6dd8e5f59d381dfbcc6a359328d74bb62205138c\", \"chrome_app_unittests\": \"197d36240e1c82dc7ea78a6c3bdeaa73c7c4e7ab\", \"chromedriver_unittests\": \"40bb03d8d661db9a5dc9bbada96f44ca8af6271e\", \"components_browsertests\": \"7622e92b204238a5d7063e542dcadf163908d164\", \"components_unittests\": \"2e490e58aeb1d4b00493beb974379975b29ec453\", \"compositor_unittests\": \"4e51912eefb20cf15f4ffa242af4c6594d786e48\", \"content_browsertests\": \"e6d7d893a9ed22528b81af08773bc3948888a7a7\", \"content_shell_crash_test\": \"a0d2929b222d9c378f20831189a74626994f3319\", \"content_unittests\": \"d7eb722e1fefba797abc5225240993e284da5dab\", \"crashpad_tests\": \"1d4284d1e48dde984f35493c125eefd6836a4073\", \"cronet_tests\": \"37bd57a73e73a66d9862fec853ddf2961142516d\", \"cronet_unittests\": \"8c1fa85c058d03c5e2680bc6122fb9561ed4d6b3\", \"crypto_unittests\": \"0f3e8d0550b6ed84e5712470abeebc18fbd77dc8\", \"dbus_unittests\": \"19e4306e2c7d33932c62fdfc111f48ac20bde380\", \"device_unittests\": \"473f7aad0bb76cf867760ea37e7fdc136385e2ff\", \"display_unittests\": \"ca4629628026988eabf07e998e525ac067616035\", \"events_unittests\": \"5be90a6c4c35b1c704531b39a1a7046739931605\", \"extensions_browsertests\": \"8a59725fc2b9cd57358cfb527ef42278577eb711\", \"extensions_unittests\": \"d0e0d5b3ba1d072d50f9feee30c79b161102bac3\", \"filesystem_service_unittests\": \"745912b699134a67fd88b4d245dd6367142352ad\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"0e80766459b2bf7506a4875cd44dc33b90fedbd3\", \"gfx_unittests\": \"3edd0ffafa326efb2c9446874a83f2e29a10962e\", \"gin_unittests\": \"d689ade7ddf5deeb823d535e5907e9505191e1ed\", \"google_apis_unittests\": \"764e52aa2ac36364108f934df66a670515841a1c\", \"gpu_unittests\": \"e6c226a7994051223ee5a7b6a483d235ac1fbb48\", \"grit_python_unittests\": \"a906de1211aa1e6d0b6deb8a9a162d6998fb6336\", \"gwp_asan_unittests\": \"a219f951a2ea1e12500c396ee85569cbc6b4d630\", \"headless_browsertests\": \"f10981b495c6501c2911a05da7ab05fd1c48e766\", \"headless_unittests\": \"ddcf48740e46493b045632d6263ec1576d89cd90\", \"interactive_ui_tests\": \"af21077fe2cd3bda89175d5099462b3f28ef158e\", \"ipc_tests\": \"27bb7d4884414141f4d182e4ddd3b3f4e31235e2\", \"jingle_unittests\": \"3ddd0af3f17c1f5da1e699011879476d1f25c5bc\", \"latency_unittests\": \"94f972c1aab7a45355c1c1e890762ad850fbe093\", \"libjingle_xmpp_unittests\": \"8010d73cb514ceefcff76d968f491461fc2c29c4\", \"media_blink_unittests\": \"fb6ce0a81b20785ef85c921abc2c99d3bc3f9f32\", \"media_service_unittests\": \"18f96ae0f184697501f2643c2060a4cf0da50ea1\", \"media_unittests\": \"fb01cb5c5caae614f6fe0b97f645ce9494f1fcbb\", \"message_center_unittests\": \"b1deb247d1dd7a9a85ddd0630d6ef4a9b6f6b591\", \"metrics_python_tests\": \"d3dc06dd5df3ba5de6f1a73b20cd1feeca05677b\", \"midi_unittests\": \"7a67d9cbe875a69e53a1769507525c0ad6e1abf5\", \"mojo_core_unittests\": \"63ee51d8af7ee96f76904cb57bcac184f7e04dc5\", \"mojo_unittests\": \"8fe4e0e6481437f190d9778adc07790a5a0b47c4\", \"nacl_helper_nonsfi_unittests\": \"c0ae4bc711b8d990c3bc1755836e2285ac94d182\", \"nacl_loader_unittests\": \"7eaab0f58517103010bc0a6766869b5049e6f391\", \"native_theme_unittests\": \"f81a689827f5492f3ad2294525b36ab19d68a7d5\", \"net_unittests\": \"acf9a8c10b42de30d640d3a148b1d3c6361456ee\", \"pdf_unittests\": \"fa9a5c37d67be04a09570dee16d6dac6ea7c737a\", \"perfetto_unittests\": \"8ce89422c77322b1a7f6d6c0b4c40dbdb8814e5c\", \"ppapi_unittests\": \"81957ad6a905d362531391b904c64137815b6ae6\", \"printing_unittests\": \"423dee6a54be2311d358a1cf1a562369ecf05bd6\", \"remoting_unittests\": \"f2d1a3947e41e21896bccbd9b5acdd23368277cd\", \"sandbox_linux_unittests\": \"c7c1d9154e48f1e84f89f7c31eb65c0ae4ed6519\", \"service_manager_unittests\": \"d200c0643b15c1c1e5d86f2ef78af05641e4e599\", \"services_unittests\": \"7b107a320a43c4bcb3c8b532074875223d085645\", \"shell_dialogs_unittests\": \"729c52e8767d08f9b62a0031894de71f447b1180\", \"skia_unittests\": \"836e773a9d041ea01d464f7284f711f34cbb075b\", \"snapshot_unittests\": \"65f829e3f3de8dfb90fc070c092694d8f9532fbc\", \"sql_unittests\": \"3eeb9b3873b39e075f1ff09a7cfa63ccb0af9808\", \"storage_unittests\": \"ca20389cf24510ac179f0280552560943f68fd43\", \"sync_integration_tests\": \"f3ece84442281a6f16c8cc8e199777b99d1583fa\", \"telemetry_gpu_unittests\": \"27b50e4a5112431faf247a1a0353a70f5925f3ed\", \"telemetry_perf_unittests\": \"3e9ac90250beccf2251d0436cdd65702ad62f237\", \"telemetry_unittests\": \"9ffaf011ced5d67c06e40edf14803f2a6482b493\", \"traffic_annotation_auditor_unittests\": \"9edf1978878f11d757baaafb0f124c7ce3157ff8\", \"ui_base_unittests\": \"3858d83186e8bcf48bd68fdb273446609b5eaa7e\", \"ui_touch_selection_unittests\": \"084ea03c5ec498e4e130ca99ad4bcd9c23286340\", \"unit_tests\": \"eaab0537f483e956b518723d4175fc753301238c\", \"url_unittests\": \"eef5be172fd0a2d39d52bb825864172fcd499892\", \"views_perftests\": \"8900bd70445ea91c019178a587cd58b87156f6c2\", \"views_unittests\": \"b9f334a43fee82236c6ae8e3c9a66e88b2b633ff\", \"viz_unittests\": \"fe47c09def5b7dbac97f8643c41fb7c8eeae9d55\", \"vr_common_unittests\": \"59a27c9595158bfd2e0e0304d97cced7f3a8faf0\", \"vr_pixeltests\": \"8cc13a7296ed094f20fa76a972ce94295e7ba488\", \"webdriver_wpt_tests\": \"55799776303de304fd9ae536cdffc3f669a1d48f\", \"weblayer_browsertests\": \"5fd439bfc764aeac1b37ca4aa7804a22bd083cf0\", \"wm_unittests\": \"39c3647ddea025c9ee671831bc4cd2ab87472bf9\", \"wtf_unittests\": \"d90a516a4e739243122bfc605bc6de8951a1e19f\", \"xr_browser_tests\": \"238b64d3e151f9d00a58701508fbd9f619f8f751\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3b0e28eebaae607a111b3d14afaf4a38944aa5084a33cb6cff32b5c412399a3d_v2\", \"builder_3e75b41c9bcf12a22209c03b338cce1fafa12941d3074ed1e1ed2450e6b71594_v2\", \"builder_562e40d9a0016a631bf2d4b73416a93abb069b959f020aa1a03e04f54d5ec620_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_bf333895421fefa78318d8049b60faf5c230ceed823f417948b15e1b2d972d6c_v2\", \"builder_dbb86507a6c3fadd4cf4e1827f5f960c6207016e30a949100a00998d3583e722_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-96-nza5\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4804-5c544e0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580433122385101",
"status": "COMPLETED",
"status_changed_ts": "1580436425487024",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168055",
"builder:Linux Builder (dbg)",
"buildset:commit/git/c667fc88e0a250442ca923287145fb4c2325a5ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c667fc88e0a250442ca923287145fb4c2325a5ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055219830645258960",
"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/8889789768544497024/+/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:4a11f4e6f92fc310",
"user_agent:luci-scheduler"
],
"updated_ts": "1580436425490572",
"url": "https://ci.chromium.org/b/8889789768544497024",
"utcnow_ts": "1580436426151604"
}
}
[02:07:06.162] Invocation finished in 59m45.853435855s with status SUCCEEDED