Abnormal start of gmrender does not work normally。。。。Please help #3263

fnsnyc · 2019-12-12T20:34:59Z

Creating a bug report/issue

Service doesn't start correctly. I'm using SofaPlay on my Mac as a client for streamming. It shows DietPi but when I press play ... nothing happens.

Actual behaviour

Gmediarender was installed through the system panel with all dependencies, but it does not work properly. Tried two times already. Seems that I missed something that I don't know.

Had tested before with RoonBridge and it worked like a charme with output thru SPDIF DigiOne.

here is the results :::

root@DietPi:~# systemctl status gmrender -l
● gmrender.service - GMediaRender (DietPi)
   Loaded: loaded (/etc/systemd/system/gmrender.service; disabled; vendor preset: enabled)
   Active: active (running) since Thu 2019-12-12 17:07:35 -03; 26min ago
  Process: 644 ExecStartPre=/usr/bin/touch /var/log/gmrender.log (code=exited, status=0/SUCCESS)
  Process: 645 ExecStartPre=/bin/chown gmrender:dietpi /var/log/gmrender.log (code=exited, status=0/SUCCESS)
 Main PID: 646 (dash)
    Tasks: 21 (limit: 4915)
   Memory: 156.1M
   CGroup: /system.slice/gmrender.service
           ├─646 /bin/dash -c gmediarender -u "20fc1862-ecff-4773-b5a6-033ef6ab6f8c" -f "DietPi" --gstout-audiodevice=sysdefault --gstout-initial-volume-db=-1 --logfile=/var/log/gmrender.log -I
 "$(sed -n 4p /DietPi/dietpi/.network)"
           └─649 gmediarender -u 20fc1862-ecff-4773-b5a6-033ef6ab6f8c -f DietPi --gstout-audiodevice=sysdefault --gstout-initial-volume-db=-1 --logfile=/var/log/gmrender.log -I 192.168.1.109

Dec 12 17:07:48 DietPi gmediarender[649]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 12 17:07:48 DietPi gmediarender[649]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (src)' failed
Dec 12 17:07:48 DietPi gmediarender[649]: gst_element_get_static_pad: assertion 'GST_IS_ELEMENT (element)' failed
Dec 12 17:07:48 DietPi gmediarender[649]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (dest)' failed
Dec 12 17:07:48 DietPi gmediarender[649]: 
                                          Trying to dispose object "mixer", but it still has a parent "(null)".
                                          You need to let the parent manage the object instead of unreffing the object directly.
Dec 12 17:07:48 DietPi gmediarender[649]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 12 17:07:48 DietPi gmediarender[649]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 12 17:07:48 DietPi gmediarender[649]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (src)' failed
Dec 12 17:07:48 DietPi gmediarender[649]: gst_element_get_static_pad: assertion 'GST_IS_ELEMENT (element)' failed
Dec 12 17:07:48 DietPi dash[646]: Ready for rendering.

Required Information

DietPi version | DietPi v6.26.3
Distro version | buster
Kernel version | Linux DietPi 4.19.75-v7l+ DietPi-System | Quirks noticed by v158 image update #1270
SBC device | RPi4 + DigiOne SPDIF board
Power supply used | (Ciaudio 5V)
SDcard used | (EG: SanDisk ultra)

The text was updated successfully, but these errors were encountered:

fnsnyc · 2019-12-12T20:46:50Z

systemctl cat gmrender:

/etc/systemd/system/gmrender.service
[Unit]
Description=GMediaRender (DietPi)
After=network.target dietpi-boot.service

[Service]
User=gmrender
Group=dietpi
ExecStartPre=+/usr/bin/touch /var/log/gmrender.log
ExecStartPre=+/bin/chown gmrender:dietpi /var/log/gmrender.log
ExecStart=/bin/dash -c 'gmediarender -u "20fc1862-ecff-4773-b5a6-033ef6ab6f8c" -f "DietPi" --gstout-audiodevice=sysdefault --gstout-initial-volume-db=-1 --logfile=/var/log/gmrender.log -I "$(sed -n 4p /Di
etPi/dietpi/.network)"'

[Install]
WantedBy=multi-user.target

fnsnyc · 2019-12-12T20:47:34Z

sed -n 4p /DietPi/dietpi/.network
192.168.1.109

fnsnyc · 2019-12-13T12:06:02Z

Please help.

MichaIng · 2019-12-13T17:37:56Z

@fnsnyc

gmediarender -u 20fc1862-ecff-4773-b5a6-033ef6ab6f8c -f DietPi --gstout-audiodevice=sysdefault --gstout-initial-volume-db=-1 --logfile=/var/log/gmrender.log -I 192.168.1.109

Ah sorry that log entry already shows a correct IP value. Hmm could you please do the following:

systemctl restart gmrender
# Then try to play something
journalctl -u gmrender # Paste the output of this command here

fnsnyc · 2019-12-13T17:48:30Z

Still not playing. DietPi shows as renderer but nogthing plays.

Here it goes Michael

-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Fri 2019-12-13 17:46:17 GMT. --
Dec 13 12:02:43 DietPi systemd[1]: Starting GMediaRender (DietPi)...
Dec 13 12:02:43 DietPi systemd[1]: Started GMediaRender (DietPi).
Dec 13 12:03:15 DietPi gmediarender[690]: file gstdtlsagent.c: line 192 (gst_dtls_agent_init): should not be reached
Dec 13 12:03:15 DietPi gmediarender[690]: gst_dtls_agent_set_property: assertion 'self->priv->ssl_context' failed
Dec 13 12:03:15 DietPi gmediarender[690]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 13 12:03:15 DietPi gmediarender[690]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 13 12:03:15 DietPi gmediarender[690]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (src)' failed
Dec 13 12:03:15 DietPi gmediarender[690]: gst_element_get_static_pad: assertion 'GST_IS_ELEMENT (element)' failed
Dec 13 12:03:15 DietPi gmediarender[690]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (dest)' failed
Dec 13 12:03:15 DietPi gmediarender[690]: 
                                          Trying to dispose object "mixer", but it still has a parent "(null)".
                                          You need to let the parent manage the object instead of unreffing the object directly.
