[01:06:58.778] New invocation is queued and will start shortly
[01:06:59.932] Starting the invocation (attempt 1)
[01:06:59.961] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6524613858dbcdc4b7e2feffad14853457ebf426"
[01:06:59.962] Popped gitiles commit info from properties and tags
[01:06:59.962] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:06:59.962] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:06:59.962] Buildbucket request:
{
  "requestId": "8869858459981896608",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "Libfuzzer Upload Linux MSan"
  },
  "properties": {
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "chromium/src",
    "id": "6524613858dbcdc4b7e2feffad14853457ebf426",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8869858459981896608"
    },
    {
      "key": "scheduler_job_id",
      "value": "chromium/Libfuzzer Upload Linux MSan"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[01:07:00.531] Scheduled build:
{
  "id": "8704428398002898449",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "Libfuzzer Upload Linux MSan"
  },
  "number": 218219,
  "createdBy": "project:chromium",
  "createTime": "2025-09-07T01:07:00.035207128Z",
  "updateTime": "2025-09-07T01:07:00.035207128Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "6524613858dbcdc4b7e2feffad14853457ebf426",
      "ref": "refs/heads/main"
    }
  }
}
[01:07:00.531] Task URL: https://cr-buildbucket.appspot.com/build/8704428398002898449
[01:07:00.531] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:2:0) after 7m56s
[01:07:01.491] Received PubSub notification, asking Buildbucket for the build status
[01:07:01.508] Build status: SCHEDULED
[01:07:15.572] Received PubSub notification, asking Buildbucket for the build status
[01:07:15.609] Build status: STARTED
[01:14:56.803] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:2:0)
[01:14:56.830] Build status: STARTED
[01:14:56.830] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:5:0) after 2m1s
[01:16:57.983] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:5:0)
[01:16:58.044] Build status: STARTED
[01:16:58.044] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:6:0) after 1m9s
[01:18:07.059] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:6:0)
[01:18:07.081] Build status: STARTED
[01:18:07.081] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:7:0) after 7m48s
[01:25:55.205] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:7:0)
[01:25:55.221] Build status: STARTED
[01:25:55.221] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:8:0) after 4m8s
[01:30:03.293] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:8:0)
[01:30:03.332] Build status: STARTED
[01:30:03.332] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:9:0) after 1m35s
[01:31:38.346] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:9:0)
[01:31:38.367] Build status: STARTED
[01:31:38.367] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:10:0) after 6m5s
[01:37:43.382] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:10:0)
[01:37:43.397] Build status: STARTED
[01:37:43.397] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:11:0) after 6m53s
[01:44:36.507] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:11:0)
[01:44:36.539] Build status: STARTED
[01:44:36.540] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:12:0) after 7m24s
[01:52:00.820] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:12:0)
[01:52:00.946] Build status: STARTED
[01:52:00.946] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:13:0) after 7m37s
[01:59:38.066] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:13:0)
[01:59:38.163] Build status: STARTED
[01:59:38.164] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:14:0) after 8m34s
[02:08:12.277] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:14:0)
[02:08:12.299] Build status: STARTED
[02:08:12.299] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:15:0) after 7m6s
[02:15:18.629] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:15:0)
[02:15:18.651] Build status: STARTED
[02:15:18.651] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:16:0) after 8m35s
[02:23:53.662] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:16:0)
[02:23:53.677] Build status: STARTED
[02:23:53.677] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:17:0) after 3m59s
[02:27:52.813] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:17:0)
[02:27:52.838] Build status: STARTED
[02:27:52.838] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:18:0) after 7m54s
[02:35:46.861] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:18:0)
[02:35:46.876] Build status: STARTED
[02:35:46.877] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:19:0) after 3m4s
[02:38:51.163] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:19:0)
[02:38:51.194] Build status: STARTED
[02:38:51.194] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:20:0) after 1m21s
[02:40:12.282] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:20:0)
[02:40:12.300] Build status: STARTED
[02:40:12.300] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:21:0) after 2m12s
[02:42:24.635] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:21:0)
[02:42:24.655] Build status: STARTED
[02:42:24.655] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:22:0) after 9m47s
[02:52:11.669] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:22:0)
[02:52:11.755] Build status: STARTED
[02:52:11.755] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:23:0) after 5m16s
[02:57:27.770] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:23:0)
[02:57:27.788] Build status: STARTED
[02:57:27.788] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:24:0) after 7m8s
[03:04:35.926] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:24:0)
[03:04:35.945] Build status: STARTED
[03:04:35.945] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:25:0) after 2m43s
[03:07:19.662] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:25:0)
[03:07:19.683] Build status: STARTED
[03:07:19.683] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:26:0) after 8m47s
[03:16:06.874] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:26:0)
[03:16:06.888] Build status: STARTED
[03:16:06.888] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869858459981896608:27:0) after 6m6s
[03:19:15.105] Received PubSub notification, asking Buildbucket for the build status
[03:19:15.121] Build:
{
  "id": "8704428398002898449",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "Libfuzzer Upload Linux MSan"
  },
  "number": 218219,
  "createdBy": "project:chromium",
  "createTime": "2025-09-07T01:07:00.035207128Z",
  "startTime": "2025-09-07T01:07:15.377751173Z",
  "endTime": "2025-09-07T03:19:14.521051007Z",
  "updateTime": "2025-09-07T03:19:14.521051007Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "6524613858dbcdc4b7e2feffad14853457ebf426",
      "ref": "refs/heads/main"
    }
  }
}
[03:19:15.121] Invocation finished in 2h12m16.356076814s with status SUCCEEDED