[13:46:01.193] New invocation is queued and will start shortly
[13:46:02.424] Starting the invocation (attempt 1)
[13:46:02.452] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9563e3d124be3f28f2de1542e303fccb166a38d4"
[13:46:02.452] Popped gitiles commit info from properties and tags
[13:46:02.452] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:46:02.452] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:46:02.452] Buildbucket request:
{
"requestId": "8890829201496792160",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9563e3d124be3f28f2de1542e303fccb166a38d4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8890829201496792160"
},
{
"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"
}
}
[13:46:02.931] Scheduled build:
{
"id": "8725399139460584513",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 36538,
"createdBy": "project:chromium",
"createTime": "2025-01-18T13:46:02.511353870Z",
"updateTime": "2025-01-18T13:46:02.511353870Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9563e3d124be3f28f2de1542e303fccb166a38d4",
"ref": "refs/heads/main"
}
}
}
[13:46:02.931] Task URL: https://cr-buildbucket.appspot.com/build/8725399139460584513
[13:46:02.931] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:2:0) after 2m31s
[13:46:03.908] Received PubSub notification, asking Buildbucket for the build status
[13:46:03.935] Build status: SCHEDULED
[13:46:42.462] Received PubSub notification, asking Buildbucket for the build status
[13:46:42.490] Build status: STARTED
[13:48:34.102] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:2:0)
[13:48:34.128] Build status: STARTED
[13:48:34.128] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:5:0) after 5m48s
[13:54:22.149] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:5:0)
[13:54:22.173] Build status: STARTED
[13:54:22.173] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:6:0) after 8m30s
[14:02:52.214] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:6:0)
[14:02:52.290] Build status: STARTED
[14:02:52.290] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:7:0) after 3m51s
[14:06:43.389] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:7:0)
[14:06:43.413] Build status: STARTED
[14:06:43.413] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:8:0) after 1m56s
[14:08:39.480] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:8:0)
[14:08:39.506] Build status: STARTED
[14:08:39.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:9:0) after 7m47s
[14:16:26.626] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:9:0)
[14:16:26.649] Build status: STARTED
[14:16:26.649] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:10:0) after 1m45s
[14:18:11.762] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:10:0)
[14:18:11.791] Build status: STARTED
[14:18:11.791] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:11:0) after 8m56s
[14:27:07.953] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:11:0)
[14:27:07.977] Build status: STARTED
[14:27:07.977] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:12:0) after 8m46s
[14:35:54.123] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:12:0)
[14:35:54.146] Build status: STARTED
[14:35:54.146] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:13:0) after 7m9s
[14:43:03.167] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:13:0)
[14:43:03.185] Build status: STARTED
[14:43:03.185] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:14:0) after 5m16s
[14:48:19.353] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:14:0)
[14:48:19.419] Build status: STARTED
[14:48:19.419] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:15:0) after 2m49s
[14:51:08.514] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:15:0)
[14:51:08.538] Build status: STARTED
[14:51:08.538] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:16:0) after 3m30s
[14:54:38.563] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:16:0)
[14:54:38.578] Build status: STARTED
[14:54:38.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:17:0) after 3m18s
[14:57:56.909] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:17:0)
[14:57:56.926] Build status: STARTED
[14:57:56.926] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:18:0) after 4m37s
[15:02:34.093] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:18:0)
[15:02:34.113] Build status: STARTED
[15:02:34.113] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:19:0) after 2m13s
[15:04:47.136] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:19:0)
[15:04:47.154] Build status: STARTED
[15:04:47.154] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:20:0) after 7m31s
[15:12:18.250] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:20:0)
[15:12:18.266] Build status: STARTED
[15:12:18.266] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:21:0) after 6m35s
[15:18:53.268] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:21:0)
[15:18:53.289] Build status: STARTED
[15:18:53.289] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:22:0) after 6m14s
[15:25:07.481] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:22:0)
[15:25:07.500] Build status: STARTED
[15:25:07.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:23:0) after 4m54s
[15:30:01.525] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:23:0)
[15:30:01.601] Build status: STARTED
[15:30:01.601] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:24:0) after 8m36s
[15:38:37.621] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:24:0)
[15:38:37.639] Build status: STARTED
[15:38:37.639] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:25:0) after 7m58s
[15:46:36.010] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:25:0)
[15:46:36.025] Build status: STARTED
[15:46:36.025] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:26:0) after 4m26s
[15:51:02.084] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:26:0)
[15:51:02.101] Build status: STARTED
[15:51:02.101] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:27:0) after 4m14s
[15:55:16.174] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:27:0)
[15:55:16.263] Build status: STARTED
[15:55:16.263] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:28:0) after 3m23s
[15:58:39.284] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:28:0)
[15:58:39.349] Build status: STARTED
[15:58:39.349] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:29:0) after 9m29s
[16:08:08.364] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:29:0)
[16:08:08.383] Build status: STARTED
[16:08:08.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:30:0) after 2m18s
[16:10:26.514] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:30:0)
[16:10:26.541] Build status: STARTED
[16:10:26.541] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:31:0) after 1m27s
[16:11:53.761] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:31:0)
[16:11:53.782] Build status: STARTED
[16:11:53.782] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:32:0) after 7m41s
[16:19:34.808] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:32:0)
[16:19:34.827] Build status: STARTED
[16:19:34.827] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:33:0) after 5m37s
[16:25:11.869] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:33:0)
[16:25:11.891] Build status: STARTED
[16:25:11.891] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:34:0) after 9m5s
[16:34:17.105] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:34:0)
[16:34:17.132] Build status: STARTED
[16:34:17.132] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:35:0) after 8m14s
[16:42:31.505] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:35:0)
[16:42:31.534] Build status: STARTED
[16:42:31.534] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:36:0) after 4m21s
[16:46:52.561] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:36:0)
[16:46:52.585] Build status: STARTED
[16:46:52.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:37:0) after 5m32s
[16:52:24.734] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:37:0)
[16:52:24.754] Build status: STARTED
[16:52:24.754] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:38:0) after 5m5s
[16:57:29.772] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:38:0)
[16:57:29.848] Build status: STARTED
[16:57:29.848] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:39:0) after 8m47s
[17:06:16.926] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:39:0)
[17:06:16.941] Build status: STARTED
[17:06:16.941] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:40:0) after 5m56s
[17:12:12.968] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:40:0)
[17:12:12.988] Build status: STARTED
[17:12:12.988] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:41:0) after 6m7s
[17:18:20.115] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:41:0)
[17:18:20.167] Build status: STARTED
[17:18:20.167] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:42:0) after 1m6s
[17:19:26.376] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:42:0)
[17:19:26.396] Build status: STARTED
[17:19:26.396] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:43:0) after 1m8s
[17:20:34.421] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:43:0)
[17:20:34.437] Build status: STARTED
[17:20:34.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8890829201496792160:44:0) after 8m4s
[17:25:59.325] Received PubSub notification, asking Buildbucket for the build status
[17:25:59.349] Build:
{
"id": "8725399139460584513",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 36538,
"createdBy": "project:chromium",
"createTime": "2025-01-18T13:46:02.511353870Z",
"startTime": "2025-01-18T13:46:42.153997248Z",
"endTime": "2025-01-18T17:25:58.968421961Z",
"updateTime": "2025-01-18T17:25:58.968421961Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9563e3d124be3f28f2de1542e303fccb166a38d4",
"ref": "refs/heads/main"
}
}
}
[17:25:59.349] Invocation finished in 3h39m58.164051386s with status FAILED