[01:39:48.131] New invocation is queued and will start shortly
[01:39:48.131] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[01:39:49.308] Starting the invocation (attempt 1)
[01:39:49.351] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:39:49.351] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:39:49.351] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9083755832638858880",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3729.25\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9083755832638858880",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[01:39:50.818] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "project:infra",
"created_ts": "1553218789656474",
"id": "8918325770376252704",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3729.25\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553218790580804",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9083755832638858880",
"scheduler_job_id:infra/Build From Tarball",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8918325770376252704/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:43bbdf6b6fd30410",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1553218790580902",
"url": "https://ci.chromium.org/b/8918325770376252704",
"utcnow_ts": "1553218790800253"
}
}
[01:39:50.818] Task URL: https://ci.chromium.org/b/8918325770376252704
[01:39:50.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:2:0) after 1m0s
[01:40:40.688] Received PubSub notification, asking Buildbucket for the build status
[01:40:40.958] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:50.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:2:0)
[01:40:50.830] Timer tick, asking Buildbucket for the build status
[01:40:50.899] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:50.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:4:0) after 1m0s
[01:41:51.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:4:0)
[01:41:51.345] Timer tick, asking Buildbucket for the build status
[01:41:51.501] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:51.501] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:5:0) after 1m0s
[01:42:54.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:5:0)
[01:42:54.244] Timer tick, asking Buildbucket for the build status
[01:42:54.620] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:54.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:6:0) after 1m0s
[01:43:54.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:6:0)
[01:43:54.668] Timer tick, asking Buildbucket for the build status
[01:43:54.762] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:54.762] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:7:0) after 1m0s
[01:44:54.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:7:0)
[01:44:54.780] Timer tick, asking Buildbucket for the build status
[01:44:54.885] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:54.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:8:0) after 1m0s
[01:45:54.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:8:0)
[01:45:54.860] Timer tick, asking Buildbucket for the build status
[01:45:55.003] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:55.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:9:0) after 1m0s
[01:46:55.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:9:0)
[01:46:55.014] Timer tick, asking Buildbucket for the build status
[01:46:55.078] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:55.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:10:0) after 1m0s
[01:47:55.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:10:0)
[01:47:55.099] Timer tick, asking Buildbucket for the build status
[01:47:55.161] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:55.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:11:0) after 1m0s
[01:48:55.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:11:0)
[01:48:55.179] Timer tick, asking Buildbucket for the build status
[01:48:55.288] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:55.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:12:0) after 1m0s
[01:49:55.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:12:0)
[01:49:55.321] Timer tick, asking Buildbucket for the build status
[01:49:55.362] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:55.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:13:0) after 1m0s
[01:50:55.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:13:0)
[01:50:55.369] Timer tick, asking Buildbucket for the build status
[01:50:55.451] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:55.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:14:0) after 1m0s
[01:51:55.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:14:0)
[01:51:55.482] Timer tick, asking Buildbucket for the build status
[01:51:55.523] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:55.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:15:0) after 1m0s
[01:52:55.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:15:0)
[01:52:55.574] Timer tick, asking Buildbucket for the build status
[01:52:55.651] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:55.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:16:0) after 1m0s
[01:53:55.660] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:16:0)
[01:53:55.660] Timer tick, asking Buildbucket for the build status
[01:53:55.755] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:55.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:17:0) after 1m0s
[01:54:55.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:17:0)
[01:54:55.774] Timer tick, asking Buildbucket for the build status
[01:54:55.884] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:55.884] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:18:0) after 1m0s
[01:55:55.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:18:0)
[01:55:55.899] Timer tick, asking Buildbucket for the build status
[01:55:55.965] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:55.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:19:0) after 1m0s
[01:56:55.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:19:0)
[01:56:55.976] Timer tick, asking Buildbucket for the build status
[01:56:56.098] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:56.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:20:0) after 1m0s
[01:57:56.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:20:0)
[01:57:56.095] Timer tick, asking Buildbucket for the build status
[01:57:56.203] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:56.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:21:0) after 1m0s
[01:58:56.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:21:0)
[01:58:56.221] Timer tick, asking Buildbucket for the build status
[01:58:56.389] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:56.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:22:0) after 1m0s
[01:59:56.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:22:0)
[01:59:56.408] Timer tick, asking Buildbucket for the build status
[01:59:56.557] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:56.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:23:0) after 1m0s
[02:00:56.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:23:0)
[02:00:56.578] Timer tick, asking Buildbucket for the build status
[02:00:56.656] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:56.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:24:0) after 1m0s
[02:01:56.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:24:0)
[02:01:56.678] Timer tick, asking Buildbucket for the build status
[02:01:56.765] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:56.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:25:0) after 1m0s
[02:02:56.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:25:0)
[02:02:56.757] Timer tick, asking Buildbucket for the build status
[02:02:56.800] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:56.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:26:0) after 1m0s
[02:03:56.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:26:0)
[02:03:56.821] Timer tick, asking Buildbucket for the build status
[02:03:57.112] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:57.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:27:0) after 1m0s
[02:04:57.090] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:27:0)
[02:04:57.090] Timer tick, asking Buildbucket for the build status
[02:04:57.153] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:57.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:28:0) after 1m0s
[02:05:57.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:28:0)
[02:05:57.187] Timer tick, asking Buildbucket for the build status
[02:05:57.490] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:57.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:29:0) after 1m0s
[02:06:57.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:29:0)
[02:06:57.460] Timer tick, asking Buildbucket for the build status
[02:06:57.600] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:57.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:30:0) after 1m0s
[02:07:57.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:30:0)
[02:07:57.592] Timer tick, asking Buildbucket for the build status
[02:07:57.701] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:57.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:31:0) after 1m0s
[02:08:57.690] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:31:0)
[02:08:57.690] Timer tick, asking Buildbucket for the build status
[02:08:57.872] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:57.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:32:0) after 1m0s
[02:09:57.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:32:0)
[02:09:57.888] Timer tick, asking Buildbucket for the build status
[02:09:57.929] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:57.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:33:0) after 1m0s
[02:10:57.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:33:0)
[02:10:57.924] Timer tick, asking Buildbucket for the build status
[02:10:57.969] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:57.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:34:0) after 1m0s
[02:11:57.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:34:0)
[02:11:57.993] Timer tick, asking Buildbucket for the build status
[02:11:58.087] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:58.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:35:0) after 1m0s
[02:12:58.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:35:0)
[02:12:58.106] Timer tick, asking Buildbucket for the build status
[02:12:58.169] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:58.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:36:0) after 1m0s
[02:13:58.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:36:0)
[02:13:58.202] Timer tick, asking Buildbucket for the build status
[02:13:58.255] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:58.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:37:0) after 1m0s
[02:14:58.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:37:0)
[02:14:58.284] Timer tick, asking Buildbucket for the build status
[02:14:58.343] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:58.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:38:0) after 1m0s
[02:15:58.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:38:0)
[02:15:58.429] Timer tick, asking Buildbucket for the build status
[02:15:58.486] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:58.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:39:0) after 1m0s
[02:16:58.505] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:39:0)
[02:16:58.505] Timer tick, asking Buildbucket for the build status
[02:16:58.559] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:58.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:40:0) after 1m0s
[02:17:58.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:40:0)
[02:17:58.554] Timer tick, asking Buildbucket for the build status
[02:17:58.596] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:58.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:41:0) after 1m0s
[02:18:58.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:41:0)
[02:18:58.618] Timer tick, asking Buildbucket for the build status
[02:18:58.665] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:58.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:42:0) after 1m0s
[02:19:58.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:42:0)
[02:19:58.689] Timer tick, asking Buildbucket for the build status
[02:19:58.802] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:58.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:43:0) after 1m0s
[02:20:58.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:43:0)
[02:20:58.796] Timer tick, asking Buildbucket for the build status
[02:20:58.844] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:58.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:44:0) after 1m0s
[02:21:58.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:44:0)
[02:21:58.843] Timer tick, asking Buildbucket for the build status
[02:21:59.051] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:59.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:45:0) after 1m0s
[02:22:59.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:45:0)
[02:22:59.084] Timer tick, asking Buildbucket for the build status
[02:22:59.164] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:59.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:46:0) after 1m0s
[02:23:59.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:46:0)
[02:23:59.151] Timer tick, asking Buildbucket for the build status
[02:23:59.207] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:59.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:47:0) after 1m0s
[02:24:59.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:47:0)
[02:24:59.269] Timer tick, asking Buildbucket for the build status
[02:24:59.460] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:59.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:48:0) after 1m0s
[02:25:59.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:48:0)
[02:25:59.469] Timer tick, asking Buildbucket for the build status
[02:26:01.025] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:01.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:49:0) after 1m0s
[02:27:01.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:49:0)
[02:27:01.049] Timer tick, asking Buildbucket for the build status
[02:27:01.138] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:01.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:50:0) after 1m0s
[02:28:01.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:50:0)
[02:28:01.113] Timer tick, asking Buildbucket for the build status
[02:28:01.213] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:01.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:51:0) after 1m0s
[02:29:01.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:51:0)
[02:29:01.195] Timer tick, asking Buildbucket for the build status
[02:29:01.278] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:01.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:52:0) after 1m0s
[02:30:01.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:52:0)
[02:30:01.309] Timer tick, asking Buildbucket for the build status
[02:30:01.368] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:01.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:53:0) after 1m0s
[02:31:01.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:53:0)
[02:31:01.512] Timer tick, asking Buildbucket for the build status
[02:31:01.576] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:01.576] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:54:0) after 1m0s
[02:32:01.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:54:0)
[02:32:01.638] Timer tick, asking Buildbucket for the build status
[02:32:01.700] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:01.700] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:55:0) after 1m0s
[02:33:01.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:55:0)
[02:33:01.728] Timer tick, asking Buildbucket for the build status
[02:33:01.846] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:01.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:56:0) after 1m0s
[02:34:01.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:56:0)
[02:34:01.872] Timer tick, asking Buildbucket for the build status
[02:34:01.928] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:01.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:57:0) after 1m0s
[02:35:01.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:57:0)
[02:35:01.949] Timer tick, asking Buildbucket for the build status
[02:35:01.999] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:01.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:58:0) after 1m0s
[02:36:02.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:58:0)
[02:36:02.014] Timer tick, asking Buildbucket for the build status
[02:36:02.480] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:02.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:59:0) after 1m0s
[02:37:02.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:59:0)
[02:37:02.503] Timer tick, asking Buildbucket for the build status
[02:37:02.565] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:02.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:60:0) after 1m0s
[02:38:02.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:60:0)
[02:38:02.562] Timer tick, asking Buildbucket for the build status
[02:38:03.166] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:03.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:61:0) after 1m0s
[02:39:03.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:61:0)
[02:39:03.193] Timer tick, asking Buildbucket for the build status
[02:39:03.365] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:03.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:62:0) after 1m0s
[02:40:03.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:62:0)
[02:40:03.356] Timer tick, asking Buildbucket for the build status
[02:40:03.495] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:03.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:63:0) after 1m0s
[02:41:03.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:63:0)
[02:41:03.514] Timer tick, asking Buildbucket for the build status
[02:41:03.608] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:03.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:64:0) after 1m0s
[02:42:03.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:64:0)
[02:42:03.595] Timer tick, asking Buildbucket for the build status
[02:42:03.716] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:03.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:65:0) after 1m0s
[02:43:03.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:65:0)
[02:43:03.727] Timer tick, asking Buildbucket for the build status
[02:43:03.767] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:03.767] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:66:0) after 1m0s
[02:44:03.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:66:0)
[02:44:03.821] Timer tick, asking Buildbucket for the build status
[02:44:03.916] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:03.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:67:0) after 1m0s
[02:45:04.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:67:0)
[02:45:04.581] Timer tick, asking Buildbucket for the build status
[02:45:04.755] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:04.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:68:0) after 1m0s
[02:46:04.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:68:0)
[02:46:04.761] Timer tick, asking Buildbucket for the build status
[02:46:04.942] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:04.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:69:0) after 1m0s
[02:47:04.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:69:0)
[02:47:04.970] Timer tick, asking Buildbucket for the build status
[02:47:05.097] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:05.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:70:0) after 1m0s
[02:48:05.400] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:70:0)
[02:48:05.400] Timer tick, asking Buildbucket for the build status
[02:48:05.484] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:05.484] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:71:0) after 1m0s
[02:49:05.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:71:0)
[02:49:05.495] Timer tick, asking Buildbucket for the build status
[02:49:05.554] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:05.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:72:0) after 1m0s
[02:50:05.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:72:0)
[02:50:05.540] Timer tick, asking Buildbucket for the build status
[02:50:05.648] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:05.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:73:0) after 1m0s
[02:51:05.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:73:0)
[02:51:05.676] Timer tick, asking Buildbucket for the build status
[02:51:05.733] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:05.733] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:74:0) after 1m0s
[02:52:05.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:74:0)
[02:52:05.728] Timer tick, asking Buildbucket for the build status
[02:52:05.769] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:05.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:75:0) after 1m0s
[02:53:05.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:75:0)
[02:53:05.801] Timer tick, asking Buildbucket for the build status
[02:53:05.891] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:05.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:76:0) after 1m0s
[02:54:05.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:76:0)
[02:54:05.924] Timer tick, asking Buildbucket for the build status
[02:54:05.990] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:05.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:77:0) after 1m0s
[02:55:06.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:77:0)
[02:55:06.041] Timer tick, asking Buildbucket for the build status
[02:55:06.137] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:06.137] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:78:0) after 1m0s
[02:56:06.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:78:0)
[02:56:06.164] Timer tick, asking Buildbucket for the build status
[02:56:06.228] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:06.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:79:0) after 1m0s
[02:57:06.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:79:0)
[02:57:06.208] Timer tick, asking Buildbucket for the build status
[02:57:06.353] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:06.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:80:0) after 1m0s
[02:58:06.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:80:0)
[02:58:06.362] Timer tick, asking Buildbucket for the build status
[02:58:06.405] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:06.405] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:81:0) after 1m0s
[02:59:06.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:81:0)
[02:59:06.390] Timer tick, asking Buildbucket for the build status
[02:59:06.456] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:06.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:82:0) after 1m0s
[03:00:06.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:82:0)
[03:00:06.521] Timer tick, asking Buildbucket for the build status
[03:00:06.564] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:06.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:83:0) after 1m0s
[03:01:06.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:83:0)
[03:01:06.584] Timer tick, asking Buildbucket for the build status
[03:01:06.664] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:06.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:84:0) after 1m0s
[03:02:06.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:84:0)
[03:02:06.766] Timer tick, asking Buildbucket for the build status
[03:02:06.836] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:06.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:85:0) after 1m0s
[03:03:06.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:85:0)
[03:03:06.859] Timer tick, asking Buildbucket for the build status
[03:03:06.919] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:06.919] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:86:0) after 1m0s
[03:04:06.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:86:0)
[03:04:06.926] Timer tick, asking Buildbucket for the build status
[03:04:06.968] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:06.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:87:0) after 1m0s
[03:05:06.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:87:0)
[03:05:06.964] Timer tick, asking Buildbucket for the build status
[03:05:07.129] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:07.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:88:0) after 1m0s
[03:06:07.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:88:0)
[03:06:07.136] Timer tick, asking Buildbucket for the build status
[03:06:07.291] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:07.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:89:0) after 1m0s
[03:07:07.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:89:0)
[03:07:07.316] Timer tick, asking Buildbucket for the build status
[03:07:07.373] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:07.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:90:0) after 1m0s
[03:08:07.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:90:0)
[03:08:07.428] Timer tick, asking Buildbucket for the build status
[03:08:07.472] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:07.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:91:0) after 1m0s
[03:09:07.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:91:0)
[03:09:07.479] Timer tick, asking Buildbucket for the build status
[03:09:07.557] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:07.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:92:0) after 1m0s
[03:10:07.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:92:0)
[03:10:07.675] Timer tick, asking Buildbucket for the build status
[03:10:07.747] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:07.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:93:0) after 1m0s
[03:11:07.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:93:0)
[03:11:07.824] Timer tick, asking Buildbucket for the build status
[03:11:07.884] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:07.884] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:94:0) after 1m0s
[03:12:07.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:94:0)
[03:12:07.895] Timer tick, asking Buildbucket for the build status
[03:12:07.955] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:07.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:95:0) after 1m0s
[03:13:08.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:95:0)
[03:13:08.000] Timer tick, asking Buildbucket for the build status
[03:13:08.056] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:08.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:96:0) after 1m0s
[03:14:08.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:96:0)
[03:14:08.046] Timer tick, asking Buildbucket for the build status
[03:14:08.100] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:08.100] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:97:0) after 1m0s
[03:15:08.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:97:0)
[03:15:08.095] Timer tick, asking Buildbucket for the build status
[03:15:08.377] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:08.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:98:0) after 1m0s
[03:16:08.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:98:0)
[03:16:08.409] Timer tick, asking Buildbucket for the build status
[03:16:08.469] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:08.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:99:0) after 1m0s
[03:17:08.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:99:0)
[03:17:08.594] Timer tick, asking Buildbucket for the build status
[03:17:08.670] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:08.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:100:0) after 1m0s
[03:18:08.676] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:100:0)
[03:18:08.676] Timer tick, asking Buildbucket for the build status
[03:18:08.973] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:08.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:101:0) after 1m0s
[03:19:08.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:101:0)
[03:19:08.990] Timer tick, asking Buildbucket for the build status
[03:19:09.171] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:09.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:102:0) after 1m0s
[03:20:09.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:102:0)
[03:20:09.205] Timer tick, asking Buildbucket for the build status
[03:20:09.283] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:09.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:103:0) after 1m0s
[03:21:09.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:103:0)
[03:21:09.301] Timer tick, asking Buildbucket for the build status
[03:21:09.383] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:09.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:104:0) after 1m0s
[03:22:09.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:104:0)
[03:22:09.397] Timer tick, asking Buildbucket for the build status
[03:22:09.461] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:09.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:105:0) after 1m0s
[03:23:09.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:105:0)
[03:23:09.487] Timer tick, asking Buildbucket for the build status
[03:23:09.540] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:09.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:106:0) after 1m0s
[03:24:09.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:106:0)
[03:24:09.522] Timer tick, asking Buildbucket for the build status
[03:24:09.596] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:09.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:107:0) after 1m0s
[03:25:09.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:107:0)
[03:25:09.624] Timer tick, asking Buildbucket for the build status
[03:25:09.752] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:09.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:108:0) after 1m0s
[03:26:09.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:108:0)
[03:26:09.784] Timer tick, asking Buildbucket for the build status
[03:26:09.876] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:09.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:109:0) after 1m0s
[03:27:09.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:109:0)
[03:27:09.883] Timer tick, asking Buildbucket for the build status
[03:27:09.942] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:09.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:110:0) after 1m0s
[03:28:09.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:110:0)
[03:28:09.968] Timer tick, asking Buildbucket for the build status
[03:28:10.091] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:10.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:111:0) after 1m0s
[03:29:10.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:111:0)
[03:29:10.099] Timer tick, asking Buildbucket for the build status
[03:29:10.162] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:10.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:112:0) after 1m0s
[03:30:10.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:112:0)
[03:30:10.172] Timer tick, asking Buildbucket for the build status
[03:30:10.259] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:10.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:113:0) after 1m0s
[03:31:10.429] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:113:0)
[03:31:10.429] Timer tick, asking Buildbucket for the build status
[03:31:10.515] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:10.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:114:0) after 1m0s
[03:32:10.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:114:0)
[03:32:10.538] Timer tick, asking Buildbucket for the build status
[03:32:10.674] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:10.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:115:0) after 1m0s
[03:33:10.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:115:0)
[03:33:10.706] Timer tick, asking Buildbucket for the build status
[03:33:10.764] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:10.764] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:116:0) after 1m0s
[03:34:10.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:116:0)
[03:34:10.870] Timer tick, asking Buildbucket for the build status
[03:34:11.050] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:11.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:117:0) after 1m0s
[03:35:11.029] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:117:0)
[03:35:11.029] Timer tick, asking Buildbucket for the build status
[03:35:11.188] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:11.188] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:118:0) after 1m0s
[03:36:11.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:118:0)
[03:36:11.173] Timer tick, asking Buildbucket for the build status
[03:36:11.253] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:11.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:119:0) after 1m0s
[03:37:11.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:119:0)
[03:37:11.551] Timer tick, asking Buildbucket for the build status
[03:37:12.685] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:12.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:120:0) after 1m0s
[03:38:12.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:120:0)
[03:38:12.748] Timer tick, asking Buildbucket for the build status
[03:38:13.102] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:13.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:121:0) after 1m0s
[03:39:13.173] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:121:0)
[03:39:13.173] Timer tick, asking Buildbucket for the build status
[03:39:13.421] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:13.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:122:0) after 1m0s
[03:40:13.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:122:0)
[03:40:13.643] Timer tick, asking Buildbucket for the build status
[03:40:13.713] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:13.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:123:0) after 1m0s
[03:41:13.739] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:123:0)
[03:41:13.739] Timer tick, asking Buildbucket for the build status
[03:41:14.064] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:14.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:124:0) after 1m0s
[03:42:14.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:124:0)
[03:42:14.069] Timer tick, asking Buildbucket for the build status
[03:42:14.127] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:14.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:125:0) after 1m0s
[03:43:14.207] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:125:0)
[03:43:14.207] Timer tick, asking Buildbucket for the build status
[03:43:14.285] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:14.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:126:0) after 1m0s
[03:44:14.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:126:0)
[03:44:14.307] Timer tick, asking Buildbucket for the build status
[03:44:14.391] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:14.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:127:0) after 1m0s
[03:45:14.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:127:0)
[03:45:14.430] Timer tick, asking Buildbucket for the build status
[03:45:14.588] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:14.588] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:128:0) after 1m0s
[03:46:14.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:128:0)
[03:46:14.632] Timer tick, asking Buildbucket for the build status
[03:46:14.783] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:14.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:129:0) after 1m0s
[03:47:14.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:129:0)
[03:47:14.838] Timer tick, asking Buildbucket for the build status
[03:47:15.092] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:15.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:130:0) after 1m0s
[03:48:15.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:130:0)
[03:48:15.096] Timer tick, asking Buildbucket for the build status
[03:48:15.283] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:15.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:131:0) after 1m0s
[03:49:15.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:131:0)
[03:49:15.282] Timer tick, asking Buildbucket for the build status
[03:49:15.334] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:15.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:132:0) after 1m0s
[03:50:15.360] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:132:0)
[03:50:15.360] Timer tick, asking Buildbucket for the build status
[03:50:15.401] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:15.401] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:133:0) after 1m0s
[03:51:15.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:133:0)
[03:51:15.426] Timer tick, asking Buildbucket for the build status
[03:51:15.491] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:15.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:134:0) after 1m0s
[03:52:15.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:134:0)
[03:52:15.520] Timer tick, asking Buildbucket for the build status
[03:52:15.574] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:15.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:135:0) after 1m0s
[03:53:15.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:135:0)
[03:53:15.588] Timer tick, asking Buildbucket for the build status
[03:53:15.671] Build 8918325770376252704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:15.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9083755832638858880:136:0) after 1m0s
[03:53:45.616] Received PubSub notification, asking Buildbucket for the build status
[03:53:45.661] Build 8918325770376252704: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:53:45.661] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1553226824875161",
"created_by": "project:infra",
"created_ts": "1553218789656474",
"id": "8918325770376252704",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3729.25\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$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\": \"build-from-tarball-0-hkpx\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"74.0.3729.25\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Build From Tarball\"], \"caches\": [\"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"build-from-tarball-0-hkpx\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4213-ad7b457\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553218839244043",
"status": "COMPLETED",
"status_changed_ts": "1553226825434265",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9083755832638858880",
"scheduler_job_id:infra/Build From Tarball",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8918325770376252704/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:43bbdf6b6fd30410",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1553226825436577",
"url": "https://ci.chromium.org/b/8918325770376252704",
"utcnow_ts": "1553226825654014"
}
}
[03:53:45.661] Invocation finished in 2h13m57.54734937s with status SUCCEEDED