[09:18:20.169] New invocation is queued and will start shortly
[09:18:21.347] Starting the invocation (attempt 1)
[09:18:21.376] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/7e4293a1fe3cf180135596a1d8e70d822963d9f2"
[09:18:21.376] Popped gitiles commit info from properties and tags
[09:18:21.376] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:18:21.377] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:18:21.377] Buildbucket request:
{
"requestId": "8969665403484777376",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8969665403484777376",
"job": "v8/V8 Blink Win",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "b7c71a6d1b8b8019c3c57a37ce4954cbb15c8087"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@b7c71a6d1b8b8019c3c57a37ce4954cbb15c8087",
"title": "b7c71a6d1b8b8019c3c57a37ce4954cbb15c8087",
"url": "https://chromium.googlesource.com/v8/v8/+/b7c71a6d1b8b8019c3c57a37ce4954cbb15c8087"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "9526c2936dc977b285732599d875a7837c09c9da"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@9526c2936dc977b285732599d875a7837c09c9da",
"title": "9526c2936dc977b285732599d875a7837c09c9da",
"url": "https://chromium.googlesource.com/v8/v8/+/9526c2936dc977b285732599d875a7837c09c9da"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "dad963c84f9fe2ef30a97344057f33dc9503bf8a"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@dad963c84f9fe2ef30a97344057f33dc9503bf8a",
"title": "dad963c84f9fe2ef30a97344057f33dc9503bf8a",
"url": "https://chromium.googlesource.com/v8/v8/+/dad963c84f9fe2ef30a97344057f33dc9503bf8a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "d1e696d03547a2457695b7f110d6d0ed0ff3fc1e"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@d1e696d03547a2457695b7f110d6d0ed0ff3fc1e",
"title": "d1e696d03547a2457695b7f110d6d0ed0ff3fc1e",
"url": "https://chromium.googlesource.com/v8/v8/+/d1e696d03547a2457695b7f110d6d0ed0ff3fc1e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "8441f26cf427c26914a0789291da065f9c3d33a2"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@8441f26cf427c26914a0789291da065f9c3d33a2",
"title": "8441f26cf427c26914a0789291da065f9c3d33a2",
"url": "https://chromium.googlesource.com/v8/v8/+/8441f26cf427c26914a0789291da065f9c3d33a2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "877541053008d7b788c7200f699ab267451fd9e6"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@877541053008d7b788c7200f699ab267451fd9e6",
"title": "877541053008d7b788c7200f699ab267451fd9e6",
"url": "https://chromium.googlesource.com/v8/v8/+/877541053008d7b788c7200f699ab267451fd9e6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "7e4293a1fe3cf180135596a1d8e70d822963d9f2"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@7e4293a1fe3cf180135596a1d8e70d822963d9f2",
"title": "7e4293a1fe3cf180135596a1d8e70d822963d9f2",
"url": "https://chromium.googlesource.com/v8/v8/+/7e4293a1fe3cf180135596a1d8e70d822963d9f2"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "7e4293a1fe3cf180135596a1d8e70d822963d9f2",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8969665403484777376"
},
{
"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"
}
}
[09:18:21.874] Scheduled build:
{
"id": "8804235341495273153",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 9462,
"createdBy": "project:v8",
"createTime": "2022-09-01T09:18:21.427810703Z",
"updateTime": "2022-09-01T09:18:21.427810703Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "7e4293a1fe3cf180135596a1d8e70d822963d9f2",
"ref": "refs/heads/main"
}
}
}
[09:18:21.874] Task URL: https://cr-buildbucket.appspot.com/build/8804235341495273153
[09:18:21.874] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:2:0) after 2m15s
[09:20:36.890] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:2:0)
[09:20:36.909] Build status: SCHEDULED
[09:20:36.909] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:3:0) after 3m18s
[09:21:12.652] Received PubSub notification, asking Buildbucket for the build status
[09:21:12.689] Build status: STARTED
[09:23:54.932] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:3:0)
[09:23:54.959] Build status: STARTED
[09:23:54.959] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:5:0) after 4m27s
[09:28:22.094] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:5:0)
[09:28:22.118] Build status: STARTED
[09:28:22.118] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:6:0) after 3m25s
[09:31:47.143] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:6:0)
[09:31:47.168] Build status: STARTED
[09:31:47.168] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:7:0) after 3m29s
[09:35:16.387] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:7:0)
[09:35:16.412] Build status: STARTED
[09:35:16.412] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:8:0) after 1m57s
[09:37:13.707] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:8:0)
[09:37:13.731] Build status: STARTED
[09:37:13.731] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:9:0) after 8m15s
[09:45:28.745] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:9:0)
[09:45:28.776] Build status: STARTED
[09:45:28.776] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:10:0) after 9m29s
[09:54:57.838] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:10:0)
[09:54:57.872] Build status: STARTED
[09:54:57.872] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:11:0) after 3m22s
[09:58:19.892] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:11:0)
[09:58:19.915] Build status: STARTED
[09:58:19.915] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:12:0) after 9m12s
[10:07:31.950] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:12:0)
[10:07:32.031] Build status: STARTED
[10:07:32.031] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:13:0) after 6m53s
[10:14:25.047] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:13:0)
[10:14:25.071] Build status: STARTED
[10:14:25.071] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:14:0) after 3m34s
[10:17:59.194] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:14:0)
[10:17:59.219] Build status: STARTED
[10:17:59.219] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:15:0) after 4m5s
[10:22:04.235] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:15:0)
[10:22:04.256] Build status: STARTED
[10:22:04.256] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:16:0) after 7m59s
[10:30:03.292] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:16:0)
[10:30:03.320] Build status: STARTED
[10:30:03.320] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:17:0) after 3m32s
[10:33:35.563] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:17:0)
[10:33:35.593] Build status: STARTED
[10:33:35.593] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8969665403484777376:18:0) after 4m53s
[10:34:54.129] Received PubSub notification, asking Buildbucket for the build status
[10:34:54.151] Build:
{
"id": "8804235341495273153",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 9462,
"createdBy": "project:v8",
"createTime": "2022-09-01T09:18:21.427810703Z",
"startTime": "2022-09-01T09:21:11.122679Z",
"endTime": "2022-09-01T10:34:53.566100550Z",
"updateTime": "2022-09-01T10:34:53.566100550Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "7e4293a1fe3cf180135596a1d8e70d822963d9f2",
"ref": "refs/heads/main"
}
}
}
[10:34:54.151] Invocation finished in 1h16m34.005060731s with status SUCCEEDED