[13:49:54.063] New invocation is queued and will start shortly
[13:49:55.138] Starting the invocation (attempt 1)
[13:49:55.174] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/f41f4fb4e66916936ed14d8f9ee20d5fb0afc548"
[13:49:55.174] Popped gitiles commit info from properties and tags
[13:49:55.174] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[13:49:55.174] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[13:49:55.174] Buildbucket request:
{
"requestId": "8999998301835788496",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "6d25f20f35b2cbcf5421ccb5d9e78bfd1191ad3c"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@6d25f20f35b2cbcf5421ccb5d9e78bfd1191ad3c",
"title": "6d25f20f35b2cbcf5421ccb5d9e78bfd1191ad3c",
"url": "https://chromium.googlesource.com/v8/v8/+/6d25f20f35b2cbcf5421ccb5d9e78bfd1191ad3c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "749e41d468690d3dd9952af723000c839a2de591"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@749e41d468690d3dd9952af723000c839a2de591",
"title": "749e41d468690d3dd9952af723000c839a2de591",
"url": "https://chromium.googlesource.com/v8/v8/+/749e41d468690d3dd9952af723000c839a2de591"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "1c3085e26a408adb53645f9b5d12fa9f3803df3c"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@1c3085e26a408adb53645f9b5d12fa9f3803df3c",
"title": "1c3085e26a408adb53645f9b5d12fa9f3803df3c",
"url": "https://chromium.googlesource.com/v8/v8/+/1c3085e26a408adb53645f9b5d12fa9f3803df3c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "f6c9a5454ee3e8018bef3ceb627d5c1e6200c542"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@f6c9a5454ee3e8018bef3ceb627d5c1e6200c542",
"title": "f6c9a5454ee3e8018bef3ceb627d5c1e6200c542",
"url": "https://chromium.googlesource.com/v8/v8/+/f6c9a5454ee3e8018bef3ceb627d5c1e6200c542"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "da35064bcafe30a8721ea73eb3b9172f35c93144"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@da35064bcafe30a8721ea73eb3b9172f35c93144",
"title": "da35064bcafe30a8721ea73eb3b9172f35c93144",
"url": "https://chromium.googlesource.com/v8/v8/+/da35064bcafe30a8721ea73eb3b9172f35c93144"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "d07f6f7f5854baa359de0d3b9387f31ef3839d46"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@d07f6f7f5854baa359de0d3b9387f31ef3839d46",
"title": "d07f6f7f5854baa359de0d3b9387f31ef3839d46",
"url": "https://chromium.googlesource.com/v8/v8/+/d07f6f7f5854baa359de0d3b9387f31ef3839d46"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "47e715085a57aa0ac213d680f945ebbdcc68a1cd"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@47e715085a57aa0ac213d680f945ebbdcc68a1cd",
"title": "47e715085a57aa0ac213d680f945ebbdcc68a1cd",
"url": "https://chromium.googlesource.com/v8/v8/+/47e715085a57aa0ac213d680f945ebbdcc68a1cd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "5bd6753230fa5270a0f0da4fcf774d21dc14b182"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@5bd6753230fa5270a0f0da4fcf774d21dc14b182",
"title": "5bd6753230fa5270a0f0da4fcf774d21dc14b182",
"url": "https://chromium.googlesource.com/v8/v8/+/5bd6753230fa5270a0f0da4fcf774d21dc14b182"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "2521064740764100e34ae08139fa1a4ef7bfdda5"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@2521064740764100e34ae08139fa1a4ef7bfdda5",
"title": "2521064740764100e34ae08139fa1a4ef7bfdda5",
"url": "https://chromium.googlesource.com/v8/v8/+/2521064740764100e34ae08139fa1a4ef7bfdda5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "b3d9c0d4ff726111e43362df14149e5e1d9af246"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@b3d9c0d4ff726111e43362df14149e5e1d9af246",
"title": "b3d9c0d4ff726111e43362df14149e5e1d9af246",
"url": "https://chromium.googlesource.com/v8/v8/+/b3d9c0d4ff726111e43362df14149e5e1d9af246"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "f41f4fb4e66916936ed14d8f9ee20d5fb0afc548"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@f41f4fb4e66916936ed14d8f9ee20d5fb0afc548",
"title": "f41f4fb4e66916936ed14d8f9ee20d5fb0afc548",
"url": "https://chromium.googlesource.com/v8/v8/+/f41f4fb4e66916936ed14d8f9ee20d5fb0afc548"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "f41f4fb4e66916936ed14d8f9ee20d5fb0afc548",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8999998301835788496"
},
{
"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"
}
}
[13:49:55.951] Scheduled build:
{
"id": "8834568239883652193",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 2852,
"createdBy": "project:v8",
"createTime": "2021-10-01T13:49:55.292696966Z",
"updateTime": "2021-10-01T13:49:55.659646444Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "f41f4fb4e66916936ed14d8f9ee20d5fb0afc548",
"ref": "refs/heads/main"
}
}
}
[13:49:55.951] Task URL: https://cr-buildbucket-dev.appspot.com/build/8834568239883652193
[13:49:55.951] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:2:0) after 4m3s
[13:53:58.971] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:2:0)
[13:53:59.018] Build status: SCHEDULED
[13:53:59.018] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:3:0) after 4m50s
[13:58:49.038] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:3:0)
[13:58:49.126] Build status: SCHEDULED
[13:58:49.126] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:4:0) after 6m51s
[14:05:40.148] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:4:0)
[14:05:40.307] Build status: SCHEDULED
[14:05:40.307] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:5:0) after 8m0s
[14:13:40.329] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:5:0)
[14:13:40.400] Build status: SCHEDULED
[14:13:40.400] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:6:0) after 6m19s
[14:19:59.421] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:6:0)
[14:19:59.504] Build status: SCHEDULED
[14:19:59.504] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:7:0) after 6m56s
[14:26:55.541] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:7:0)
[14:26:55.580] Build status: SCHEDULED
[14:26:55.580] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:8:0) after 2m45s
[14:29:40.626] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:8:0)
[14:29:40.690] Build status: SCHEDULED
[14:29:40.690] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:9:0) after 5m30s
[14:35:10.717] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:9:0)
[14:35:10.772] Build status: SCHEDULED
[14:35:10.772] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:10:0) after 4m54s
[14:40:04.797] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:10:0)
[14:40:04.842] Build status: SCHEDULED
[14:40:04.842] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:11:0) after 7m36s
[14:47:40.866] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:11:0)
[14:47:40.921] Build status: SCHEDULED
[14:47:40.921] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:12:0) after 1m50s
[14:49:30.946] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:12:0)
[14:49:30.991] Build status: SCHEDULED
[14:49:30.991] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:13:0) after 7m53s
[14:57:24.082] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:13:0)
[14:57:24.150] Build status: SCHEDULED
[14:57:24.150] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:14:0) after 4m38s
[15:02:02.171] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:14:0)
[15:02:02.247] Build status: SCHEDULED
[15:02:02.247] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:15:0) after 8m56s
[15:10:58.269] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:15:0)
[15:10:58.321] Build status: SCHEDULED
[15:10:58.321] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:16:0) after 3m30s
[15:14:28.347] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:16:0)
[15:14:28.414] Build status: SCHEDULED
[15:14:28.414] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:17:0) after 4m23s
[15:18:51.467] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:17:0)
[15:18:51.524] Build status: SCHEDULED
[15:18:51.524] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:18:0) after 3m50s
[15:22:41.555] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:18:0)
[15:22:41.613] Build status: SCHEDULED
[15:22:41.613] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:19:0) after 8m54s
[15:31:35.636] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:19:0)
[15:31:35.694] Build status: SCHEDULED
[15:31:35.694] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:20:0) after 3m3s
[15:34:33.625] Received PubSub notification, asking Buildbucket for the build status
[15:34:33.699] Build status: STARTED
[15:34:38.717] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:20:0)
[15:34:38.771] Build status: STARTED
[15:34:38.771] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8999998301835788496:22:0) after 9m37s
[15:43:34.863] Received PubSub notification, asking Buildbucket for the build status
[15:43:34.946] Build:
{
"id": "8834568239883652193",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 2852,
"createdBy": "project:v8",
"createTime": "2021-10-01T13:49:55.292696966Z",
"startTime": "2021-10-01T15:34:31.637353Z",
"endTime": "2021-10-01T15:43:34.477934984Z",
"updateTime": "2021-10-01T15:43:34.478279032Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "f41f4fb4e66916936ed14d8f9ee20d5fb0afc548",
"ref": "refs/heads/main"
}
}
}
[15:43:34.946] Invocation finished in 1h53m40.898719325s with status SUCCEEDED