[16:34:18.386] New invocation is queued and will start shortly
[16:34:19.658] Starting the invocation (attempt 1)
[16:34:19.716] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:34:19.716] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:34:19.717] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054164944252998576",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"caa3c767efc46a066784dfeddb61e461e4bb2bb1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054164944252998576",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/caa3c767efc46a066784dfeddb61e461e4bb2bb1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/caa3c767efc46a066784dfeddb61e461e4bb2bb1",
"gitiles_ref:refs/heads/master"
]
}
[16:34:20.330] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581438859856503",
"id": "8888734882046336400",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"caa3c767efc46a066784dfeddb61e461e4bb2bb1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581438860139820",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168488",
"builder:Linux Builder (dbg)",
"buildset:commit/git/caa3c767efc46a066784dfeddb61e461e4bb2bb1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/caa3c767efc46a066784dfeddb61e461e4bb2bb1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054164944252998576",
"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/8888734882046336400/+/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": "1581438860139931",
"url": "https://ci.chromium.org/b/8888734882046336400",
"utcnow_ts": "1581438860320756"
}
}
[16:34:20.332] Task URL: https://ci.chromium.org/b/8888734882046336400
[16:34:20.332] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:2:0) after 1m0s
[16:35:20.385] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:2:0)
[16:35:20.385] Timer tick, asking Buildbucket for the build status
[16:35:21.139] Build 8888734882046336400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:35:21.139] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:3:0) after 1m0s
[16:36:21.195] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:3:0)
[16:36:21.195] Timer tick, asking Buildbucket for the build status
[16:36:21.348] Build 8888734882046336400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:36:21.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:4:0) after 1m0s
[16:37:21.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:4:0)
[16:37:21.399] Timer tick, asking Buildbucket for the build status
[16:37:21.707] Build 8888734882046336400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:37:21.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:5:0) after 1m0s
[16:38:21.719] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:5:0)
[16:38:21.719] Timer tick, asking Buildbucket for the build status
[16:38:21.869] Build 8888734882046336400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:38:21.869] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:6:0) after 1m0s
[16:39:04.589] Received PubSub notification, asking Buildbucket for the build status
[16:39:04.713] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:21.962] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:6:0)
[16:39:21.962] Timer tick, asking Buildbucket for the build status
[16:39:22.114] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:22.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:8:0) after 1m0s
[16:40:22.197] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:8:0)
[16:40:22.197] Timer tick, asking Buildbucket for the build status
[16:40:22.334] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:22.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:9:0) after 1m0s
[16:41:22.388] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:9:0)
[16:41:22.388] Timer tick, asking Buildbucket for the build status
[16:41:22.524] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:22.524] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:10:0) after 1m0s
[16:42:22.621] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:10:0)
[16:42:22.621] Timer tick, asking Buildbucket for the build status
[16:42:23.157] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:23.157] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:11:0) after 1m0s
[16:43:23.217] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:11:0)
[16:43:23.217] Timer tick, asking Buildbucket for the build status
[16:43:23.930] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:23.930] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:12:0) after 1m0s
[16:44:23.928] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:12:0)
[16:44:23.928] Timer tick, asking Buildbucket for the build status
[16:44:24.101] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:24.101] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:13:0) after 1m0s
[16:45:24.122] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:13:0)
[16:45:24.122] Timer tick, asking Buildbucket for the build status
[16:45:24.963] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:24.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:14:0) after 1m0s
[16:46:25.038] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:14:0)
[16:46:25.039] Timer tick, asking Buildbucket for the build status
[16:46:25.561] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:25.561] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:15:0) after 1m0s
[16:47:25.591] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:15:0)
[16:47:25.591] Timer tick, asking Buildbucket for the build status
[16:47:25.963] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:25.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:16:0) after 1m0s
[16:48:26.036] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:16:0)
[16:48:26.036] Timer tick, asking Buildbucket for the build status
[16:48:26.489] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:26.489] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:17:0) after 1m0s
[16:49:26.580] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:17:0)
[16:49:26.580] Timer tick, asking Buildbucket for the build status
[16:49:26.744] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:26.744] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:18:0) after 1m0s
[16:50:26.888] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:18:0)
[16:50:26.888] Timer tick, asking Buildbucket for the build status
[16:50:27.081] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:27.081] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:19:0) after 1m0s
[16:51:27.162] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:19:0)
[16:51:27.162] Timer tick, asking Buildbucket for the build status
[16:51:27.669] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:27.670] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:20:0) after 1m0s
[16:52:27.800] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:20:0)
[16:52:27.800] Timer tick, asking Buildbucket for the build status
[16:52:28.023] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:28.023] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:21:0) after 1m0s
[16:53:28.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:21:0)
[16:53:28.086] Timer tick, asking Buildbucket for the build status
[16:53:28.239] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:28.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:22:0) after 1m0s
[16:54:28.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:22:0)
[16:54:28.399] Timer tick, asking Buildbucket for the build status
[16:54:28.568] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:28.568] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:23:0) after 1m0s
[16:55:28.746] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:23:0)
[16:55:28.746] Timer tick, asking Buildbucket for the build status
[16:55:29.200] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:29.200] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:24:0) after 1m0s
[16:56:29.262] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:24:0)
[16:56:29.262] Timer tick, asking Buildbucket for the build status
[16:56:29.397] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:29.397] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:25:0) after 1m0s
[16:57:29.411] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:25:0)
[16:57:29.411] Timer tick, asking Buildbucket for the build status
[16:57:29.556] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:29.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:26:0) after 1m0s
[16:58:29.836] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:26:0)
[16:58:29.836] Timer tick, asking Buildbucket for the build status
[16:58:29.996] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:29.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:27:0) after 1m0s
[16:59:30.129] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:27:0)
[16:59:30.129] Timer tick, asking Buildbucket for the build status
[16:59:31.043] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:31.043] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:28:0) after 1m0s
[17:00:31.134] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:28:0)
[17:00:31.134] Timer tick, asking Buildbucket for the build status
[17:00:31.318] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:31.318] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:29:0) after 1m0s
[17:01:31.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:29:0)
[17:01:31.353] Timer tick, asking Buildbucket for the build status
[17:01:31.556] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:31.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:30:0) after 1m0s
[17:02:31.817] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:30:0)
[17:02:31.817] Timer tick, asking Buildbucket for the build status
[17:02:32.147] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:32.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:31:0) after 1m0s
[17:03:32.339] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:31:0)
[17:03:32.339] Timer tick, asking Buildbucket for the build status
[17:03:32.593] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:32.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:32:0) after 1m0s
[17:04:32.744] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:32:0)
[17:04:32.744] Timer tick, asking Buildbucket for the build status
[17:04:33.164] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:33.164] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:33:0) after 1m0s
[17:05:33.458] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:33:0)
[17:05:33.458] Timer tick, asking Buildbucket for the build status
[17:05:33.616] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:33.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:34:0) after 1m0s
[17:06:33.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:34:0)
[17:06:33.809] Timer tick, asking Buildbucket for the build status
[17:06:35.070] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:35.071] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:35:0) after 1m0s
[17:07:35.390] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:35:0)
[17:07:35.390] Timer tick, asking Buildbucket for the build status
[17:07:35.619] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:35.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:36:0) after 1m0s
[17:08:35.862] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:36:0)
[17:08:35.862] Timer tick, asking Buildbucket for the build status
[17:08:36.245] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:36.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:37:0) after 1m0s
[17:09:36.298] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:37:0)
[17:09:36.298] Timer tick, asking Buildbucket for the build status
[17:09:36.481] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:36.481] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:38:0) after 1m0s
[17:10:36.622] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:38:0)
[17:10:36.622] Timer tick, asking Buildbucket for the build status
[17:10:36.771] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:36.771] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:39:0) after 1m0s
[17:11:36.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:39:0)
[17:11:36.844] Timer tick, asking Buildbucket for the build status
[17:11:36.983] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:36.983] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:40:0) after 1m0s
[17:12:37.024] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:40:0)
[17:12:37.024] Timer tick, asking Buildbucket for the build status
[17:12:37.170] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:37.170] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:41:0) after 1m0s
[17:13:37.321] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:41:0)
[17:13:37.321] Timer tick, asking Buildbucket for the build status
[17:13:37.974] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:37.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:42:0) after 1m0s
[17:14:38.105] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:42:0)
[17:14:38.105] Timer tick, asking Buildbucket for the build status
[17:14:38.554] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:38.554] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:43:0) after 1m0s
[17:15:38.819] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:43:0)
[17:15:38.819] Timer tick, asking Buildbucket for the build status
[17:15:39.009] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:39.009] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:44:0) after 1m0s
[17:16:39.114] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:44:0)
[17:16:39.114] Timer tick, asking Buildbucket for the build status
[17:16:39.361] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:39.361] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:45:0) after 1m0s
[17:17:39.608] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:45:0)
[17:17:39.608] Timer tick, asking Buildbucket for the build status
[17:17:39.813] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:39.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:46:0) after 1m0s
[17:18:39.922] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:46:0)
[17:18:39.922] Timer tick, asking Buildbucket for the build status
[17:18:40.542] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:40.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:47:0) after 1m0s
[17:19:40.607] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:47:0)
[17:19:40.607] Timer tick, asking Buildbucket for the build status
[17:19:40.970] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:40.970] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:48:0) after 1m0s
[17:20:41.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:48:0)
[17:20:41.005] Timer tick, asking Buildbucket for the build status
[17:20:41.966] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:41.966] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:49:0) after 1m0s
[17:21:42.105] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:49:0)
[17:21:42.105] Timer tick, asking Buildbucket for the build status
[17:21:42.474] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:42.475] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:50:0) after 1m0s
[17:22:42.516] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:50:0)
[17:22:42.516] Timer tick, asking Buildbucket for the build status
[17:22:43.782] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:43.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:51:0) after 1m0s
[17:23:43.847] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:51:0)
[17:23:43.847] Timer tick, asking Buildbucket for the build status
[17:23:43.997] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:43.997] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:52:0) after 1m0s
[17:24:44.025] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:52:0)
[17:24:44.025] Timer tick, asking Buildbucket for the build status
[17:24:45.155] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:45.155] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:53:0) after 1m0s
[17:25:45.212] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:53:0)
[17:25:45.212] Timer tick, asking Buildbucket for the build status
[17:25:45.901] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:45.901] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:54:0) after 1m0s
[17:26:45.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:54:0)
[17:26:45.966] Timer tick, asking Buildbucket for the build status
[17:26:46.250] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:46.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:55:0) after 1m0s
[17:27:46.367] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:55:0)
[17:27:46.367] Timer tick, asking Buildbucket for the build status
[17:27:46.578] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:46.578] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:56:0) after 1m0s
[17:28:46.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:56:0)
[17:28:46.780] Timer tick, asking Buildbucket for the build status
[17:28:47.368] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:47.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:57:0) after 1m0s
[17:29:47.408] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:57:0)
[17:29:47.408] Timer tick, asking Buildbucket for the build status
[17:29:48.535] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:48.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:58:0) after 1m0s
[17:30:49.393] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:58:0)
[17:30:49.393] Timer tick, asking Buildbucket for the build status
[17:30:49.531] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:49.531] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:59:0) after 1m0s
[17:31:49.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:59:0)
[17:31:49.794] Timer tick, asking Buildbucket for the build status
[17:31:49.924] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:49.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:60:0) after 1m0s
[17:32:50.276] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:60:0)
[17:32:50.276] Timer tick, asking Buildbucket for the build status
[17:32:50.743] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:50.743] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:61:0) after 1m0s
[17:33:50.764] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:61:0)
[17:33:50.764] Timer tick, asking Buildbucket for the build status
[17:33:51.393] Build 8888734882046336400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:51.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054164944252998576:62:0) after 1m0s
[17:34:18.110] Received PubSub notification, asking Buildbucket for the build status
[17:34:18.280] Build 8888734882046336400: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:34:18.280] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581442457451584",
"created_by": "project:chromium",
"created_ts": "1581438859856503",
"id": "8888734882046336400",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"caa3c767efc46a066784dfeddb61e461e4bb2bb1\"}}",
"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-78-o6lt\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168488, \"got_angle_revision\": \"b40d48a67c932625f349c6e58036c2d96b62489b\", \"got_dawn_revision\": \"713d9cbf939e525e025a69a17bda0bf51b449c47\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"caa3c767efc46a066784dfeddb61e461e4bb2bb1\", \"got_revision_cp\": \"refs/heads/master@{#740315}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"196d81774ef86d1c6c2d028dd5407b47eaa51a99\", \"got_v8_revision\": \"d0da7b4db4d335d91a374ec3be53199c482e7174\", \"got_v8_revision_cp\": \"refs/heads/8.2.56@{#1}\", \"got_webrtc_revision\": \"d4c3c3a454215b21f02d2a772a729b4c87d6b33e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30486}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"caa3c767efc46a066784dfeddb61e461e4bb2bb1\", \"swarm_hashes_refs/heads/master(at){#740315}_without_patch\": {\"accessibility_unittests\": \"6d75fc96c3f6d25da9ffec3a0bf59767fb7c4a05\", \"angle_unittests\": \"45d49712fb055a6351f209eeb3aeb298900ed9ac\", \"app_shell_unittests\": \"1942f6694ed6f9629e0c93be3c24522f9eddf053\", \"aura_unittests\": \"225b18354f754d7b0209d107846f6ca9b182628e\", \"base_unittests\": \"9c30b539264583b659c3f12f63d1dd0c1136a66c\", \"base_util_unittests\": \"c05025d6be5cb337a3dbd3063e68ed2a1628689d\", \"blink_common_unittests\": \"ac1f1e19797da04717c58cd51d54e91d354220c3\", \"blink_fuzzer_unittests\": \"4eb7684a34076ebb7aee136e594a1d1e1e103e32\", \"blink_heap_unittests\": \"1488c4432c7e609a92adc5799bf115ef8259447e\", \"blink_platform_unittests\": \"8477713a0f0ec11f7292e17d53f4f557b564a4fc\", \"blink_python_tests\": \"ec2cd98d54b45dd73d2b3194d1cd009a08359eee\", \"blink_unittests\": \"821c9cbdfe6589daa1235030c1b069c1da254ec1\", \"blink_web_tests\": \"0aba2126bc0c66ee40ac96b7e9c9461d6c1737c0\", \"boringssl_crypto_tests\": \"95e11b3ad1485ef5ca655ac0b86d8ff3dba78047\", \"boringssl_ssl_tests\": \"8866b6c4ad6d38ade44b9b37bae3ea235aadd8cc\", \"browser_tests\": \"726b893ca8a6b6f69cc1384d0c1b1be0cb31fd8b\", \"cacheinvalidation_unittests\": \"1d10521a9370a4731b63a49784b78475af69f943\", \"capture_unittests\": \"1945f3200f0ccc802b5279092e20daccc673e3fb\", \"cast_unittests\": \"6d5049d7190310261fcaad8912402b19a0d64a37\", \"cc_unittests\": \"f7bd5aa193f60f2c253afece5c2f5fbb4f9f4216\", \"chrome_app_unittests\": \"88dbc472b769edd690d49ad8e5ed64eaeba4595f\", \"chromedriver_unittests\": \"4decaa56083019165a21cd2d020cb4f3e15af1b8\", \"components_browsertests\": \"29e0bccf1df44a0fed19d82a9759666246610f0d\", \"components_unittests\": \"f2f11fbc516fd8113ab870166b95ba5201524311\", \"compositor_unittests\": \"e65a93c731d30400b27a2a248236abec98bd161b\", \"content_browsertests\": \"4ec5ad3ea7350234cd14f3d4022787c8b0342f14\", \"content_shell_crash_test\": \"8184e28145484bdcfcd11c82911410daa2be0c20\", \"content_unittests\": \"da6c57bbd9df74de653c3d05622e87eac074b8c2\", \"crashpad_tests\": \"023803b06a5c1d9219866baadc639dd403479e66\", \"cronet_tests\": \"641d6924c25a6e0682d02b0dd2b31acba732659e\", \"cronet_unittests\": \"e3283667e03d9ce2173bedce16830c2ab718a94e\", \"crypto_unittests\": \"d22b6be1d474b603123318a330a4f75492406c0b\", \"dbus_unittests\": \"c6c5b33b5c4c1bd03bc012e23724f7a0b2e043ce\", \"device_unittests\": \"eef716512f7e29015ab46c0a6a5736a565d35376\", \"display_unittests\": \"220226b1795161546a42379dadac29a503d99431\", \"events_unittests\": \"90c696dc27c1a6952253564fc611161df469c497\", \"extensions_browsertests\": \"11ebd67bafe5b394156f786145733edecce1d8fa\", \"extensions_unittests\": \"60d925066c8dcb1aa44dc99fe4b27c24bf611063\", \"filesystem_service_unittests\": \"3d6a8fd75ea261b00a21ca9b3fae8c996ef395ee\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"78fa2388474861c11f2ddb340df563f20ffc6be6\", \"gfx_unittests\": \"8741c46dbca8a29d646c6f007e2f57b68479291f\", \"gin_unittests\": \"660e6221e90e5b24fdfd4797224b2ccce057d423\", \"google_apis_unittests\": \"9c1014326f6719db89b0386400f8e64b11c770f3\", \"gpu_unittests\": \"714793b81909497dca539d664146665785d59cb2\", \"grit_python_unittests\": \"2c197a1bd065e31b00ed1c7636679f90a901aabe\", \"gwp_asan_unittests\": \"6fba2dccc1827b6afc3e6376d168a769b8d93843\", \"headless_browsertests\": \"0df8f23d29de5582ea8be4a2c801cbd514f7f577\", \"headless_unittests\": \"c87c6b460f7ffa18ddfc9bd599c8139739be63ee\", \"interactive_ui_tests\": \"09c27ebca15f67d309fbba38b7291a5025d6a242\", \"ipc_tests\": \"eaed91cb9e85f21f9ba771c2aa46928d8ca3d3d4\", \"jingle_unittests\": \"847a880c354b606cc1b44fa171d9d53ec40bfa85\", \"latency_unittests\": \"24463cad2cfc3cdf5001f492307aeedd7c53609a\", \"libjingle_xmpp_unittests\": \"7bdaea3e92e59ce6fa46b679d90884c9510f4e10\", \"media_blink_unittests\": \"7f389ff5d3b4ce1da53f6d8fcd8ae7b4ae5b2b88\", \"media_service_unittests\": \"d0089537c30e1ca0a4b91d7c6be07d339798d704\", \"media_unittests\": \"78937e93682447ec799eec96801e0d79f5f42ba7\", \"message_center_unittests\": \"492b4aff44f3d069adb4a47c03b285058781b730\", \"metrics_python_tests\": \"40b8f4f5fabca3fe2281c9b5f0e0b8d4fbbf4745\", \"midi_unittests\": \"6a2efd20b2ef6d361bd28f3bd66f0e0d587cd7f3\", \"mojo_core_unittests\": \"f88b9a3addfffc8a12d94a8ed58ea0f43f9a433a\", \"mojo_unittests\": \"a89a825414e11faee233d0cdb9408492b3459f5a\", \"nacl_helper_nonsfi_unittests\": \"34bfb4402e9d109cce9f85ddab7c2e0cb200480a\", \"nacl_loader_unittests\": \"fdf249d5d8fa9fdf0a6e05113e571379d0e8c87b\", \"native_theme_unittests\": \"e99a868c4e797caa84eebedbbda7049f694e0356\", \"net_unittests\": \"3c7e95e93c7442ba829b5212df4dafad78a06008\", \"pdf_unittests\": \"9aee1ee2284f841a9466eb78843add3cd703b6e4\", \"perfetto_unittests\": \"a0abf6c6ff861323f27a0e76f608e7e4c047cd3b\", \"ppapi_unittests\": \"ea235023528107cf9be6975ca8c840c4e386733f\", \"printing_unittests\": \"65e24367ae137fcdec887d83a4111b70fa7ce864\", \"remoting_unittests\": \"f659fb4144ffacea428461765c6a115aa38ffa87\", \"sandbox_linux_unittests\": \"e8d4ed764c6c7c4768ad6000eb2e73ddb502bc6a\", \"service_manager_unittests\": \"41b6c7be75e1fb8e20373eaa3af30729ca44ce7f\", \"services_unittests\": \"27aa9b398615ffbbfc19fda009f45f368e0b2f71\", \"shell_dialogs_unittests\": \"bbabb878089184df6a2188d9af7dab05788a8f80\", \"skia_unittests\": \"8b96c5240ecf50f2087e12fef9a61685f421a0c1\", \"snapshot_unittests\": \"5b9b3c4bd9b9fd1cd98180bd6b3ebf8e71ee0cf7\", \"sql_unittests\": \"1d3e703df90a5bbda98999360d9009e9aefeebd2\", \"storage_unittests\": \"de70709aa926181924015c00aac00006c94e5302\", \"sync_integration_tests\": \"40022bb5e3ad8d81aedeb394403b85d6e7adb712\", \"telemetry_gpu_unittests\": \"65037f3ed8cd3772be7a2d46094def6751dd5da0\", \"telemetry_perf_unittests\": \"2c7fce7663af77e086d169bc4c9467e01aee5d01\", \"telemetry_unittests\": \"ddce82247f29d95b16c2457b5a76d10ff01aac49\", \"traffic_annotation_auditor_unittests\": \"e5d92524611892f7593cc41a33435f7b10b6b859\", \"ui_base_unittests\": \"d434beb94ced02b1169253d15baf3d4aa0edd97a\", \"ui_touch_selection_unittests\": \"df36ea9592e03f9f49e86edefd089656c0777212\", \"unit_tests\": \"5a4d750d8e76dc533916a125d97889b1270e258b\", \"url_unittests\": \"49f2783dcb6b3311e0355455f39271753b48522a\", \"views_perftests\": \"e735be9ae6431dbad7ff7f3e0d849cd794758308\", \"views_unittests\": \"02487650018cff6569977d1b2a2c5c6a2f1cb697\", \"viz_unittests\": \"4337e0de6adad032db21fbf1ae80ac8c246f5921\", \"vr_common_unittests\": \"f15bd172ed23b22af75b941b9f8ea633fc8f42d7\", \"vr_pixeltests\": \"d66013186c0eed6825f0fb27cb379cb033689c1d\", \"webdriver_wpt_tests\": \"aa09fca3396a5534b4ec19dfa3c7350e73c5b4a2\", \"weblayer_browsertests\": \"6ac8180bef0e919dcbc51b3180a11be77ecf4700\", \"wm_unittests\": \"5b654b35131a3780946e773834ccace07296b202\", \"wtf_unittests\": \"eed3af1f427d81ecff52316ddecd73bd215f39d9\", \"xr_browser_tests\": \"8783b781bf2754ae905d303e94044e5d1717829b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_3f701152d865ce3ec8feb15f973214ad980aeace5cbda24477548a965269fca6_v2\", \"builder_bea46eaeedb7de1bd52f00a0308b818fe481ae542888a2fa5825ab1ef5ba3493_v2\", \"builder_c9091f6bc58db7983401393b6dacc93b3db8fae2c2ae9b40177b9e0904c8bc80_v2\", \"builder_d78b729059d6d7166c1c60b6c63641496a1541bf700cb46d40203a280149f161_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-78-o6lt\"], \"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\": [\"4812-ea7e8fc\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581439142794634",
"status": "COMPLETED",
"status_changed_ts": "1581442457794090",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168488",
"builder:Linux Builder (dbg)",
"buildset:commit/git/caa3c767efc46a066784dfeddb61e461e4bb2bb1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/caa3c767efc46a066784dfeddb61e461e4bb2bb1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054164944252998576",
"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/8888734882046336400/+/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:4a4deb8547729010",
"user_agent:luci-scheduler"
],
"updated_ts": "1581442457798029",
"url": "https://ci.chromium.org/b/8888734882046336400",
"utcnow_ts": "1581442458269612"
}
}
[17:34:18.280] Invocation finished in 59m59.911963601s with status SUCCEEDED