[03:35:36.374] New invocation is queued and will start shortly
[03:35:37.606] Starting the invocation (attempt 1)
[03:35:37.640] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/42faab623d0a88aa3b01bbabf8ececd9348971a4"
[03:35:37.640] Popped gitiles commit info from properties and tags
[03:35:37.640] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:35:37.640] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:35:37.640] Buildbucket request:
{
"requestId": "8988440538223265760",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "42faab623d0a88aa3b01bbabf8ececd9348971a4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8988440538223265760"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTMacASan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:35:38.266] Scheduled build:
{
"id": "8823010476167485073",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 26498,
"createdBy": "project:chromium",
"createTime": "2022-02-06T03:35:37.703552984Z",
"updateTime": "2022-02-06T03:35:37.703552984Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "42faab623d0a88aa3b01bbabf8ececd9348971a4",
"ref": "refs/heads/main"
}
}
}
[03:35:38.266] Task URL: https://cr-buildbucket.appspot.com/build/8823010476167485073
[03:35:38.266] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:2:0) after 1m9s
[03:35:59.720] Received PubSub notification, asking Buildbucket for the build status
[03:35:59.760] Build status: STARTED
[03:36:47.410] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:2:0)
[03:36:47.449] Build status: STARTED
[03:36:47.449] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:4:0) after 9m3s
[03:45:50.466] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:4:0)
[03:45:50.500] Build status: STARTED
[03:45:50.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:5:0) after 4m1s
[03:49:51.496] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:5:0)
[03:49:51.559] Build status: STARTED
[03:49:51.559] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:6:0) after 2m38s
[03:52:29.586] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:6:0)
[03:52:29.605] Build status: STARTED
[03:52:29.605] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:7:0) after 3m14s
[03:55:43.625] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:7:0)
[03:55:43.643] Build status: STARTED
[03:55:43.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:8:0) after 6m6s
[04:01:49.665] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:8:0)
[04:01:49.682] Build status: STARTED
[04:01:49.682] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:9:0) after 8m48s
[04:10:37.797] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:9:0)
[04:10:37.815] Build status: STARTED
[04:10:37.815] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:10:0) after 4m53s
[04:15:30.849] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:10:0)
[04:15:30.872] Build status: STARTED
[04:15:30.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:11:0) after 3m47s
[04:19:17.890] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:11:0)
[04:19:17.921] Build status: STARTED
[04:19:17.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:12:0) after 5m59s
[04:25:16.894] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:12:0)
[04:25:16.962] Build status: STARTED
[04:25:16.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:13:0) after 3m59s
[04:29:15.981] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:13:0)
[04:29:16.000] Build status: STARTED
[04:29:16.000] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:14:0) after 7m33s
[04:36:49.017] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:14:0)
[04:36:49.050] Build status: STARTED
[04:36:49.050] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:15:0) after 4m7s
[04:40:56.067] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:15:0)
[04:40:56.109] Build status: STARTED
[04:40:56.109] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:16:0) after 1m20s
[04:42:16.675] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:16:0)
[04:42:16.700] Build status: STARTED
[04:42:16.700] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:17:0) after 8m35s
[04:50:52.292] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:17:0)
[04:50:52.321] Build status: STARTED
[04:50:52.321] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:18:0) after 1m42s
[04:52:34.338] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:18:0)
[04:52:34.365] Build status: STARTED
[04:52:34.365] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:19:0) after 4m0s
[04:56:34.471] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:19:0)
[04:56:34.494] Build status: STARTED
[04:56:34.494] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:20:0) after 1m22s
[04:57:56.518] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:20:0)
[04:57:56.618] Build status: STARTED
[04:57:56.618] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:21:0) after 1m21s
[04:59:17.648] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:21:0)
[04:59:17.672] Build status: STARTED
[04:59:17.672] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:22:0) after 9m48s
[05:09:06.234] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:22:0)
[05:09:06.256] Build status: STARTED
[05:09:06.256] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8988440538223265760:23:0) after 2m8s
[05:09:19.096] Received PubSub notification, asking Buildbucket for the build status
[05:09:19.124] Build:
{
"id": "8823010476167485073",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 26498,
"createdBy": "project:chromium",
"createTime": "2022-02-06T03:35:37.703552984Z",
"startTime": "2022-02-06T03:35:58.758578Z",
"endTime": "2022-02-06T05:09:18.863430656Z",
"updateTime": "2022-02-06T05:09:18.863430656Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "42faab623d0a88aa3b01bbabf8ececd9348971a4",
"ref": "refs/heads/main"
}
}
}
[05:09:19.124] Invocation finished in 1h33m42.764406564s with status FAILED