[15:00:50.683] New invocation is queued and will start shortly
[15:00:51.926] Starting the invocation (attempt 1)
[15:00:51.959] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/086dfea685bb9f04d9c61a2750d56f1963299248"
[15:00:51.959] Popped gitiles commit info from properties and tags
[15:00:51.959] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:00:51.959] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:00:51.959] Buildbucket request:
{
"requestId": "8894085984720745008",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Mac deterministic (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "086dfea685bb9f04d9c61a2750d56f1963299248",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8894085984720745008"
},
{
"key": "scheduler_job_id",
"value": "chromium/Mac deterministic (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[15:00:52.861] Scheduled build:
{
"id": "8728655922656531905",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Mac deterministic (dbg)"
},
"number": 43390,
"createdBy": "project:chromium",
"createTime": "2024-12-13T15:00:52.023487608Z",
"updateTime": "2024-12-13T15:00:52.023487608Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "086dfea685bb9f04d9c61a2750d56f1963299248",
"ref": "refs/heads/main"
}
}
}
[15:00:52.861] Task URL: https://cr-buildbucket.appspot.com/build/8728655922656531905
[15:00:52.862] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:2:0) after 1m10s
[15:00:53.796] Received PubSub notification, asking Buildbucket for the build status
[15:00:53.814] Build status: SCHEDULED
[15:01:04.257] Received PubSub notification, asking Buildbucket for the build status
[15:01:04.275] Build status: STARTED
[15:02:03.055] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:2:0)
[15:02:03.081] Build status: STARTED
[15:02:03.081] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:5:0) after 4m57s
[15:07:00.097] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:5:0)
[15:07:00.130] Build status: STARTED
[15:07:00.131] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:6:0) after 6m56s
[15:13:56.512] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:6:0)
[15:13:56.539] Build status: STARTED
[15:13:56.539] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:7:0) after 2m48s
[15:16:44.584] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:7:0)
[15:16:44.617] Build status: STARTED
[15:16:44.617] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:8:0) after 3m26s
[15:20:10.637] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:8:0)
[15:20:10.662] Build status: STARTED
[15:20:10.663] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:9:0) after 5m39s
[15:25:49.693] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:9:0)
[15:25:49.735] Build status: STARTED
[15:25:49.735] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:10:0) after 2m7s
[15:27:56.755] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:10:0)
[15:27:56.778] Build status: STARTED
[15:27:56.778] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:11:0) after 1m10s
[15:29:06.811] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:11:0)
[15:29:06.853] Build status: STARTED
[15:29:06.853] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:12:0) after 7m9s
[15:36:15.871] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:12:0)
[15:36:16.089] Build status: STARTED
[15:36:16.089] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:13:0) after 7m17s
[15:43:33.160] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:13:0)
[15:43:33.188] Build status: STARTED
[15:43:33.188] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:14:0) after 4m3s
[15:47:36.233] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:14:0)
[15:47:36.250] Build status: STARTED
[15:47:36.250] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:15:0) after 9m34s
[15:57:10.314] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:15:0)
[15:57:10.374] Build status: STARTED
[15:57:10.374] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:16:0) after 2m24s
[15:59:34.473] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:16:0)
[15:59:34.538] Build status: STARTED
[15:59:34.538] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:17:0) after 4m31s
[16:04:05.555] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:17:0)
[16:04:05.575] Build status: STARTED
[16:04:05.575] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:18:0) after 8m3s
[16:12:08.602] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:18:0)
[16:12:08.628] Build status: STARTED
[16:12:08.628] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:19:0) after 2m58s
[16:15:06.603] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:19:0)
[16:15:06.623] Build status: STARTED
[16:15:06.624] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:20:0) after 5m37s
[16:20:43.715] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:20:0)
[16:20:43.731] Build status: STARTED
[16:20:43.731] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:21:0) after 6m33s
[16:27:16.795] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:21:0)
[16:27:16.811] Build status: STARTED
[16:27:16.811] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:22:0) after 2m6s
[16:29:22.829] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:22:0)
[16:29:22.846] Build status: STARTED
[16:29:22.846] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:23:0) after 8m29s
[16:37:51.917] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:23:0)
[16:37:51.936] Build status: STARTED
[16:37:51.936] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:24:0) after 7m14s
[16:45:06.056] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:24:0)
[16:45:06.181] Build status: STARTED
[16:45:06.181] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:25:0) after 6m50s
[16:51:56.202] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:25:0)
[16:51:56.265] Build status: STARTED
[16:51:56.265] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic (dbg):8894085984720745008:26:0) after 8m2s
[16:57:34.859] Received PubSub notification, asking Buildbucket for the build status
[16:57:34.881] Build:
{
"id": "8728655922656531905",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Mac deterministic (dbg)"
},
"number": 43390,
"createdBy": "project:chromium",
"createTime": "2024-12-13T15:00:52.023487608Z",
"startTime": "2024-12-13T15:01:03.799046926Z",
"endTime": "2024-12-13T16:57:34.398769078Z",
"updateTime": "2024-12-13T16:57:34.398769078Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "086dfea685bb9f04d9c61a2750d56f1963299248",
"ref": "refs/heads/main"
}
}
}
[16:57:34.881] Invocation finished in 1h56m44.210144669s with status FAILED