[14:59:10.082] New invocation is queued and will start shortly
[14:59:11.166] Starting the invocation (attempt 1)
[14:59:11.203] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/48eaa74931b0e59ca549d4ed2a4995df5fb464c9"
[14:59:11.203] Popped gitiles commit info from properties and tags
[14:59:11.203] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[14:59:11.203] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[14:59:11.203] Buildbucket request:
{
"requestId": "9011137370810121264",
"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/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "ca1a2cfb391bcf02c0a55ceab616e67f81ea5eaa"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@ca1a2cfb391bcf02c0a55ceab616e67f81ea5eaa",
"title": "ca1a2cfb391bcf02c0a55ceab616e67f81ea5eaa",
"url": "https://chromium.googlesource.com/v8/v8/+/ca1a2cfb391bcf02c0a55ceab616e67f81ea5eaa"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "0e6263ec22824f9ba2fd2455b49fe9f77edbe32a"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@0e6263ec22824f9ba2fd2455b49fe9f77edbe32a",
"title": "0e6263ec22824f9ba2fd2455b49fe9f77edbe32a",
"url": "https://chromium.googlesource.com/v8/v8/+/0e6263ec22824f9ba2fd2455b49fe9f77edbe32a"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "7261bf01d7a244afee69eb342dcced303dd73072"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@7261bf01d7a244afee69eb342dcced303dd73072",
"title": "7261bf01d7a244afee69eb342dcced303dd73072",
"url": "https://chromium.googlesource.com/v8/v8/+/7261bf01d7a244afee69eb342dcced303dd73072"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "687ba031939b2fb0fb5792ba65ff36975f87d975"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@687ba031939b2fb0fb5792ba65ff36975f87d975",
"title": "687ba031939b2fb0fb5792ba65ff36975f87d975",
"url": "https://chromium.googlesource.com/v8/v8/+/687ba031939b2fb0fb5792ba65ff36975f87d975"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "81181a8ad80ac978a6a8732d05f615c645df95d2"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@81181a8ad80ac978a6a8732d05f615c645df95d2",
"title": "81181a8ad80ac978a6a8732d05f615c645df95d2",
"url": "https://chromium.googlesource.com/v8/v8/+/81181a8ad80ac978a6a8732d05f615c645df95d2"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "8ff6a214b176a13e92ab3b3030f35a73cd046deb"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@8ff6a214b176a13e92ab3b3030f35a73cd046deb",
"title": "8ff6a214b176a13e92ab3b3030f35a73cd046deb",
"url": "https://chromium.googlesource.com/v8/v8/+/8ff6a214b176a13e92ab3b3030f35a73cd046deb"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "85e5f795d1b349e2a5d80f44d44619040d6a84a7"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@85e5f795d1b349e2a5d80f44d44619040d6a84a7",
"title": "85e5f795d1b349e2a5d80f44d44619040d6a84a7",
"url": "https://chromium.googlesource.com/v8/v8/+/85e5f795d1b349e2a5d80f44d44619040d6a84a7"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "c211cb43a002d6607e85e9ef353436e6a769846a"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@c211cb43a002d6607e85e9ef353436e6a769846a",
"title": "c211cb43a002d6607e85e9ef353436e6a769846a",
"url": "https://chromium.googlesource.com/v8/v8/+/c211cb43a002d6607e85e9ef353436e6a769846a"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "48eaa74931b0e59ca549d4ed2a4995df5fb464c9"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@48eaa74931b0e59ca549d4ed2a4995df5fb464c9",
"title": "48eaa74931b0e59ca549d4ed2a4995df5fb464c9",
"url": "https://chromium.googlesource.com/v8/v8/+/48eaa74931b0e59ca549d4ed2a4995df5fb464c9"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "48eaa74931b0e59ca549d4ed2a4995df5fb464c9",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9011137370810121264"
},
{
"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"
}
}
[14:59:11.932] Scheduled build:
{
"id": "8845707308813917137",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 1609,
"createdBy": "project:v8",
"createTime": "2021-05-31T14:59:11.345027075Z",
"updateTime": "2021-05-31T14:59:11.701721669Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "48eaa74931b0e59ca549d4ed2a4995df5fb464c9",
"ref": "refs/heads/master"
}
}
}
[14:59:11.932] Task URL: https://cr-buildbucket-dev.appspot.com/build/8845707308813917137
[14:59:11.932] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:2:0) after 4m34s
[15:03:45.947] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:2:0)
[15:03:45.999] Build status: SCHEDULED
[15:03:45.999] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:3:0) after 9m16s
[15:13:02.021] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:3:0)
[15:13:02.100] Build status: SCHEDULED
[15:13:02.100] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:4:0) after 5m21s
[15:18:23.132] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:4:0)
[15:18:23.279] Build status: SCHEDULED
[15:18:23.279] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:5:0) after 6m52s
[15:25:15.298] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:5:0)
[15:25:15.415] Build status: SCHEDULED
[15:25:15.415] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:6:0) after 4m45s
[15:30:00.441] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:6:0)
[15:30:00.605] Build status: SCHEDULED
[15:30:00.605] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:7:0) after 6m49s
[15:36:49.622] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:7:0)
[15:36:49.934] Build status: SCHEDULED
[15:36:49.934] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:8:0) after 2m31s
[15:39:20.954] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:8:0)
[15:39:21.027] Build status: SCHEDULED
[15:39:21.027] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:9:0) after 6m14s
[15:45:35.131] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:9:0)
[15:45:35.190] Build status: SCHEDULED
[15:45:35.190] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:10:0) after 6m32s
[15:52:07.212] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:10:0)
[15:52:07.268] Build status: SCHEDULED
[15:52:07.268] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:11:0) after 6m46s
[15:58:53.257] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:11:0)
[15:58:53.331] Build status: SCHEDULED
[15:58:53.331] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:12:0) after 8m32s
[16:07:25.358] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:12:0)
[16:07:25.428] Build status: SCHEDULED
[16:07:25.428] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:13:0) after 9m22s
[16:16:47.449] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:13:0)
[16:16:47.515] Build status: SCHEDULED
[16:16:47.515] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:14:0) after 7m1s
[16:23:48.538] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:14:0)
[16:23:48.609] Build status: SCHEDULED
[16:23:48.609] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:15:0) after 4m49s
[16:28:37.632] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:15:0)
[16:28:37.773] Build status: SCHEDULED
[16:28:37.773] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:16:0) after 3m55s
[16:32:32.793] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:16:0)
[16:32:32.872] Build status: SCHEDULED
[16:32:32.872] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:17:0) after 5m38s
[16:38:10.891] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:17:0)
[16:38:10.981] Build status: STARTED
[16:38:10.981] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:18:0) after 6m13s
[16:44:23.998] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011137370810121264:18:0)
[16:44:24.068] Build:
{
"id": "8845707308813917137",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 1609,
"createdBy": "project:v8",
"createTime": "2021-05-31T14:59:11.345027075Z",
"startTime": "2021-05-31T16:38:07.250677155Z",
"endTime": "2021-05-31T16:40:46.278710223Z",
"updateTime": "2021-05-31T16:40:46.279097505Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "48eaa74931b0e59ca549d4ed2a4995df5fb464c9",
"ref": "refs/heads/master"
}
}
}
[16:44:24.068] Invocation finished in 1h45m14.011555747s with status SUCCEEDED