[19:52:26.064] New invocation is queued and will start shortly
[19:52:27.141] Starting the invocation (attempt 1)
[19:52:27.178] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/946c1767a4a028dcbfb572a00656c12e76394ee6"
[19:52:27.178] Popped gitiles commit info from properties and tags
[19:52:27.178] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[19:52:27.178] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[19:52:27.179] Buildbucket request:
{
"requestId": "8922786877826656784",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8922786877826656784",
"job": "infra/infra-continuous-win10-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "89ae1d7a2a24916a2faad54932cfc7e96bc66f3f"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@89ae1d7a2a24916a2faad54932cfc7e96bc66f3f",
"title": "89ae1d7a2a24916a2faad54932cfc7e96bc66f3f",
"url": "https://chromium.googlesource.com/infra/infra/+/89ae1d7a2a24916a2faad54932cfc7e96bc66f3f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "683624fdf52f05bbd6e297b13c89f9444d7594f8"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@683624fdf52f05bbd6e297b13c89f9444d7594f8",
"title": "683624fdf52f05bbd6e297b13c89f9444d7594f8",
"url": "https://chromium.googlesource.com/infra/infra/+/683624fdf52f05bbd6e297b13c89f9444d7594f8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "bec8d794a92edcaeaa3ead3975b0cd808d39ebea"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@bec8d794a92edcaeaa3ead3975b0cd808d39ebea",
"title": "bec8d794a92edcaeaa3ead3975b0cd808d39ebea",
"url": "https://chromium.googlesource.com/infra/infra/+/bec8d794a92edcaeaa3ead3975b0cd808d39ebea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "231c36afb6141cff6ab8eb72728990f86a292153"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@231c36afb6141cff6ab8eb72728990f86a292153",
"title": "231c36afb6141cff6ab8eb72728990f86a292153",
"url": "https://chromium.googlesource.com/infra/infra/+/231c36afb6141cff6ab8eb72728990f86a292153"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "2fb7e2540ea0b9e5abb82a5ed308e0523db5032c"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@2fb7e2540ea0b9e5abb82a5ed308e0523db5032c",
"title": "2fb7e2540ea0b9e5abb82a5ed308e0523db5032c",
"url": "https://chromium.googlesource.com/infra/infra/+/2fb7e2540ea0b9e5abb82a5ed308e0523db5032c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "3250a1553be4013342b618974f312e2984eea9c5"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@3250a1553be4013342b618974f312e2984eea9c5",
"title": "3250a1553be4013342b618974f312e2984eea9c5",
"url": "https://chromium.googlesource.com/infra/infra/+/3250a1553be4013342b618974f312e2984eea9c5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "c8e94fe74978071b9e4fc9076a572f12cd9c73a9"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@c8e94fe74978071b9e4fc9076a572f12cd9c73a9",
"title": "c8e94fe74978071b9e4fc9076a572f12cd9c73a9",
"url": "https://chromium.googlesource.com/infra/infra/+/c8e94fe74978071b9e4fc9076a572f12cd9c73a9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "aecde2f21ab6e4d96f73690b1142e5fac5727ad0"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@aecde2f21ab6e4d96f73690b1142e5fac5727ad0",
"title": "aecde2f21ab6e4d96f73690b1142e5fac5727ad0",
"url": "https://chromium.googlesource.com/infra/infra/+/aecde2f21ab6e4d96f73690b1142e5fac5727ad0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "afb2ab54419db21aeee862947dd95f928f1406ea"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@afb2ab54419db21aeee862947dd95f928f1406ea",
"title": "afb2ab54419db21aeee862947dd95f928f1406ea",
"url": "https://chromium.googlesource.com/infra/infra/+/afb2ab54419db21aeee862947dd95f928f1406ea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "08cdc78454f732e160a42263fef9fe43454edca5"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@08cdc78454f732e160a42263fef9fe43454edca5",
"title": "08cdc78454f732e160a42263fef9fe43454edca5",
"url": "https://chromium.googlesource.com/infra/infra/+/08cdc78454f732e160a42263fef9fe43454edca5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "d232d0292cc671fdee7e2259d103623f3bdd47f5"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@d232d0292cc671fdee7e2259d103623f3bdd47f5",
"title": "d232d0292cc671fdee7e2259d103623f3bdd47f5",
"url": "https://chromium.googlesource.com/infra/infra/+/d232d0292cc671fdee7e2259d103623f3bdd47f5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "946c1767a4a028dcbfb572a00656c12e76394ee6"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@946c1767a4a028dcbfb572a00656c12e76394ee6",
"title": "946c1767a4a028dcbfb572a00656c12e76394ee6",
"url": "https://chromium.googlesource.com/infra/infra/+/946c1767a4a028dcbfb572a00656c12e76394ee6"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "946c1767a4a028dcbfb572a00656c12e76394ee6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8922786877826656784"
},
{
"key": "scheduler_job_id",
"value": "infra/infra-continuous-win10-64"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[19:52:28.424] Scheduled build:
{
"id": "8757356815802728609",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 27454,
"createdBy": "project:infra",
"createTime": "2024-01-31T19:52:27.371557064Z",
"updateTime": "2024-01-31T19:52:27.371557064Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "946c1767a4a028dcbfb572a00656c12e76394ee6",
"ref": "refs/heads/main"
}
}
}
[19:52:28.424] Task URL: https://cr-buildbucket-dev.appspot.com/build/8757356815802728609
[19:52:28.424] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:2:0) after 1m40s
[19:52:29.249] Received PubSub notification, asking Buildbucket for the build status
[19:52:29.288] Build status: SCHEDULED
[19:54:08.442] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:2:0)
[19:54:08.547] Build status: SCHEDULED
[19:54:08.547] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:4:0) after 6m58s
[20:01:06.632] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:4:0)
[20:01:06.706] Build status: SCHEDULED
[20:01:06.706] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:5:0) after 4m34s
[20:05:40.730] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:5:0)
[20:05:40.778] Build status: SCHEDULED
[20:05:40.778] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:6:0) after 4m56s
[20:10:36.798] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:6:0)
[20:10:36.912] Build status: SCHEDULED
[20:10:36.912] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:7:0) after 9m42s
[20:20:18.933] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:7:0)
[20:20:18.991] Build status: SCHEDULED
[20:20:18.991] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:8:0) after 5m17s
[20:25:36.012] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:8:0)
[20:25:36.089] Build status: SCHEDULED
[20:25:36.089] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:9:0) after 2m39s
[20:28:15.071] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:9:0)
[20:28:15.104] Build status: SCHEDULED
[20:28:15.104] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:10:0) after 6m19s
[20:29:30.397] Received PubSub notification, asking Buildbucket for the build status
[20:29:30.420] Build status: STARTED
[20:34:34.124] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:10:0)
[20:34:34.168] Build status: STARTED
[20:34:34.168] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:12:0) after 8m54s
[20:43:28.195] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:12:0)
[20:43:28.232] Build status: STARTED
[20:43:28.232] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:13:0) after 4m10s
[20:47:38.249] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:13:0)
[20:47:38.278] Build status: STARTED
[20:47:38.278] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:14:0) after 9m46s
[20:57:24.296] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:14:0)
[20:57:24.323] Build status: STARTED
[20:57:24.323] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8922786877826656784:15:0) after 4m13s
[20:57:41.142] Received PubSub notification, asking Buildbucket for the build status
[20:57:41.180] Build:
{
"id": "8757356815802728609",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 27454,
"createdBy": "project:infra",
"createTime": "2024-01-31T19:52:27.371557064Z",
"startTime": "2024-01-31T20:29:30.042476110Z",
"endTime": "2024-01-31T20:57:40.742145151Z",
"updateTime": "2024-01-31T20:57:40.742145151Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "946c1767a4a028dcbfb572a00656c12e76394ee6",
"ref": "refs/heads/main"
}
}
}
[20:57:41.180] Invocation finished in 1h5m15.12275019s with status SUCCEEDED