[04:43:40.439] New invocation is queued and will start shortly
[04:43:41.683] Starting the invocation (attempt 1)
[04:43:41.714] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:43:41.714] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:43:41.714] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9055749801805797216",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"927ecfcf3eff94234b172a4d613361c34c1f6f2b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9055749801805797216",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/927ecfcf3eff94234b172a4d613361c34c1f6f2b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/927ecfcf3eff94234b172a4d613361c34c1f6f2b",
"gitiles_ref:refs/heads/master"
]
}
[04:43:42.592] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579927421856380",
"id": "8890319739657650544",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"927ecfcf3eff94234b172a4d613361c34c1f6f2b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579927422273573",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167809",
"builder:Linux Builder (dbg)",
"buildset:commit/git/927ecfcf3eff94234b172a4d613361c34c1f6f2b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/927ecfcf3eff94234b172a4d613361c34c1f6f2b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055749801805797216",
"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/8890319739657650544/+/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": "1579927422273675",
"url": "https://ci.chromium.org/b/8890319739657650544",
"utcnow_ts": "1579927422572646"
}
}
[04:43:42.592] Task URL: https://ci.chromium.org/b/8890319739657650544
[04:43:42.592] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:2:0) after 1m0s
[04:43:43.884] Received PubSub notification, asking Buildbucket for the build status
[04:43:44.034] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:42.651] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:2:0)
[04:44:42.651] Timer tick, asking Buildbucket for the build status
[04:44:42.837] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:42.837] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:4:0) after 1m0s
[04:45:42.894] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:4:0)
[04:45:42.894] Timer tick, asking Buildbucket for the build status
[04:45:43.058] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:43.058] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:5:0) after 1m0s
[04:46:43.038] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:5:0)
[04:46:43.038] Timer tick, asking Buildbucket for the build status
[04:46:43.328] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:43.328] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:6:0) after 1m0s
[04:47:43.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:6:0)
[04:47:43.425] Timer tick, asking Buildbucket for the build status
[04:47:43.648] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:43.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:7:0) after 1m0s
[04:48:43.672] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:7:0)
[04:48:43.672] Timer tick, asking Buildbucket for the build status
[04:48:43.836] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:43.836] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:8:0) after 1m0s
[04:49:43.855] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:8:0)
[04:49:43.855] Timer tick, asking Buildbucket for the build status
[04:49:44.027] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:44.027] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:9:0) after 1m0s
[04:50:44.046] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:9:0)
[04:50:44.046] Timer tick, asking Buildbucket for the build status
[04:50:44.490] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:44.490] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:10:0) after 1m0s
[04:51:44.567] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:10:0)
[04:51:44.567] Timer tick, asking Buildbucket for the build status
[04:51:44.759] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:44.759] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:11:0) after 1m0s
[04:52:44.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:11:0)
[04:52:44.890] Timer tick, asking Buildbucket for the build status
[04:52:45.031] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:45.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:12:0) after 1m0s
[04:53:45.129] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:12:0)
[04:53:45.129] Timer tick, asking Buildbucket for the build status
[04:53:45.364] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:45.364] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:13:0) after 1m0s
[04:54:45.347] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:13:0)
[04:54:45.347] Timer tick, asking Buildbucket for the build status
[04:54:45.514] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:45.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:14:0) after 1m0s
[04:55:45.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:14:0)
[04:55:45.535] Timer tick, asking Buildbucket for the build status
[04:55:45.751] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:45.751] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:15:0) after 1m0s
[04:56:45.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:15:0)
[04:56:45.823] Timer tick, asking Buildbucket for the build status
[04:56:46.297] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:46.297] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:16:0) after 1m0s
[04:57:46.409] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:16:0)
[04:57:46.409] Timer tick, asking Buildbucket for the build status
[04:57:47.085] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:47.085] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:17:0) after 1m0s
[04:58:47.218] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:17:0)
[04:58:47.218] Timer tick, asking Buildbucket for the build status
[04:58:47.412] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:47.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:18:0) after 1m0s
[04:59:47.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:18:0)
[04:59:47.462] Timer tick, asking Buildbucket for the build status
[04:59:48.044] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:48.044] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:19:0) after 1m0s
[05:00:48.176] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:19:0)
[05:00:48.176] Timer tick, asking Buildbucket for the build status
[05:00:48.576] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:48.576] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:20:0) after 1m0s
[05:01:48.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:20:0)
[05:01:48.718] Timer tick, asking Buildbucket for the build status
[05:01:48.941] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:48.941] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:21:0) after 1m0s
[05:02:49.034] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:21:0)
[05:02:49.034] Timer tick, asking Buildbucket for the build status
[05:02:49.338] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:49.338] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:22:0) after 1m0s
[05:03:49.359] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:22:0)
[05:03:49.359] Timer tick, asking Buildbucket for the build status
[05:03:49.818] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:49.818] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:23:0) after 1m0s
[05:04:49.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:23:0)
[05:04:49.816] Timer tick, asking Buildbucket for the build status
[05:04:50.076] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:50.076] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:24:0) after 1m0s
[05:05:50.137] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:24:0)
[05:05:50.137] Timer tick, asking Buildbucket for the build status
[05:05:50.294] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:50.294] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:25:0) after 1m0s
[05:06:50.300] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:25:0)
[05:06:50.300] Timer tick, asking Buildbucket for the build status
[05:06:50.499] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:50.499] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:26:0) after 1m0s
[05:07:50.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:26:0)
[05:07:50.553] Timer tick, asking Buildbucket for the build status
[05:07:50.997] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:50.997] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:27:0) after 1m0s
[05:08:51.069] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:27:0)
[05:08:51.069] Timer tick, asking Buildbucket for the build status
[05:08:51.281] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:51.281] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:28:0) after 1m0s
[05:09:51.339] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:28:0)
[05:09:51.339] Timer tick, asking Buildbucket for the build status
[05:09:52.898] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:52.898] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:29:0) after 1m0s
[05:10:52.950] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:29:0)
[05:10:52.950] Timer tick, asking Buildbucket for the build status
[05:10:53.158] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:53.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:30:0) after 1m0s
[05:11:53.177] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:30:0)
[05:11:53.177] Timer tick, asking Buildbucket for the build status
[05:11:53.466] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:53.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:31:0) after 1m0s
[05:12:53.487] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:31:0)
[05:12:53.487] Timer tick, asking Buildbucket for the build status
[05:12:53.694] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:53.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:32:0) after 1m0s
[05:13:53.704] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:32:0)
[05:13:53.704] Timer tick, asking Buildbucket for the build status
[05:13:53.926] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:53.926] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:33:0) after 1m0s
[05:14:53.959] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:33:0)
[05:14:53.959] Timer tick, asking Buildbucket for the build status
[05:14:54.219] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:54.219] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:34:0) after 1m0s
[05:15:54.235] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:34:0)
[05:15:54.235] Timer tick, asking Buildbucket for the build status
[05:15:54.899] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:54.899] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:35:0) after 1m0s
[05:16:54.931] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:35:0)
[05:16:54.931] Timer tick, asking Buildbucket for the build status
[05:16:55.972] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:55.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:36:0) after 1m0s
[05:17:55.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:36:0)
[05:17:55.984] Timer tick, asking Buildbucket for the build status
[05:17:56.434] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:56.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:37:0) after 1m0s
[05:18:56.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:37:0)
[05:18:56.543] Timer tick, asking Buildbucket for the build status
[05:18:56.729] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:56.729] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:38:0) after 1m0s
[05:19:56.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:38:0)
[05:19:56.780] Timer tick, asking Buildbucket for the build status
[05:19:57.156] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:57.156] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:39:0) after 1m0s
[05:20:57.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:39:0)
[05:20:57.226] Timer tick, asking Buildbucket for the build status
[05:20:58.372] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:58.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:40:0) after 1m0s
[05:21:58.376] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:40:0)
[05:21:58.376] Timer tick, asking Buildbucket for the build status
[05:21:58.558] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:58.558] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:41:0) after 1m0s
[05:22:58.648] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:41:0)
[05:22:58.648] Timer tick, asking Buildbucket for the build status
[05:22:59.050] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:59.050] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:42:0) after 1m0s
[05:23:59.048] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:42:0)
[05:23:59.048] Timer tick, asking Buildbucket for the build status
[05:23:59.757] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:59.757] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:43:0) after 1m0s
[05:24:59.760] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:43:0)
[05:24:59.760] Timer tick, asking Buildbucket for the build status
[05:25:00.169] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:00.169] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:44:0) after 1m0s
[05:26:00.241] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:44:0)
[05:26:00.241] Timer tick, asking Buildbucket for the build status
[05:26:00.744] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:00.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:45:0) after 1m0s
[05:27:00.833] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:45:0)
[05:27:00.833] Timer tick, asking Buildbucket for the build status
[05:27:01.697] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:01.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:46:0) after 1m0s
[05:28:01.715] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:46:0)
[05:28:01.715] Timer tick, asking Buildbucket for the build status
[05:28:01.962] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:01.962] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:47:0) after 1m0s
[05:29:02.018] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:47:0)
[05:29:02.018] Timer tick, asking Buildbucket for the build status
[05:29:02.333] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:02.333] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:48:0) after 1m0s
[05:30:02.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:48:0)
[05:30:02.353] Timer tick, asking Buildbucket for the build status
[05:30:02.556] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:02.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:49:0) after 1m0s
[05:31:02.573] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:49:0)
[05:31:02.573] Timer tick, asking Buildbucket for the build status
[05:31:03.473] Build 8890319739657650544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:03.473] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055749801805797216:50:0) after 1m0s
[05:31:35.000] Received PubSub notification, asking Buildbucket for the build status
[05:31:35.532] Build 8890319739657650544: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:31:35.532] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579930294350091",
"created_by": "project:chromium",
"created_ts": "1579927421856380",
"id": "8890319739657650544",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"927ecfcf3eff94234b172a4d613361c34c1f6f2b\"}}",
"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-129-k9bo\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167809, \"got_angle_revision\": \"3712b2e4453a8a1bca3dedd2d56623337fe7fd76\", \"got_dawn_revision\": \"1d036f776698b607763aa025d4bce5df5c867ac1\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"927ecfcf3eff94234b172a4d613361c34c1f6f2b\", \"got_revision_cp\": \"refs/heads/master@{#735218}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"748ad200897b5023ba16e527aa1221400f90b4fe\", \"got_v8_revision\": \"ded3dd5b397722ce87c787130cc352afe9f8e35e\", \"got_v8_revision_cp\": \"refs/heads/8.1.281@{#1}\", \"got_webrtc_revision\": \"760fd5249488a7a8698679a026b247423caa7cce\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30366}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"927ecfcf3eff94234b172a4d613361c34c1f6f2b\", \"swarm_hashes_refs/heads/master(at){#735218}_without_patch\": {\"accessibility_unittests\": \"b6070db852c09202e9f9f4bad7e89de708ce0b37\", \"angle_unittests\": \"2638dfdf39ee950cc08b0f8f03d3bbf76af1a374\", \"app_shell_unittests\": \"125cf7ff0ecbbc98d10303ecdd2888a270acb57a\", \"aura_unittests\": \"1bfe0cf6595c1d4e0b7c4a1d1c402390dddd1c29\", \"base_unittests\": \"ce3bf1c9e197a55b01a39b549c525be91e263e85\", \"base_util_unittests\": \"420206b72ca279945cf6f705df1d852bfa03147d\", \"blink_common_unittests\": \"7a5b0e0086806d8bd4a48ef12c6a8a4dee31f0cc\", \"blink_fuzzer_unittests\": \"af9b12b0fc0086428fae3dae8885f3a3f20b3bc1\", \"blink_heap_unittests\": \"f6a15b09985f3ef2d5137326261f2cf44e212392\", \"blink_platform_unittests\": \"448128c05a650f784611f0108bc11e8ee45e4b21\", \"blink_python_tests\": \"0dd57d360ce28158f64b9754b66b40e88922d35b\", \"blink_unittests\": \"43e618baec215db0544c123074d0258303865a90\", \"blink_web_tests\": \"167ac354e31b7af3828f9fa2f8b188dfe40e5b8a\", \"boringssl_crypto_tests\": \"328ee71280265216a0a48c8a8b55a5f1983c50be\", \"boringssl_ssl_tests\": \"5af0d873bd0fb5f33f377e0309b7f5834611de4f\", \"browser_tests\": \"b103a6d6e826259a296f0b19e43d2c61be2257aa\", \"cacheinvalidation_unittests\": \"212b9778234a7fa07ba191efc7e7fef3062d5055\", \"capture_unittests\": \"42c14fb0f51cadb58e6f4ebb47348e4ff544c465\", \"cast_unittests\": \"f24050bee28c2ff96bec9952834b13e5e9fb0747\", \"cc_unittests\": \"eb97f79d608c1eb639884d4e622ee0ed2827150d\", \"chrome_app_unittests\": \"e1b9ca106e8665b882fc559f2530b4dd2d38fbe3\", \"chromedriver_unittests\": \"c59489b19d4ccc45791ef8e729ccb75e6d6d10af\", \"components_browsertests\": \"8122b93f7a5689b721a142725fcbe7706759e0b5\", \"components_unittests\": \"dbc3247fd0cb2593101a8d1feafb315cba537002\", \"compositor_unittests\": \"12de4a139452bbd4d4f690dac33bd15457bbd65d\", \"content_browsertests\": \"9e63418d77f87c4fdd62766c4af730971c0e4266\", \"content_shell_crash_test\": \"f247740cd08cd7b37561d0faeb02b8363ddf236e\", \"content_unittests\": \"2c6fd714c66279231eda228a7b4a6bfbd6309a0f\", \"crashpad_tests\": \"9eebb2c1558ccb7f8d8eaf93dc4392ac4d539bc4\", \"cronet_tests\": \"b9b7d3101b3642965e1e289cf09f73aad5ef468f\", \"cronet_unittests\": \"8e33606b480dbd3af0a18d3528a3acf3e384a18c\", \"crypto_unittests\": \"0a1992c42c1957b339f801ded34a3b7091024630\", \"dbus_unittests\": \"beea563155e13eebfc61552914c7438fe19ba5ee\", \"device_unittests\": \"9a134e25e22e46e1b9703fd15f28c17297a8e108\", \"display_unittests\": \"a8b6629148c62daaa802d7fd74df55610b0c29fb\", \"events_unittests\": \"3c8da490e4f49a09314830d571e59560dfb55590\", \"extensions_browsertests\": \"08ccfe6af131cc07e1b6e48b991148b969a2db7c\", \"extensions_unittests\": \"c78c4f4fa2639bc0a158bbb89db6fe56d186e1a2\", \"filesystem_service_unittests\": \"7dca9f1f37309e0d0a1696952ceb68fa65a0c969\", \"flatbuffers_unittests\": \"92c174f535dbe8e15f58589d59dc88efc29a10d4\", \"gcm_unit_tests\": \"267146da1c13fa7fe9770a37da9baf73342ed285\", \"gfx_unittests\": \"628317bff8a2fb6e7edecb4f1cbfa7208e39da26\", \"gin_unittests\": \"074dd1e08d985c82c50ea02f8cbefe5ba6a0ebaf\", \"google_apis_unittests\": \"71207403d7e1eb029d4fe0d3d463ef80d885949c\", \"gpu_unittests\": \"391d86fdf63044aaa955e5baddf954589df40ff0\", \"grit_python_unittests\": \"e78e7c3110422cb6e387105e898475b15c4b1be1\", \"gwp_asan_unittests\": \"850fa1a1bb3b5e38cb4a1fd700275b146d7dc0a8\", \"headless_browsertests\": \"aa0ef784376741d85f94c3285bfb5a8f215db61c\", \"headless_unittests\": \"cdd2a8caae0975caffeac75f13eff53e0195ba9b\", \"interactive_ui_tests\": \"43f3f141b36d7f5825efe76a9314e6074c88ff52\", \"ipc_tests\": \"406e3ff3e3b7d7509e20421265ee06552aef4580\", \"jingle_unittests\": \"6b14451ec80d960414c90235cf0037bd2dfbd692\", \"latency_unittests\": \"1d414a0bb1247e4e0c1b7dfe02636a1f684a1197\", \"libjingle_xmpp_unittests\": \"4ca55dc1f0477d8bf46a03d3632891f88b93bef3\", \"media_blink_unittests\": \"c23e2c468f531a96ba093d52b06829c87db112dc\", \"media_service_unittests\": \"d991310e264591360bde928c07feaac0a2f346c5\", \"media_unittests\": \"5c5057e14e309d144b423e7f1954ce8b378cf6ce\", \"message_center_unittests\": \"82a3e24c1a036a48d33eafdce2217836576939c1\", \"metrics_python_tests\": \"079331c8c2ecb26e578c2eae52aa6e241935d6c2\", \"midi_unittests\": \"74f15c79181175fa51ea950c9308ed78b397e2ab\", \"mojo_core_unittests\": \"b36c977e93732c47f6c917c40522601b2ce012da\", \"mojo_unittests\": \"e15b0406ccd342fe17d63b600b0083633c2ad9d3\", \"nacl_helper_nonsfi_unittests\": \"38804235b469c9e03f44c888c9c518826e0a226a\", \"nacl_loader_unittests\": \"d5efeb878ec04b49948a9c580264a494dd0b0005\", \"native_theme_unittests\": \"0e527dbc06b0df2decef6972b970249c2e8c71ed\", \"net_unittests\": \"216d542c26d3b144674e2f67dc65aaf27a2e05b9\", \"pdf_unittests\": \"bb861b81004f16632e51090f004702b39d66f5ec\", \"perfetto_unittests\": \"ead46d51c9fad1b6c557aff5606f1d11702ba9bd\", \"ppapi_unittests\": \"3c5cc4012f045a75bfe2e52c0a3a049b2523ee62\", \"printing_unittests\": \"d0dfa91e0270d1ebc2446616b956fa9b3e7e9312\", \"remoting_unittests\": \"efa40be8aca3ac1e2c4528078de178e6dedaa659\", \"sandbox_linux_unittests\": \"f12b5a39183a4739b749cef4340b32535a529fa3\", \"service_manager_unittests\": \"79ced4bb271847241e23a6d7d48766c2d064381f\", \"services_unittests\": \"4a9083bb514664f0d88093b7864bbcee2ba3a825\", \"shell_dialogs_unittests\": \"405f19669fa44d9a5f5645d7414616253e16f494\", \"skia_unittests\": \"6ea8d6b3d5fac8321c1429aeb5f1f55b87602700\", \"snapshot_unittests\": \"9bac405181222bed49f05921b8fc30c76c17b264\", \"sql_unittests\": \"543f669346d51122542477475217870f461e94a0\", \"storage_unittests\": \"bf2ad7832e5de0aa6223f663308cbf2bdc6c9f76\", \"sync_integration_tests\": \"11a17ee62f7bc884147e2083772d54d301e476a6\", \"telemetry_gpu_unittests\": \"a24a5b6056c05463129589b1ff34407d7b69f4d6\", \"telemetry_perf_unittests\": \"528e2315dffb1f4bbc4316fa5e211629d015fd82\", \"telemetry_unittests\": \"99a120ef47324d51a1552398f50c06e12283381f\", \"traffic_annotation_auditor_unittests\": \"faf018eaf3bc0fdab26c466f767e62a8bc767a3a\", \"ui_base_unittests\": \"38c423d7ba44e6b3ef94d0a6aa78f574f652fa89\", \"ui_touch_selection_unittests\": \"0ed3415fea4a69dc90c4e5f4073439030dc884a2\", \"unit_tests\": \"5ec8d45001db8015c768a5c0537417659b149594\", \"url_unittests\": \"b1124b25b271990e27d000fc3527ad587fd133c1\", \"views_perftests\": \"31ea04a96e2681976888b11449541bfd14f56483\", \"views_unittests\": \"7ef6d8f6c8d9536a1da700af4844c6732f8145fd\", \"viz_unittests\": \"8835a19f55ab5a8e97e6d296cddf2b82d4d4eac2\", \"vr_common_unittests\": \"8922429f6e5b86b4772cf77fc8ed328af6ac0875\", \"vr_pixeltests\": \"1e3a96ba01b7855d18d95f83776263df052fa2f6\", \"webdriver_wpt_tests\": \"d382e37cf4ad9d5cda09be6fd20c5d82c246359a\", \"weblayer_browsertests\": \"33f293777575f6f1a0b1c42da70da3b9691c89fd\", \"wm_unittests\": \"d5bfe0988f5ab831f7f7c2b55528b7ddff60a045\", \"wtf_unittests\": \"89a82e1587bd09cd9422cd0007e4adb7015a952f\", \"xr_browser_tests\": \"b86f344a746d97e91b609cd86d8e73c36a63a2e2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_06f1ed350fecda52f561db3eb00cb39e105b14f5c02d94078111c7babc8da8c6_v2\", \"builder_4f073cad989671df25347297d55a43b2baed7923c7c8febe8eed874750cd540a_v2\", \"builder_d0ba3cd99c36b697d8651140d51bf4b4f4b9cd9aa25af11363af5ede5f6e35b1_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_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-129-k9bo\"], \"image\": [\"chrome-xenial-20011200-47b90f1b63b\"], \"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\": [\"4792-2014df3\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579927422928306",
"status": "COMPLETED",
"status_changed_ts": "1579930294621410",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167809",
"builder:Linux Builder (dbg)",
"buildset:commit/git/927ecfcf3eff94234b172a4d613361c34c1f6f2b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/927ecfcf3eff94234b172a4d613361c34c1f6f2b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055749801805797216",
"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/8890319739657650544/+/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:49f3d4cebdd17e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1579930294625025",
"url": "https://ci.chromium.org/b/8890319739657650544",
"utcnow_ts": "1579930295524205"
}
}
[05:31:35.532] Invocation finished in 47m55.108249536s with status SUCCEEDED