[15:44:14.095] New invocation is queued and will start shortly
[15:44:15.167] Starting the invocation (attempt 1)
[15:44:15.199] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:44:15.199] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:44:15.199] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017657517018885328",
"parameters_json": "{\"builder_name\":\"ToTiOSDevice\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f17953d7423937a14b21ec15f08b28cefa7a4f1f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOSDevice",
"scheduler_invocation_id:9017657517018885328",
"scheduler_job_id:chromium/ToTiOSDevice",
"user_agent:luci-scheduler",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f17953d7423937a14b21ec15f08b28cefa7a4f1f",
"gitiles_ref:refs/heads/master"
]
}
[15:44:16.213] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1616255055308143",
"id": "8852227455083561408",
"parameters_json": "{\"builder_name\": \"ToTiOSDevice\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f17953d7423937a14b21ec15f08b28cefa7a4f1f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616255056013583",
"tags": [
"build_address:luci.chromium.ci/ToTiOSDevice/21750",
"builder:ToTiOSDevice",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f17953d7423937a14b21ec15f08b28cefa7a4f1f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017657517018885328",
"scheduler_job_id:chromium/ToTiOSDevice",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852227455083561408/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1616255056013717",
"url": "https://ci.chromium.org/b/8852227455083561408",
"utcnow_ts": "1616255056188680"
}
}
[15:44:16.213] Task URL: https://ci.chromium.org/b/8852227455083561408
[15:44:16.213] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:2:0) after 1m0s
[15:45:16.258] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:2:0)
[15:45:16.287] Build status: SCHEDULED
[15:45:16.287] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:3:0) after 3m38s
[15:47:12.779] Received PubSub notification, asking Buildbucket for the build status
[15:47:12.805] Build status: STARTED
[15:48:54.301] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:3:0)
[15:48:54.301] Timer tick, asking Buildbucket for the build status
[15:48:54.488] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:54.488] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:5:0) after 1m0s
[15:49:54.503] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:5:0)
[15:49:54.503] Timer tick, asking Buildbucket for the build status
[15:49:54.595] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:54.595] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:6:0) after 1m0s
[15:50:54.731] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:6:0)
[15:50:54.731] Timer tick, asking Buildbucket for the build status
[15:50:55.009] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:55.009] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:7:0) after 1m0s
[15:51:55.073] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:7:0)
[15:51:55.073] Timer tick, asking Buildbucket for the build status
[15:51:55.263] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:55.263] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:8:0) after 1m0s
[15:52:55.295] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:8:0)
[15:52:55.295] Timer tick, asking Buildbucket for the build status
[15:52:55.516] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:55.516] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:9:0) after 1m0s
[15:53:55.498] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:9:0)
[15:53:55.533] Build status: STARTED
[15:53:55.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:10:0) after 6m46s
[16:00:41.577] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:10:0)
[16:00:41.612] Build status: STARTED
[16:00:41.612] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:11:0) after 9m19s
[16:10:00.979] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:11:0)
[16:10:00.979] Timer tick, asking Buildbucket for the build status
[16:10:01.245] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:01.245] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:12:0) after 1m0s
[16:11:01.291] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:12:0)
[16:11:01.291] Timer tick, asking Buildbucket for the build status
[16:11:01.435] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:01.435] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:13:0) after 1m0s
[16:12:01.451] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:13:0)
[16:12:01.479] Build status: STARTED
[16:12:01.479] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:14:0) after 2m15s
[16:14:16.714] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:14:0)
[16:14:16.714] Timer tick, asking Buildbucket for the build status
[16:14:16.809] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:16.809] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:15:0) after 1m0s
[16:15:16.825] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:15:0)
[16:15:16.856] Build status: STARTED
[16:15:16.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:16:0) after 7m23s
[16:22:39.860] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:16:0)
[16:22:39.860] Timer tick, asking Buildbucket for the build status
[16:22:39.941] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:39.941] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:17:0) after 1m0s
[16:23:39.960] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:17:0)
[16:23:39.990] Build status: STARTED
[16:23:39.990] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:18:0) after 9m45s
[16:33:25.044] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:18:0)
[16:33:25.084] Build status: STARTED
[16:33:25.084] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:19:0) after 8m30s
[16:41:55.084] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:19:0)
[16:41:55.107] Build status: STARTED
[16:41:55.107] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:20:0) after 2m24s
[16:44:19.125] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:20:0)
[16:44:19.154] Build status: STARTED
[16:44:19.154] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:21:0) after 6m55s
[16:51:14.192] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:21:0)
[16:51:14.192] Timer tick, asking Buildbucket for the build status
[16:51:14.311] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:14.311] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:22:0) after 1m0s
[16:52:14.334] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:22:0)
[16:52:14.334] Timer tick, asking Buildbucket for the build status
[16:52:14.420] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:14.420] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:23:0) after 1m0s
[16:53:14.617] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:23:0)
[16:53:14.617] Timer tick, asking Buildbucket for the build status
[16:53:14.697] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:14.697] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:24:0) after 1m0s
[16:54:14.716] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:24:0)
[16:54:14.716] Timer tick, asking Buildbucket for the build status
[16:54:14.793] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:14.793] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:25:0) after 1m0s
[16:55:14.818] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:25:0)
[16:55:14.818] Timer tick, asking Buildbucket for the build status
[16:55:14.986] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:14.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:26:0) after 1m0s
[16:56:15.006] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:26:0)
[16:56:15.006] Timer tick, asking Buildbucket for the build status
[16:56:15.071] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:15.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:27:0) after 1m0s
[16:57:15.098] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:27:0)
[16:57:15.098] Timer tick, asking Buildbucket for the build status
[16:57:15.167] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:15.167] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:28:0) after 1m0s
[16:58:15.193] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:28:0)
[16:58:15.193] Timer tick, asking Buildbucket for the build status
[16:58:15.265] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:15.265] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:29:0) after 1m0s
[16:59:15.376] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:29:0)
[16:59:15.377] Timer tick, asking Buildbucket for the build status
[16:59:15.654] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:15.654] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:30:0) after 1m0s
[17:00:15.715] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:30:0)
[17:00:15.715] Timer tick, asking Buildbucket for the build status
[17:00:15.801] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:15.801] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:31:0) after 1m0s
[17:01:15.823] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:31:0)
[17:01:15.823] Timer tick, asking Buildbucket for the build status
[17:01:15.892] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:15.892] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:32:0) after 1m0s
[17:02:16.141] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:32:0)
[17:02:16.174] Build status: STARTED
[17:02:16.174] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:33:0) after 5m20s
[17:07:36.208] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:33:0)
[17:07:36.234] Build status: STARTED
[17:07:36.234] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:34:0) after 6m46s
[17:14:22.254] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:34:0)
[17:14:22.335] Build status: STARTED
[17:14:22.335] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:35:0) after 7m14s
[17:21:36.354] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:35:0)
[17:21:36.354] Timer tick, asking Buildbucket for the build status
[17:21:36.538] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:36.538] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:36:0) after 1m0s
[17:22:36.562] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:36:0)
[17:22:36.562] Timer tick, asking Buildbucket for the build status
[17:22:36.650] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:36.650] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:37:0) after 1m0s
[17:23:36.668] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:37:0)
[17:23:36.694] Build status: STARTED
[17:23:36.694] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:38:0) after 9m34s
[17:33:10.730] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:38:0)
[17:33:10.770] Build status: STARTED
[17:33:10.770] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:39:0) after 8m20s
[17:41:30.797] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:39:0)
[17:41:30.837] Build status: STARTED
[17:41:30.837] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:40:0) after 7m27s
[17:48:58.134] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:40:0)
[17:48:58.134] Timer tick, asking Buildbucket for the build status
[17:48:58.200] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:58.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:41:0) after 1m0s
[17:49:58.346] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:41:0)
[17:49:58.375] Build status: STARTED
[17:49:58.375] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:42:0) after 3m50s
[17:53:48.584] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:42:0)
[17:53:48.615] Build status: STARTED
[17:53:48.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:43:0) after 6m28s
[18:00:16.640] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:43:0)
[18:00:16.640] Timer tick, asking Buildbucket for the build status
[18:00:17.297] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:17.297] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:44:0) after 1m0s
[18:01:17.318] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:44:0)
[18:01:17.318] Timer tick, asking Buildbucket for the build status
[18:01:17.403] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:17.403] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:45:0) after 1m0s
[18:02:17.421] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:45:0)
[18:02:17.421] Timer tick, asking Buildbucket for the build status
[18:02:17.495] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:17.495] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:46:0) after 1m0s
[18:03:17.541] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:46:0)
[18:03:17.541] Timer tick, asking Buildbucket for the build status
[18:03:17.635] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:17.635] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:47:0) after 1m0s
[18:04:17.660] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:47:0)
[18:04:17.688] Build status: STARTED
[18:04:17.688] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:48:0) after 1m51s
[18:06:08.706] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:48:0)
[18:06:08.706] Timer tick, asking Buildbucket for the build status
[18:06:08.795] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:08.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:49:0) after 1m0s
[18:07:08.919] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:49:0)
[18:07:08.919] Timer tick, asking Buildbucket for the build status
[18:07:09.045] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:09.045] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:50:0) after 1m0s
[18:08:09.092] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:50:0)
[18:08:09.134] Build status: STARTED
[18:08:09.134] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:51:0) after 3m48s
[18:11:57.206] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:51:0)
[18:11:57.206] Timer tick, asking Buildbucket for the build status
[18:11:57.296] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:57.296] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:52:0) after 1m0s
[18:12:57.329] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:52:0)
[18:12:57.329] Timer tick, asking Buildbucket for the build status
[18:12:57.423] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:57.423] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:53:0) after 1m0s
[18:13:57.445] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:53:0)
[18:13:57.472] Build status: STARTED
[18:13:57.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:54:0) after 1m6s
[18:15:03.600] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:54:0)
[18:15:03.600] Timer tick, asking Buildbucket for the build status
[18:15:03.663] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:03.663] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:55:0) after 1m0s
[18:16:03.686] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:55:0)
[18:16:03.712] Build status: STARTED
[18:16:03.712] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:56:0) after 7m36s
[18:23:39.897] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:56:0)
[18:23:39.897] Timer tick, asking Buildbucket for the build status
[18:23:39.981] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:39.981] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:57:0) after 1m0s
[18:24:40.253] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:57:0)
[18:24:40.315] Build status: STARTED
[18:24:40.315] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:58:0) after 1m39s
[18:26:19.336] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:58:0)
[18:26:19.337] Timer tick, asking Buildbucket for the build status
[18:26:19.512] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:19.512] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:59:0) after 1m0s
[18:27:19.537] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:59:0)
[18:27:19.567] Build status: STARTED
[18:27:19.567] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:60:0) after 6m57s
[18:34:16.735] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:60:0)
[18:34:16.735] Timer tick, asking Buildbucket for the build status
[18:34:16.972] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:16.972] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:61:0) after 1m0s
[18:35:16.979] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:61:0)
[18:35:17.008] Build status: STARTED
[18:35:17.008] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:62:0) after 2m53s
[18:38:10.069] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:62:0)
[18:38:10.069] Timer tick, asking Buildbucket for the build status
[18:38:10.156] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:10.156] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:63:0) after 1m0s
[18:39:10.210] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:63:0)
[18:39:10.210] Timer tick, asking Buildbucket for the build status
[18:39:10.286] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:10.286] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:64:0) after 1m0s
[18:40:10.337] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:64:0)
[18:40:10.369] Build status: STARTED
[18:40:10.369] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:65:0) after 1m39s
[18:41:49.417] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:65:0)
[18:41:49.447] Build status: STARTED
[18:41:49.447] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:66:0) after 3m25s
[18:45:14.566] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:66:0)
[18:45:14.592] Build status: STARTED
[18:45:14.592] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:67:0) after 5m15s
[18:50:29.688] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:67:0)
[18:50:29.715] Build status: STARTED
[18:50:29.715] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:68:0) after 8m42s
[18:59:11.706] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:68:0)
[18:59:11.742] Build status: STARTED
[18:59:11.742] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:69:0) after 4m15s
[19:03:26.759] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:69:0)
[19:03:26.759] Timer tick, asking Buildbucket for the build status
[19:03:26.857] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:26.857] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:70:0) after 1m0s
[19:04:26.960] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:70:0)
[19:04:26.988] Build status: STARTED
[19:04:26.988] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:71:0) after 9m34s
[19:14:01.328] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:71:0)
[19:14:01.358] Build status: STARTED
[19:14:01.358] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:72:0) after 2m57s
[19:16:58.379] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:72:0)
[19:16:58.408] Build status: STARTED
[19:16:58.408] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:73:0) after 1m39s
[19:18:37.529] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:73:0)
[19:18:37.529] Timer tick, asking Buildbucket for the build status
[19:18:37.771] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:37.771] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:74:0) after 1m0s
[19:19:37.855] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:74:0)
[19:19:37.855] Timer tick, asking Buildbucket for the build status
[19:19:38.029] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:38.029] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:75:0) after 1m0s
[19:20:38.220] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:75:0)
[19:20:38.250] Build status: STARTED
[19:20:38.250] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:76:0) after 6m1s
[19:26:39.238] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:76:0)
[19:26:39.238] Timer tick, asking Buildbucket for the build status
[19:26:39.357] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:39.357] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:77:0) after 1m0s
[19:27:39.374] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:77:0)
[19:27:39.374] Timer tick, asking Buildbucket for the build status
[19:27:39.445] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:39.445] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:78:0) after 1m0s
[19:28:39.483] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:78:0)
[19:28:39.483] Timer tick, asking Buildbucket for the build status
[19:28:39.685] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:39.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:79:0) after 1m0s
[19:29:39.703] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:79:0)
[19:29:39.703] Timer tick, asking Buildbucket for the build status
[19:29:39.802] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:39.802] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:80:0) after 1m0s
[19:30:39.847] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:80:0)
[19:30:39.872] Build status: STARTED
[19:30:39.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:81:0) after 9m41s
[19:40:20.897] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:81:0)
[19:40:20.923] Build status: STARTED
[19:40:20.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:82:0) after 6m8s
[19:46:28.967] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:82:0)
[19:46:28.967] Timer tick, asking Buildbucket for the build status
[19:46:29.100] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:29.100] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:83:0) after 1m0s
[19:47:29.119] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:83:0)
[19:47:29.189] Build status: STARTED
[19:47:29.189] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:84:0) after 6m53s
[19:54:22.246] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:84:0)
[19:54:22.272] Build status: STARTED
[19:54:22.272] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:85:0) after 2m17s
[19:56:39.292] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:85:0)
[19:56:39.321] Build status: STARTED
[19:56:39.321] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:86:0) after 7m15s
[20:03:54.410] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:86:0)
[20:03:54.453] Build status: STARTED
[20:03:54.453] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:87:0) after 6m6s
[20:10:01.021] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:87:0)
[20:10:01.021] Timer tick, asking Buildbucket for the build status
[20:10:01.124] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:01.124] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:88:0) after 1m0s
[20:11:01.291] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:88:0)
[20:11:01.291] Timer tick, asking Buildbucket for the build status
[20:11:01.542] Build 8852227455083561408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:01.542] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:89:0) after 1m0s
[20:12:01.693] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:89:0)
[20:12:01.723] Build status: STARTED
[20:12:01.723] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:90:0) after 4m54s
[20:16:55.883] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:90:0)
[20:16:55.916] Build status: STARTED
[20:16:55.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:91:0) after 4m41s
[20:21:36.934] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:91:0)
[20:21:36.958] Build status: STARTED
[20:21:36.958] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:92:0) after 5m0s
[20:26:36.929] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:92:0)
[20:26:36.970] Build status: STARTED
[20:26:36.970] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9017657517018885328:93:0) after 9m53s
[20:30:39.064] Received PubSub notification, asking Buildbucket for the build status
[20:30:39.089] Build:
{
"id": "8852227455083561408",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 21750,
"createdBy": "project:chromium",
"createTime": "2021-03-20T15:44:15.308143Z",
"startTime": "2021-03-20T15:47:10.134533Z",
"endTime": "2021-03-20T20:30:38.734751975Z",
"updateTime": "2021-03-20T20:30:38.735290571Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f17953d7423937a14b21ec15f08b28cefa7a4f1f",
"ref": "refs/heads/master"
}
}
}
[20:30:39.089] Invocation finished in 4h46m25.00974196s with status FAILED