[10:31:55.188] New invocation is queued and will start shortly
[10:31:57.884] Starting the invocation (attempt 1)
[10:31:57.910] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/a5b3c3df9b22f441ce61b70c3a2fcf652f9e3f9e"
[10:31:57.910] Popped gitiles commit info from properties and tags
[10:31:57.910] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:31:57.910] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:31:57.911] Buildbucket request:
{
"requestId": "8969026595223497712",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8969026595223497712",
"job": "v8/V8 Blink Win",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "f1026c1917c2142b59bb5272bf8c15e579409715"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@f1026c1917c2142b59bb5272bf8c15e579409715",
"title": "f1026c1917c2142b59bb5272bf8c15e579409715",
"url": "https://chromium.googlesource.com/v8/v8/+/f1026c1917c2142b59bb5272bf8c15e579409715"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "3c4654da69d96e9c590d80f38d65ef2f7cd1e06e"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@3c4654da69d96e9c590d80f38d65ef2f7cd1e06e",
"title": "3c4654da69d96e9c590d80f38d65ef2f7cd1e06e",
"url": "https://chromium.googlesource.com/v8/v8/+/3c4654da69d96e9c590d80f38d65ef2f7cd1e06e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "178f2eeb13458e66f89d70b3aae9e163b629436f"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@178f2eeb13458e66f89d70b3aae9e163b629436f",
"title": "178f2eeb13458e66f89d70b3aae9e163b629436f",
"url": "https://chromium.googlesource.com/v8/v8/+/178f2eeb13458e66f89d70b3aae9e163b629436f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "319af35d1d1e836872d22186476f60491eb2aa1c"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@319af35d1d1e836872d22186476f60491eb2aa1c",
"title": "319af35d1d1e836872d22186476f60491eb2aa1c",
"url": "https://chromium.googlesource.com/v8/v8/+/319af35d1d1e836872d22186476f60491eb2aa1c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "556e44de284a8aeb75578249fdc4e1dbeebefb83"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@556e44de284a8aeb75578249fdc4e1dbeebefb83",
"title": "556e44de284a8aeb75578249fdc4e1dbeebefb83",
"url": "https://chromium.googlesource.com/v8/v8/+/556e44de284a8aeb75578249fdc4e1dbeebefb83"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "cab126f9eeae6d5657108bfa9420a1b65d82a537"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@cab126f9eeae6d5657108bfa9420a1b65d82a537",
"title": "cab126f9eeae6d5657108bfa9420a1b65d82a537",
"url": "https://chromium.googlesource.com/v8/v8/+/cab126f9eeae6d5657108bfa9420a1b65d82a537"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "a5b3c3df9b22f441ce61b70c3a2fcf652f9e3f9e"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@a5b3c3df9b22f441ce61b70c3a2fcf652f9e3f9e",
"title": "a5b3c3df9b22f441ce61b70c3a2fcf652f9e3f9e",
"url": "https://chromium.googlesource.com/v8/v8/+/a5b3c3df9b22f441ce61b70c3a2fcf652f9e3f9e"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "a5b3c3df9b22f441ce61b70c3a2fcf652f9e3f9e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8969026595223497712"
},
{
"key": "scheduler_job_id",
"value": "v8/V8 Blink Win"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[10:31:58.431] Scheduled build:
{
"id": "8803596531662840193",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 9514,
"createdBy": "project:v8",
"createTime": "2022-09-08T10:31:57.958671753Z",
"updateTime": "2022-09-08T10:31:57.958671753Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "a5b3c3df9b22f441ce61b70c3a2fcf652f9e3f9e",
"ref": "refs/heads/main"
}
}
}
[10:31:58.431] Task URL: https://cr-buildbucket.appspot.com/build/8803596531662840193
[10:31:58.431] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:2:0) after 6m29s
[10:32:33.193] Received PubSub notification, asking Buildbucket for the build status
[10:32:33.236] Build status: STARTED
[10:38:27.526] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:2:0)
[10:38:27.554] Build status: STARTED
[10:38:27.554] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:4:0) after 9m19s
[10:47:46.651] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:4:0)
[10:47:46.673] Build status: STARTED
[10:47:46.673] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:5:0) after 4m45s
[10:52:31.789] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:5:0)
[10:52:31.820] Build status: STARTED
[10:52:31.820] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:6:0) after 6m41s
[10:59:12.836] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:6:0)
[10:59:12.858] Build status: STARTED
[10:59:12.858] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:7:0) after 5m54s
[11:05:06.879] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:7:0)
[11:05:06.904] Build status: STARTED
[11:05:06.904] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:8:0) after 9m4s
[11:14:11.040] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:8:0)
[11:14:11.068] Build status: STARTED
[11:14:11.068] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:9:0) after 4m0s
[11:18:11.090] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:9:0)
[11:18:11.117] Build status: STARTED
[11:18:11.118] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:10:0) after 9m19s
[11:27:30.134] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:10:0)
[11:27:30.158] Build status: STARTED
[11:27:30.158] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:11:0) after 1m18s
[11:28:48.447] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:11:0)
[11:28:48.475] Build status: STARTED
[11:28:48.476] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:12:0) after 7m7s
[11:35:55.499] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:12:0)
[11:35:55.527] Build status: STARTED
[11:35:55.527] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969026595223497712:13:0) after 2m17s
[11:38:08.782] Received PubSub notification, asking Buildbucket for the build status
[11:38:08.806] Build:
{
"id": "8803596531662840193",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 9514,
"createdBy": "project:v8",
"createTime": "2022-09-08T10:31:57.958671753Z",
"startTime": "2022-09-08T10:32:31.734579Z",
"endTime": "2022-09-08T11:38:07.740851359Z",
"updateTime": "2022-09-08T11:38:07.740851359Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "a5b3c3df9b22f441ce61b70c3a2fcf652f9e3f9e",
"ref": "refs/heads/main"
}
}
}
[11:38:08.806] Invocation finished in 1h6m13.627442602s with status SUCCEEDED