[06:55:07.898] New invocation is queued and will start shortly
[06:55:09.007] Starting the invocation (attempt 1)
[06:55:09.028] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/514da917ca326f050ed6de949a70a9c88dcd86ee"
[06:55:09.028] Popped gitiles commit info from properties and tags
[06:55:09.028] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:55:09.028] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:55:09.030] Buildbucket request:
{
"requestId": "8911964145526022880",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "514da917ca326f050ed6de949a70a9c88dcd86ee",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8911964145526022880"
},
{
"key": "scheduler_job_id",
"value": "chromium/CrWinAsan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:55:10.064] Scheduled build:
{
"id": "8746534083615331345",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 15673,
"createdBy": "project:chromium",
"createTime": "2024-05-30T06:55:09.092895167Z",
"updateTime": "2024-05-30T06:55:09.092895167Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "514da917ca326f050ed6de949a70a9c88dcd86ee",
"ref": "refs/heads/main"
}
}
}
[06:55:10.064] Task URL: https://cr-buildbucket.appspot.com/build/8746534083615331345
[06:55:10.064] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:2:0) after 2m1s
[06:55:10.957] Received PubSub notification, asking Buildbucket for the build status
[06:55:10.974] Build status: SCHEDULED
[06:57:11.082] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:2:0)
[06:57:11.099] Build status: SCHEDULED
[06:57:11.099] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:4:0) after 3m29s
[07:00:40.186] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:4:0)
[07:00:40.212] Build status: SCHEDULED
[07:00:40.212] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:5:0) after 8m7s
[07:08:47.225] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:5:0)
[07:08:47.257] Build status: SCHEDULED
[07:08:47.257] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:6:0) after 7m32s
[07:16:19.274] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:6:0)
[07:16:19.301] Build status: SCHEDULED
[07:16:19.302] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:7:0) after 9m30s
[07:25:49.378] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:7:0)
[07:25:49.395] Build status: SCHEDULED
[07:25:49.395] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:8:0) after 4m0s
[07:29:49.511] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:8:0)
[07:29:49.537] Build status: SCHEDULED
[07:29:49.537] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:9:0) after 6m18s
[07:33:36.074] Received PubSub notification, asking Buildbucket for the build status
[07:33:36.096] Build status: STARTED
[07:36:07.848] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:9:0)
[07:36:07.869] Build status: STARTED
[07:36:07.869] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:11:0) after 5m15s
[07:41:23.048] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:11:0)
[07:41:23.116] Build status: STARTED
[07:41:23.116] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:12:0) after 6m10s
[07:47:33.162] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:12:0)
[07:47:33.180] Build status: STARTED
[07:47:33.180] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:13:0) after 6m14s
[07:53:47.410] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:13:0)
[07:53:47.426] Build status: STARTED
[07:53:47.426] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:14:0) after 1m31s
[07:55:18.444] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:14:0)
[07:55:18.474] Build status: STARTED
[07:55:18.474] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:15:0) after 2m19s
[07:57:37.836] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:15:0)
[07:57:37.856] Build status: STARTED
[07:57:37.856] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:16:0) after 5m17s
[08:02:55.461] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:16:0)
[08:02:55.509] Build status: STARTED
[08:02:55.509] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:17:0) after 1m42s
[08:04:37.708] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:17:0)
[08:04:37.738] Build status: STARTED
[08:04:37.738] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:18:0) after 2m54s
[08:07:31.810] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:18:0)
[08:07:31.834] Build status: STARTED
[08:07:31.834] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:19:0) after 9m46s
[08:17:17.862] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:19:0)
[08:17:17.878] Build status: STARTED
[08:17:17.878] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:20:0) after 7m28s
[08:24:45.948] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:20:0)
[08:24:45.965] Build status: STARTED
[08:24:45.965] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:21:0) after 1m42s
[08:26:27.988] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:21:0)
[08:26:28.008] Build status: STARTED
[08:26:28.008] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:22:0) after 5m16s
[08:31:44.052] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:22:0)
[08:31:44.088] Build status: STARTED
[08:31:44.088] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:23:0) after 7m3s
[08:38:47.392] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:23:0)
[08:38:47.408] Build status: STARTED
[08:38:47.408] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:24:0) after 7m28s
[08:46:15.535] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:24:0)
[08:46:15.553] Build status: STARTED
[08:46:15.553] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:25:0) after 8m51s
[08:55:06.568] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:25:0)
[08:55:06.607] Build status: STARTED
[08:55:06.607] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:26:0) after 1m4s
[08:56:10.618] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:26:0)
[08:56:10.636] Build status: STARTED
[08:56:10.636] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:27:0) after 1m31s
[08:57:41.663] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:27:0)
[08:57:41.681] Build status: STARTED
[08:57:41.681] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:28:0) after 9m3s
[09:06:44.723] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:28:0)
[09:06:44.753] Build status: STARTED
[09:06:44.753] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:29:0) after 2m44s
[09:09:28.778] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:29:0)
[09:09:28.794] Build status: STARTED
[09:09:28.794] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:30:0) after 4m49s
[09:14:17.948] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:30:0)
[09:14:18.018] Build status: STARTED
[09:14:18.018] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:31:0) after 6m52s
[09:21:10.759] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:31:0)
[09:21:10.780] Build status: STARTED
[09:21:10.780] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8911964145526022880:32:0) after 7m28s
[09:21:55.941] Received PubSub notification, asking Buildbucket for the build status
[09:21:55.970] Build:
{
"id": "8746534083615331345",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 15673,
"createdBy": "project:chromium",
"createTime": "2024-05-30T06:55:09.092895167Z",
"startTime": "2024-05-30T07:33:35.809391955Z",
"endTime": "2024-05-30T09:21:55.231023841Z",
"updateTime": "2024-05-30T09:21:55.231023841Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "514da917ca326f050ed6de949a70a9c88dcd86ee",
"ref": "refs/heads/main"
}
}
}
[09:21:55.970] Invocation finished in 2h26m48.084203585s with status FAILED