[11:29:40.043] New invocation is queued and will start shortly
[11:29:41.111] Starting the invocation (attempt 1)
[11:29:41.138] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/c463cb7369ac6a8b888963d719f8d776c35e8652"
[11:29:41.138] Popped gitiles commit info from properties and tags
[11:29:41.139] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[11:29:41.139] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[11:29:41.139] Buildbucket request:
{
"requestId": "8904336728044934208",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8904336728044934208",
"job": "v8/V8 Win64 - dev image",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "2439cece7208cb285419b1ec66ad23791af985ad"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@2439cece7208cb285419b1ec66ad23791af985ad",
"title": "2439cece7208cb285419b1ec66ad23791af985ad",
"url": "https://chromium.googlesource.com/v8/v8/+/2439cece7208cb285419b1ec66ad23791af985ad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "e6d277bbc5088a3d2653faa89ef492bf51271af1"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@e6d277bbc5088a3d2653faa89ef492bf51271af1",
"title": "e6d277bbc5088a3d2653faa89ef492bf51271af1",
"url": "https://chromium.googlesource.com/v8/v8/+/e6d277bbc5088a3d2653faa89ef492bf51271af1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "8f00b0575a5ff650ec8a351a6cee679fbb95db35"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@8f00b0575a5ff650ec8a351a6cee679fbb95db35",
"title": "8f00b0575a5ff650ec8a351a6cee679fbb95db35",
"url": "https://chromium.googlesource.com/v8/v8/+/8f00b0575a5ff650ec8a351a6cee679fbb95db35"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "01eb135d6e5e91c7e205e3913cc534ce79012b16"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@01eb135d6e5e91c7e205e3913cc534ce79012b16",
"title": "01eb135d6e5e91c7e205e3913cc534ce79012b16",
"url": "https://chromium.googlesource.com/v8/v8/+/01eb135d6e5e91c7e205e3913cc534ce79012b16"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "10359bb2961c571bf5e2d54c915ab8d8e379a9cd"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@10359bb2961c571bf5e2d54c915ab8d8e379a9cd",
"title": "10359bb2961c571bf5e2d54c915ab8d8e379a9cd",
"url": "https://chromium.googlesource.com/v8/v8/+/10359bb2961c571bf5e2d54c915ab8d8e379a9cd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "395de046e246bcc69bc70cec5cabafa429887161"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@395de046e246bcc69bc70cec5cabafa429887161",
"title": "395de046e246bcc69bc70cec5cabafa429887161",
"url": "https://chromium.googlesource.com/v8/v8/+/395de046e246bcc69bc70cec5cabafa429887161"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "b801d485d6b35c8866ba79750931af0c611a68bb"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@b801d485d6b35c8866ba79750931af0c611a68bb",
"title": "b801d485d6b35c8866ba79750931af0c611a68bb",
"url": "https://chromium.googlesource.com/v8/v8/+/b801d485d6b35c8866ba79750931af0c611a68bb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "c463cb7369ac6a8b888963d719f8d776c35e8652"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@c463cb7369ac6a8b888963d719f8d776c35e8652",
"title": "c463cb7369ac6a8b888963d719f8d776c35e8652",
"url": "https://chromium.googlesource.com/v8/v8/+/c463cb7369ac6a8b888963d719f8d776c35e8652"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "c463cb7369ac6a8b888963d719f8d776c35e8652",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8904336728044934208"
},
{
"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"
}
}
[11:29:41.889] Scheduled build:
{
"id": "8738906666157682225",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 12015,
"createdBy": "project:v8",
"createTime": "2024-08-22T11:29:41.222262137Z",
"updateTime": "2024-08-22T11:29:41.222262137Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "c463cb7369ac6a8b888963d719f8d776c35e8652",
"ref": "refs/heads/main"
}
}
}
[11:29:41.889] Task URL: https://cr-buildbucket-dev.appspot.com/build/8738906666157682225
[11:29:41.889] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:2:0) after 8m56s
[11:29:42.740] Received PubSub notification, asking Buildbucket for the build status
[11:29:42.904] Build status: SCHEDULED
[11:38:37.908] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:2:0)
[11:38:37.979] Build status: SCHEDULED
[11:38:37.979] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:4:0) after 1m6s
[11:39:44.000] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:4:0)
[11:39:44.055] Build status: SCHEDULED
[11:39:44.055] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:5:0) after 9m41s
[11:49:25.075] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:5:0)
[11:49:25.197] Build status: SCHEDULED
[11:49:25.197] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:6:0) after 6m16s
[11:55:41.219] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:6:0)
[11:55:41.278] Build status: SCHEDULED
[11:55:41.278] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:7:0) after 1m7s
[11:56:48.296] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:7:0)
[11:56:49.430] Build status: SCHEDULED
[11:56:49.430] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:8:0) after 2m19s
[11:59:08.446] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:8:0)
[11:59:08.661] Build status: SCHEDULED
[11:59:08.661] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:9:0) after 8m33s
[12:07:41.676] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:9:0)
[12:07:41.772] Build status: SCHEDULED
[12:07:41.772] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:10:0) after 3m16s
[12:10:57.793] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:10:0)
[12:10:57.869] Build status: SCHEDULED
[12:10:57.869] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:11:0) after 7m35s
[12:18:32.887] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:11:0)
[12:18:32.979] Build status: SCHEDULED
[12:18:32.979] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:12:0) after 1m13s
[12:19:45.996] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:12:0)
[12:19:46.164] Build status: SCHEDULED
[12:19:46.164] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:13:0) after 2m0s
[12:21:46.183] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:13:0)
[12:21:46.244] Build status: SCHEDULED
[12:21:46.244] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:14:0) after 2m32s
[12:24:18.268] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:14:0)
[12:24:18.310] Build status: SCHEDULED
[12:24:18.310] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:15:0) after 9m34s
[12:33:52.330] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:15:0)
[12:33:52.385] Build status: SCHEDULED
[12:33:52.385] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:16:0) after 1m35s
[12:35:27.404] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:16:0)
[12:35:27.464] Build status: SCHEDULED
[12:35:27.464] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:17:0) after 5m26s
[12:40:53.480] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:17:0)
[12:40:53.541] Build status: SCHEDULED
[12:40:53.541] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:18:0) after 3m7s
[12:44:00.562] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:18:0)
[12:44:00.630] Build status: SCHEDULED
[12:44:00.630] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:19:0) after 3m37s
[12:45:40.317] Received PubSub notification, asking Buildbucket for the build status
[12:45:40.372] Build status: STARTED
[12:47:37.647] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:19:0)
[12:47:37.696] Build status: STARTED
[12:47:37.696] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:21:0) after 1m21s
[12:48:58.716] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:21:0)
[12:48:58.829] Build status: STARTED
[12:48:58.829] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8904336728044934208:22:0) after 6m45s
[12:49:00.652] Received PubSub notification, asking Buildbucket for the build status
[12:49:00.685] Build:
{
"id": "8738906666157682225",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 12015,
"createdBy": "project:v8",
"createTime": "2024-08-22T11:29:41.222262137Z",
"startTime": "2024-08-22T12:45:35.652088704Z",
"endTime": "2024-08-22T12:49:00.291004919Z",
"updateTime": "2024-08-22T12:49:00.291004919Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "c463cb7369ac6a8b888963d719f8d776c35e8652",
"ref": "refs/heads/main"
}
}
}
[12:49:00.685] Invocation finished in 1h19m20.646686316s with status SUCCEEDED