Notification scripts randomly doesn't get executed

[tfonfara]

Version: v2.1.1-beta Branch: beta Commit hash: 1d3cd431ebd02a6d9b7e7aa1d9a2cd935c033fd9 Operating system: Linux 4.4.0-119-generic Python version: 2.7.14 (default, Dec 14 2017, 15:51:29) [GCC 6.4.0] What you did? Used script notification agent What happened? Sometimes randomly script is not beeing executed What you expected? Script to be always executed Further information:

• The attached logs show the play and stop notification, no information inbetween is cut off
• In the bash script I have as first line an echo call after that a curl POST call will be executed.
• At 2018-04-12 18:48:21 you can see the expected output. Also you can see that it takes some time to execute the script (time between Executing script in a new thread. and Script notification sent. is 4 seconds).
• At 2018-04-12 18:45:08 there should be an execution of that script as well, parameters are almost the same (only action changed).
• As you can see there is no time between Executing script in a new thread. and Script notification sent. and also no output.
• On the destination endpoint I cannot see any log entries of that curl call as well, that brings up the assumption that the script doesn't get executed at all (at least the first echo line should be displayed in the logs, even if the curl fails).
• If the script fails because of any reason my expectation is, that this appears in the logs as well.

Logs:

2018-04-12 18:45:08 - DEBUG   :: Thread-3 : Tautulli ActivityHandler :: Session 10 started by user 14834074 (Fabian) with ratingKey 10499 (Game of Thrones - Töte den Jungen).
2018-04-12 18:45:08 - DEBUG   :: Thread-2 : Tautulli NotificationHandler :: Checking custom notification conditions for notifier_id 3.
2018-04-12 18:45:08 - DEBUG   :: Thread-2 : Tautulli NotificationHandler :: Custom condition evaluated to 'True'.
2018-04-12 18:45:08 - INFO    :: Thread-1 : Tautulli NotificationHandler :: Preparing notifications for notifier_id 3.
2018-04-12 18:45:08 - DEBUG   :: Thread-1 : Tautulli Notifiers :: Trying to run notify script, action: play, arguments: [u'--action', 'play', u'--serverMachineId', '********ea', u'--serverName', u'Server', u'--user', u'Fabian', u'--title', u'Game of Thrones - T\xf6te den Jungen', u'--ratingKey', u'10499', u'--sessionId', u'effcoe8roa9ped6gzi0k8cd5']
2018-04-12 18:45:08 - DEBUG   :: Thread-1 : Tautulli Notifiers :: Full script is: [u'/config/scripts/notify.sh', u'--action', 'play', u'--serverMachineId', '********ea', u'--serverName', u'Server', u'--user', u'Fabian', u'--title', u'Game of Thrones - T\xf6te den Jungen', u'--ratingKey', u'10499', u'--sessionId', u'effcoe8roa9ped6gzi0k8cd5']
2018-04-12 18:45:08 - DEBUG   :: Thread-1 : Tautulli Notifiers :: Executing script in a new thread.
------ MISSING SCRIPT EXECUTION OUTPUT ------
2018-04-12 18:45:08 - INFO    :: Thread-31 : Tautulli Notifiers :: Script notification sent.
2018-04-12 18:48:17 - DEBUG   :: Thread-3 : Tautulli ActivityHandler :: Session 10 stopped.
2018-04-12 18:48:17 - DEBUG   :: Thread-3 : Tautulli ActivityProcessor :: Fetching metadata for item ratingKey 10499
2018-04-12 18:48:17 - DEBUG   :: Thread-2 : Tautulli NotificationHandler :: Checking custom notification conditions for notifier_id 3.
2018-04-12 18:48:17 - DEBUG   :: Thread-2 : Tautulli NotificationHandler :: Custom condition evaluated to 'True'.
2018-04-12 18:48:17 - INFO    :: Thread-2 : Tautulli NotificationHandler :: Preparing notifications for notifier_id 3.
2018-04-12 18:48:17 - DEBUG   :: Thread-2 : Tautulli Notifiers :: Trying to run notify script, action: stop, arguments: [u'--action', 'stop', u'--serverMachineId', '********ea', u'--serverName', u'Server', u'--user', u'Fabian', u'--title', u'Game of Thrones - T\xf6te den Jungen', u'--ratingKey', u'10499', u'--sessionId', u'effcoe8roa9ped6gzi0k8cd5']
2018-04-12 18:48:17 - DEBUG   :: Thread-2 : Tautulli Notifiers :: Full script is: [u'/config/scripts/notify.sh', u'--action', 'stop', u'--serverMachineId', '********ea', u'--serverName', u'Server', u'--user', u'Fabian', u'--title', u'Game of Thrones - T\xf6te den Jungen', u'--ratingKey', u'10499', u'--sessionId', u'effcoe8roa9ped6gzi0k8cd5']
2018-04-12 18:48:17 - DEBUG   :: Thread-2 : Tautulli Notifiers :: Executing script in a new thread.
2018-04-12 18:48:17 - DEBUG   :: Thread-3 : Tautulli ActivityHandler :: Removing sessionKey 10 ratingKey 10499 from session queue
2018-04-12 18:48:21 - DEBUG   :: Thread-33 : Tautulli Notifiers :: Script returned: 
  Starting script
  Payload: {"action":"stop","serverMachineId":"********ea","serverName":"Server","user":"Fabian","title":"Game of Thrones - T*te den Jungen","ratingKey":"10499","sessionId":"effcoe8roa9ped6gzi0k8cd5"}
  Successfully sent message: "8414807880372496241"
2018-04-12 18:48:21 - INFO    :: Thread-33 : Tautulli Notifiers :: Script notification sent.
2018-04-12 18:50:15 - INFO    :: CP Server Thread-13 : Tautulli Pmsconnect :: Requesting name from server...
2018-04-12 18:50:15 - INFO    :: CP Server Thread-13 : Retrieving latest version information from GitHub
2018-04-12 18:50:15 - DEBUG   :: CP Server Thread-13 : Requesting URL via GET method: https://api.github.com/repos/Tautulli/Tautulli/commits/beta
2018-04-12 18:50:15 - DEBUG   :: CP Server Thread-6 : Tautulli PlexTV :: Retrieving current server version.
2018-04-12 18:50:15 - INFO    :: CP Server Thread-6 : Tautulli Config :: Writing configuration to file
2018-04-12 18:50:15 - DEBUG   :: CP Server Thread-6 : Tautulli PlexTV :: Plex update channel is public.
2018-04-12 18:50:15 - DEBUG   :: CP Server Thread-13 : Latest version is 1d3cd431ebd02a6d9b7e7aa1d9a2cd935c033fd9
2018-04-12 18:50:15 - INFO    :: CP Server Thread-13 : Tautulli is up to date
2018-04-12 18:50:26 - INFO    :: CP Server Thread-13 : Tautulli Pmsconnect :: Requesting name from server...

[tfonfara]

Let me know if you need something :)

[BindersFullOfWomen]

I've been having this issue as well. Essentially, after updating to the latest version from 2.0.8, everything broke. No idea how or why.

[JonnyWong16]

I believe this has been identified as a script returning with status code -11. The status code is now logged when the script exits so that the error can be identified.

Status code 11 is a segmentation fault which indicates an error with system memory. This is outside the scope of Tautulli and can't be fixed.