[00:57:31.288] New invocation is queued and will start shortly
[00:57:32.956] Starting the invocation (attempt 1)
[00:57:32.981] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/2e498cff8acbce7a1a369c63ea996d27645b9f31"
[00:57:32.981] Popped gitiles commit info from properties and tags
[00:57:32.981] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:57:32.981] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:57:32.981] Buildbucket request:
{
"requestId": "8927116337802699888",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8927116337802699888",
"job": "fuchsia/core.arm64-asan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "787a53c52b3bdc6a2148d1fc809e1d047703a663"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@787a53c52b3bdc6a2148d1fc809e1d047703a663",
"title": "787a53c52b3bdc6a2148d1fc809e1d047703a663",
"url": "https://fuchsia.googlesource.com/integration/+/787a53c52b3bdc6a2148d1fc809e1d047703a663"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b5c2672e3a04d60520035f9dd5819b1dd42d70cc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b5c2672e3a04d60520035f9dd5819b1dd42d70cc",
"title": "b5c2672e3a04d60520035f9dd5819b1dd42d70cc",
"url": "https://fuchsia.googlesource.com/integration/+/b5c2672e3a04d60520035f9dd5819b1dd42d70cc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d04c0b07a516bb56422b754b42687f09bb69ef79"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d04c0b07a516bb56422b754b42687f09bb69ef79",
"title": "d04c0b07a516bb56422b754b42687f09bb69ef79",
"url": "https://fuchsia.googlesource.com/integration/+/d04c0b07a516bb56422b754b42687f09bb69ef79"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "19ffb83ed9900b6fbcc66cb5bd2f2c57b20ff87d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@19ffb83ed9900b6fbcc66cb5bd2f2c57b20ff87d",
"title": "19ffb83ed9900b6fbcc66cb5bd2f2c57b20ff87d",
"url": "https://fuchsia.googlesource.com/integration/+/19ffb83ed9900b6fbcc66cb5bd2f2c57b20ff87d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3203051117c3c4581ec0566de0fd4b892f0ce121"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3203051117c3c4581ec0566de0fd4b892f0ce121",
"title": "3203051117c3c4581ec0566de0fd4b892f0ce121",
"url": "https://fuchsia.googlesource.com/integration/+/3203051117c3c4581ec0566de0fd4b892f0ce121"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "36f865718d4ad2c781be4a3087a3e4f0451ddc1b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@36f865718d4ad2c781be4a3087a3e4f0451ddc1b",
"title": "36f865718d4ad2c781be4a3087a3e4f0451ddc1b",
"url": "https://fuchsia.googlesource.com/integration/+/36f865718d4ad2c781be4a3087a3e4f0451ddc1b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a983b34e01356a3596839d2237f07116b194007e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a983b34e01356a3596839d2237f07116b194007e",
"title": "a983b34e01356a3596839d2237f07116b194007e",
"url": "https://fuchsia.googlesource.com/integration/+/a983b34e01356a3596839d2237f07116b194007e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2e498cff8acbce7a1a369c63ea996d27645b9f31"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2e498cff8acbce7a1a369c63ea996d27645b9f31",
"title": "2e498cff8acbce7a1a369c63ea996d27645b9f31",
"url": "https://fuchsia.googlesource.com/integration/+/2e498cff8acbce7a1a369c63ea996d27645b9f31"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2e498cff8acbce7a1a369c63ea996d27645b9f31",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8927116337802699888"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-asan-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:57:33.480] Scheduled build:
{
"id": "8761686275291155009",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-15T00:57:33.053379791Z",
"updateTime": "2023-12-15T00:57:33.053379791Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2e498cff8acbce7a1a369c63ea996d27645b9f31",
"ref": "refs/heads/main"
}
}
}
[00:57:33.480] Task URL: https://cr-buildbucket.appspot.com/build/8761686275291155009
[00:57:33.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:2:0) after 6m23s
[00:57:34.387] Received PubSub notification, asking Buildbucket for the build status
[00:57:34.421] Build status: SCHEDULED
[00:58:58.267] Received PubSub notification, asking Buildbucket for the build status
[00:58:58.299] Build status: STARTED
[01:03:56.676] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:2:0)
[01:03:56.697] Build status: STARTED
[01:03:56.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:5:0) after 6m24s
[01:10:20.722] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:5:0)
[01:10:20.767] Build status: STARTED
[01:10:20.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:6:0) after 9m58s
[01:20:18.773] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:6:0)
[01:20:18.843] Build status: STARTED
[01:20:18.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:7:0) after 4m9s
[01:24:27.858] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:7:0)
[01:24:27.885] Build status: STARTED
[01:24:27.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:8:0) after 5m17s
[01:29:45.134] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:8:0)
[01:29:45.160] Build status: STARTED
[01:29:45.160] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:9:0) after 3m0s
[01:32:45.254] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:9:0)
[01:32:45.280] Build status: STARTED
[01:32:45.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:10:0) after 8m2s
[01:40:47.295] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:10:0)
[01:40:47.315] Build status: STARTED
[01:40:47.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:11:0) after 1m16s
[01:42:03.416] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:11:0)
[01:42:03.431] Build status: STARTED
[01:42:03.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:12:0) after 6m48s
[01:48:51.451] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:12:0)
[01:48:51.502] Build status: STARTED
[01:48:51.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:13:0) after 3m43s
[01:52:34.477] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:13:0)
[01:52:34.495] Build status: STARTED
[01:52:34.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:14:0) after 5m27s
[01:58:01.496] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:14:0)
[01:58:01.516] Build status: STARTED
[01:58:01.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:15:0) after 2m15s
[02:00:16.606] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:15:0)
[02:00:16.731] Build status: STARTED
[02:00:16.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:16:0) after 7m2s
[02:07:18.759] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:16:0)
[02:07:18.781] Build status: STARTED
[02:07:18.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927116337802699888:17:0) after 7m15s
[02:09:57.082] Received PubSub notification, asking Buildbucket for the build status
[02:09:57.099] Build:
{
"id": "8761686275291155009",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-15T00:57:33.053379791Z",
"startTime": "2023-12-15T00:58:57.810098Z",
"endTime": "2023-12-15T02:09:56.099510Z",
"updateTime": "2023-12-15T02:09:56.099510Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2e498cff8acbce7a1a369c63ea996d27645b9f31",
"ref": "refs/heads/main"
}
}
}
[02:09:57.099] Invocation finished in 1h12m25.824614511s with status SUCCEEDED