Dec 13 12:03:15 DietPi gmediarender[690]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 13 12:03:15 DietPi gmediarender[690]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 13 12:03:15 DietPi gmediarender[690]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (src)' failed
Dec 13 12:03:15 DietPi gmediarender[690]: gst_element_get_static_pad: assertion 'GST_IS_ELEMENT (element)' failed
Dec 13 12:03:16 DietPi dash[687]: Ready for rendering.
Dec 13 17:46:14 DietPi systemd[1]: Stopping GMediaRender (DietPi)...
Dec 13 17:46:14 DietPi systemd[1]: gmrender.service: Main process exited, code=killed, status=15/TERM
Dec 13 17:46:14 DietPi systemd[1]: gmrender.service: Succeeded.
Dec 13 17:46:14 DietPi systemd[1]: Stopped GMediaRender (DietPi).
Dec 13 17:46:14 DietPi systemd[1]: Starting GMediaRender (DietPi)...
Dec 13 17:46:14 DietPi systemd[1]: Started GMediaRender (DietPi).
Dec 13 17:46:16 DietPi gmediarender[4933]: file gstdtlsagent.c: line 192 (gst_dtls_agent_init): should not be reached

Dec 13 17:46:16 DietPi gmediarender[4933]: gst_dtls_agent_set_property: assertion 'self->priv->ssl_context' failed
Dec 13 17:46:17 DietPi gmediarender[4933]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 13 17:46:17 DietPi gmediarender[4933]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 13 17:46:17 DietPi gmediarender[4933]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (src)' failed
Dec 13 17:46:17 DietPi gmediarender[4933]: gst_element_get_static_pad: assertion 'GST_IS_ELEMENT (element)' failed
Dec 13 17:46:17 DietPi gmediarender[4933]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 13 17:46:17 DietPi gmediarender[4933]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 13 17:46:17 DietPi gmediarender[4933]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (src)' failed
Dec 13 17:46:17 DietPi gmediarender[4933]: gst_element_get_static_pad: assertion 'GST_IS_ELEMENT (element)' failed
Dec 13 17:46:17 DietPi gmediarender[4933]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (dest)' failed
Dec 13 17:46:17 DietPi gmediarender[4933]: 
                                           Trying to dispose object "mixer", but it still has a parent "(null)".
                                           You need to let the parent manage the object instead of unreffing the object directly.
Dec 13 17:46:17 DietPi dash[4931]: Ready for rendering.

MichaIng · 2019-12-14T11:45:42Z

Hmm I'm thinking if gmrender has issues with active mixer. Did you try to disable the mixer via dietpi-config > Audio options?

fnsnyc · 2019-12-14T12:22:29Z

Sorry but I can't find this option to disable the mixer. It don't show any menu under Audio Options.

MichaIng · 2019-12-14T15:23:36Z

Ah I meant the equalizer 😉.

fnsnyc · 2019-12-14T15:35:26Z

EQ is Off already.

fnsnyc · 2019-12-14T15:48:30Z

Do you know if it's an issue with Rpi4 and I should downgrade to Rpi3 ?

