[06:38:21.057] New invocation is queued and will start shortly
[06:38:22.137] Starting the invocation (attempt 1)
[06:38:22.170] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a188dd2b99cf14f44f0b42b68f2be99b1a979261"
[06:38:22.170] Popped gitiles commit info from properties and tags
[06:38:22.170] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:38:25.769] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:38:25.769] The invocation will be retried
[06:38:30.832] Starting the invocation (attempt 2)
[06:38:30.859] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a188dd2b99cf14f44f0b42b68f2be99b1a979261"
[06:38:30.859] Popped gitiles commit info from properties and tags
[06:38:30.859] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:38:34.398] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:38:34.398] The invocation will be retried
[06:38:44.455] Starting the invocation (attempt 3)
[06:38:44.482] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a188dd2b99cf14f44f0b42b68f2be99b1a979261"
[06:38:44.482] Popped gitiles commit info from properties and tags
[06:38:44.482] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:38:50.391] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:38:50.391] The invocation will be retried
[06:39:10.448] Starting the invocation (attempt 4)
[06:39:10.485] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a188dd2b99cf14f44f0b42b68f2be99b1a979261"
[06:39:10.485] Popped gitiles commit info from properties and tags
[06:39:10.485] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:39:15.051] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:39:15.052] The invocation will be retried
[06:39:55.163] Starting the invocation (attempt 5)
[06:39:55.190] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a188dd2b99cf14f44f0b42b68f2be99b1a979261"
[06:39:55.190] Popped gitiles commit info from properties and tags
[06:39:55.190] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:39:59.755] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:39:59.755] The invocation will be retried
[06:41:19.889] Starting the invocation (attempt 6)
[06:41:19.912] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a188dd2b99cf14f44f0b42b68f2be99b1a979261"
[06:41:19.912] Popped gitiles commit info from properties and tags
[06:41:19.912] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:41:24.565] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:41:24.565] The invocation will be retried
[06:44:04.708] Starting the invocation (attempt 7)
[06:44:04.733] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a188dd2b99cf14f44f0b42b68f2be99b1a979261"
[06:44:04.733] Popped gitiles commit info from properties and tags
[06:44:04.733] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:44:09.381] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:44:09.381] The invocation will be retried
[06:49:29.436] Starting the invocation (attempt 8)
[06:49:29.460] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a188dd2b99cf14f44f0b42b68f2be99b1a979261"
[06:49:29.461] Popped gitiles commit info from properties and tags
[06:49:29.461] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:49:34.063] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:49:34.063] The invocation will be retried
[06:59:34.126] Starting the invocation (attempt 9)
[06:59:34.153] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a188dd2b99cf14f44f0b42b68f2be99b1a979261"
[06:59:34.153] Popped gitiles commit info from properties and tags
[06:59:34.153] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:59:38.741] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:59:38.741] The invocation will be retried
[07:09:38.796] Starting the invocation (attempt 10)
[07:09:38.828] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a188dd2b99cf14f44f0b42b68f2be99b1a979261"
[07:09:38.828] Popped gitiles commit info from properties and tags
[07:09:38.828] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[07:09:43.545] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[07:09:43.545] The invocation will be retried
[07:19:43.620] Starting the invocation (attempt 11)
[07:19:43.648] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a188dd2b99cf14f44f0b42b68f2be99b1a979261"
[07:19:43.648] Popped gitiles commit info from properties and tags
[07:19:43.648] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[07:19:48.440] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[07:19:48.440] The invocation will be retried
[07:29:48.502] Starting the invocation (attempt 12)
[07:29:48.528] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a188dd2b99cf14f44f0b42b68f2be99b1a979261"
[07:29:48.528] Popped gitiles commit info from properties and tags
[07:29:48.528] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[07:29:48.528] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[07:29:48.529] Buildbucket request:
{
"requestId": "8904989234858709200",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8904989234858709200",
"job": "infra/infra-continuous-win10-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"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "a188dd2b99cf14f44f0b42b68f2be99b1a979261"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@a188dd2b99cf14f44f0b42b68f2be99b1a979261",
"title": "a188dd2b99cf14f44f0b42b68f2be99b1a979261",
"url": "https://chromium.googlesource.com/infra/infra/+/a188dd2b99cf14f44f0b42b68f2be99b1a979261"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "a188dd2b99cf14f44f0b42b68f2be99b1a979261",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8904989234858709200"
},
{
"key": "scheduler_job_id",
"value": "infra/infra-continuous-win10-64"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[07:29:49.289] Received PubSub notification, asking Buildbucket for the build status
[07:29:49.289] Fatal error when handling PubSub notification, aborting invocation - unexpected invocation status "RETRYING", expecting "RUNNING"
[07:29:49.289] Invocation finished in 51m28.239907798s with status FAILED