[08:39:11.318] New invocation is queued and will start shortly
[08:39:12.623] Starting the invocation (attempt 1)
[08:39:12.667] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/8ce95d119152f0f6dfb074f49c034af647e7ee35"
[08:39:12.667] Popped gitiles commit info from properties and tags
[08:39:12.667] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:39:12.667] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:39:12.667] Buildbucket request:
{
"requestId": "8906340586916204784",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Linux64 - coverage - debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8906340586916204784",
"job": "v8/V8 Linux64 - coverage - debug",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "40855af28849ba2b98dc46d6f737e432bbe4bf9d"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@40855af28849ba2b98dc46d6f737e432bbe4bf9d",
"title": "40855af28849ba2b98dc46d6f737e432bbe4bf9d",
"url": "https://chromium.googlesource.com/v8/v8/+/40855af28849ba2b98dc46d6f737e432bbe4bf9d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "f6eb763fb58c28c64e2b742dba4137d696aa0af0"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@f6eb763fb58c28c64e2b742dba4137d696aa0af0",
"title": "f6eb763fb58c28c64e2b742dba4137d696aa0af0",
"url": "https://chromium.googlesource.com/v8/v8/+/f6eb763fb58c28c64e2b742dba4137d696aa0af0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "f96ce9703f6d59c578549dfef5cb722fc8eb5aa7"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@f96ce9703f6d59c578549dfef5cb722fc8eb5aa7",
"title": "f96ce9703f6d59c578549dfef5cb722fc8eb5aa7",
"url": "https://chromium.googlesource.com/v8/v8/+/f96ce9703f6d59c578549dfef5cb722fc8eb5aa7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "7d5305b8487ac6ae63a54ac0c885509b0920ef6b"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@7d5305b8487ac6ae63a54ac0c885509b0920ef6b",
"title": "7d5305b8487ac6ae63a54ac0c885509b0920ef6b",
"url": "https://chromium.googlesource.com/v8/v8/+/7d5305b8487ac6ae63a54ac0c885509b0920ef6b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "73ae5eb331f47c7e46a6691083f21bd9ffa794fb"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@73ae5eb331f47c7e46a6691083f21bd9ffa794fb",
"title": "73ae5eb331f47c7e46a6691083f21bd9ffa794fb",
"url": "https://chromium.googlesource.com/v8/v8/+/73ae5eb331f47c7e46a6691083f21bd9ffa794fb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "8ce95d119152f0f6dfb074f49c034af647e7ee35"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@8ce95d119152f0f6dfb074f49c034af647e7ee35",
"title": "8ce95d119152f0f6dfb074f49c034af647e7ee35",
"url": "https://chromium.googlesource.com/v8/v8/+/8ce95d119152f0f6dfb074f49c034af647e7ee35"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "8ce95d119152f0f6dfb074f49c034af647e7ee35",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8906340586916204784"
},
{
"key": "scheduler_job_id",
"value": "v8/V8 Linux64 - coverage - debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[08:39:13.299] Scheduled build:
{
"id": "8740910524752197553",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Linux64 - coverage - debug"
},
"number": 4114,
"createdBy": "project:v8",
"createTime": "2024-07-31T08:39:12.746673855Z",
"updateTime": "2024-07-31T08:39:12.746673855Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "8ce95d119152f0f6dfb074f49c034af647e7ee35",
"ref": "refs/heads/main"
}
}
}
[08:39:13.299] Task URL: https://cr-buildbucket.appspot.com/build/8740910524752197553
[08:39:13.299] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:2:0) after 8m36s
[08:39:14.256] Received PubSub notification, asking Buildbucket for the build status
[08:39:14.333] Build status: SCHEDULED
[08:43:35.994] Received PubSub notification, asking Buildbucket for the build status
[08:43:36.017] Build status: STARTED
[08:47:49.328] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:2:0)
[08:47:49.365] Build status: STARTED
[08:47:49.365] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:5:0) after 8m38s
[08:56:27.395] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:5:0)
[08:56:27.418] Build status: STARTED
[08:56:27.418] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:6:0) after 5m25s
[09:01:52.541] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:6:0)
[09:01:52.566] Build status: STARTED
[09:01:52.566] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:7:0) after 6m12s
[09:08:05.083] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:7:0)
[09:08:05.110] Build status: STARTED
[09:08:05.110] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:8:0) after 5m31s
[09:13:36.149] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:8:0)
[09:13:36.185] Build status: STARTED
[09:13:36.185] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:9:0) after 4m42s
[09:18:18.221] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:9:0)
[09:18:18.401] Build status: STARTED
[09:18:18.401] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:10:0) after 4m13s
[09:22:31.427] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:10:0)
[09:22:31.452] Build status: STARTED
[09:22:31.452] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:11:0) after 6m28s
[09:28:59.624] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:11:0)
[09:28:59.655] Build status: STARTED
[09:28:59.655] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:12:0) after 3m17s
[09:32:16.712] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:12:0)
[09:32:16.728] Build status: STARTED
[09:32:16.728] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:13:0) after 7m31s
[09:39:48.035] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:13:0)
[09:39:48.098] Build status: STARTED
[09:39:48.098] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:14:0) after 5m42s
[09:45:30.132] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:14:0)
[09:45:30.149] Build status: STARTED
[09:45:30.149] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:15:0) after 1m12s
[09:46:42.182] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:15:0)
[09:46:42.281] Build status: STARTED
[09:46:42.281] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:16:0) after 2m6s
[09:48:48.348] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:16:0)
[09:48:48.364] Build status: STARTED
[09:48:48.364] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:17:0) after 7m26s
[09:56:14.409] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:17:0)
[09:56:14.437] Build status: STARTED
[09:56:14.437] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - coverage - debug:8906340586916204784:18:0) after 5m9s
[10:00:07.705] Received PubSub notification, asking Buildbucket for the build status
[10:00:07.746] Build:
{
"id": "8740910524752197553",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Linux64 - coverage - debug"
},
"number": 4114,
"createdBy": "project:v8",
"createTime": "2024-07-31T08:39:12.746673855Z",
"startTime": "2024-07-31T08:43:35.716298535Z",
"endTime": "2024-07-31T10:00:06.881847897Z",
"updateTime": "2024-07-31T10:00:06.881847897Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "8ce95d119152f0f6dfb074f49c034af647e7ee35",
"ref": "refs/heads/main"
}
}
}
[10:00:07.746] Invocation finished in 1h20m56.447508629s with status SUCCEEDED