My NanoPi works flawlessly. But I just bought the Allo Digione (SPDIF board) to use with Rpi4. When I test it with RoonBridge, it works fine and plays thru SPDIF. But I need the DLNA working :-(

MichaIng · 2019-12-14T15:55:46Z

Hmm just found that gstreamer issue reported here: https://gitlab.freedesktop.org/gstreamer/gst-plugins-bad/issues/811

Log entries fit yours, but not sure if this is the actual reason that makes the service stop or this one:

Dec 13 17:46:17 DietPi gmediarender[4933]:
Trying to dispose object "mixer", but it still has a parent "(null)".
You need to let the parent manage the object instead of unreffing the object directly.

Also I see that we assign audio device sysdefault via cmd option/argument, while we name the PCM in /etc/asound.conf "default" only....

Have to test myself later.

fnsnyc · 2019-12-15T13:28:52Z

Well ... removed gmrender thru dietpi-config and tried to install manually "gmrender-resurrect" following the instructions at https://github.com/hzeller/gmrender-resurrect/blob/master/INSTALL.md

Same thing. At the end, didn't run, same errors.

Got another SD Card and installed Raspbian Buster Lite and then edited /boot/config.txt to enable DigiOne Allo board. And then followed the instructions on gmrender-resurrect Install.

Now it's working perfectly under Raspbian. I would prefer to run over DietPi because I love the tiny OS .... will keep the other SD card and wait for a solution if there is any.

MichaIng · 2019-12-15T13:41:05Z

Many thanks for testing. On DietPi the compiling went through without error as well?

Also did you enable the dtoverlay on DietPi the same way? Through dietpi-config, it should be offered, leading to the same /DietPi/config.txt entry (note that config.txt is in RAMdisk on DietPi why the RAMdisk location needs to be edited.

And could you paste the following from the Raspbian system:

systemctl cat gmrender
cat /etc/asound.conf

fnsnyc · 2019-12-15T13:47:55Z

Didn't enable dtoverlay on Dietpi... The only thing that I did on Dietpi was menu >> Audio >> Select DigioneAllo ... that's it.

I had to edit boot/config.txt on Raspbian because my sound card was not showing when aplay -l was entered...

Here are the results under Raspbian :

sudo nano /etc/asound.conf .... empty file.

pi@raspberrypi:~ $ systemctl cat gmrender-resurrect
 /etc/systemd/system/gmrender-resurrect.service
[Unit]
Description=gmrender-resurrect service
After=network.target sound.target

[Service]
Environment="UPNP_DEVICE_NAME=Allo-DigiOne"
ExecStartPre=/bin/sh -c "/bin/systemctl set-environment UPNP_UUID=`ip link show | awk '/ether/ {print \"salt:)-\" $2}' | head -1 | md5sum | awk '{print $1

ExecStart=/usr/local/bin/gmediarender -f "$UPNP_DEVICE_NAME" -u "$UPNP_UUID" \
                                      --gstout-audiosink=alsasink --gstout-audiodevice=sysdefault \
                                      --logfile=/tmp/gmediarenderer.log --gstout-initial-volume-db=-10
Restart=always

[Install]
WantedBy=multi-user.target

MichaIng · 2019-12-15T14:04:55Z

Okay only difference then is the asound.conf.

Could you paste: aplay -l
The second part of our entry is suggested on gmrender install instructions for Raspbian as well, the first part should define sound card + device only, which should be 0,0 which should match the default as well.

fnsnyc · 2019-12-15T14:08:13Z

Removed the other audio stuff with dtparam=audio=off and inserted dtoverlay=allo-digione in boot/config.txt

~ $ aplay -l **** List of PLAYBACK Hardware Devices **** card 0: sndallodigione [snd_allo_digione], device 0: Allo DigiOne HiFi wm8804-spdif-0 [] Subdevices: 0/1 Subdevice #0: subdevice #0

MichaIng · 2019-12-15T15:39:49Z

@fnsnyc
Okay, card 0 device 0 as expected, so all this should be correct on DietPi as well. I'll run some test to check the influence of the network related options we add with our systemd unit, especially since there are some error messages about that in your service log, and the default systemd unit does not contain any of them.

For reference, same issue reported plus binary file logs: #3246
UPnP permissions issue?

Network info added due to: #2576

MichaIng · 2019-12-16T00:04:39Z

@fnsnyc
I made some update: 32c5e49

It potentially solves your issue, if its due to permissions indeed. I observed in some cases, where running a systemd unit as specific group ("Group=dietpi" in our case), supplementary user groups were overridden. Since GMediaRender does not require dietpi group permissions for anything that I can think of, I just removed that, hence it will run as gmrender:gmrender now, with supplementary "audio" and "render" group permissions assured. When you find time, would be great if you could test the following:

cat << _EOF_ > /etc/systemd/system/gmrender.service
[Unit]
Description=GMediaRender (DietPi)
Wants=network-online.target
After=network-online.target dietpi-boot.service

[Service]
User=gmrender
ExecStartPre=+/bin/dash -c 'systemctl set-environment ACTIVE_IP=\$(mawk 'NR==4' /DietPi/dietpi/.network)'
ExecStart=gmediarender -u '$(mawk 'NR==5' /DietPi/dietpi/.hw_model)' -f '$(</etc/hostname)' --gstout-audiosink=alsasink --gstout-audiodevice=sysdefault --gstout-initial-volume-db=-1 --logfile=stdout -I "\$ACTIVE_IP"

[Install]
WantedBy=multi-user.target
_EOF_
systemctl daemon-reload
systemctl restart gmrender

With this, debugging is easier as well, as all logs are available via journalctl -u gmrender without having binary logs split. Especially in this case, file logging is a bad thing since the gstreamer modules as well still log to STDOUT, which makes it difficult to read everything in correct order...

Aside of that, on VM, everything works fine (before and after the change), so it is at least RPi-specific.

fnsnyc · 2019-12-16T11:16:09Z

systemctl status gmrender -l
● gmrender.service - GMediaRender (DietPi)
   Loaded: loaded (/etc/systemd/system/gmrender.service; disabled; vendor preset: enabled)
   Active: failed (Result: exit-code) since Mon 2019-12-16 11:10:47 GMT; 4s ago
  Process: 791 ExecStartPre=/bin/dash -c systemctl set-environment ACTIVE_IP=$(mawk NR==4 /DietPi/dietpi/.network) (code=exited, status=0/SUCCESS)
  Process: 794 ExecStart=/usr/bin/gmediarender -u 5a84151a-60e7-4772-b6ed-b2c73e125201 -f DietPi --gstout-audiosink=alsasink --gstout-audiodevice=sysdef
ault --gstout-initial-volume-db=-1 --logfile=stdout -I $ACTIVE_IP (code=exited, status=1/FAILURE)
 Main PID: 794 (code=exited, status=1/FAILURE)

Dec 16 11:10:42 DietPi gmediarender[794]: ERROR [2019-12-16 11:10:42.486423 | upnp] UpnpInit(ip=fe80::dea6:32ff:fe4f:218f, port=49494) Error: UPNP_E_SOC
KET_ERROR (-208). Retrying... (5s)
Dec 16 11:10:43 DietPi gmediarender[794]: ERROR [2019-12-16 11:10:43.489009 | upnp] UpnpInit(ip=fe80::dea6:32ff:fe4f:218f, port=49494) Error: UPNP_E_SOC
KET_ERROR (-208). Retrying... (4s)
Dec 16 11:10:44 DietPi gmediarender[794]: ERROR [2019-12-16 11:10:44.492973 | upnp] UpnpInit(ip=fe80::dea6:32ff:fe4f:218f, port=49494) Error: UPNP_E_SOC
KET_ERROR (-208). Retrying... (3s)
Dec 16 11:10:45 DietPi gmediarender[794]: ERROR [2019-12-16 11:10:45.495584 | upnp] UpnpInit(ip=fe80::dea6:32ff:fe4f:218f, port=49494) Error: UPNP_E_SOC
KET_ERROR (-208). Retrying... (2s)
Dec 16 11:10:46 DietPi gmediarender[794]: ERROR [2019-12-16 11:10:46.498324 | upnp] UpnpInit(ip=fe80::dea6:32ff:fe4f:218f, port=49494) Error: UPNP_E_SOC
KET_ERROR (-208). Retrying... (1s)
Dec 16 11:10:47 DietPi gmediarender[794]: ERROR [2019-12-16 11:10:47.501184 | upnp] UpnpInit(ip=fe80::dea6:32ff:fe4f:218f, port=49494) Error: UPNP_E_SOC
KET_ERROR (-208). Retrying... (0s)
Dec 16 11:10:47 DietPi gmediarender[794]: ERROR [2019-12-16 11:10:47.503769 | upnp] UpnpInit(ip=fe80::dea6:32ff:fe4f:218f, port=49494) Error: UPNP_E_SOC
KET_ERROR (-208). Giving up.
Dec 16 11:10:47 DietPi gmediarender[794]: ERROR [2019-12-16 11:10:47.503825 | main] ERROR: Failed to initialize UPnP device
Dec 16 11:10:47 DietPi systemd[1]: gmrender.service: Main process exited, code=exited, status=1/FAILURE
Dec 16 11:10:47 DietPi systemd[1]: gmrender.service: Failed with result 'exit-code'.

MichaIng · 2019-12-16T12:19:56Z

@fnsnyc
At least we have full module logging now:

UpnpInit(ip=fe80::dea6:32ff:fe4f:218f, port=49494)

LibUPnP tries to bind socket to IPv6 address, while "mawk NR==4 /DietPi/dietpi/.network" should be an IPv4 address, as long as there is one attached to the network adapter. I verified this method of ACTIVE_IP variable creation on all systems and it works very well, so this should not be an issue.

You could try to disable IPv6 (dietpi-config > Network Options: Adapters). As long as your RPi is behind a NAT/router in local network, IPv6 should be pretty useless overhead anyway.

@hifitime
Did you resolved the issue: #3246
Do I understand correctly that in your case a source build worked well on DietPi? #3246 (comment)
I'm confused about identical header, or simply new account/changed name 😄?

fnsnyc · 2019-12-16T12:32:41Z

Turned IPv6 off

Now it plays... but little bit strange because does not show the minutes progress bar, and does not allow me to forward or rewind ...

systemctl status gmrender -l
● gmrender.service - GMediaRender (DietPi)
   Loaded: loaded (/etc/systemd/system/gmrender.service; disabled; vendor preset: enabled)
   Active: active (running) since Mon 2019-12-16 12:26:42 GMT; 4min 23s ago
  Process: 708 ExecStartPre=/bin/dash -c systemctl set-environment ACTIVE_IP=$(mawk NR==4 /DietPi/dietpi/.network) (code=exited, status=0/SUCCESS)
 Main PID: 711 (gmediarender)
    Tasks: 19 (limit: 4915)
   Memory: 61.8M
   CGroup: /system.slice/gmrender.service
           └─711 /usr/bin/gmediarender -u 5a84151a-60e7-4772-b6ed-b2c73e125201 -f DietPi --gstout-audiosink=alsasink --gstout-audiodevice=sysdefault --gstout-initial-volume-d
b=-1 --logfile=stdout -I 192.168.1.109

Dec 16 12:31:01 DietPi gmediarender[711]: <CurrentTransportActions val="PLAY,SEEK"></CurrentTransportActions>
Dec 16 12:31:01 DietPi gmediarender[711]: </InstanceID>
Dec 16 12:31:01 DietPi gmediarender[711]: </Event>
Dec 16 12:31:01 DietPi gmediarender[711]: ERROR [2019-12-16 12:31:01.935722 | gstreamer] source: Error: Not Found (Debug: gstsouphttpsrc.c(1426): gst_soup_http_src_parse_stat
us (): /GstPlayBin:play/GstURIDecodeBin:uridecodebin2/GstSoupHTTPSrc:source:
Dec 16 12:31:01 DietPi gmediarender[711]: Not Found (404), URL: http://192.168.1.110:60060/Test.flac, Redirect to: (NULL))
Dec 16 12:31:01 DietPi gmediarender[711]: ERROR [2019-12-16 12:31:01.936191 | gstreamer] source: Error: Internal data stream error. (Debug: gstbasesrc.c(3055): gst_base_src_l
oop (): /GstPlayBin:play/GstURIDecodeBin:uridecodebin2/GstSoupHTTPSrc:source:
Dec 16 12:31:01 DietPi gmediarender[711]: streaming stopped, reason error (-5))
Dec 16 12:31:01 DietPi gmediarender[711]: ERROR [2019-12-16 12:31:01.936383 | gstreamer] typefindelement7: Error: Stream doesn't contain enough data. (Debug: gsttypefindeleme
nt.c(991): gst_type_find_element_chain_do_typefinding (): /GstPlayBin:play/GstURIDecodeBin:uridecodebin2/GstTypeFindElement:typefindelement7:
Dec 16 12:31:01 DietPi gmediarender[711]: Can't typefind stream)
Dec 16 12:31:03 DietPi gmediarender[711]: INFO  [2019-12-16 12:31:03.912179 | control] cmd_obtain_variable: CurrentMute for instance 0

fnsnyc · 2019-12-16T12:44:39Z

It's not playing thru Audirvana ... it doen't allow me to select the render. It shows but when I select it ... is automatically unselect.

MichaIng · 2019-12-16T13:37:32Z

So far so good. Okay so now I see:

Not Found (404), URL: http://192.168.1.110:60060/Test.flac, Redirect to: (NULL))

Generally about any issues, best to debug is to replicate the issue to force fresh logs, then run journalctl -u gmrender and check all logs from matching timestamp til end.

The following additional gstreamer plugins which allow wider range of A/V formats: G_AGI gstreamer1.0-libav
Could be related if some standard decoder can play the format but not read it very well to know current playback time, forward or rewind etc.

Btw I just recognised the same error logs your printed above on my VM as well:

...
Dec 16 13:28:54 VM-Buster gmediarender[5769]: INFO  [2019-12-16 13:28:54.755466 | connmgr] Registering support for 'application/x-rtcp'
Dec 16 13:28:54 VM-Buster gmediarender[5769]: file gstdtlsagent.c: line 192 (gst_dtls_agent_init): should not be reached
Dec 16 13:28:54 VM-Buster gmediarender[5769]: gst_dtls_agent_set_property: assertion 'self->priv->ssl_context' failed
Dec 16 13:28:54 VM-Buster gmediarender[5769]: INFO  [2019-12-16 13:28:54.841817 | connmgr] Registering support for 'application/x-dtls'
...
Dec 16 13:28:54 VM-Buster gmediarender[5769]: INFO  [2019-12-16 13:28:54.939538 | connmgr] Registering support for 'application/x-subtitle-vtt'
Dec 16 13:28:54 VM-Buster gmediarender[5769]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 16 13:28:54 VM-Buster gmediarender[5769]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 16 13:28:54 VM-Buster gmediarender[5769]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (src)' failed
Dec 16 13:28:54 VM-Buster gmediarender[5769]: gst_element_get_static_pad: assertion 'GST_IS_ELEMENT (element)' failed
Dec 16 13:28:54 VM-Buster gmediarender[5769]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (dest)' failed
Dec 16 13:28:54 VM-Buster gmediarender[5769]: Trying to dispose object "mixer", but it still has a parent "(null)".
                                              You need to let the parent manage the object instead of unreffing the object directly.
Dec 16 13:28:54 VM-Buster gmediarender[5769]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 16 13:28:54 VM-Buster gmediarender[5769]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 16 13:28:54 VM-Buster gmediarender[5769]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (src)' failed
Dec 16 13:28:54 VM-Buster gmediarender[5769]: gst_element_get_static_pad: assertion 'GST_IS_ELEMENT (element)' failed
Dec 16 13:28:54 VM-Buster gmediarender[5769]: INFO  [2019-12-16 13:28:54.961966 | connmgr] Registering support for 'application/vnd.ms-sstr+xml'
...

But these do not break start. Since module logs were missing, we were unable to see what really made the process terminate.

About LibUPnP + IPv6 issue: https://bugs.debian.org/cgi-bin/bugreport.cgi?bug=813249
On Buster its libupnp13, but probably the underlying issue is the same. Probably gmediarender has some change to force bind to the IP explicitly given via -I option. It doesn't make sense that one specifies this but gstreamer binds to a different address...

fnsnyc · 2019-12-16T13:58:48Z

It doesn't work on Audirvana. Can I remove gmrender thru menu and then install gmrender-resurrect manually ?

MichaIng · 2019-12-16T15:08:57Z

@fnsnyc
Jep of course. DietPi is based on standard Raspbian, you can install any software outside of dietpi-software by following usual guides for Debian/Raspbian.

When you do, would be nice if you could test the repo package first (apt install gmediarender gstreamer1.0-alsa), before doing a source build, and just use the sysvinit service is ships + default configs. Would be interesting if this fails the same way, as we install this package as well. AFAIK it pulls too few dependencies, at least gstreamer1.0-plugins-good seems to be required additionally, gstreamer1.0-alsa definitely is. We install gstreamer1.0-plugins-bad + gstreamer1.0-plugins-ugly packages as well, but not sure which features they actually enable.

fnsnyc · 2019-12-16T16:49:32Z

Now it's working perfectly! Just installed gmrender-resurrect. Tested the src after make and before install. At the end copied the gmrender-resurrect.service to /etc/systemd/system/gmrender-resurrect.service.

I don't know what we did because I get the same errors, but a Ready for rendering. at the end :


systemctl status gmrender-resurrect -l
● gmrender-resurrect.service - GMediaRender (DietPi)
   Loaded: loaded (/etc/systemd/system/gmrender-resurrect.service; enabled; vendor preset: enabled)
   Active: active (running) since Mon 2019-12-16 14:34:35 -03; 53s ago
  Process: 719 ExecStartPre=/bin/sh -c /bin/systemctl set-environment UPNP_UUID=`ip link show | awk '/ether/ {print "salt:)-" $2}' | head -1 | md5sum | awk '{pr
int $1}'` (code=exited, status=0/SUCCESS)
 Main PID: 733 (gmediarender)
    Tasks: 22 (limit: 4915)
   Memory: 152.0M
   CGroup: /system.slice/gmrender-resurrect.service
           └─733 /usr/local/bin/gmediarender -f DigiOne-Allo -u fd9818eea06e73dbfae4bd01f7932fae --gstout-audiosink=alsasink --gstout-audiodevice=sysdefault --l
ogfile=/tmp/gmediarenderer.log --gstout-initial-volume-db=0

Dec 16 14:34:52 DietPi gmediarender[733]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 16 14:34:52 DietPi gmediarender[733]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (src)' failed
Dec 16 14:34:52 DietPi gmediarender[733]: gst_element_get_static_pad: assertion 'GST_IS_ELEMENT (element)' failed
Dec 16 14:34:52 DietPi gmediarender[733]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (dest)' failed
Dec 16 14:34:52 DietPi gmediarender[733]: 
                                          Trying to dispose object "mixer", but it still has a parent "(null)".
                                          You need to let the parent manage the object instead of unreffing the object directly.
Dec 16 14:34:52 DietPi gmediarender[733]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 16 14:34:52 DietPi gmediarender[733]: gst_bin_add: assertion 'GST_IS_ELEMENT (element)' failed
Dec 16 14:34:52 DietPi gmediarender[733]: gst_element_link_pads_full: assertion 'GST_IS_ELEMENT (src)' failed
Dec 16 14:34:52 DietPi gmediarender[733]: gst_element_get_static_pad: assertion 'GST_IS_ELEMENT (element)' failed
Dec 16 14:34:53 DietPi gmediarender[733]: Ready for rendering.

MichaIng · 2019-12-16T22:06:14Z

@fnsnyc
Great. Yeah as I mentioned above, I see the same errors, but they do not break the service. Logging is done to /tmp/gmediarenderer.log now, hence again you do not see the (successful/informational) gstreamer module logs via journalctl/systemctl status that are before, between and after those errors until "Ready for rendering.".

So the question is now if the Debian/Raspbian build on Buster has issues on RPi or if our systemd unit is (still) the issue, e.g. on RPi still some permissions are missing for user "gmrender". The service now runs with root permissions, which I don't like to ship as default, not because of mistrust against gmrender-resurrect, but as a general security strategy on UNIX systems, no grant any program not more permissions as it requires, which as well protects against accidents, bugs, misuse, etc.

If you're in mood you could test it:

# Create/edit user to assure it has all permissions, usually required for media playback
usercmd='useradd -rMU'
getent passwd gmrender &> /dev/null && usercmd='usermod -a'
$usercmd -G audio,render -s $(command -v nologin) gmrender
# Create systemd unit, note it does not override your current one, as of different file name
cat << _EOF_ > /etc/systemd/system/gmrender.service
[Unit]
Description=GMediaRender (DietPi)
Wants=network-online.target
After=network-online.target dietpi-boot.service

[Service]
User=gmrender
ExecStartPre=+/bin/dash -c 'systemctl set-environment ACTIVE_IP=\$(mawk 'NR==4' /DietPi/dietpi/.network)'
ExecStart=$(command -v gmediarender) -u '$(mawk 'NR==5' /DietPi/dietpi/.hw_model)' -f '$(</etc/hostname)' --gstout-audiosink=alsasink --gstout-audiodevice=sysdefault --gstout-initial-volume-db=-1 --logfile=stdout -I "\$ACTIVE_IP"

[Install]
WantedBy=multi-user.target
_EOF_
systemctl daemon-reload
# Stop functional service
systemctl stop gmrender-resurrect
# Start ours
systemctl start gmrender
journalctl -u gmrender
# If it fails again, check if running it as root solves it (highly expected then)
sed -i '/^User=/d' /etc/systemd/system/gmrender.service
systemctl daemon-reload
systemctl restart gmrender
journalctl -u gmrender

The run user is the only relevant difference, everything else is only optical, like UUID and name of the server for clients. The IP is only relevant, if one has more then one network adapter, or more then one IP attached to it, like in your previous case the IPv6. I guess IPv6 was also the reason why your previous source build attempt did not work, but we were not able to see it due to split log to file 😉.

Btw, GMediaRender can be used for video files as well, right? Seems to be a rare use case, but as far as I can see possible. Hence it makes sense to add the user to video group as well, to allow framebuffer and monitor access, in case, not sure if some GPU decoder features require this group as well or all this is outsourced to new render group 🤔.

fnsnyc · 2019-12-17T11:21:28Z

You are right. It's not recommended running as root. I did your suggestion and is running perfect. I'm going to use gmrender for Audio only.
Also removed the gmrender-resurrect.service file from systemctl

MichaIng · 2019-12-18T16:17:57Z

Changelog: a5b68d1

But ahh, while the made changes resolve the potential permissions issue, the faulty Raspbian binary is still an issue...
Workaround is a source build, however I'll try to ship compiled v0.0.8 binaries for RPi.

MichaIng · 2019-12-28T15:02:53Z

@fnsnyc
Could you please test the following:

[[ -f '/usr/local/bin/gmediarender' ]] && mv /usr/local/bin/gmediarender /usr/local/bin/gmediarender_bak
wget https://dietpi.com/downloads/binaries/buster/gmediarender_armv6l -O /usr/local/bin/gmediarender
chmod +x /usr/local/bin/gmediarender
cat << _EOF_ > /etc/systemd/system/gmrender.service
[Unit]
Description=GMediaRender (DietPi)
Wants=network-online.target
After=network-online.target dietpi-boot.service

[Service]
User=gmrender
ExecStartPre=+/bin/dash -c 'systemctl set-environment ACTIVE_IP=\$(mawk 'NR==4' /DietPi/dietpi/.network)'
ExecStart=/usr/local/bin/gmediarender -u '$(mawk 'NR==5' /DietPi/dietpi/.hw_model)' -f '$(</etc/hostname)' --gstout-audiosink=alsasink --gstout-audiodevice=sysdefault --gstout-initial-volume-db=-1 --logfile=stdout -I "\$ACTIVE_IP"

[Install]
WantedBy=multi-user.target
_EOF_
systemctl daemon-reload
systemctl restart gmrender

fnsnyc · 2019-12-28T17:18:39Z

Opps .. I guess I did something wrong. I was under root

Dec 28 00:30:26 DietPi gmediarender[713]: INFO  [2019-12-28 00:30:26.186170 | webserver] Access /upnp/grender-64x64.png (image/png) len=8209
Dec 28 00:30:26 DietPi gmediarender[713]: INFO  [2019-12-28 00:30:26.188773 | webserver] Access /upnp/grender-128x128.png (image/png) len=25033
Dec 28 00:30:26 DietPi gmediarender[713]: INFO  [2019-12-28 00:30:26.193706 | webserver] Access /upnp/renderconnmgrSCPD.xml (text/xml) len=4783
Dec 28 00:30:26 DietPi gmediarender[713]: INFO  [2019-12-28 00:30:26.203156 | webserver] Access /upnp/rendercontrolSCPD.xml (text/xml) len=13317
Dec 28 00:30:26 DietPi gmediarender[713]: INFO  [2019-12-28 00:30:26.211505 | webserver] Access /upnp/rendertransportSCPD.xml (text/xml) len=15697
Dec 28 00:30:38 DietPi gmediarender[713]: INFO  [2019-12-28 00:30:38.590485 | webserver] Access /upnp/grender-64x64.png (image/png) len=8209
Dec 28 00:30:38 DietPi gmediarender[713]: INFO  [2019-12-28 00:30:38.595349 | webserver] Access /upnp/grender-128x128.png (image/png) len=25033
Dec 28 00:30:38 DietPi gmediarender[713]: INFO  [2019-12-28 00:30:38.605501 | webserver] Access /upnp/renderconnmgrSCPD.xml (text/xml) len=4783
Dec 28 00:30:38 DietPi gmediarender[713]: INFO  [2019-12-28 00:30:38.620258 | webserver] Access /upnp/rendercontrolSCPD.xml (text/xml) len=13317
Dec 28 00:30:38 DietPi gmediarender[713]: INFO  [2019-12-28 00:30:38.631837 | webserver] Access /upnp/rendertransportSCPD.xml (text/xml) len=15697
Dec 28 14:13:35 DietPi systemd[1]: gmrender.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Dec 28 14:13:38 DietPi gmediarender[713]: INFO  [2019-12-28 14:13:38.174431 | main] Exiting.
Dec 28 14:13:38 DietPi systemd[1]: Stopping GMediaRender (DietPi)...
Dec 28 14:13:38 DietPi systemd[1]: gmrender.service: Succeeded.
Dec 28 14:13:38 DietPi systemd[1]: Stopped GMediaRender (DietPi).
Dec 28 14:13:38 DietPi systemd[1]: Starting GMediaRender (DietPi)...
Dec 28 14:13:38 DietPi systemd[1]: Started GMediaRender (DietPi).
Dec 28 14:13:38 DietPi systemd[17822]: gmrender.service: Failed to execute command: Permission denied
Dec 28 14:13:38 DietPi systemd[17822]: gmrender.service: Failed at step EXEC spawning /usr/local/bin/gmediarender: Permission denied
Dec 28 14:13:38 DietPi systemd[1]: gmrender.service: Main process exited, code=exited, status=203/EXEC
Dec 28 14:13:38 DietPi systemd[1]: gmrender.service: Failed with result 'exit-code'.

MichaIng · 2019-12-28T20:59:26Z

@fnsnyc
Sorry, I forgot the execute permissions: chmod +x /usr/local/bin/gmediarender

fnsnyc · 2019-12-28T21:02:45Z

Yeap, now working flawlessly.

MichaIng · 2019-12-28T22:06:55Z

Many thanks for testing. Great, I'm currently compiling binaries for all architectures and all distro versions, luckily this is going pretty fast 😃.

+ DietPi-Software | Allo web UI: Install new version 13.1 with some hotfixes and updates required for DietPi v6.27 compatibility + DietPi-Software | Allo web UI: Always perform a clean install by removing the old web UI dir first + DietPi-Software | Allo web UI: Assure install and uninstall states of the web UI software ID + quick update ID are always the same + DietPi-Software | GMediaRender: Resolve certain startup issues by installing our fresh compiled v0.0.8 binaries instead of the APT package: #3263, #3246 + DietPi-Software | Minor coding

MichaIng · 2019-12-29T21:32:47Z

Issue resolved with: f70a008
Changelog: 3f95e2e

MichaIng · 2019-12-30T11:47:28Z

@fnsnyc
Last question: There are two icons the binary expects/the build installs:

/usr/local/share/gmediarender/grender-128x128.png
/usr/local/share/gmediarender/grender-64x64.png

On startup it complains if those are missing, although just as a warning. Since I have not much experience with GMediaRender, do you know where those are used and if its bad when missing?

fnsnyc · 2019-12-31T12:16:55Z

I guess is nothing major, those icons are shown on devices like TVs... when listing renderers in your network.

MichaIng · 2019-12-31T16:14:50Z

Okay indeed nothing urgent then, although to not confuse users with error messages I'll add those files.

chips023 · 2020-01-01T13:17:35Z

It doesn't matter. At least I don't see any impact

…

在 2019年12月30日，下午7:47，MichaIng ***@***.***> 写道： @fnsnyc <https://github.com/fnsnyc> Last question: There are two icons the binary expects/the build installs: /usr/local/share/gmediarender/grender-128x128.png /usr/local/share/gmediarender/grender-64x64.png On startup it complains if those are missing, although just as a warning. Since I have not much experience with GMediaRender, do you know where those are used and if its bad when missing? — You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub <#3263?email_source=notifications&email_token=AI42PPECWRUHVUMYHSKZDZDQ3HNVBA5CNFSM4J2EBXN2YY3PNVWWK3TUL52HS4DFVREXG43VMVBW63LNMVXHJKTDN5WW2ZLOORPWSZGOEH2EHZI#issuecomment-569656293>, or unsubscribe <https://github.com/notifications/unsubscribe-auth/AI42PPA3362V7VMXHTOTH73Q3HNVBANCNFSM4J2EBXNQ>.

MichaIng added the Investigating 🤔 label Dec 13, 2019

MichaIng added the Raspberry Pi label Dec 16, 2019

MichaIng added Testing/testers required 🔽 and removed Investigating 🤔 labels Dec 18, 2019

MichaIng mentioned this issue Dec 18, 2019

Open Beta v6.27 | Please help testing and hardening the upcoming release #3257

Closed

MichaIng mentioned this issue Dec 18, 2019

Beta v6.27.1 #3270

Merged

MichaIng mentioned this issue Dec 28, 2019

Abnormal start of gmrender does not work normally。。。。Please help #3246

Closed

MichaIng added the Buster label Dec 28, 2019

MichaIng removed the Raspberry Pi label Dec 28, 2019

MichaIng added Solution available 🥂 Definite solution has been done and removed Workaround available 🆗 Workaround is available/has been implemented, but a definite solution should be found when possible. labels Dec 29, 2019

MichaIng mentioned this issue Dec 29, 2019

Beta v6.27.2 #3282

Merged

MichaIng mentioned this issue Dec 30, 2019

Release v6.27 #3290

Merged

MichaIng closed this as completed Jan 1, 2020

gaeldoyen mentioned this issue Jan 19, 2020

DietPi-Software | GMediaRender: "sysdefault" does not work in every case #3348

Closed

Abnormal start of gmrender does not work normally。。。。Please help #3263

Abnormal start of gmrender does not work normally。。。。Please help #3263

Comments

fnsnyc commented Dec 12, 2019 • edited Loading

Creating a bug report/issue

Actual behaviour

Required Information

fnsnyc commented Dec 12, 2019 • edited Loading

fnsnyc commented Dec 12, 2019 • edited Loading

fnsnyc commented Dec 13, 2019

MichaIng commented Dec 13, 2019

fnsnyc commented Dec 13, 2019 • edited Loading

MichaIng commented Dec 14, 2019

fnsnyc commented Dec 14, 2019

MichaIng commented Dec 14, 2019

fnsnyc commented Dec 14, 2019

fnsnyc commented Dec 14, 2019

MichaIng commented Dec 14, 2019

fnsnyc commented Dec 15, 2019

MichaIng commented Dec 15, 2019 • edited Loading

fnsnyc commented Dec 15, 2019 • edited Loading

MichaIng commented Dec 15, 2019

fnsnyc commented Dec 15, 2019 • edited Loading

MichaIng commented Dec 15, 2019 • edited Loading

MichaIng commented Dec 16, 2019 • edited Loading

fnsnyc commented Dec 16, 2019

MichaIng commented Dec 16, 2019 • edited Loading

fnsnyc commented Dec 16, 2019

fnsnyc commented Dec 16, 2019

MichaIng commented Dec 16, 2019 • edited Loading

fnsnyc commented Dec 16, 2019 • edited Loading

MichaIng commented Dec 16, 2019 • edited Loading

fnsnyc commented Dec 16, 2019 • edited Loading

MichaIng commented Dec 16, 2019

fnsnyc commented Dec 17, 2019

MichaIng commented Dec 18, 2019 • edited Loading

MichaIng commented Dec 28, 2019 • edited Loading

fnsnyc commented Dec 28, 2019 • edited Loading

MichaIng commented Dec 28, 2019

fnsnyc commented Dec 28, 2019

MichaIng commented Dec 28, 2019

MichaIng commented Dec 29, 2019

MichaIng commented Dec 30, 2019

fnsnyc commented Dec 31, 2019 • edited Loading

MichaIng commented Dec 31, 2019

chips023 commented Jan 1, 2020 via email

fnsnyc commented Dec 12, 2019 •

edited

Loading

fnsnyc commented Dec 12, 2019 •

edited

Loading

fnsnyc commented Dec 12, 2019 •

edited

Loading

fnsnyc commented Dec 13, 2019 •

edited

Loading

MichaIng commented Dec 15, 2019 •

edited

Loading

fnsnyc commented Dec 15, 2019 •

edited

Loading

fnsnyc commented Dec 15, 2019 •

edited

Loading

MichaIng commented Dec 15, 2019 •

edited

Loading

MichaIng commented Dec 16, 2019 •

edited

Loading

MichaIng commented Dec 16, 2019 •

edited

Loading

MichaIng commented Dec 16, 2019 •

edited

Loading

fnsnyc commented Dec 16, 2019 •

edited

Loading

MichaIng commented Dec 16, 2019 •

edited

Loading

fnsnyc commented Dec 16, 2019 •

edited

Loading

MichaIng commented Dec 18, 2019 •

edited

Loading

MichaIng commented Dec 28, 2019 •

edited

Loading

fnsnyc commented Dec 28, 2019 •

edited

Loading

fnsnyc commented Dec 31, 2019 •

edited

Loading