[03:16:24.241] New invocation is queued and will start shortly
[03:16:25.387] Starting the invocation (attempt 1)
[03:16:25.427] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f510acc8cbd4a21c7463338ca465646dd0a4fc4c"
[03:16:25.427] Popped gitiles commit info from properties and tags
[03:16:25.427] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:16:25.427] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:16:25.427] Buildbucket request:
{
"requestId": "8976482946756656832",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f510acc8cbd4a21c7463338ca465646dd0a4fc4c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8976482946756656832"
},
{
"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"
}
}
[03:16:25.958] Scheduled build:
{
"id": "8811052884792801409",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 25204,
"createdBy": "project:chromium",
"createTime": "2022-06-18T03:16:25.483902398Z",
"updateTime": "2022-06-18T03:16:25.483902398Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f510acc8cbd4a21c7463338ca465646dd0a4fc4c",
"ref": "refs/heads/main"
}
}
}
[03:16:25.958] Task URL: https://cr-buildbucket.appspot.com/build/8811052884792801409
[03:16:25.958] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:2:0) after 4m44s
[03:17:52.935] Received PubSub notification, asking Buildbucket for the build status
[03:17:52.962] Build status: STARTED
[03:21:10.082] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:2:0)
[03:21:10.102] Build status: STARTED
[03:21:10.102] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:4:0) after 6m1s
[03:27:11.500] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:4:0)
[03:27:11.531] Build status: STARTED
[03:27:11.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:5:0) after 3m34s
[03:30:45.558] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:5:0)
[03:30:45.580] Build status: STARTED
[03:30:45.580] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:6:0) after 2m54s
[03:33:39.602] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:6:0)
[03:33:39.634] Build status: STARTED
[03:33:39.634] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:7:0) after 5m47s
[03:39:26.655] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:7:0)
[03:39:26.685] Build status: STARTED
[03:39:26.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:8:0) after 1m9s
[03:40:35.734] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:8:0)
[03:40:35.755] Build status: STARTED
[03:40:35.756] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:9:0) after 5m22s
[03:45:57.782] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:9:0)
[03:45:57.812] Build status: STARTED
[03:45:57.812] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:10:0) after 3m42s
[03:49:39.829] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:10:0)
[03:49:39.856] Build status: STARTED
[03:49:39.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:11:0) after 5m48s
[03:55:27.984] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:11:0)
[03:55:28.005] Build status: STARTED
[03:55:28.005] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:12:0) after 8m23s
[04:03:51.024] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:12:0)
[04:03:51.045] Build status: STARTED
[04:03:51.045] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:13:0) after 8m28s
[04:12:19.071] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:13:0)
[04:12:19.103] Build status: STARTED
[04:12:19.103] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:14:0) after 3m11s
[04:15:30.124] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:14:0)
[04:15:30.155] Build status: STARTED
[04:15:30.156] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:15:0) after 6m48s
[04:22:18.173] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:15:0)
[04:22:18.196] Build status: STARTED
[04:22:18.196] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:16:0) after 5m46s
[04:28:04.253] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:16:0)
[04:28:04.294] Build status: STARTED
[04:28:04.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:17:0) after 6m9s
[04:34:13.315] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:17:0)
[04:34:13.354] Build status: STARTED
[04:34:13.354] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:18:0) after 9m53s
[04:44:06.452] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:18:0)
[04:44:06.473] Build status: STARTED
[04:44:06.473] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:19:0) after 6m14s
[04:50:20.550] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:19:0)
[04:50:20.579] Build status: STARTED
[04:50:20.579] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:20:0) after 3m2s
[04:53:22.671] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:20:0)
[04:53:22.741] Build status: STARTED
[04:53:22.741] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:21:0) after 2m18s
[04:55:40.809] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:21:0)
[04:55:40.846] Build status: STARTED
[04:55:40.846] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:22:0) after 1m15s
[04:56:55.869] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:22:0)
[04:56:55.891] Build status: STARTED
[04:56:55.891] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:23:0) after 1m30s
[04:58:25.924] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:23:0)
[04:58:25.990] Build status: STARTED
[04:58:25.990] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:24:0) after 6m16s
[05:04:42.031] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:24:0)
[05:04:42.063] Build status: STARTED
[05:04:42.063] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:25:0) after 3m49s
[05:08:31.230] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:25:0)
[05:08:31.273] Build status: STARTED
[05:08:31.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:26:0) after 3m25s
[05:11:56.571] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:26:0)
[05:11:56.609] Build status: STARTED
[05:11:56.609] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:27:0) after 2m27s
[05:14:23.656] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:27:0)
[05:14:23.681] Build status: STARTED
[05:14:23.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:28:0) after 8m36s
[05:22:59.700] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:28:0)
[05:22:59.723] Build status: STARTED
[05:22:59.724] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:29:0) after 9m30s
[05:32:29.746] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:29:0)
[05:32:29.779] Build status: STARTED
[05:32:29.779] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:30:0) after 9m36s
[05:42:05.804] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:30:0)
[05:42:05.825] Build status: STARTED
[05:42:05.825] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:31:0) after 8m50s
[05:50:55.917] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:31:0)
[05:50:55.954] Build status: STARTED
[05:50:55.954] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:32:0) after 6m13s
[05:57:08.975] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:32:0)
[05:57:09.001] Build status: STARTED
[05:57:09.001] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:33:0) after 8m42s
[06:05:51.030] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:33:0)
[06:05:51.052] Build status: STARTED
[06:05:51.052] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:34:0) after 3m19s
[06:09:10.271] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:34:0)
[06:09:10.290] Build status: STARTED
[06:09:10.290] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:35:0) after 1m53s
[06:11:03.364] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:35:0)
[06:11:03.389] Build status: STARTED
[06:11:03.389] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:36:0) after 1m53s
[06:12:56.411] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:36:0)
[06:12:56.431] Build status: STARTED
[06:12:56.431] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:37:0) after 5m42s
[06:18:38.631] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:37:0)
[06:18:38.671] Build status: STARTED
[06:18:38.671] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:38:0) after 8m39s
[06:27:17.835] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:38:0)
[06:27:17.931] Build status: STARTED
[06:27:17.931] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:39:0) after 4m0s
[06:31:18.002] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:39:0)
[06:31:18.023] Build status: STARTED
[06:31:18.023] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:40:0) after 8m42s
[06:40:00.311] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:40:0)
[06:40:00.347] Build status: STARTED
[06:40:00.347] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:41:0) after 3m29s
[06:43:29.445] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:41:0)
[06:43:29.464] Build status: STARTED
[06:43:29.464] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:42:0) after 5m17s
[06:48:46.656] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:42:0)
[06:48:46.681] Build status: STARTED
[06:48:46.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:43:0) after 7m38s
[06:56:24.721] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:43:0)
[06:56:24.744] Build status: STARTED
[06:56:24.744] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:44:0) after 7m8s
[07:03:32.772] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:44:0)
[07:03:32.797] Build status: STARTED
[07:03:32.797] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:45:0) after 6m19s
[07:09:52.013] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:45:0)
[07:09:52.055] Build status: STARTED
[07:09:52.055] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8976482946756656832:46:0) after 9m13s
[07:17:56.306] Received PubSub notification, asking Buildbucket for the build status
[07:17:56.342] Build:
{
"id": "8811052884792801409",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 25204,
"createdBy": "project:chromium",
"createTime": "2022-06-18T03:16:25.483902398Z",
"startTime": "2022-06-18T03:17:52.314471Z",
"endTime": "2022-06-18T07:17:55.931609584Z",
"updateTime": "2022-06-18T07:17:55.931609584Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f510acc8cbd4a21c7463338ca465646dd0a4fc4c",
"ref": "refs/heads/main"
}
}
}
[07:17:56.342] Invocation finished in 4h1m32.115496599s with status SUCCEEDED