[20:34:58.566] New invocation is queued and will start shortly
[20:34:59.632] Starting the invocation (attempt 1)
[20:34:59.687] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:34:59.687] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:34:59.687] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9078520386587339216",
"parameters_json": "{\"builder_name\":\"Deterministic Linux\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"2da2d86180bd2448704de06fdfc01a30ec9e3d4f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux",
"scheduler_invocation_id:9078520386587339216",
"scheduler_job_id:chromium/Deterministic Linux",
"user_agent:luci-scheduler",
"buildset:commit/git/2da2d86180bd2448704de06fdfc01a30ec9e3d4f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2da2d86180bd2448704de06fdfc01a30ec9e3d4f",
"gitiles_ref:refs/heads/master"
]
}
[20:35:03.405] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1558211700462670",
"id": "8913090323934516720",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2da2d86180bd2448704de06fdfc01a30ec9e3d4f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558211702873440",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/23254",
"builder:Deterministic Linux",
"buildset:commit/git/2da2d86180bd2448704de06fdfc01a30ec9e3d4f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2da2d86180bd2448704de06fdfc01a30ec9e3d4f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078520386587339216",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8913090323934516720/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1558211702873535",
"url": "https://ci.chromium.org/b/8913090323934516720",
"utcnow_ts": "1558211703334267"
}
}
[20:35:03.405] Task URL: https://ci.chromium.org/b/8913090323934516720
[20:35:03.405] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:2:0) after 1m0s
[20:35:30.908] Received PubSub notification, asking Buildbucket for the build status
[20:35:31.033] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:06.372] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:2:0)
[20:36:06.372] Timer tick, asking Buildbucket for the build status
[20:36:06.711] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:06.711] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:4:0) after 1m0s
[20:37:06.743] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:4:0)
[20:37:06.743] Timer tick, asking Buildbucket for the build status
[20:37:07.780] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:07.781] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:5:0) after 1m0s
[20:38:07.843] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:5:0)
[20:38:07.843] Timer tick, asking Buildbucket for the build status
[20:38:08.026] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:08.026] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:6:0) after 1m0s
[20:39:08.034] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:6:0)
[20:39:08.034] Timer tick, asking Buildbucket for the build status
[20:39:08.237] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:08.237] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:7:0) after 1m0s
[20:40:08.264] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:7:0)
[20:40:08.264] Timer tick, asking Buildbucket for the build status
[20:40:08.665] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:08.665] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:8:0) after 1m0s
[20:41:08.756] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:8:0)
[20:41:08.756] Timer tick, asking Buildbucket for the build status
[20:41:08.983] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:08.983] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:9:0) after 1m0s
[20:42:08.970] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:9:0)
[20:42:08.970] Timer tick, asking Buildbucket for the build status
[20:42:09.088] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:09.088] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:10:0) after 1m0s
[20:43:09.215] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:10:0)
[20:43:09.215] Timer tick, asking Buildbucket for the build status
[20:43:09.510] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:09.510] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:11:0) after 1m0s
[20:44:09.534] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:11:0)
[20:44:09.534] Timer tick, asking Buildbucket for the build status
[20:44:10.564] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:10.564] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:12:0) after 1m0s
[20:45:10.658] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:12:0)
[20:45:10.658] Timer tick, asking Buildbucket for the build status
[20:45:10.915] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:10.915] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:13:0) after 1m0s
[20:46:11.064] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:13:0)
[20:46:11.064] Timer tick, asking Buildbucket for the build status
[20:46:11.551] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:11.551] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:14:0) after 1m0s
[20:47:11.596] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:14:0)
[20:47:11.596] Timer tick, asking Buildbucket for the build status
[20:47:13.598] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:13.598] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:15:0) after 1m0s
[20:48:13.631] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:15:0)
[20:48:13.631] Timer tick, asking Buildbucket for the build status
[20:48:13.876] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:13.876] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:16:0) after 1m0s
[20:49:13.894] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:16:0)
[20:49:13.894] Timer tick, asking Buildbucket for the build status
[20:49:14.013] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:14.013] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:17:0) after 1m0s
[20:50:14.046] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:17:0)
[20:50:14.046] Timer tick, asking Buildbucket for the build status
[20:50:16.000] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:16.000] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:18:0) after 1m0s
[20:51:15.988] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:18:0)
[20:51:15.988] Timer tick, asking Buildbucket for the build status
[20:51:16.084] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:16.084] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:19:0) after 1m0s
[20:52:16.111] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:19:0)
[20:52:16.111] Timer tick, asking Buildbucket for the build status
[20:52:18.112] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:18.112] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:20:0) after 1m0s
[20:53:18.175] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:20:0)
[20:53:18.175] Timer tick, asking Buildbucket for the build status
[20:53:19.084] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:19.084] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:21:0) after 1m0s
[20:54:19.112] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:21:0)
[20:54:19.112] Timer tick, asking Buildbucket for the build status
[20:54:19.609] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:19.609] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:22:0) after 1m0s
[20:55:19.584] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:22:0)
[20:55:19.584] Timer tick, asking Buildbucket for the build status
[20:55:19.957] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:19.957] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:23:0) after 1m0s
[20:56:20.046] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:23:0)
[20:56:20.046] Timer tick, asking Buildbucket for the build status
[20:56:21.156] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:21.156] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:24:0) after 1m0s
[20:57:21.174] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:24:0)
[20:57:21.174] Timer tick, asking Buildbucket for the build status
[20:57:22.395] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:22.395] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:25:0) after 1m0s
[20:58:22.414] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:25:0)
[20:58:22.415] Timer tick, asking Buildbucket for the build status
[20:58:22.652] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:22.652] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:26:0) after 1m0s
[20:59:22.715] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:26:0)
[20:59:22.715] Timer tick, asking Buildbucket for the build status
[20:59:22.803] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:22.803] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:27:0) after 1m0s
[21:00:22.839] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:27:0)
[21:00:22.839] Timer tick, asking Buildbucket for the build status
[21:00:23.141] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:23.141] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:28:0) after 1m0s
[21:01:23.173] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:28:0)
[21:01:23.173] Timer tick, asking Buildbucket for the build status
[21:01:23.535] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:23.535] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:29:0) after 1m0s
[21:02:23.557] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:29:0)
[21:02:23.557] Timer tick, asking Buildbucket for the build status
[21:02:23.977] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:23.977] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:30:0) after 1m0s
[21:03:24.001] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:30:0)
[21:03:24.001] Timer tick, asking Buildbucket for the build status
[21:03:24.281] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:24.281] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:31:0) after 1m0s
[21:04:24.305] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:31:0)
[21:04:24.305] Timer tick, asking Buildbucket for the build status
[21:04:26.210] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:26.210] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:32:0) after 1m0s
[21:05:26.235] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:32:0)
[21:05:26.235] Timer tick, asking Buildbucket for the build status
[21:05:26.814] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:26.814] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:33:0) after 1m0s
[21:06:26.838] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:33:0)
[21:06:26.838] Timer tick, asking Buildbucket for the build status
[21:06:26.932] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:26.932] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:34:0) after 1m0s
[21:07:26.961] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:34:0)
[21:07:26.961] Timer tick, asking Buildbucket for the build status
[21:07:27.926] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:27.926] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:35:0) after 1m0s
[21:08:27.979] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:35:0)
[21:08:27.979] Timer tick, asking Buildbucket for the build status
[21:08:28.486] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:28.486] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:36:0) after 1m0s
[21:09:28.513] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:36:0)
[21:09:28.513] Timer tick, asking Buildbucket for the build status
[21:09:29.094] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:29.094] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:37:0) after 1m0s
[21:10:29.127] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:37:0)
[21:10:29.127] Timer tick, asking Buildbucket for the build status
[21:10:30.009] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:30.009] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:38:0) after 1m0s
[21:11:30.065] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:38:0)
[21:11:30.065] Timer tick, asking Buildbucket for the build status
[21:11:30.644] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:30.644] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:39:0) after 1m0s
[21:12:30.746] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:39:0)
[21:12:30.747] Timer tick, asking Buildbucket for the build status
[21:12:31.365] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:31.365] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:40:0) after 1m0s
[21:13:31.391] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:40:0)
[21:13:31.391] Timer tick, asking Buildbucket for the build status
[21:13:32.099] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:32.099] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:41:0) after 1m0s
[21:14:32.130] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:41:0)
[21:14:32.130] Timer tick, asking Buildbucket for the build status
[21:14:32.367] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:32.367] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:42:0) after 1m0s
[21:15:32.436] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:42:0)
[21:15:32.436] Timer tick, asking Buildbucket for the build status
[21:15:32.573] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:32.573] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:43:0) after 1m0s
[21:16:32.629] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:43:0)
[21:16:32.629] Timer tick, asking Buildbucket for the build status
[21:16:35.375] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:35.375] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:44:0) after 1m0s
[21:17:35.397] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:44:0)
[21:17:35.397] Timer tick, asking Buildbucket for the build status
[21:17:36.503] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:36.503] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:45:0) after 1m0s
[21:18:36.521] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:45:0)
[21:18:36.521] Timer tick, asking Buildbucket for the build status
[21:18:37.106] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:37.106] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:46:0) after 1m0s
[21:19:37.123] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:46:0)
[21:19:37.123] Timer tick, asking Buildbucket for the build status
[21:19:37.409] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:37.409] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:47:0) after 1m0s
[21:20:37.505] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:47:0)
[21:20:37.505] Timer tick, asking Buildbucket for the build status
[21:20:38.417] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:38.417] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:48:0) after 1m0s
[21:21:38.439] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:48:0)
[21:21:38.439] Timer tick, asking Buildbucket for the build status
[21:21:38.639] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:38.639] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:49:0) after 1m0s
[21:22:38.669] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:49:0)
[21:22:38.669] Timer tick, asking Buildbucket for the build status
[21:22:40.664] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:40.664] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:50:0) after 1m0s
[21:23:40.708] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:50:0)
[21:23:40.708] Timer tick, asking Buildbucket for the build status
[21:23:41.105] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:41.105] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:51:0) after 1m0s
[21:24:41.143] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:51:0)
[21:24:41.143] Timer tick, asking Buildbucket for the build status
[21:24:41.424] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:41.424] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:52:0) after 1m0s
[21:25:41.452] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:52:0)
[21:25:41.452] Timer tick, asking Buildbucket for the build status
[21:25:42.007] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:42.007] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:53:0) after 1m0s
[21:26:42.209] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:53:0)
[21:26:42.209] Timer tick, asking Buildbucket for the build status
[21:26:42.364] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:42.364] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:54:0) after 1m0s
[21:27:42.389] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:54:0)
[21:27:42.389] Timer tick, asking Buildbucket for the build status
[21:27:43.712] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:43.712] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:55:0) after 1m0s
[21:28:43.739] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:55:0)
[21:28:43.739] Timer tick, asking Buildbucket for the build status
[21:28:43.881] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:43.881] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:56:0) after 1m0s
[21:29:43.905] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:56:0)
[21:29:43.905] Timer tick, asking Buildbucket for the build status
[21:29:44.286] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:44.286] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:57:0) after 1m0s
[21:30:44.315] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:57:0)
[21:30:44.315] Timer tick, asking Buildbucket for the build status
[21:30:44.487] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:44.487] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:58:0) after 1m0s
[21:31:44.507] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:58:0)
[21:31:44.507] Timer tick, asking Buildbucket for the build status
[21:31:45.280] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:45.280] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:59:0) after 1m0s
[21:32:45.331] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:59:0)
[21:32:45.331] Timer tick, asking Buildbucket for the build status
[21:32:46.386] Build 8913090323934516720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:46.386] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9078520386587339216:60:0) after 1m0s
[21:33:26.123] Received PubSub notification, asking Buildbucket for the build status
[21:33:26.247] Build 8913090323934516720: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:33:26.247] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1558215205177492",
"created_by": "project:chromium",
"created_ts": "1558211700462670",
"id": "8913090323934516720",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2da2d86180bd2448704de06fdfc01a30ec9e3d4f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 500.0}, \"$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-trusty-8-1-qy1w\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux\", \"buildnumber\": 23254, \"got_angle_revision\": \"b16d69c32cff965a01d0a6f6c8b5a7c4ac94436b\", \"got_dawn_revision\": \"f3f5bf480d3b4160617a02c74ad76f17b3084759\", \"got_nacl_revision\": \"ca374f5112fca6b36fc5d73e117a60b82f394993\", \"got_revision\": \"2da2d86180bd2448704de06fdfc01a30ec9e3d4f\", \"got_revision_cp\": \"refs/heads/master@{#661192}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"c9178292dbb05caf07f5e1cd985f90383fbba6b9\", \"got_v8_revision_cp\": \"refs/heads/7.6.203@{#1}\", \"got_webrtc_revision\": \"519d74a5fcd869478f2bd8cc3a38eca7dfb608e6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27973}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2da2d86180bd2448704de06fdfc01a30ec9e3d4f\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3805b8ba4abc77de369f5d4399a0a76b312e2fd8c5f804cd390624b9118dfa64_v2\", \"builder_5962259a05f00f2add6b886e805611a7d5779224d38d219d9f2179a6416f230e_v2\", \"builder_78902ab2d70d1593a97658cf8910c0e117cf7950e257117b8c551c6fe1cfb403_v2\", \"builder_86caf262f74ae694bec00fbb86541daa2df96eb35c1a4d9a06c3c1dcd129237e_v2\", \"builder_b9f10e617e36b89b59cf2468f05559e0083126a9b3741fee04e133349127cbf2_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-trusty-8-1-qy1w\"], \"image\": [\"chrome-trusty-19041600-95c8804d634\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4301-0053cb9\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558211729271593",
"status": "COMPLETED",
"status_changed_ts": "1558215205699463",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/23254",
"builder:Deterministic Linux",
"buildset:commit/git/2da2d86180bd2448704de06fdfc01a30ec9e3d4f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2da2d86180bd2448704de06fdfc01a30ec9e3d4f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078520386587339216",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8913090323934516720/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:44e5794155d16810",
"user_agent:luci-scheduler"
],
"updated_ts": "1558215205706380",
"url": "https://ci.chromium.org/b/8913090323934516720",
"utcnow_ts": "1558215206240028"
}
}
[21:33:26.247] Invocation finished in 58m27.697266906s with status SUCCEEDED