[04:47:53.045] New invocation is queued and will start shortly
[04:47:54.130] Starting the invocation (attempt 1)
[04:47:54.157] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[04:47:54.157] Popped gitiles commit info from properties and tags
[04:47:54.157] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[04:47:58.857] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[04:47:58.857] The invocation will be retried
[04:48:03.934] Starting the invocation (attempt 2)
[04:48:03.973] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[04:48:03.973] Popped gitiles commit info from properties and tags
[04:48:03.973] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[04:48:08.751] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[04:48:08.751] The invocation will be retried
[04:48:18.803] Starting the invocation (attempt 3)
[04:48:18.838] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[04:48:18.838] Popped gitiles commit info from properties and tags
[04:48:18.838] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[04:48:23.376] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[04:48:23.376] The invocation will be retried
[04:48:44.148] Starting the invocation (attempt 4)
[04:48:44.176] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[04:48:44.177] Popped gitiles commit info from properties and tags
[04:48:44.177] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[04:48:48.274] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[04:48:48.274] The invocation will be retried
[04:49:28.428] Starting the invocation (attempt 5)
[04:49:28.457] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[04:49:28.457] Popped gitiles commit info from properties and tags
[04:49:28.457] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[04:49:32.793] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[04:49:32.793] The invocation will be retried
[04:50:52.847] Starting the invocation (attempt 6)
[04:50:52.885] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[04:50:52.885] Popped gitiles commit info from properties and tags
[04:50:52.885] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[04:50:57.544] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[04:50:57.544] The invocation will be retried
[04:53:37.601] Starting the invocation (attempt 7)
[04:53:37.632] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[04:53:37.632] Popped gitiles commit info from properties and tags
[04:53:37.632] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[04:53:42.265] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[04:53:42.265] The invocation will be retried
[04:59:02.336] Starting the invocation (attempt 8)
[04:59:02.370] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[04:59:02.370] Popped gitiles commit info from properties and tags
[04:59:02.370] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[04:59:11.062] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[04:59:11.063] The invocation will be retried
[05:09:11.118] Starting the invocation (attempt 9)
[05:09:11.146] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[05:09:11.146] Popped gitiles commit info from properties and tags
[05:09:11.146] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:09:15.654] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:09:15.654] The invocation will be retried
[05:19:15.709] Starting the invocation (attempt 10)
[05:19:15.736] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[05:19:15.736] Popped gitiles commit info from properties and tags
[05:19:15.736] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:19:20.469] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:19:20.469] The invocation will be retried
[05:29:20.739] Starting the invocation (attempt 11)
[05:29:20.770] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[05:29:20.770] Popped gitiles commit info from properties and tags
[05:29:20.770] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:29:25.373] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:29:25.374] The invocation will be retried
[05:39:25.440] Starting the invocation (attempt 12)
[05:39:25.472] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[05:39:25.472] Popped gitiles commit info from properties and tags
[05:39:25.472] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:39:31.346] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:39:31.346] The invocation will be retried
[05:49:31.397] Starting the invocation (attempt 13)
[05:49:31.427] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[05:49:31.427] Popped gitiles commit info from properties and tags
[05:49:31.427] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:49:35.983] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:49:35.983] The invocation will be retried
[05:59:36.113] Starting the invocation (attempt 14)
[05:59:36.141] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[05:59:36.141] Popped gitiles commit info from properties and tags
[05:59:36.141] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:59:43.994] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[05:59:43.994] The invocation will be retried
[06:09:44.071] Starting the invocation (attempt 15)
[06:09:44.096] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[06:09:44.096] Popped gitiles commit info from properties and tags
[06:09:44.096] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:09:48.337] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:09:48.337] The invocation will be retried
[06:19:48.388] Starting the invocation (attempt 16)
[06:19:48.414] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[06:19:48.414] Popped gitiles commit info from properties and tags
[06:19:48.414] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:19:52.873] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:19:52.873] The invocation will be retried
[06:29:52.939] Starting the invocation (attempt 17)
[06:29:52.976] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[06:29:52.976] Popped gitiles commit info from properties and tags
[06:29:52.976] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:29:57.464] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:29:57.464] The invocation will be retried
[06:39:57.523] Starting the invocation (attempt 18)
[06:39:57.554] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[06:39:57.555] Popped gitiles commit info from properties and tags
[06:39:57.555] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:40:04.569] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:40:04.569] The invocation will be retried
[06:50:04.629] Starting the invocation (attempt 19)
[06:50:04.658] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[06:50:04.658] Popped gitiles commit info from properties and tags
[06:50:04.658] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[06:50:12.760] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[06:50:12.760] The invocation will be retried
[07:00:12.819] Starting the invocation (attempt 20)
[07:00:12.845] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[07:00:12.845] Popped gitiles commit info from properties and tags
[07:00:12.845] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[07:00:17.469] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[07:00:17.469] The invocation will be retried
[07:10:17.527] Starting the invocation (attempt 21)
[07:10:17.566] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[07:10:17.566] Popped gitiles commit info from properties and tags
[07:10:17.566] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[07:10:21.283] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[07:10:21.283] The invocation will be retried
[07:20:21.349] Starting the invocation (attempt 22)
[07:20:21.377] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[07:20:21.377] Popped gitiles commit info from properties and tags
[07:20:21.377] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[07:20:26.047] Failed to prepare PubSub topic - auth: HTTP code (500) when fetching https://cr-buildbucket-dev.appspot.com/auth/api/v1/server/info
[07:20:26.047] The invocation will be retried
[07:30:26.102] Starting the invocation (attempt 23)
[07:30:26.128] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
[07:30:26.128] Popped gitiles commit info from properties and tags
[07:30:26.128] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[07:30:26.128] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[07:30:26.128] Buildbucket request:
{
"requestId": "8904996184829858544",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8904996184829858544",
"job": "v8/V8 Win64 - dev image",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb",
"title": "4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb",
"url": "https://chromium.googlesource.com/v8/v8/+/4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "4c4ca06745a8a90aaaa99d2a3f5db139cccb9bcb",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8904996184829858544"
},
{
"key": "scheduler_job_id",
"value": "v8/V8 Win64 - dev image"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[07:30:26.849] Received PubSub notification, asking Buildbucket for the build status
[07:30:26.849] Fatal error when handling PubSub notification, aborting invocation - unexpected invocation status "RETRYING", expecting "RUNNING"
[07:30:26.849] Invocation finished in 2h42m33.808669536s with status FAILED