[19:54:50.337] New invocation is queued and will start shortly
[19:54:51.421] Starting the invocation (attempt 1)
[19:54:51.468] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aebee6cdc2c0e2a4434a10f93b2184102bc0844d"
[19:54:51.468] Popped gitiles commit info from properties and tags
[19:54:51.468] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:54:51.468] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:54:51.468] Buildbucket request:
{
"requestId": "8951958949728003840",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aebee6cdc2c0e2a4434a10f93b2184102bc0844d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8951958949728003840"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTiOSDevice"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:54:52.041] Scheduled build:
{
"id": "8786528887758325601",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 29079,
"createdBy": "project:chromium",
"createTime": "2023-03-15T19:54:51.591062542Z",
"updateTime": "2023-03-15T19:54:51.591062542Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aebee6cdc2c0e2a4434a10f93b2184102bc0844d",
"ref": "refs/heads/main"
}
}
}
[19:54:52.041] Task URL: https://cr-buildbucket.appspot.com/build/8786528887758325601
[19:54:52.041] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:2:0) after 8m58s
[19:56:48.851] Received PubSub notification, asking Buildbucket for the build status
[19:56:48.890] Build status: STARTED
[20:03:50.128] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:2:0)
[20:03:50.150] Build status: STARTED
[20:03:50.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:4:0) after 6m54s
[20:10:44.293] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:4:0)
[20:10:44.324] Build status: STARTED
[20:10:44.324] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:5:0) after 9m28s
[20:20:12.361] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:5:0)
[20:20:12.385] Build status: STARTED
[20:20:12.385] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:6:0) after 6m51s
[20:27:03.416] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:6:0)
[20:27:03.538] Build status: STARTED
[20:27:03.538] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:7:0) after 4m43s
[20:31:46.568] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:7:0)
[20:31:46.589] Build status: STARTED
[20:31:46.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:8:0) after 2m2s
[20:33:48.613] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:8:0)
[20:33:48.639] Build status: STARTED
[20:33:48.639] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:9:0) after 4m35s
[20:38:23.664] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:9:0)
[20:38:23.685] Build status: STARTED
[20:38:23.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:10:0) after 1m15s
[20:39:39.101] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:10:0)
[20:39:39.214] Build status: STARTED
[20:39:39.214] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:11:0) after 3m47s
[20:43:26.203] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:11:0)
[20:43:26.226] Build status: STARTED
[20:43:26.226] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:12:0) after 9m21s
[20:52:47.252] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:12:0)
[20:52:47.274] Build status: STARTED
[20:52:47.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:13:0) after 1m52s
[20:54:39.501] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:13:0)
[20:54:39.524] Build status: STARTED
[20:54:39.524] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:14:0) after 6m44s
[21:01:23.564] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:14:0)
[21:01:23.593] Build status: STARTED
[21:01:23.593] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:15:0) after 5m28s
[21:06:51.679] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:15:0)
[21:06:51.753] Build status: STARTED
[21:06:51.753] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:16:0) after 4m5s
[21:10:56.885] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:16:0)
[21:10:56.908] Build status: STARTED
[21:10:56.908] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:17:0) after 6m18s
[21:17:14.991] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:17:0)
[21:17:15.010] Build status: STARTED
[21:17:15.010] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:18:0) after 1m4s
[21:18:19.136] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:18:0)
[21:18:19.162] Build status: STARTED
[21:18:19.162] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:19:0) after 6m1s
[21:24:20.197] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:19:0)
[21:24:20.221] Build status: STARTED
[21:24:20.221] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:20:0) after 4m24s
[21:28:44.262] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:20:0)
[21:28:44.282] Build status: STARTED
[21:28:44.282] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:21:0) after 3m1s
[21:31:45.565] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:21:0)
[21:31:45.591] Build status: STARTED
[21:31:45.591] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:22:0) after 4m42s
[21:36:27.620] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:22:0)
[21:36:27.642] Build status: STARTED
[21:36:27.642] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:23:0) after 1m53s
[21:38:20.885] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:23:0)
[21:38:20.908] Build status: STARTED
[21:38:20.908] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:24:0) after 4m50s
[21:43:11.478] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:24:0)
[21:43:11.500] Build status: STARTED
[21:43:11.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:25:0) after 2m52s
[21:46:04.051] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:25:0)
[21:46:04.077] Build status: STARTED
[21:46:04.077] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:26:0) after 7m34s
[21:53:38.328] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:26:0)
[21:53:38.353] Build status: STARTED
[21:53:38.353] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:27:0) after 1m26s
[21:55:04.378] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:27:0)
[21:55:04.400] Build status: STARTED
[21:55:04.400] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:28:0) after 9m19s
[22:04:23.443] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:28:0)
[22:04:23.464] Build status: STARTED
[22:04:23.464] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:29:0) after 9m22s
[22:13:45.609] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:29:0)
[22:13:45.629] Build status: STARTED
[22:13:45.629] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:30:0) after 3m56s
[22:17:41.658] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:30:0)
[22:17:41.761] Build status: STARTED
[22:17:41.761] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:31:0) after 9m34s
[22:27:15.997] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:31:0)
[22:27:16.024] Build status: STARTED
[22:27:16.024] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:32:0) after 7m0s
[22:34:16.053] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:32:0)
[22:34:16.071] Build status: STARTED
[22:34:16.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:33:0) after 6m36s
[22:40:52.270] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:33:0)
[22:40:52.301] Build status: STARTED
[22:40:52.301] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:34:0) after 2m47s
[22:43:39.557] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:34:0)
[22:43:39.577] Build status: STARTED
[22:43:39.577] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:35:0) after 1m58s
[22:45:38.104] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:35:0)
[22:45:38.126] Build status: STARTED
[22:45:38.126] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:36:0) after 5m12s
[22:50:50.257] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:36:0)
[22:50:50.297] Build status: STARTED
[22:50:50.297] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:37:0) after 2m45s
[22:53:35.333] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:37:0)
[22:53:35.359] Build status: STARTED
[22:53:35.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:38:0) after 2m2s
[22:55:37.385] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:38:0)
[22:55:37.407] Build status: STARTED
[22:55:37.407] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:39:0) after 1m4s
[22:56:41.580] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:39:0)
[22:56:41.600] Build status: STARTED
[22:56:41.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:40:0) after 8m45s
[23:05:26.728] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:40:0)
[23:05:26.748] Build status: STARTED
[23:05:26.748] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:41:0) after 5m7s
[23:10:33.774] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:41:0)
[23:10:33.795] Build status: STARTED
[23:10:33.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:42:0) after 9m29s
[23:20:02.875] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:42:0)
[23:20:02.907] Build status: STARTED
[23:20:02.907] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:43:0) after 3m30s
[23:23:32.931] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:43:0)
[23:23:32.956] Build status: STARTED
[23:23:32.956] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:44:0) after 7m59s
[23:31:31.994] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:44:0)
[23:31:32.016] Build status: STARTED
[23:31:32.016] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:45:0) after 6m19s
[23:37:51.126] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:45:0)
[23:37:51.153] Build status: STARTED
[23:37:51.153] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:46:0) after 6m9s
[23:44:00.167] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:46:0)
[23:44:00.218] Build status: STARTED
[23:44:00.218] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:47:0) after 4m45s
[23:48:45.572] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:47:0)
[23:48:45.597] Build status: STARTED
[23:48:45.597] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:48:0) after 8m37s
[23:57:22.825] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:48:0)
[23:57:22.849] Build status: STARTED
[23:57:22.849] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:49:0) after 7m8s
[00:04:31.168] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:49:0)
[00:04:31.401] Build status: STARTED
[00:04:31.401] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:50:0) after 3m41s
[00:08:12.421] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:50:0)
[00:08:12.444] Build status: STARTED
[00:08:12.444] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:51:0) after 8m17s
[00:16:29.464] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:51:0)
[00:16:29.483] Build status: STARTED
[00:16:29.483] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:52:0) after 4m16s
[00:20:45.706] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:52:0)
[00:20:45.731] Build status: STARTED
[00:20:45.731] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:53:0) after 4m34s
[00:25:19.909] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:53:0)
[00:25:19.932] Build status: STARTED
[00:25:19.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:54:0) after 5m4s
[00:30:23.966] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:54:0)
[00:30:24.005] Build status: STARTED
[00:30:24.005] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:55:0) after 1m32s
[00:31:56.322] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:55:0)
[00:31:56.346] Build status: STARTED
[00:31:56.346] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:56:0) after 1m23s
[00:33:19.379] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:56:0)
[00:33:19.409] Build status: STARTED
[00:33:19.409] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:57:0) after 6m46s
[00:40:05.538] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:57:0)
[00:40:05.576] Build status: STARTED
[00:40:05.576] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:58:0) after 4m36s
[00:44:41.968] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:58:0)
[00:44:42.000] Build status: STARTED
[00:44:42.000] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:59:0) after 6m36s
[00:51:18.150] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:59:0)
[00:51:18.176] Build status: STARTED
[00:51:18.176] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:60:0) after 9m30s
[01:00:48.209] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:60:0)
[01:00:48.246] Build status: STARTED
[01:00:48.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8951958949728003840:61:0) after 6m14s
[01:06:32.613] Received PubSub notification, asking Buildbucket for the build status
[01:06:32.648] Build:
{
"id": "8786528887758325601",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 29079,
"createdBy": "project:chromium",
"createTime": "2023-03-15T19:54:51.591062542Z",
"startTime": "2023-03-15T19:56:48.429509433Z",
"endTime": "2023-03-16T01:06:32.062545226Z",
"updateTime": "2023-03-16T01:06:32.062545226Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aebee6cdc2c0e2a4434a10f93b2184102bc0844d",
"ref": "refs/heads/main"
}
}
}
[01:06:32.648] Invocation finished in 5h11m42.318583931s with status SUCCEEDED