[22:53:58.167] New invocation is queued and will start shortly
[22:53:59.300] Starting the invocation (attempt 1)
[22:53:59.336] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:53:59.336] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:53:59.336] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9086212384029586800",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"7fa8ccb5428e25f1ece3efe4d2560a1346c06512\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9086212384029586800",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/7fa8ccb5428e25f1ece3efe4d2560a1346c06512",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7fa8ccb5428e25f1ece3efe4d2560a1346c06512",
"gitiles_ref:refs/heads/master"
]
}
[22:54:00.587] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550876039480651",
"id": "8920782321984739696",
"lease_expiration_ts": "1550994840241444",
"lease_key": "2033896003",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7fa8ccb5428e25f1ece3efe4d2560a1346c06512\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1550876040439945",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5340",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/7fa8ccb5428e25f1ece3efe4d2560a1346c06512",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7fa8ccb5428e25f1ece3efe4d2560a1346c06512",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9086212384029586800",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8920782321984739696/+/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:43303be362c4d110",
"user_agent:luci-scheduler"
],
"updated_ts": "1550876040440041",
"url": "https://ci.chromium.org/b/8920782321984739696",
"utcnow_ts": "1550876040578184"
}
}
[22:54:00.587] Task URL: https://ci.chromium.org/b/8920782321984739696
[22:54:00.587] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:2:0) after 1m0s
[22:54:01.555] Received PubSub notification, asking Buildbucket for the build status
[22:54:01.611] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:00.614] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:2:0)
[22:55:00.614] Timer tick, asking Buildbucket for the build status
[22:55:00.784] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:00.784] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:4:0) after 1m0s
[22:56:00.813] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:4:0)
[22:56:00.813] Timer tick, asking Buildbucket for the build status
[22:56:00.858] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:00.858] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:5:0) after 1m0s
[22:57:00.871] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:5:0)
[22:57:00.871] Timer tick, asking Buildbucket for the build status
[22:57:00.960] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:00.960] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:6:0) after 1m0s
[22:58:00.978] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:6:0)
[22:58:00.978] Timer tick, asking Buildbucket for the build status
[22:58:01.026] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:01.026] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:7:0) after 1m0s
[22:59:01.049] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:7:0)
[22:59:01.049] Timer tick, asking Buildbucket for the build status
[22:59:01.137] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:01.137] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:8:0) after 1m0s
[23:00:01.157] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:8:0)
[23:00:01.157] Timer tick, asking Buildbucket for the build status
[23:00:01.372] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:01.372] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:9:0) after 1m0s
[23:01:01.394] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:9:0)
[23:01:01.394] Timer tick, asking Buildbucket for the build status
[23:01:01.583] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:01.583] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:10:0) after 1m0s
[23:02:01.618] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:10:0)
[23:02:01.618] Timer tick, asking Buildbucket for the build status
[23:02:01.687] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:01.687] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:11:0) after 1m0s
[23:03:01.714] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:11:0)
[23:03:01.714] Timer tick, asking Buildbucket for the build status
[23:03:01.759] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:01.759] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:12:0) after 1m0s
[23:04:01.781] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:12:0)
[23:04:01.781] Timer tick, asking Buildbucket for the build status
[23:04:01.856] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:01.856] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:13:0) after 1m0s
[23:05:01.874] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:13:0)
[23:05:01.874] Timer tick, asking Buildbucket for the build status
[23:05:01.924] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:01.924] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:14:0) after 1m0s
[23:06:01.961] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:14:0)
[23:06:01.961] Timer tick, asking Buildbucket for the build status
[23:06:02.019] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:02.019] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:15:0) after 1m0s
[23:07:02.038] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:15:0)
[23:07:02.038] Timer tick, asking Buildbucket for the build status
[23:07:02.112] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:02.112] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:16:0) after 1m0s
[23:08:02.145] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:16:0)
[23:08:02.145] Timer tick, asking Buildbucket for the build status
[23:08:02.220] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:02.220] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:17:0) after 1m0s
[23:09:02.246] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:17:0)
[23:09:02.246] Timer tick, asking Buildbucket for the build status
[23:09:02.323] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:02.323] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:18:0) after 1m0s
[23:10:02.349] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:18:0)
[23:10:02.349] Timer tick, asking Buildbucket for the build status
[23:10:02.407] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:02.407] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:19:0) after 1m0s
[23:11:02.434] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:19:0)
[23:11:02.434] Timer tick, asking Buildbucket for the build status
[23:11:02.588] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:02.588] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:20:0) after 1m0s
[23:12:02.641] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:20:0)
[23:12:02.641] Timer tick, asking Buildbucket for the build status
[23:12:02.809] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:02.809] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:21:0) after 1m0s
[23:13:02.869] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:21:0)
[23:13:02.869] Timer tick, asking Buildbucket for the build status
[23:13:02.939] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:02.939] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:22:0) after 1m0s
[23:14:03.050] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:22:0)
[23:14:03.050] Timer tick, asking Buildbucket for the build status
[23:14:03.133] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:03.133] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:23:0) after 1m0s
[23:15:03.204] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:23:0)
[23:15:03.204] Timer tick, asking Buildbucket for the build status
[23:15:03.284] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:03.284] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:24:0) after 1m0s
[23:16:03.313] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:24:0)
[23:16:03.313] Timer tick, asking Buildbucket for the build status
[23:16:03.491] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:03.491] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:25:0) after 1m0s
[23:17:03.527] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:25:0)
[23:17:03.527] Timer tick, asking Buildbucket for the build status
[23:17:03.571] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:03.571] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:26:0) after 1m0s
[23:18:03.592] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:26:0)
[23:18:03.592] Timer tick, asking Buildbucket for the build status
[23:18:03.782] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:03.782] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:27:0) after 1m0s
[23:19:03.805] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:27:0)
[23:19:03.805] Timer tick, asking Buildbucket for the build status
[23:19:03.850] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:03.850] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:28:0) after 1m0s
[23:20:03.870] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:28:0)
[23:20:03.870] Timer tick, asking Buildbucket for the build status
[23:20:04.162] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:04.162] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:29:0) after 1m0s
[23:21:04.239] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:29:0)
[23:21:04.239] Timer tick, asking Buildbucket for the build status
[23:21:04.375] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:04.375] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:30:0) after 1m0s
[23:22:04.394] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:30:0)
[23:22:04.394] Timer tick, asking Buildbucket for the build status
[23:22:04.458] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:04.458] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:31:0) after 1m0s
[23:23:04.477] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:31:0)
[23:23:04.477] Timer tick, asking Buildbucket for the build status
[23:23:04.537] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:04.537] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:32:0) after 1m0s
[23:24:04.557] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:32:0)
[23:24:04.557] Timer tick, asking Buildbucket for the build status
[23:24:04.968] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:04.969] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:33:0) after 1m0s
[23:25:04.987] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:33:0)
[23:25:04.987] Timer tick, asking Buildbucket for the build status
[23:25:05.162] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:05.163] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:34:0) after 1m0s
[23:26:05.182] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:34:0)
[23:26:05.182] Timer tick, asking Buildbucket for the build status
[23:26:05.230] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:05.230] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:35:0) after 1m0s
[23:27:05.262] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:35:0)
[23:27:05.262] Timer tick, asking Buildbucket for the build status
[23:27:05.316] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:05.316] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:36:0) after 1m0s
[23:28:05.335] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:36:0)
[23:28:05.335] Timer tick, asking Buildbucket for the build status
[23:28:05.391] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:05.391] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:37:0) after 1m0s
[23:29:05.413] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:37:0)
[23:29:05.413] Timer tick, asking Buildbucket for the build status
[23:29:05.461] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:05.461] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:38:0) after 1m0s
[23:30:05.488] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:38:0)
[23:30:05.488] Timer tick, asking Buildbucket for the build status
[23:30:05.560] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:05.560] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:39:0) after 1m0s
[23:31:05.582] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:39:0)
[23:31:05.582] Timer tick, asking Buildbucket for the build status
[23:31:05.663] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:05.663] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:40:0) after 1m0s
[23:32:05.686] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:40:0)
[23:32:05.686] Timer tick, asking Buildbucket for the build status
[23:32:05.723] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:05.723] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:41:0) after 1m0s
[23:33:05.757] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:41:0)
[23:33:05.757] Timer tick, asking Buildbucket for the build status
[23:33:05.813] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:05.813] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:42:0) after 1m0s
[23:34:05.849] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:42:0)
[23:34:05.849] Timer tick, asking Buildbucket for the build status
[23:34:05.908] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:05.908] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:43:0) after 1m0s
[23:35:05.926] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:43:0)
[23:35:05.926] Timer tick, asking Buildbucket for the build status
[23:35:06.014] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:06.014] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:44:0) after 1m0s
[23:36:06.035] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:44:0)
[23:36:06.035] Timer tick, asking Buildbucket for the build status
[23:36:06.169] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:06.169] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:45:0) after 1m0s
[23:37:06.189] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:45:0)
[23:37:06.189] Timer tick, asking Buildbucket for the build status
[23:37:06.233] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:06.233] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:46:0) after 1m0s
[23:38:06.257] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:46:0)
[23:38:06.257] Timer tick, asking Buildbucket for the build status
[23:38:06.374] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:06.374] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:47:0) after 1m0s
[23:39:06.406] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:47:0)
[23:39:06.406] Timer tick, asking Buildbucket for the build status
[23:39:06.523] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:06.523] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:48:0) after 1m0s
[23:40:06.560] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:48:0)
[23:40:06.560] Timer tick, asking Buildbucket for the build status
[23:40:06.613] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:06.613] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:49:0) after 1m0s
[23:41:06.636] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:49:0)
[23:41:06.636] Timer tick, asking Buildbucket for the build status
[23:41:06.771] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:06.771] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:50:0) after 1m0s
[23:42:06.807] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:50:0)
[23:42:06.807] Timer tick, asking Buildbucket for the build status
[23:42:06.953] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:06.953] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:51:0) after 1m0s
[23:43:06.974] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:51:0)
[23:43:06.974] Timer tick, asking Buildbucket for the build status
[23:43:07.091] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:07.091] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:52:0) after 1m0s
[23:44:07.120] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:52:0)
[23:44:07.120] Timer tick, asking Buildbucket for the build status
[23:44:07.168] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:07.168] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:53:0) after 1m0s
[23:45:07.189] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:53:0)
[23:45:07.189] Timer tick, asking Buildbucket for the build status
[23:45:07.232] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:07.232] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:54:0) after 1m0s
[23:46:07.251] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:54:0)
[23:46:07.251] Timer tick, asking Buildbucket for the build status
[23:46:07.384] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:07.384] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:55:0) after 1m0s
[23:47:07.424] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:55:0)
[23:47:07.424] Timer tick, asking Buildbucket for the build status
[23:47:07.651] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:07.651] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:56:0) after 1m0s
[23:48:07.688] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:56:0)
[23:48:07.688] Timer tick, asking Buildbucket for the build status
[23:48:07.731] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:07.731] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:57:0) after 1m0s
[23:49:07.794] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:57:0)
[23:49:07.794] Timer tick, asking Buildbucket for the build status
[23:49:08.013] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:08.014] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:58:0) after 1m0s
[23:50:08.049] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:58:0)
[23:50:08.049] Timer tick, asking Buildbucket for the build status
[23:50:08.107] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:08.107] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:59:0) after 1m0s
[23:51:08.146] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:59:0)
[23:51:08.146] Timer tick, asking Buildbucket for the build status
[23:51:08.242] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:08.242] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:60:0) after 1m0s
[23:52:08.282] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:60:0)
[23:52:08.282] Timer tick, asking Buildbucket for the build status
[23:52:08.366] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:08.366] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:61:0) after 1m0s
[23:53:08.385] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:61:0)
[23:53:08.385] Timer tick, asking Buildbucket for the build status
[23:53:08.592] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:08.592] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:62:0) after 1m0s
[23:54:08.654] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:62:0)
[23:54:08.654] Timer tick, asking Buildbucket for the build status
[23:54:08.732] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:08.732] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:63:0) after 1m0s
[23:55:08.753] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:63:0)
[23:55:08.753] Timer tick, asking Buildbucket for the build status
[23:55:08.804] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:08.804] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:64:0) after 1m0s
[23:56:08.828] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:64:0)
[23:56:08.828] Timer tick, asking Buildbucket for the build status
[23:56:08.899] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:08.899] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:65:0) after 1m0s
[23:57:08.921] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:65:0)
[23:57:08.921] Timer tick, asking Buildbucket for the build status
[23:57:08.990] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:08.990] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:66:0) after 1m0s
[23:58:09.027] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:66:0)
[23:58:09.027] Timer tick, asking Buildbucket for the build status
[23:58:09.089] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:09.089] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:67:0) after 1m0s
[23:59:09.128] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:67:0)
[23:59:09.128] Timer tick, asking Buildbucket for the build status
[23:59:09.168] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:09.168] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:68:0) after 1m0s
[00:00:09.182] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:68:0)
[00:00:09.182] Timer tick, asking Buildbucket for the build status
[00:00:09.280] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:09.280] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:69:0) after 1m0s
[00:01:09.424] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:69:0)
[00:01:09.424] Timer tick, asking Buildbucket for the build status
[00:01:09.476] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:09.476] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:70:0) after 1m0s
[00:02:09.522] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:70:0)
[00:02:09.522] Timer tick, asking Buildbucket for the build status
[00:02:09.731] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:09.731] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:71:0) after 1m0s
[00:03:09.749] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:71:0)
[00:03:09.749] Timer tick, asking Buildbucket for the build status
[00:03:09.808] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:09.808] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:72:0) after 1m0s
[00:04:09.852] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:72:0)
[00:04:09.852] Timer tick, asking Buildbucket for the build status
[00:04:09.908] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:09.909] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:73:0) after 1m0s
[00:05:09.931] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:73:0)
[00:05:09.931] Timer tick, asking Buildbucket for the build status
[00:05:10.010] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:10.010] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:74:0) after 1m0s
[00:06:10.022] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:74:0)
[00:06:10.022] Timer tick, asking Buildbucket for the build status
[00:06:10.080] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:10.080] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:75:0) after 1m0s
[00:07:10.114] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:75:0)
[00:07:10.114] Timer tick, asking Buildbucket for the build status
[00:07:10.170] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:10.170] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:76:0) after 1m0s
[00:08:10.207] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:76:0)
[00:08:10.207] Timer tick, asking Buildbucket for the build status
[00:08:10.257] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:10.257] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:77:0) after 1m0s
[00:09:10.280] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:77:0)
[00:09:10.280] Timer tick, asking Buildbucket for the build status
[00:09:10.376] Build 8920782321984739696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:10.376] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9086212384029586800:78:0) after 1m0s
[00:09:12.265] Received PubSub notification, asking Buildbucket for the build status
[00:09:12.531] Build 8920782321984739696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:09:12.531] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1550880551257419",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550876039480651",
"id": "8920782321984739696",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7fa8ccb5428e25f1ece3efe4d2560a1346c06512\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Deterministic Linux (dbg)\"], \"kvm\": [\"1\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-a-n2zs\"], \"server_version\": [\"4120-35fcaef\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-a\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_swarming_client_revision\": \"d50a88f50782ba29076061b94c7b9d08a6c7e424\", \"got_revision\": \"7fa8ccb5428e25f1ece3efe4d2560a1346c06512\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"87bf834109fd5d372e62901101f285d4fb65fe67\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26820}\", \"$build/goma\": {\"jobs\": 500}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#634848}\", \"branch\": \"refs/heads/master\", \"revision\": \"7fa8ccb5428e25f1ece3efe4d2560a1346c06512\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Deterministic Linux (dbg)\", \"got_webrtc_revision\": \"487c09bb73c18fd7ae8c2efc746531422c324c1f\", \"mastername\": \"chromium.linux\", \"got_angle_revision\": \"d09546e1ec906c5027c25ba0ba3a49e5affb6643\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550876039480651, \"tags\": [\"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5340\", \"builder:Deterministic Linux (dbg)\", \"buildset:commit/git/7fa8ccb5428e25f1ece3efe4d2560a1346c06512\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7fa8ccb5428e25f1ece3efe4d2560a1346c06512\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9086212384029586800\", \"scheduler_job_id:chromium/Deterministic Linux (dbg)\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8920782321984739696\"}}, \"got_v8_revision\": \"6b0fdfceb1d6b3c73a1e84d510307ef965601465\", \"got_v8_revision_cp\": \"refs/heads/7.4.206@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 5340, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Deterministic Linux (dbg)\", \"key\": \"builder\"}, {\"value\": \"commit/git/7fa8ccb5428e25f1ece3efe4d2560a1346c06512\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/7fa8ccb5428e25f1ece3efe4d2560a1346c06512\", \"key\": \"buildset\"}, {\"value\": \"9086212384029586800\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Deterministic Linux (dbg)\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Deterministic Linux (dbg)\", \"bucket\": \"ci\"}, \"number\": 5340, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"095769ecaa2f9cf22e7a4bf3cb85e05f9464312f\", \"canary\": true}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8920782321984739696\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"7fa8ccb5428e25f1ece3efe4d2560a1346c06512\"}}, \"createTime\": \"2019-02-22T22:53:59.480651Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-e833d7b0-us-west1-a-n2zs\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1550876040872933",
"status": "COMPLETED",
"status_changed_ts": "1550880551993521",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5340",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/7fa8ccb5428e25f1ece3efe4d2560a1346c06512",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7fa8ccb5428e25f1ece3efe4d2560a1346c06512",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9086212384029586800",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8920782321984739696/+/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:43303be362c4d110",
"user_agent:luci-scheduler"
],
"updated_ts": "1550880551995051",
"url": "https://ci.chromium.org/b/8920782321984739696",
"utcnow_ts": "1550880552521431"
}
}
[00:09:12.531] Invocation finished in 1h15m14.384638141s with status SUCCEEDED