[08:49:24.148] New invocation is queued and will start shortly
[08:49:25.305] Starting the invocation (attempt 1)
[08:49:25.388] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:49:25.388] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:49:25.388] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053378820788821280",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a2ffa578dabcc2329b3bc9d1c97021cd11d35a54\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053378820788821280",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/a2ffa578dabcc2329b3bc9d1c97021cd11d35a54",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a2ffa578dabcc2329b3bc9d1c97021cd11d35a54",
"gitiles_ref:refs/heads/master"
]
}
[08:49:25.994] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582188565492040",
"id": "8887948758708960576",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a2ffa578dabcc2329b3bc9d1c97021cd11d35a54\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582188565884994",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168818",
"builder:Linux Builder (dbg)",
"buildset:commit/git/a2ffa578dabcc2329b3bc9d1c97021cd11d35a54",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a2ffa578dabcc2329b3bc9d1c97021cd11d35a54",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053378820788821280",
"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/8887948758708960576/+/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": "1582188565885126",
"url": "https://ci.chromium.org/b/8887948758708960576",
"utcnow_ts": "1582188565986666"
}
}
[08:49:25.994] Task URL: https://ci.chromium.org/b/8887948758708960576
[08:49:25.994] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:2:0) after 1m0s
[08:50:26.204] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:2:0)
[08:50:26.204] Timer tick, asking Buildbucket for the build status
[08:50:26.332] Build 8887948758708960576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:50:26.332] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:3:0) after 1m0s
[08:51:26.401] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:3:0)
[08:51:26.401] Timer tick, asking Buildbucket for the build status
[08:51:26.511] Build 8887948758708960576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:51:26.511] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:4:0) after 1m0s
[08:52:26.600] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:4:0)
[08:52:26.600] Timer tick, asking Buildbucket for the build status
[08:52:26.938] Build 8887948758708960576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:52:26.938] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:5:0) after 1m0s
[08:53:27.257] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:5:0)
[08:53:27.257] Timer tick, asking Buildbucket for the build status
[08:53:27.358] Build 8887948758708960576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:53:27.358] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:6:0) after 1m0s
[08:54:03.811] Received PubSub notification, asking Buildbucket for the build status
[08:54:03.979] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:27.405] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:6:0)
[08:54:27.405] Timer tick, asking Buildbucket for the build status
[08:54:27.477] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:27.477] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:8:0) after 1m0s
[08:55:27.627] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:8:0)
[08:55:27.627] Timer tick, asking Buildbucket for the build status
[08:55:27.868] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:27.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:9:0) after 1m0s
[08:56:27.905] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:9:0)
[08:56:27.905] Timer tick, asking Buildbucket for the build status
[08:56:28.013] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:28.013] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:10:0) after 1m0s
[08:57:28.026] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:10:0)
[08:57:28.026] Timer tick, asking Buildbucket for the build status
[08:57:28.226] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:28.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:11:0) after 1m0s
[08:58:28.263] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:11:0)
[08:58:28.263] Timer tick, asking Buildbucket for the build status
[08:58:28.477] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:28.477] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:12:0) after 1m0s
[08:59:28.498] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:12:0)
[08:59:28.498] Timer tick, asking Buildbucket for the build status
[08:59:28.550] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:28.550] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:13:0) after 1m0s
[09:00:28.569] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:13:0)
[09:00:28.569] Timer tick, asking Buildbucket for the build status
[09:00:28.792] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:28.792] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:14:0) after 1m0s
[09:01:28.815] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:14:0)
[09:01:28.815] Timer tick, asking Buildbucket for the build status
[09:01:28.913] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:28.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:15:0) after 1m0s
[09:02:28.968] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:15:0)
[09:02:28.968] Timer tick, asking Buildbucket for the build status
[09:02:29.224] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:29.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:16:0) after 1m0s
[09:03:29.241] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:16:0)
[09:03:29.241] Timer tick, asking Buildbucket for the build status
[09:03:29.407] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:29.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:17:0) after 1m0s
[09:04:29.512] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:17:0)
[09:04:29.512] Timer tick, asking Buildbucket for the build status
[09:04:29.750] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:29.750] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:18:0) after 1m0s
[09:05:29.790] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:18:0)
[09:05:29.790] Timer tick, asking Buildbucket for the build status
[09:05:30.305] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:30.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:19:0) after 1m0s
[09:06:30.451] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:19:0)
[09:06:30.451] Timer tick, asking Buildbucket for the build status
[09:06:30.692] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:30.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:20:0) after 1m0s
[09:07:30.840] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:20:0)
[09:07:30.840] Timer tick, asking Buildbucket for the build status
[09:07:30.945] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:30.945] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:21:0) after 1m0s
[09:08:31.135] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:21:0)
[09:08:31.135] Timer tick, asking Buildbucket for the build status
[09:08:31.965] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:31.965] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:22:0) after 1m0s
[09:09:32.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:22:0)
[09:09:32.033] Timer tick, asking Buildbucket for the build status
[09:09:32.180] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:32.180] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:23:0) after 1m0s
[09:10:32.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:23:0)
[09:10:32.399] Timer tick, asking Buildbucket for the build status
[09:10:32.781] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:32.781] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:24:0) after 1m0s
[09:11:32.952] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:24:0)
[09:11:32.952] Timer tick, asking Buildbucket for the build status
[09:11:33.131] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:33.131] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:25:0) after 1m0s
[09:12:33.388] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:25:0)
[09:12:33.388] Timer tick, asking Buildbucket for the build status
[09:12:33.560] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:33.560] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:26:0) after 1m0s
[09:13:33.674] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:26:0)
[09:13:33.674] Timer tick, asking Buildbucket for the build status
[09:13:33.792] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:33.792] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:27:0) after 1m0s
[09:14:33.879] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:27:0)
[09:14:33.879] Timer tick, asking Buildbucket for the build status
[09:14:34.157] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:34.157] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:28:0) after 1m0s
[09:15:34.237] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:28:0)
[09:15:34.237] Timer tick, asking Buildbucket for the build status
[09:15:34.625] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:34.625] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:29:0) after 1m0s
[09:16:34.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:29:0)
[09:16:34.769] Timer tick, asking Buildbucket for the build status
[09:16:34.970] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:34.970] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:30:0) after 1m0s
[09:17:35.068] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:30:0)
[09:17:35.068] Timer tick, asking Buildbucket for the build status
[09:17:35.241] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:35.242] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:31:0) after 1m0s
[09:18:35.306] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:31:0)
[09:18:35.306] Timer tick, asking Buildbucket for the build status
[09:18:35.495] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:35.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:32:0) after 1m0s
[09:19:35.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:32:0)
[09:19:35.587] Timer tick, asking Buildbucket for the build status
[09:19:35.755] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:35.756] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:33:0) after 1m0s
[09:20:35.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:33:0)
[09:20:35.810] Timer tick, asking Buildbucket for the build status
[09:20:35.974] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:35.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:34:0) after 1m0s
[09:21:36.106] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:34:0)
[09:21:36.106] Timer tick, asking Buildbucket for the build status
[09:21:36.371] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:36.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:35:0) after 1m0s
[09:22:36.488] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:35:0)
[09:22:36.488] Timer tick, asking Buildbucket for the build status
[09:22:36.682] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:36.682] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:36:0) after 1m0s
[09:23:36.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:36:0)
[09:23:36.741] Timer tick, asking Buildbucket for the build status
[09:23:37.165] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:37.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:37:0) after 1m0s
[09:24:37.184] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:37:0)
[09:24:37.184] Timer tick, asking Buildbucket for the build status
[09:24:37.393] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:37.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:38:0) after 1m0s
[09:25:37.471] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:38:0)
[09:25:37.471] Timer tick, asking Buildbucket for the build status
[09:25:37.569] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:37.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:39:0) after 1m0s
[09:26:37.605] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:39:0)
[09:26:37.605] Timer tick, asking Buildbucket for the build status
[09:26:37.698] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:37.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:40:0) after 1m0s
[09:27:37.705] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:40:0)
[09:27:37.705] Timer tick, asking Buildbucket for the build status
[09:27:37.998] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:37.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:41:0) after 1m0s
[09:28:38.024] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:41:0)
[09:28:38.024] Timer tick, asking Buildbucket for the build status
[09:28:38.141] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:38.141] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:42:0) after 1m0s
[09:29:38.173] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:42:0)
[09:29:38.173] Timer tick, asking Buildbucket for the build status
[09:29:38.394] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:38.394] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:43:0) after 1m0s
[09:30:38.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:43:0)
[09:30:38.415] Timer tick, asking Buildbucket for the build status
[09:30:39.033] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:39.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:44:0) after 1m0s
[09:31:39.012] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:44:0)
[09:31:39.012] Timer tick, asking Buildbucket for the build status
[09:31:39.138] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:39.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:45:0) after 1m0s
[09:32:39.162] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:45:0)
[09:32:39.162] Timer tick, asking Buildbucket for the build status
[09:32:39.259] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:39.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:46:0) after 1m0s
[09:33:39.235] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:46:0)
[09:33:39.235] Timer tick, asking Buildbucket for the build status
[09:33:39.460] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:39.460] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:47:0) after 1m0s
[09:34:39.473] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:47:0)
[09:34:39.473] Timer tick, asking Buildbucket for the build status
[09:34:39.570] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:39.570] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:48:0) after 1m0s
[09:35:39.635] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:48:0)
[09:35:39.635] Timer tick, asking Buildbucket for the build status
[09:35:39.979] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:39.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:49:0) after 1m0s
[09:36:40.045] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:49:0)
[09:36:40.045] Timer tick, asking Buildbucket for the build status
[09:36:40.186] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:40.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:50:0) after 1m0s
[09:37:40.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:50:0)
[09:37:40.309] Timer tick, asking Buildbucket for the build status
[09:37:40.755] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:40.755] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:51:0) after 1m0s
[09:38:40.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:51:0)
[09:38:40.844] Timer tick, asking Buildbucket for the build status
[09:38:41.523] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:41.523] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:52:0) after 1m0s
[09:39:41.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:52:0)
[09:39:41.543] Timer tick, asking Buildbucket for the build status
[09:39:41.780] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:41.780] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:53:0) after 1m0s
[09:40:41.812] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:53:0)
[09:40:41.812] Timer tick, asking Buildbucket for the build status
[09:40:42.152] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:42.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:54:0) after 1m0s
[09:41:42.212] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:54:0)
[09:41:42.212] Timer tick, asking Buildbucket for the build status
[09:41:42.532] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:42.532] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:55:0) after 1m0s
[09:42:42.584] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:55:0)
[09:42:42.584] Timer tick, asking Buildbucket for the build status
[09:42:42.946] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:42.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:56:0) after 1m0s
[09:43:42.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:56:0)
[09:43:42.984] Timer tick, asking Buildbucket for the build status
[09:43:43.823] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:43.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:57:0) after 1m0s
[09:44:43.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:57:0)
[09:44:43.805] Timer tick, asking Buildbucket for the build status
[09:44:43.921] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:43.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:58:0) after 1m0s
[09:45:43.935] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:58:0)
[09:45:43.935] Timer tick, asking Buildbucket for the build status
[09:45:44.832] Build 8887948758708960576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:44.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053378820788821280:59:0) after 1m0s
[09:46:19.033] Received PubSub notification, asking Buildbucket for the build status
[09:46:19.101] Build 8887948758708960576: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:46:19.101] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582191977508656",
"created_by": "project:chromium",
"created_ts": "1582188565492040",
"id": "8887948758708960576",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a2ffa578dabcc2329b3bc9d1c97021cd11d35a54\"}}",
"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-39-2tvi\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168818, \"got_angle_revision\": \"2801bf43716e8642efe6132c50d2a1708756bba8\", \"got_dawn_revision\": \"b330ea6b491e3e000c11fe919e8099dece1c939b\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"a2ffa578dabcc2329b3bc9d1c97021cd11d35a54\", \"got_revision_cp\": \"refs/heads/master@{#743015}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"74d3f371f2015deeedb985a8b24ba242ea65caef\", \"got_v8_revision\": \"07a0ee92dfd89a66d57b9fb242d90e33607e3c44\", \"got_v8_revision_cp\": \"refs/heads/8.2.120@{#1}\", \"got_webrtc_revision\": \"1282babe6621d3a2084bbd24e77652e0b3a4eb93\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30549}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a2ffa578dabcc2329b3bc9d1c97021cd11d35a54\", \"swarm_hashes_refs/heads/master(at){#743015}_without_patch\": {\"accessibility_unittests\": \"343563695525563f414b30064b84a7810a71081a\", \"angle_unittests\": \"7ea3cc25d0256ef058b558e084dbc4f438bb17d2\", \"app_shell_unittests\": \"ff5e877964e727fb65dfc989bfbf2890b2aba763\", \"aura_unittests\": \"d1cf3be039f6d85bc3dbd89e571d341ad13a4a1a\", \"base_unittests\": \"b42cddc5c21bfdf069687a59159d66dbe48ae145\", \"base_util_unittests\": \"774aa7bbcc06277cee27287eb6c31a57094fbfd8\", \"blink_common_unittests\": \"a7606abadaf89192fb237b0497e43e21e9ce8697\", \"blink_fuzzer_unittests\": \"76bf1caab5de40f8a064f2a4b272ed34ad002612\", \"blink_heap_unittests\": \"11648ae6503b6d1d7c9cbc42d893017cc1d8b274\", \"blink_platform_unittests\": \"ce4aba73b1262af02fcc9469eddc81da57288b77\", \"blink_python_tests\": \"bd1d509966ec727df1fe0a559b478df5717ced0e\", \"blink_unittests\": \"03af6e04ba1c2f64069a8d0b8fc2d6e786b3db1b\", \"blink_web_tests\": \"20a79ae3e7e06b9cfa03571204f35aa647bcbc67\", \"boringssl_crypto_tests\": \"e134738b0b11b0c76b1f6ae86b8180418508349a\", \"boringssl_ssl_tests\": \"2f79b72cc9bea4cf1dfbeab03c32b34daa6e9708\", \"browser_tests\": \"d1df77faa9d93cc6840728a03b673f102b480423\", \"cacheinvalidation_unittests\": \"58101dc47a4877ae1278842a018e0c70e368c9f7\", \"capture_unittests\": \"37f72ea1362fcc6c8cc97b5b834c980a760b75de\", \"cast_unittests\": \"a59c9501ba853bd1ad579e9a24e15659c89a163f\", \"cc_unittests\": \"7f7280ced2dd8e4a472ae34cd396fe99a3c0a0c1\", \"chrome_app_unittests\": \"78066680af2e654918a87a42e2568b4d9280bc5f\", \"chromedriver_unittests\": \"fcca1045d35ed3c2aa599c03cef5ef5729b327bc\", \"components_browsertests\": \"89b1ca94de9c3e2a9aee12e8278b6fde1652c175\", \"components_unittests\": \"c3be63902c04a3c381de5bb22c7a1b1f6134831d\", \"compositor_unittests\": \"8903c21494683e7876ea3c056a2379868116a37f\", \"content_browsertests\": \"2064896c93b23c0736c5dcffbfd3b0a6a7dd2a60\", \"content_shell_crash_test\": \"c039c60cef9cc0ace3d791cfe3ab78b8c28970a2\", \"content_unittests\": \"2dcb8e0b4f6a623ad1e06dc3b7cd3ef0b57af503\", \"crashpad_tests\": \"50f14793322b6fe88b1bc4a19d554cfa98dbc2eb\", \"cronet_tests\": \"6040dd193179c0581694b844e64763b179a21ef7\", \"cronet_unittests\": \"0e9dd50a2d201eab03d6fe0e6fbe4a1a3399a28c\", \"crypto_unittests\": \"1a3c1bb87b9adc899c71c6a674e750c4eda2e0f1\", \"dbus_unittests\": \"cf3f5200c3b9aa67fa6545afbcd65a15038bf01a\", \"device_unittests\": \"225b01ec465f8db8e326639d5fab6e8e5f96ee14\", \"display_unittests\": \"1326cf8dea1e5d96b728fe90419ee7c0999b8471\", \"events_unittests\": \"60417deb7f8254f38b886f0058443a209e095d70\", \"extensions_browsertests\": \"e9172141bb7dce51f818754e87a2cc2a9b54ab38\", \"extensions_unittests\": \"8fc2bac6d7773e11969741ad1011e24d9bfb0695\", \"filesystem_service_unittests\": \"780f1a45c5bdc4701e5dffb029dfb7fee802e12f\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"2dfceda7e6deda703414ee51315cd78c7d3f8c53\", \"gfx_unittests\": \"5fdfd5ccf8446139c574333ccb48bdf8c0fbcc68\", \"gin_unittests\": \"8a7808d309805a8c18d91f4c68ff2c836b8daa7e\", \"google_apis_unittests\": \"a4168a4babaae941ed4418b594364ac55ef57772\", \"gpu_unittests\": \"b1572e6f268be9780709a9b9801e20f7c81ad662\", \"grit_python_unittests\": \"0a186e54539d3475bae4b9d19cc134d9a92e2108\", \"gwp_asan_unittests\": \"6542385fbcebfd1876b97303d131d873ed011186\", \"headless_browsertests\": \"14e7e473b5b82d65999cef309c3f37445cec7a6f\", \"headless_unittests\": \"2da117d6a2b9afb6ccc71be9050b16266d5869d5\", \"interactive_ui_tests\": \"70d788b48c2a748c1dc6276f5af382273fa3c029\", \"ipc_tests\": \"5bb7a33f733b3225c33db733723b0a5767bde059\", \"jingle_unittests\": \"daea307b2c79043aca43d7b42d998a7f4e4c8cb0\", \"latency_unittests\": \"56664df2b0def09627fcd99c1565d91a211ef4af\", \"libjingle_xmpp_unittests\": \"e183bbe4b8b650b9813dfb4bb9da021d1cae30d1\", \"media_blink_unittests\": \"a34195e269b38cb5df3da275501f41b549011595\", \"media_service_unittests\": \"3daf6399a7d6350939413a6787dcee0e34af3b05\", \"media_unittests\": \"745af551f0410ba65241f3ef24f9916579954166\", \"message_center_unittests\": \"d897729e53659ae41e301775360b3f0f0ea1aee5\", \"metrics_python_tests\": \"18f8cad0c2a04a82b72da55ebfc931333db4a631\", \"midi_unittests\": \"75a5c69196df26799a72832114641b5f2dde2071\", \"mojo_core_unittests\": \"c54fd327134f787dbcd0e014c81323c81985d8fc\", \"mojo_unittests\": \"8d0bc14a0d429ce1bad6384c04701066bcf2af0f\", \"nacl_helper_nonsfi_unittests\": \"d7b6fde31300c1648322130abdddac1f8e56aade\", \"nacl_loader_unittests\": \"643d49999704a5d837bdb06e36ef68fd4a12ba46\", \"native_theme_unittests\": \"b5842ccd5ef723f4a2d3f931e3e9b58ac1309ca6\", \"net_unittests\": \"971fb3c1f9809703c625ad994c44461bf248db19\", \"pdf_unittests\": \"d1aff12ecdb15c17d20f9866f6ecee2b6bca5a3f\", \"perfetto_unittests\": \"7c5b7624fbfce2391fe9943e375f96c4099e3ada\", \"ppapi_unittests\": \"234adf82c93dfdaaf57e8297a9eceacd4671a3ab\", \"printing_unittests\": \"ee8fffbca7fb44a58f34a9875144a2eae89ada38\", \"remoting_unittests\": \"688a723b656cd9a62f5e5f26d9c9aed57a3b8231\", \"sandbox_linux_unittests\": \"235180178d48bbdb5576d9f9634c38e2de9cb175\", \"service_manager_unittests\": \"b19988a2364f8c0691dbabb4cfa7db8395ceca68\", \"services_unittests\": \"b1352934ac77d8a530d20d2fae6e2a8fda019502\", \"shell_dialogs_unittests\": \"ce43f11fd15a8da9e142fed311952b63aac42305\", \"skia_unittests\": \"a8af4eeee1efa7ea8524a575160d870636c4e146\", \"snapshot_unittests\": \"a51d284c523a94c48f8f32147a892afb462ef691\", \"sql_unittests\": \"d2c42f946372cab6379d7672c3ee8a8f0c9433a4\", \"storage_unittests\": \"45bc112a242d35214affbc27564f206b14cc687c\", \"sync_integration_tests\": \"cac97ac9d10d3f9809193ebdf90a82dbf247853a\", \"telemetry_gpu_unittests\": \"514749f66c0f6cae0eef5c717b747ae10051856d\", \"telemetry_perf_unittests\": \"f7a3efc7f631e74d47a64937315cc03dc2d64861\", \"telemetry_unittests\": \"f4194f00bcee8a7c9ffb3de96fef141dd7246625\", \"traffic_annotation_auditor_unittests\": \"a05ced982179bafc2cfd127ff1613aa752832926\", \"ui_base_unittests\": \"32b382c004497cf2d82b55ef7f88e4c863ae4b18\", \"ui_touch_selection_unittests\": \"8e58f0808c9e36af2943811dd24ae6c64b5ef8d1\", \"unit_tests\": \"174bc627822e2bd4eedd8d7c7f827f11aee6a527\", \"url_unittests\": \"4eb3d41f2f4782909567e9d4d00548491f2b886b\", \"views_perftests\": \"36297659d52bc25887dce6caccdf253aa2255246\", \"views_unittests\": \"f15e5c5c4444314ac16410d881e2c92941f6e531\", \"viz_unittests\": \"12b72182dfd86d74b41528e10f1ee9277546879c\", \"vr_common_unittests\": \"e767dabfa1397568936521d97968780cbe956e39\", \"vr_pixeltests\": \"94a029d30fb6d47e51cf3f1d0f427372c61952db\", \"webdriver_wpt_tests\": \"e7c00eddd8d64b3f4ad0f41ec8f4e3488a66bb3e\", \"weblayer_browsertests\": \"f0fb4ab72abc78d2c7ed2a971ce9eae79501e418\", \"wm_unittests\": \"414c5674e2914501640531487119bc00e99375a4\", \"wtf_unittests\": \"e9f599977c68623398966711202500e126c34be5\", \"xr_browser_tests\": \"6669d471b06be7cad761494154fbaad9a60c2cf2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_980e79f61a9eb10b3ecd5f710f08f0308a5299da9889db3d2b6a7010c019cc1f_v2\", \"builder_9abb531f9fa28fcfd3ce68b2370bf6d67201ddb083beddbd2671f7c5fe25be9e_v2\", \"builder_a9a41095618141e4757e131cf792803c0a27ecf4e01eb5d0d372c002309e764f_v2\", \"builder_bf60de3a2a061c3c4c67ad9afdde23d2342e42cdda6e8a4003b52312ca24fd40_v2\", \"builder_cfdb55a1f65ed072d1fe07f7fc1107a97a5e848a8d1b6b0015401ab521f6b501_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-39-2tvi\"], \"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\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582188843200984",
"status": "COMPLETED",
"status_changed_ts": "1582191977975204",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168818",
"builder:Linux Builder (dbg)",
"buildset:commit/git/a2ffa578dabcc2329b3bc9d1c97021cd11d35a54",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a2ffa578dabcc2329b3bc9d1c97021cd11d35a54",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053378820788821280",
"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/8887948758708960576/+/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:4a7a9b1ee0641a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1582191978024053",
"url": "https://ci.chromium.org/b/8887948758708960576",
"utcnow_ts": "1582191979092015"
}
}
[09:46:19.101] Invocation finished in 56m54.976081943s with status SUCCEEDED