XCP-ng
    • Categories
    • Recent
    • Tags
    • Popular
    • Users
    • Groups
    • Register
    • Login

    XO task watcher issue/CR broken

    Scheduled Pinned Locked Moved Solved Xen Orchestra
    71 Posts 6 Posters 13.0k Views 7 Watching
    Loading More Posts
    • Oldest to Newest
    • Newest to Oldest
    • Most Votes
    Reply
    • Reply as topic
    Log in to reply
    This topic has been deleted. Only users with topic management privileges can see it.
    • julien-fJ Offline
      julien-f Vates 🪐 Co-Founder XO Team @Andrew
      last edited by

      @Andrew I need to understand why it's working now 😅

      Which version of XCP-ng/XenServer are you using as source and as the target of the CR?

      julien-fJ 1 Reply Last reply Reply Quote 0
      • GheppyG Offline
        Gheppy
        last edited by Gheppy

        This commit 27d81 is working for me to.
        XCP-ng is 8.2.1, up to date

        julien-fJ 1 Reply Last reply Reply Quote 0
        • julien-fJ Offline
          julien-f Vates 🪐 Co-Founder XO Team @Gheppy
          last edited by julien-f

          @Andrew & @Gheppy: I've just pushed a new commit in the cr-issue branch which adds some debug logs, it adds something like that:

          putResource(b1imtu1j0y): taskRef: OpaqueRef:10eb89fd-d31e-495b-955d-672c61a4ea48
          putResource(b1imtu1j0y): useHack: false
          putResource(b1imtu1j0y): body#end
          putResource(b1imtu1j0y): request#unpipe
          putResource(b1imtu1j0y): request#prefinish
          putResource(b1imtu1j0y): request#finish
          putResource(b1imtu1j0y): response#resume
          putResource(b1imtu1j0y): body#close
          putResource(b1imtu1j0y): response#readable
          putResource(b1imtu1j0y): response#end
          putResource(b1imtu1j0y): response#close
          putResource(b1imtu1j0y): request#close
          

          If you could try it and show me the output, that would help me understand what's going on 🙂

          A 1 Reply Last reply Reply Quote 1
          • olivierlambertO Online
            olivierlambert Vates 🪐 Co-Founder CEO
            last edited by

            Eager to read the output, it's weird we can't reproduce here, there's something 🤔

            1 Reply Last reply Reply Quote 0
            • GheppyG Offline
              Gheppy
              last edited by Gheppy

              @julien-f
              with command -- journalctl -u xo-server -f -n 70

              Feb 10 13:33:31 tb-xoce-e-19 xo-server[32712]: putResource(ty99546973n): body#drain
              Feb 10 13:33:31 tb-xoce-e-19 xo-server[32712]: putResource(vcybyae8pn): body#drain
              Feb 10 13:33:31 tb-xoce-e-19 xo-server[32712]: putResource(ty99546973n): body#drain
              Feb 10 13:33:31 tb-xoce-e-19 xo-server[32712]: putResource(vcybyae8pn): body#drain
              Feb 10 13:33:31 tb-xoce-e-19 xo-server[32712]: putResource(ty99546973n): body#drain
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(ty99546973n): response#readable
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(ty99546973n): request#close
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(ty99546973n): request#unpipe
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(ty99546973n): body#error
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(ty99546973n): body#unpipe
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(ty99546973n): body#close
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(ty99546973n): response#resume
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(ty99546973n): response#end
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(ty99546973n): response#close
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(vcybyae8pn): response#readable
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(vcybyae8pn): request#close
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(vcybyae8pn): request#unpipe
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(vcybyae8pn): body#error
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(vcybyae8pn): body#unpipe
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(vcybyae8pn): body#close
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(vcybyae8pn): response#resume
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(vcybyae8pn): response#end
              Feb 10 13:33:32 tb-xoce-e-19 xo-server[32712]: putResource(vcybyae8pn): response#close
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]: [WARN] plugin transport-email: Error: No recipients defined
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at SMTPConnection._formatError (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:790:19)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at SMTPConnection._setEnvelope (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:1007:34)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at SMTPConnection.send (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:621:14)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at sendMessage (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-transport/index.js:228:28)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at /opt/xen-orchestra/node_modules/nodemailer/lib/smtp-transport/index.js:289:21
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at SMTPConnection.<anonymous> (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:213:17)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at Object.onceWrapper (node:events:627:28)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at SMTPConnection.emit (node:events:513:28)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at SMTPConnection.patchedEmit [as emit] (/opt/xen-orchestra/@xen-orchestra/log/configure.js:52:17)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at SMTPConnection._actionEHLO (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:1331:14)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at SMTPConnection._processResponse (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:953:20)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at SMTPConnection._onData (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:755:14)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at Socket.SMTPConnection._onSocketData (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:193:44)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at Socket.emit (node:events:513:28)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at Socket.patchedEmit [as emit] (/opt/xen-orchestra/@xen-orchestra/log/configure.js:52:17)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at addChunk (node:internal/streams/readable:324:12)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at readableAddChunk (node:internal/streams/readable:297:9)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at Socket.Readable.push (node:internal/streams/readable:234:10)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at TCP.onStreamRead (node:internal/stream_base_commons:190:23)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     at TCP.callbackTrampoline (node:internal/async_hooks:130:17)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]: 2023-02-10T11:34:05.207Z xo:xo-server WARN possibly unhandled rejection {
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:   error: Error: No recipients defined
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at SMTPConnection._formatError (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:790:19)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at SMTPConnection._setEnvelope (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:1007:34)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at SMTPConnection.send (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:621:14)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at sendMessage (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-transport/index.js:228:28)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at /opt/xen-orchestra/node_modules/nodemailer/lib/smtp-transport/index.js:289:21
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at SMTPConnection.<anonymous> (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:213:17)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at Object.onceWrapper (node:events:627:28)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at SMTPConnection.emit (node:events:513:28)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at SMTPConnection.patchedEmit [as emit] (/opt/xen-orchestra/@xen-orchestra/log/configure.js:52:17)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at SMTPConnection._actionEHLO (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:1331:14)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at SMTPConnection._processResponse (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:953:20)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at SMTPConnection._onData (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:755:14)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at Socket.SMTPConnection._onSocketData (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:193:44)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at Socket.emit (node:events:513:28)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at Socket.patchedEmit [as emit] (/opt/xen-orchestra/@xen-orchestra/log/configure.js:52:17)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at addChunk (node:internal/streams/readable:324:12)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at readableAddChunk (node:internal/streams/readable:297:9)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at Socket.Readable.push (node:internal/streams/readable:234:10)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at TCP.onStreamRead (node:internal/stream_base_commons:190:23)
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:       at TCP.callbackTrampoline (node:internal/async_hooks:130:17) {
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     code: 'EENVELOPE',
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:     command: 'API'
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]:   }
              Feb 10 13:34:05 tb-xoce-e-19 xo-server[32617]: }
              

              On backup log I have this

              {
                "data": {
                  "mode": "delta",
                  "reportWhen": "always"
                },
                "id": "1676028066913",
                "jobId": "dca46bf4-dca6-4e07-8dab-dc644d6f47c1",
                "jobName": "productie-Sincronizare-Activa-NU-STERGE",
                "message": "backup",
                "scheduleId": "118e0756-3588-4d70-97d8-f6705bc3785f",
                "start": 1676028066913,
                "status": "success",
                "infos": [
                  {
                    "data": {
                      "vms": [
                        "629bdfeb-7700-561c-74ac-e151068721c2"
                      ]
                    },
                    "message": "vms"
                  }
                ],
                "tasks": [
                  {
                    "data": {
                      "type": "VM",
                      "id": "629bdfeb-7700-561c-74ac-e151068721c2"
                    },
                    "id": "1676028068026:0",
                    "message": "backup VM",
                    "start": 1676028068026,
                    "status": "success",
                    "tasks": [
                      {
                        "id": "1676028068072",
                        "message": "snapshot",
                        "start": 1676028068072,
                        "status": "success",
                        "end": 1676028071566,
                        "result": "6f52cc17-328e-589a-82a8-c217ac34d6f1"
                      },
                      {
                        "data": {
                          "id": "5148208b-acf6-13f3-4f36-5817e3eb6ab1",
                          "isFull": false,
                          "type": "SR"
                        },
                        "id": "1676028071568",
                        "message": "export",
                        "start": 1676028071568,
                        "status": "success",
                        "tasks": [
                          {
                            "id": "1676028071596",
                            "message": "transfer",
                            "start": 1676028071596,
                            "status": "success",
                            "end": 1676028094729,
                            "result": {
                              "size": 162336256
                            }
                          }
                        ],
                        "end": 1676028107179
                      },
                      {
                        "data": {
                          "id": "7a4829c0-e59a-726c-34bc-1a1052b0334f",
                          "isFull": false,
                          "type": "SR"
                        },
                        "id": "1676028071567",
                        "message": "export",
                        "start": 1676028071567,
                        "status": "success",
                        "tasks": [
                          {
                            "id": "1676028071592",
                            "message": "transfer",
                            "start": 1676028071592,
                            "status": "success",
                            "end": 1676028094954,
                            "result": {
                              "size": 162336256
                            }
                          }
                        ],
                        "end": 1676028110020
                      }
                    ],
                    "end": 1676028129516
                  }
                ],
                "end": 1676028129517
              }
              
              julien-fJ 1 Reply Last reply Reply Quote 0
              • julien-fJ Offline
                julien-f Vates 🪐 Co-Founder XO Team @Gheppy
                last edited by

                @Gheppy Could you please include all the logs up to the taskRef entry?

                1 Reply Last reply Reply Quote 0
                • A Online
                  Andrew Top contributor @julien-f
                  last edited by

                  @julien-f Hosts are XCP 8.2.1 and updated.

                  Here's the logs... It did suppress 373874 messages... only 417267 left...

                  rename output.txt as output.txt.gz and uncompress.

                  output.txt

                  julien-fJ 1 Reply Last reply Reply Quote 0
                  • julien-fJ Offline
                    julien-f Vates 🪐 Co-Founder XO Team @Andrew
                    last edited by

                    @Andrew Thank you, I just pushed a new commit in which I suppressed the drain event 😅

                    julien-fJ 1 Reply Last reply Reply Quote 0
                    • julien-fJ Offline
                      julien-f Vates 🪐 Co-Founder XO Team @julien-f
                      last edited by julien-f

                      Once filtered, here's what I see:

                      putResource(hhprvsf5i): taskRef: OpaqueRef:bb9bbc77-8c88-4621-82a7-745197306c3b
                      putResource(hhprvsf5i): useHack: false
                      putResource(hhprvsf5i): response#readable
                      putResource(hhprvsf5i): request#close
                      putResource(hhprvsf5i): request#unpipe
                      putResource(hhprvsf5i): body#error
                      putResource(hhprvsf5i): body#unpipe
                      putResource(hhprvsf5i): body#close
                      putResource(hhprvsf5i): response#resume
                      putResource(hhprvsf5i): response#end
                      putResource(hhprvsf5i): response#close
                      

                      I don't understand why there is not a request#finish event like in my test 🤔

                      julien-fJ 1 Reply Last reply Reply Quote 0
                      • julien-fJ Offline
                        julien-f Vates 🪐 Co-Founder XO Team @julien-f
                        last edited by

                        It's probably related to the body#error event.

                        I will add more debug and will keep you posted when the branch is ready.

                        A GheppyG 2 Replies Last reply Reply Quote 0
                        • A Online
                          Andrew Top contributor @julien-f
                          last edited by

                          @julien-f I'll do another run after the next debug update.

                          1 Reply Last reply Reply Quote 0
                          • GheppyG Offline
                            Gheppy @julien-f
                            last edited by

                            @julien-f
                            Logs with commit dd1edd2519c593b06372ddec9041bd525f40ba05

                            Feb 10 17:58:17 tb-xoce-e-19 systemd[1]: Stopping XO Server...
                            Feb 10 17:58:17 tb-xoce-e-19 xo-server[9598]: 2023-02-10T15:58:17.143Z xo:main INFO SIGTERM caught, closing…
                            Feb 10 17:58:17 tb-xoce-e-19 xo-server[9598]: 2023-02-10T15:58:17.181Z xo:main INFO bye :-)
                            Feb 10 17:58:17 tb-xoce-e-19 systemd[1]: xo-server.service: Deactivated successfully.
                            Feb 10 17:58:17 tb-xoce-e-19 systemd[1]: Stopped XO Server.
                            Feb 10 17:58:17 tb-xoce-e-19 systemd[1]: xo-server.service: Consumed 19.958s CPU time.
                            -- Boot 077eda8b3bde4af6b29a23add0da503a --
                            Feb 10 17:58:41 tb-xoce-e-19 systemd[1]: Started XO Server.
                            Feb 10 17:59:02 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:02.632Z xo:main INFO Configuration loaded.
                            Feb 10 17:59:02 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:02.711Z xo:main INFO Web server listening on https://0.0.0.0:443
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:03.263Z xo:mixins:hooks WARN start failure {
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:   error: Error: Command failed with exit code 5: vgchange -an
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     Logical volume ubuntu-vg/ubuntu-lv contains a filesystem in use.
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     Can't deactivate volume group "ubuntu-vg" with 1 open logical volume(s)
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:       at makeError (file:///opt/xen-orchestra/packages/xo-server/node_modules/execa/lib/error.js:59:11)
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:       at handlePromise (file:///opt/xen-orchestra/packages/xo-server/node_modules/execa/index.js:119:26) {
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     shortMessage: 'Command failed with exit code 5: vgchange -an',
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     command: 'vgchange -an',
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     escapedCommand: 'vgchange -an',
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     exitCode: 5,
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     signal: undefined,
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     signalDescription: undefined,
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     stdout: '',
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     stderr: '  Logical volume ubuntu-vg/ubuntu-lv contains a filesystem in use.\n' +
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:       `  Can't deactivate volume group "ubuntu-vg" with 1 open logical volume(s)`,
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     failed: true,
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     timedOut: false,
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     isCanceled: false,
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:     killed: false
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]:   }
                            Feb 10 17:59:03 tb-xoce-e-19 xo-server[837]: }
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.300Z xo:main INFO Setting up / → /opt/xen-orchestra/packages/xo-web/dist
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.315Z xo:plugin INFO register audit
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.331Z xo:plugin INFO register auth-github
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.332Z xo:plugin INFO register auth-google
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.341Z xo:plugin INFO register auth-ldap
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.343Z xo:plugin INFO register auth-saml
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.355Z xo:plugin INFO register backup-reports
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.367Z xo:plugin INFO register load-balancer
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.368Z xo:plugin INFO register netbox
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.370Z xo:plugin INFO register perf-alert
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.379Z xo:plugin INFO register sdn-controller
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.380Z xo:plugin INFO register test-plugin
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.381Z xo:plugin INFO register transport-email
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.395Z xo:plugin INFO register transport-icinga2
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.401Z xo:plugin INFO register transport-nagios
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.403Z xo:plugin INFO register transport-slack
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.408Z xo:plugin INFO register transport-xmpp
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.413Z xo:plugin INFO register usage-report
                            Feb 10 17:59:05 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:05.414Z xo:plugin INFO register web-hooks
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register auth-github
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register auth-google
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register auth-ldap
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register auth-saml
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register netbox
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register test-plugin
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register transport-icinga2
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register transport-nagios
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register transport-slack
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register transport-xmpp
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register web-hooks
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register usage-report
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register backup-reports
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.945Z xo:plugin INFO successfully register transport-email
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.956Z xo:plugin INFO successfully register audit
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.957Z xo:plugin INFO successfully register load-balancer
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.957Z xo:plugin INFO successfully register perf-alert
                            Feb 10 17:59:07 tb-xoce-e-19 xo-server[837]: 2023-02-10T15:59:07.966Z xo:plugin INFO successfully register sdn-controller
                            Feb 10 17:59:37 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): taskRef: OpaqueRef:655531f1-ba0b-4583-95a2-f376bf85f526
                            Feb 10 17:59:37 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): useHack: false
                            Feb 10 17:59:37 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): taskRef: OpaqueRef:1b623504-9dd2-4d4a-96a8-fca88adbb66e
                            Feb 10 17:59:37 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): useHack: false
                            Feb 10 17:59:37 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): taskRef: OpaqueRef:0323510a-df09-4072-8e43-c9a34449c396
                            Feb 10 17:59:37 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): useHack: false
                            Feb 10 17:59:37 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): taskRef: OpaqueRef:72eed850-b1e3-438c-987e-469cd1331845
                            Feb 10 17:59:37 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): useHack: false
                            Feb 10 17:59:37 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): waiting for request to finish
                            Feb 10 17:59:37 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): waiting for request to finish
                            Feb 10 17:59:37 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): waiting for request to finish
                            Feb 10 17:59:37 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): waiting for request to finish
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): response#readable
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): request#close
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): request#unpipe
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): body#error
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): body#unpipe
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): body#close
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): waiting for response to finish
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): response#resume
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): response#end
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): response#close
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(3n9ugoikq5v): returning task result promise
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): body#prefinish
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): body#end
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): request#unpipe
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): request#prefinish
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): body#finish
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): body#unpipe
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): request#finish
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): body#close
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): waiting for response to finish
                            Feb 10 17:59:38 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): response#resume
                            Feb 10 17:59:39 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): response#readable
                            Feb 10 17:59:39 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): response#end
                            Feb 10 17:59:39 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): response#close
                            Feb 10 17:59:39 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): returning task result promise
                            Feb 10 17:59:39 tb-xoce-e-19 xo-server[1181]: putResource(rhnuq5h5ix): request#close
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): response#readable
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): request#close
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): request#unpipe
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): body#error
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): body#unpipe
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): body#close
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): waiting for response to finish
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): response#resume
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): response#end
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): response#close
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(q3eg2gp4h0m): returning task result promise
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): response#readable
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): request#close
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): request#unpipe
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): body#error
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): body#unpipe
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): body#close
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): waiting for response to finish
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): response#resume
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): response#end
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): response#close
                            Feb 10 17:59:47 tb-xoce-e-19 xo-server[1181]: putResource(7x5rgk2ksq4): returning task result promise
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]: [WARN] plugin transport-email: Error: No recipients defined
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at SMTPConnection._formatError (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:790:19)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at SMTPConnection._setEnvelope (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:1007:34)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at SMTPConnection.send (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:621:14)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at sendMessage (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-transport/index.js:228:28)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at /opt/xen-orchestra/node_modules/nodemailer/lib/smtp-transport/index.js:289:21
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at SMTPConnection.<anonymous> (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:213:17)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at Object.onceWrapper (node:events:627:28)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at SMTPConnection.emit (node:events:513:28)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at SMTPConnection.patchedEmit [as emit] (/opt/xen-orchestra/@xen-orchestra/log/configure.js:52:17)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at SMTPConnection._actionEHLO (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:1331:14)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at SMTPConnection._processResponse (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:953:20)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at SMTPConnection._onData (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:755:14)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at Socket.SMTPConnection._onSocketData (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:193:44)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at Socket.emit (node:events:513:28)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at Socket.patchedEmit [as emit] (/opt/xen-orchestra/@xen-orchestra/log/configure.js:52:17)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at addChunk (node:internal/streams/readable:324:12)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at readableAddChunk (node:internal/streams/readable:297:9)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at Socket.Readable.push (node:internal/streams/readable:234:10)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at TCP.onStreamRead (node:internal/stream_base_commons:190:23)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     at TCP.callbackTrampoline (node:internal/async_hooks:130:17)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]: 2023-02-10T16:00:23.194Z xo:xo-server WARN possibly unhandled rejection {
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:   error: Error: No recipients defined
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at SMTPConnection._formatError (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:790:19)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at SMTPConnection._setEnvelope (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:1007:34)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at SMTPConnection.send (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:621:14)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at sendMessage (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-transport/index.js:228:28)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at /opt/xen-orchestra/node_modules/nodemailer/lib/smtp-transport/index.js:289:21
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at SMTPConnection.<anonymous> (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:213:17)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at Object.onceWrapper (node:events:627:28)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at SMTPConnection.emit (node:events:513:28)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at SMTPConnection.patchedEmit [as emit] (/opt/xen-orchestra/@xen-orchestra/log/configure.js:52:17)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at SMTPConnection._actionEHLO (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:1331:14)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at SMTPConnection._processResponse (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:953:20)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at SMTPConnection._onData (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:755:14)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at Socket.SMTPConnection._onSocketData (/opt/xen-orchestra/node_modules/nodemailer/lib/smtp-connection/index.js:193:44)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at Socket.emit (node:events:513:28)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at Socket.patchedEmit [as emit] (/opt/xen-orchestra/@xen-orchestra/log/configure.js:52:17)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at addChunk (node:internal/streams/readable:324:12)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at readableAddChunk (node:internal/streams/readable:297:9)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at Socket.Readable.push (node:internal/streams/readable:234:10)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at TCP.onStreamRead (node:internal/stream_base_commons:190:23)
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:       at TCP.callbackTrampoline (node:internal/async_hooks:130:17) {
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     code: 'EENVELOPE',
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:     command: 'API'
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]:   }
                            Feb 10 18:00:23 tb-xoce-e-19 xo-server[837]: }
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): taskRef: OpaqueRef:f3ef509e-4121-437b-b140-282419c869ba
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): useHack: false
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): waiting for request to finish
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): taskRef: OpaqueRef:0b25fc40-83f1-44b3-86cf-59f00bbbc272
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): useHack: false
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): taskRef: OpaqueRef:099a9a90-719d-4b5e-839a-7bdacfccf50d
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): useHack: false
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): waiting for request to finish
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): waiting for request to finish
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): taskRef: OpaqueRef:028adad2-d651-418d-b3bb-ca767daf3801
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): useHack: false
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): taskRef: OpaqueRef:fd44c094-7dda-4a96-8ff4-2c76e4d75c80
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): useHack: false
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): waiting for request to finish
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): waiting for request to finish
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): taskRef: OpaqueRef:4fe09fe7-63d7-4318-aa94-65362ce00cbd
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): useHack: false
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): waiting for request to finish
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): taskRef: OpaqueRef:2b216564-d923-46b0-a377-08806e4d28a1
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): useHack: false
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): taskRef: OpaqueRef:9ad749aa-baed-4ce1-8ded-27ee463bbd87
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): useHack: false
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): waiting for request to finish
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): waiting for request to finish
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): taskRef: OpaqueRef:40ac30f8-240b-4278-93a1-5cb6b3d2e681
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): useHack: false
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): taskRef: OpaqueRef:948bec59-35a8-41fb-849e-3c3c33214fc2
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): useHack: false
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): waiting for request to finish
                            Feb 10 18:00:30 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): waiting for request to finish
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): response#readable
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): request#close
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): request#unpipe
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): body#error
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): body#unpipe
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): body#close
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): waiting for response to finish
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): response#resume
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): response#end
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): response#close
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(ytgd25jz6wi): returning task result promise
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): body#prefinish
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): body#end
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): request#unpipe
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): request#prefinish
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): body#finish
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): body#unpipe
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): request#finish
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): body#close
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): waiting for response to finish
                            Feb 10 18:00:32 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): response#resume
                            Feb 10 18:00:33 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): response#readable
                            Feb 10 18:00:33 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): response#end
                            Feb 10 18:00:33 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): response#close
                            Feb 10 18:00:33 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): returning task result promise
                            Feb 10 18:00:33 tb-xoce-e-19 xo-server[1209]: putResource(eqjv69wmqff): request#close
                            Feb 10 18:07:19 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): response#readable
                            Feb 10 18:07:19 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): request#close
                            Feb 10 18:07:19 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): request#unpipe
                            Feb 10 18:07:19 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): body#error
                            Feb 10 18:07:19 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): body#unpipe
                            Feb 10 18:07:19 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): body#close
                            Feb 10 18:07:19 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): waiting for response to finish
                            Feb 10 18:07:19 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): response#resume
                            Feb 10 18:07:19 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): response#end
                            Feb 10 18:07:19 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): response#close
                            Feb 10 18:07:19 tb-xoce-e-19 xo-server[1209]: putResource(n0ygkko6hs): returning task result promise
                            Feb 10 18:07:20 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): response#readable
                            Feb 10 18:07:20 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): request#close
                            Feb 10 18:07:20 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): request#unpipe
                            Feb 10 18:07:20 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): body#error
                            Feb 10 18:07:20 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): body#unpipe
                            Feb 10 18:07:20 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): body#close
                            Feb 10 18:07:20 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): waiting for response to finish
                            Feb 10 18:07:20 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): response#resume
                            Feb 10 18:07:20 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): response#end
                            Feb 10 18:07:20 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): response#close
                            Feb 10 18:07:20 tb-xoce-e-19 xo-server[1209]: putResource(7dnih4mm1qf): returning task result promise
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): response#readable
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): request#close
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): request#unpipe
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): body#error
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): body#unpipe
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): body#close
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): waiting for response to finish
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): response#resume
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): response#end
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): response#close
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(v44nlq1wrs8): returning task result promise
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): response#readable
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): request#close
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): request#unpipe
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): body#error
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): body#unpipe
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): body#close
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): waiting for response to finish
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): response#resume
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): response#end
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): response#close
                            Feb 10 18:09:05 tb-xoce-e-19 xo-server[1209]: putResource(uljxc697y2l): returning task result promise
                            Feb 10 18:28:03 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): response#readable
                            Feb 10 18:28:03 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): request#close
                            Feb 10 18:28:03 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): request#unpipe
                            Feb 10 18:28:03 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): body#error
                            Feb 10 18:28:03 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): body#unpipe
                            Feb 10 18:28:03 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): body#close
                            Feb 10 18:28:03 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): waiting for response to finish
                            Feb 10 18:28:03 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): response#resume
                            Feb 10 18:28:03 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): response#end
                            Feb 10 18:28:03 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): response#close
                            Feb 10 18:28:03 tb-xoce-e-19 xo-server[1209]: putResource(zwad8yuqlz): returning task result promise
                            Feb 10 18:28:04 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): response#readable
                            Feb 10 18:28:04 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): request#close
                            Feb 10 18:28:04 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): request#unpipe
                            Feb 10 18:28:04 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): body#error
                            Feb 10 18:28:04 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): body#unpipe
                            Feb 10 18:28:04 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): body#close
                            Feb 10 18:28:04 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): waiting for response to finish
                            Feb 10 18:28:04 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): response#resume
                            Feb 10 18:28:04 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): response#end
                            Feb 10 18:28:04 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): response#close
                            Feb 10 18:28:04 tb-xoce-e-19 xo-server[1209]: putResource(e9in7oo6sxp): returning task result promise
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): response#readable
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): request#close
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): request#unpipe
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): body#error
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): body#unpipe
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): body#close
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): waiting for response to finish
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): response#resume
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): response#end
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): response#close
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(30gsda5k9w): returning task result promise
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): response#readable
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): request#close
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): request#unpipe
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): body#error
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): body#unpipe
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): body#close
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): waiting for response to finish
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): response#resume
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): response#end
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): response#close
                            Feb 10 18:28:55 tb-xoce-e-19 xo-server[1209]: putResource(oabtodmaeda): returning task result promise
                            
                            
                            julien-fJ 1 Reply Last reply Reply Quote 0
                            • julien-fJ Offline
                              julien-f Vates 🪐 Co-Founder XO Team @Gheppy
                              last edited by

                              Thanks to you both, I have what I need to work on this for the moment, I'll keep you posted when I have something more to test 🙂

                              A 1 Reply Last reply Reply Quote 1
                              • A Online
                                Andrew Top contributor @julien-f
                                last edited by

                                @julien-f Any new thoughts on this issue?

                                julien-fJ 1 Reply Last reply Reply Quote 0
                                • julien-fJ Offline
                                  julien-f Vates 🪐 Co-Founder XO Team @Andrew
                                  last edited by

                                  @Andrew Yes, I'm working on this and should have something to test tomorrow 🙂

                                  1 Reply Last reply Reply Quote 0
                                  • julien-fJ Offline
                                    julien-f Vates 🪐 Co-Founder XO Team @julien-f
                                    last edited by

                                    @Andrew @Gheppy, I have something new test, it's an important change concerning a low-level component of XO, hopefully I did not break anything 🤞

                                    The cr-issue branch has been rebased, make sure to reset it properly, re-install dependencies and rebuild:

                                    cd xen-orchestra/
                                    git checkout cr-issue
                                    git fetch
                                    git reset --hard origin/cr-issue
                                    yarn
                                    yarn build
                                    

                                    Let me know if you have any issues 🙂

                                    GheppyG A 2 Replies Last reply Reply Quote 0
                                    • GheppyG Offline
                                      Gheppy @julien-f
                                      last edited by Gheppy

                                      @julien-f
                                      I'll tested in a short time, I am in the middle of CR for next 6 hours

                                      1 Reply Last reply Reply Quote 0
                                      • A Online
                                        Andrew Top contributor @julien-f
                                        last edited by Andrew

                                        @julien-f Broken. Job failed. Lots of Error: Premature close. Data not replicated.

                                        Job report: job.txt (text format)

                                        Journal XO output: out.txt.gz (GZ format).

                                        1 Reply Last reply Reply Quote 0
                                        • GheppyG Offline
                                          Gheppy
                                          last edited by

                                          same error

                                          CR log
                                          CR-log.txt

                                          XOCE log
                                          Srv-log.txt

                                          julien-fJ 1 Reply Last reply Reply Quote 0
                                          • julien-fJ Offline
                                            julien-f Vates 🪐 Co-Founder XO Team @Gheppy
                                            last edited by

                                            I still don't understand exactly the issue, not sure if it comes from XO or XCP-ng/XenServer, but latest version does integrate a work-around, if you encounter Premature close error during CR, you can add the following to your xo-server's configuration file (usually /etc/xo-server/config.toml) :

                                            [xapiOptions]
                                            ignorePrematureClose = true
                                            

                                            It's not enabled by default until completely understand the root cause and it's properly fixed.

                                            @Gheppy Thanks a lot for your tests and feedbacks 🙂

                                            @Andrew Thank you very much for the test appliance, it was an invaluable help investigating this. If you can keep it online for the time being I'll probably have further tests to do with it next week 🙏

                                            A 1 Reply Last reply Reply Quote 0
                                            • First post
                                              Last post