[05:33:36.045] New invocation is queued and will start shortly
[05:33:37.103] Starting the invocation (attempt 1)
[05:33:37.131] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[05:33:37.131] Popped gitiles commit info from properties and tags
[05:33:37.131] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:33:41.849] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:33:41.849] The invocation will be retried
[05:33:46.918] Starting the invocation (attempt 2)
[05:33:46.946] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[05:33:46.946] Popped gitiles commit info from properties and tags
[05:33:46.946] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:33:51.850] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:33:51.850] The invocation will be retried
[05:34:02.027] Starting the invocation (attempt 3)
[05:34:02.064] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[05:34:02.064] Popped gitiles commit info from properties and tags
[05:34:02.064] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:34:06.759] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:34:06.759] The invocation will be retried
[05:34:26.902] Starting the invocation (attempt 4)
[05:34:26.929] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[05:34:26.929] Popped gitiles commit info from properties and tags
[05:34:26.929] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:34:32.520] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:34:32.521] The invocation will be retried
[05:35:12.586] Starting the invocation (attempt 5)
[05:35:12.613] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[05:35:12.613] Popped gitiles commit info from properties and tags
[05:35:12.613] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:35:16.369] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:35:16.369] The invocation will be retried
[05:36:36.434] Starting the invocation (attempt 6)
[05:36:36.461] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[05:36:36.461] Popped gitiles commit info from properties and tags
[05:36:36.461] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:36:43.153] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:36:43.153] The invocation will be retried
[05:39:23.202] Starting the invocation (attempt 7)
[05:39:23.227] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[05:39:23.227] Popped gitiles commit info from properties and tags
[05:39:23.228] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:39:26.844] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:39:26.844] The invocation will be retried
[05:44:46.998] Starting the invocation (attempt 8)
[05:44:47.041] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[05:44:47.041] Popped gitiles commit info from properties and tags
[05:44:47.041] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:44:52.622] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:44:52.623] The invocation will be retried
[05:54:52.675] Starting the invocation (attempt 9)
[05:54:52.709] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[05:54:52.709] Popped gitiles commit info from properties and tags
[05:54:52.709] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:54:57.361] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:54:57.361] The invocation will be retried
[06:04:57.791] Starting the invocation (attempt 10)
[06:04:57.817] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[06:04:57.817] Popped gitiles commit info from properties and tags
[06:04:57.817] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:05:02.562] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:05:02.562] The invocation will be retried
[06:15:02.703] Starting the invocation (attempt 11)
[06:15:02.730] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[06:15:02.730] Popped gitiles commit info from properties and tags
[06:15:02.730] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:15:07.281] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:15:07.281] The invocation will be retried
[06:25:07.406] Starting the invocation (attempt 12)
[06:25:07.430] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[06:25:07.430] Popped gitiles commit info from properties and tags
[06:25:07.430] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:25:11.139] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:25:11.139] The invocation will be retried
[06:35:11.201] Starting the invocation (attempt 13)
[06:35:11.231] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[06:35:11.231] Popped gitiles commit info from properties and tags
[06:35:11.231] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:35:15.660] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:35:15.660] The invocation will be retried
[06:45:15.720] Starting the invocation (attempt 14)
[06:45:15.754] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[06:45:15.754] Popped gitiles commit info from properties and tags
[06:45:15.754] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:45:20.499] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:45:20.499] The invocation will be retried
[06:55:20.568] Starting the invocation (attempt 15)
[06:55:20.594] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[06:55:20.594] Popped gitiles commit info from properties and tags
[06:55:20.594] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:55:25.252] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:55:25.252] The invocation will be retried
[07:05:25.307] Starting the invocation (attempt 16)
[07:05:25.333] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[07:05:25.333] Popped gitiles commit info from properties and tags
[07:05:25.333] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[07:05:29.960] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[07:05:29.960] The invocation will be retried
[07:15:30.064] Starting the invocation (attempt 17)
[07:15:30.092] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[07:15:30.093] Popped gitiles commit info from properties and tags
[07:15:30.093] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[07:15:34.838] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[07:15:34.838] The invocation will be retried
[07:25:34.896] Starting the invocation (attempt 18)
[07:25:34.926] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[07:25:34.926] Popped gitiles commit info from properties and tags
[07:25:34.926] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[07:25:39.662] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[07:25:39.663] The invocation will be retried
[07:35:39.741] Starting the invocation (attempt 19)
[07:35:39.765] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
[07:35:39.765] Popped gitiles commit info from properties and tags
[07:35:39.765] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[07:35:39.765] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[07:35:39.766] Buildbucket request:
{
"requestId": "8904993308588248400",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8904993308588248400",
"job": "infra/infra-continuous-win11-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "0703589a85bc540aada237c1affe588b09843dcd"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@0703589a85bc540aada237c1affe588b09843dcd",
"title": "0703589a85bc540aada237c1affe588b09843dcd",
"url": "https://chromium.googlesource.com/infra/infra/+/0703589a85bc540aada237c1affe588b09843dcd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "20c6fae374c3a89e326c4ea4318a5be9e6f50c2d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@20c6fae374c3a89e326c4ea4318a5be9e6f50c2d",
"title": "20c6fae374c3a89e326c4ea4318a5be9e6f50c2d",
"url": "https://chromium.googlesource.com/infra/infra/+/20c6fae374c3a89e326c4ea4318a5be9e6f50c2d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@06b9f49cefd5e6409e159c58ef1d2bb25a2438e7",
"title": "06b9f49cefd5e6409e159c58ef1d2bb25a2438e7",
"url": "https://chromium.googlesource.com/infra/infra/+/06b9f49cefd5e6409e159c58ef1d2bb25a2438e7"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "06b9f49cefd5e6409e159c58ef1d2bb25a2438e7",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8904993308588248400"
},
{
"key": "scheduler_job_id",
"value": "infra/infra-continuous-win11-64"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[07:35:40.576] Received PubSub notification, asking Buildbucket for the build status
[07:35:40.576] Fatal error when handling PubSub notification, aborting invocation - unexpected invocation status "RETRYING", expecting "RUNNING"
[07:35:40.576] Invocation finished in 2h2m4.538573414s with status FAILED