Migration Mail start fails with HTTP 500 error

[DavidePrincipi]

When the NS7 migration tool starts the migration of Mail with Webtop and Imapsync modules an HTTP 500 is returned by api-server and an error occurs.

Steps to reproduce

This is not easy to reproduce. The NS8 server seems to have a slow disk.

• Start Mail migration with Webtop and Imapsync

Expected behavior

Migration is successful.

Actual behavior

Modules are created successfully on the NS8 side, but migration tool returns an error.

On the NS7 side, the instance of Mail is still not started. Starting again leads to the same error.

Traceback (most recent call last):
  File "/usr/sbin/ns8-action", line 129, in <module>
    ret = wait_task(api_endpoint, payload['token'], task_id)
  File "/usr/sbin/ns8-action", line 66, in wait_task
    if request.urlopen(context_request).getcode() == 200:
  File "/usr/lib64/python3.6/urllib/request.py", line 223, in urlopen
    return opener.open(url, data, timeout)
  File "/usr/lib64/python3.6/urllib/request.py", line 532, in open
    response = meth(req, response)
  File "/usr/lib64/python3.6/urllib/request.py", line 642, in http_response
    'http', request, response, code, msg, hdrs)
  File "/usr/lib64/python3.6/urllib/request.py", line 570, in error
    return self._call_chain(*args)
  File "/usr/lib64/python3.6/urllib/request.py", line 504, in _call_chain
    result = func(*args)
  File "/usr/lib64/python3.6/urllib/request.py", line 650, in http_error_default
    raise HTTPError(req.full_url, code, msg, hdrs, fp)
urllib.error.HTTPError: HTTP Error 500: Internal Server Error

On the NS8 side this api-server error is logged

May 15 12:15:15 ns8 api-server[793]: Websocket auth error: token contains an invalid number of segments
May 15 12:15:15 ns8 api-server[793]: 2025/05/15 12:15:15 http: response.Write on hijacked connection from github.com/gin-gonic/gin.(*responseWriter).Write (response_writer.go:83)
May 15 12:15:34 ns8 api-server[793]: 2025/05/15 12:15:34 http: response.Write on hijacked connection from github.com/gin-gonic/gin.(*responseWriter).Write (response_writer.go:83)
May 15 12:15:56 ns8 api-server[793]: [AUDIT][STORE] error in audit file schema begin: database is locked
May 15 12:15:56 ns8 api-server[793]: 2025/05/15 12:15:56 [Recovery] 2025/05/15 - 12:15:56 panic recovered:
May 15 12:15:56 ns8 api-server[793]: runtime error: invalid memory address or nil pointer dereference
May 15 12:15:56 ns8 api-server[793]: /usr/local/go/src/runtime/panic.go:262 (0x474118)
May 15 12:15:56 ns8 api-server[793]: /usr/local/go/src/runtime/signal_unix.go:925 (0x4740e8)
May 15 12:15:56 ns8 api-server[793]: /usr/local/go/src/database/sql/sql.go:2254 (0x821646)
May 15 12:15:56 ns8 api-server[793]: /usr/local/go/src/database/sql/sql.go:2373 (0x821d75)
May 15 12:15:56 ns8 api-server[793]: /usr/local/go/src/database/sql/sql.go:2398 (0x837d4f)
May 15 12:15:56 ns8 api-server[793]: /usr/src/core/api-server/audit/audit.go:118 (0x837d30)
May 15 12:15:56 ns8 api-server[793]: /usr/src/core/api-server/methods/tasks.go:302 (0x86ecf7)
May 15 12:15:56 ns8 api-server[793]: /usr/src/core/api-server/methods/tasks.go:484 (0x870134)
May 15 12:15:56 ns8 api-server[793]: /var/lib/misc/mcache/github.com/gin-gonic/gin@v1.9.1/context.go:174 (0x83e5ea)
May 15 12:15:56 ns8 api-server[793]: /var/lib/misc/mcache/github.com/appleboy/gin-jwt/v2@v2.9.1/auth_jwt.go:462 (0x83e444)
May 15 12:15:56 ns8 api-server[793]: /var/lib/misc/mcache/github.com/appleboy/gin-jwt/v2@v2.9.1/auth_jwt.go:415 (0x83e1dc)
May 15 12:15:56 ns8 api-server[793]: /var/lib/misc/mcache/github.com/gin-gonic/gin@v1.9.1/context.go:174 (0x80854e)
May 15 12:15:56 ns8 api-server[793]: /var/lib/misc/mcache/github.com/gin-gonic/gin@v1.9.1/recovery.go:102 (0x80853b)
May 15 12:15:56 ns8 api-server[793]: /var/lib/misc/mcache/github.com/gin-gonic/gin@v1.9.1/context.go:174 (0x80769c)
May 15 12:15:56 ns8 api-server[793]: /var/lib/misc/mcache/github.com/gin-gonic/gin@v1.9.1/logger.go:240 (0x807683)
May 15 12:15:56 ns8 api-server[793]: /var/lib/misc/mcache/github.com/gin-gonic/gin@v1.9.1/context.go:174 (0x806bad)
May 15 12:15:56 ns8 api-server[793]: /var/lib/misc/mcache/github.com/gin-gonic/gin@v1.9.1/gin.go:620 (0x806844)
May 15 12:15:56 ns8 api-server[793]: /var/lib/misc/mcache/github.com/gin-gonic/gin@v1.9.1/gin.go:576 (0x8064e9)
May 15 12:15:56 ns8 api-server[793]: /usr/local/go/src/net/http/server.go:3301 (0x664b0d)
May 15 12:15:56 ns8 api-server[793]: /usr/local/go/src/net/http/server.go:2102 (0x657e24)
May 15 12:15:56 ns8 api-server[793]: /usr/local/go/src/runtime/asm_amd64.s:1700 (0x479b40)

The error error in audit file schema begin: database is locked is a common symptom of slow disk I/O.

Components

• Core 3.7.1
• Migration Tool 1.3.0

---

Thanks to @nrauso

[nethbot]

in 7.9.2009/testing:

• nethserver-ns8-migration-1.3.0-1.1.g433e23d.ns7.x86_64.rpm x86_64

[nethbot]

in 7.9.2009/testing:

• nethserver-ns8-migration-1.3.0-1.3.g21712e5.ns7.x86_64.rpm x86_64

[nethbot]

in 7.9.2009/testing:

• nethserver-ns8-migration-1.3.0-1.4.g7c70b4e.ns7.x86_64.rpm x86_64