MuseScore icon indicating copy to clipboard operation
MuseScore copied to clipboard

[MU4 Issue] Muse Hub cannot connect to helper service

Open aufarg opened this issue 2 years ago • 0 comments

Describe the bug Muse Hub GUI can be opened but it always shows Muse Hub helper service is not running when the service already runs.

muse-hub-service log:

● muse-hub.service - Muse Hub Helper Service
     Loaded: loaded (/usr/lib/systemd/system/muse-hub.service; enabled; preset: disabled)
     Active: active (running) since Fri 2022-12-16 17:30:12 +08; 4min 51s ago
   Main PID: 23519 (muse-hub-servic)
      Tasks: 13 (limit: 19058)
     Memory: 23.6M
        CPU: 226ms
     CGroup: /system.slice/muse-hub.service
             ├─23519 /bin/sh /usr/bin/muse-hub-service
             └─23522 /opt/muse-hub/Muse.Service

Dec 16 17:30:12 InkHouse muse-hub-service[23522]: 2022-12-16 17:30:12.9136|INFO|Microsoft.Hosting.Lifetime|Application started. Press Ctrl+C to shut down.
Dec 16 17:30:12 InkHouse muse-hub-service[23522]: 2022-12-16 17:30:12.9136|INFO|Microsoft.Hosting.Lifetime|Hosting environment: Production
Dec 16 17:30:12 InkHouse muse-hub-service[23522]: 2022-12-16 17:30:12.9136|INFO|Microsoft.Hosting.Lifetime|Content root path: /opt/muse-hub
Dec 16 17:30:12 InkHouse Muse.Service[23522]: Muse.Service Information: 0 :
Dec 16 17:30:12 InkHouse Muse.Service[23522]: 2022-12-16 17:30:12.9136|INFO|Microsoft.Hosting.Lifetime|Hosting environment: Production
Dec 16 17:30:12 InkHouse Muse.Service[23522]: Muse.Service Information: 0 :
Dec 16 17:30:12 InkHouse Muse.Service[23522]: 2022-12-16 17:30:12.9136|INFO|Microsoft.Hosting.Lifetime|Content root path: /opt/muse-hub
Dec 16 17:30:12 InkHouse Muse.Service[23522]: Muse.Service Information: 0 :
Dec 16 17:30:12 InkHouse Muse.Service[23522]: 2022-12-16 17:30:12.9197|INFO|Muse.Service.Services.MuseBackgroundService|Waiting for client to connect...
Dec 16 17:30:12 InkHouse muse-hub-service[23522]: 2022-12-16 17:30:12.9197|INFO|Muse.Service.Services.MuseBackgroundService|Waiting for client to connect...

muse-hub log (after clicking connect on the banner):

2022-12-16 17:29:01.0708|INFO|Muse.Client.Linux.App|Attempting to set the users language to en-US using file /opt/muse-hub/Assets/Strings/en-US/Localizable.strings
2022-12-16 17:29:01.6352|INFO|System.Net.Http.HttpClient.CosmosApi.LogicalHandler|Start processing HTTP request POST https://muse-webservice.azurewebsites.net/graphql
2022-12-16 17:29:01.6352|INFO|System.Net.Http.HttpClient.CosmosApi.LogicalHandler|Start processing HTTP request POST https://muse-webservice.azurewebsites.net/graphql
2022-12-16 17:29:01.6352|INFO|System.Net.Http.HttpClient.CosmosApi.LogicalHandler|Start processing HTTP request POST https://muse-webservice.azurewebsites.net/graphql
2022-12-16 17:29:01.6502|INFO|System.Net.Http.HttpClient.CosmosApi.ClientHandler|Sending HTTP request POST https://muse-webservice.azurewebsites.net/graphql
2022-12-16 17:29:01.6502|INFO|System.Net.Http.HttpClient.CosmosApi.ClientHandler|Sending HTTP request POST https://muse-webservice.azurewebsites.net/graphql
2022-12-16 17:29:01.6502|INFO|System.Net.Http.HttpClient.CosmosApi.ClientHandler|Sending HTTP request POST https://muse-webservice.azurewebsites.net/graphql
2022-12-16 17:29:01.6531|INFO|Muse.Client.Common.Services.MuseBackgroundService|Attempting to connect to background service (starting background thread)...
2022-12-16 17:29:03.0738|INFO|System.Net.Http.HttpClient.CosmosApi.ClientHandler|Received HTTP response headers after 1422.218ms - 200
2022-12-16 17:29:03.0770|INFO|System.Net.Http.HttpClient.CosmosApi.LogicalHandler|End processing HTTP request after 1446.7048ms - 200
2022-12-16 17:29:03.6618|INFO|System.Net.Http.HttpClient.CosmosApi.ClientHandler|Received HTTP response headers after 2011.4647ms - 200
2022-12-16 17:29:03.6618|INFO|System.Net.Http.HttpClient.CosmosApi.LogicalHandler|End processing HTTP request after 2031.7945ms - 200
2022-12-16 17:29:04.1275|INFO|System.Net.Http.HttpClient.CosmosApi.ClientHandler|Received HTTP response headers after 2477.1578ms - 200
2022-12-16 17:29:04.1275|INFO|System.Net.Http.HttpClient.CosmosApi.LogicalHandler|End processing HTTP request after 2497.4663ms - 200
2022-12-16 17:29:05.2002|INFO|Muse.Client.Common.Services.MuseBackgroundService|Attempting to connect to background service
2022-12-16 17:29:05.2002|INFO|Muse.Client.Common.Services.MuseBackgroundService|Attempting to connect to background service (starting background thread)...
^C

sudo ss -tunlap shows that it has bind the port 6881 and listens on loopback and the network interface too:

tcp     LISTEN    0         5                                       192.168.18.109%enp6s0:6881                                     0.0.0.0:*        users:(("Muse.Service",pid=23522,fd=131))
tcp     LISTEN    0         5                                                127.0.0.1%lo:6881                                     0.0.0.0:*        users:(("Muse.Service",pid=23522,fd=129))

I tried to connect via netcat (nc -v localhost 6881):

localhost [127.0.0.1] 6881 open

Shows that port is open, but it seems that it's closed immediately (I can't send any input).

Expected behavior I expect Muse Hub to connect to the helper service because port is already open.

Platform information

  • OS: Arch Linux

Installing via the following AUR package and extracting the .deb package results in the same scenario

sha256sum:

55f3200a4ff1c0edd37dbf7d0def58d16327f073cf045a4598a98f3bf9e6ad6f  /usr/bin/muse-hub-service
4fe7a612cade706d8470f50559d41737a9676774848dbf6875493480eadc7de0  /usr/bin/muse-hub

aufarg avatar Dec 16 '22 09:12 aufarg