SOLVED: Exception 'Exception' with message 'Action does not exist!' in /opt/kaltura/app/infra/log/KalturaLog.php:74


#1

CentOS 7, Single server (Everything installed on one server), Kaltura 12.5.0.

Seeing various errors recurring in Kaltlog:
]
2016-12-03 10:55:49 [0.000465] [96.48.8.194] [1343803784] [4] [API] [KalturaDispatcher->dispatch] CRIT: exception ‘Exception’ with message ‘Action does not exist!’ in /opt/kaltura/app/infra/log/KalturaLog.php:74
Stack trace:
#0 /opt/kaltura/app/api_v3/lib/KalturaDispatcher.php(56): KalturaLog::crit(‘Action does not…’)

#3 {main}
2016-12-03 10:55:49 [0.000393] [96.48.8.194] [1343803784] [5] [API] [KalturaFrontController->getExceptionObject] ERR: exception ‘KalturaAPIException’ with message ‘Action “trackevent” does not exists for service “analytics”’ in /opt/kaltura/app/api_v3/lib/KalturaDispatcher.php:57
Stack trace:
#0 /opt/kaltura/app/api_v3/lib/KalturaFrontController.php(113): KalturaDispatcher->dispatch(‘analytics’, ‘trackEvent’, Array)

2016-12-03 10:57:07 [0.000310] [96.48.8.194] [312091814] [5] [PS2] [sfFrontWebController->dispatch] INFO: {sfController} dispatch request
2016-12-03 10:57:07 [0.000458] [96.48.8.194] [10412509] [51] [PS2] [KExternalErrors::dieError] ERR: exception ‘Exception’ with message ‘exiting on error 6 - requested flavor was not found’ in /opt/kaltura/app/infra/log/KalturaLog.php:83
Stack trace:
#0 /opt/kaltura/app/alpha/apps/kaltura/lib/KExternalErrors.class.php(128): KalturaLog::err(‘exiting on erro…’)

2016-12-03 10:57:07 [0.000657] [96.48.8.194] [1605495579] [50] [PS2] [KalturaStatement->execute] DEBUG: Sql took - 0.00043392181396484 seconds
2016-12-03 10:57:07 [0.000443] [96.48.8.194] [1605495579] [51] [PS2] [KExternalErrors::dieError] ERR: exception ‘Exception’ with message ‘exiting on error 6 - requested flavor was not found’ in /opt/kaltura/app/infra/log/KalturaLog.php:83
Stack trace:
#0 /opt/kaltura/app/alpha/apps/kaltura/lib/KExternalErrors.class.php(128): KalturaLog::err(‘exiting on erro…’)

2016-12-03 10:57:07 [0.000434] [96.48.8.194] [312091814] [44] [PS2] [KalturaStatement->execute] DEBUG: Sql took - 0.00030612945556641 seconds
2016-12-03 10:57:07 [0.000263] [96.48.8.194] [1477985303] [51] [PS2] [KExternalErrors::dieError] ERR: exception ‘Exception’ with message ‘exiting on error 6 - requested flavor was not found’ in /opt/kaltura/app/infra/log/KalturaLog.php:83
Stack trace:
#0 /opt/kaltura/app/alpha/apps/kaltura/lib/KExternalErrors.class.php(128): KalturaLog::err(‘exiting on erro…’)

2016-12-03 10:57:07 [0.000512] [96.48.8.194] [312091814] [50] [PS2] [KalturaStatement->execute] DEBUG: Sql took - 0.00038290023803711 seconds
2016-12-03 10:57:07 [0.000264] [96.48.8.194] [312091814] [51] [PS2] [KExternalErrors::dieError] ERR: exception ‘Exception’ with message ‘exiting on error 6 - requested flavor was not found’ in /opt/kaltura/app/infra/log/KalturaLog.php:83
Stack trace:
#0 /opt/kaltura/app/alpha/apps/kaltura/lib/KExternalErrors.class.php(128): KalturaLog::err(‘exiting on erro…’)

2016-12-03 11:16:17 [0.000981] [96.48.8.194] [1999188215] [10] [API] [KalturaStatement->execute] DEBUG: Sql took - 0.00078201293945312 seconds
2016-12-03 11:16:17 [0.000731] [96.48.8.194] [1999188215] [11] [API] [kPermissionManager::errorIfKsNotValid] ERR: exception ‘Exception’ with message ‘KS Expired [2016-12-03 11:16:14]’ in /opt/kaltura/app/infra/log/KalturaLog.php:83
Stack trace:
#0 /opt/kaltura/app/alpha/apps/kaltura/lib/kPermissionManager.php(715): KalturaLog::err(‘KS Expired [201…’)

#5 {main}
2016-12-03 11:16:17 [0.001094] [96.48.8.194] [1999188215] [12] [API] [kCoreException->__construct] ERR: exception ‘kCoreException’ with message ‘Invalid KS’ in /opt/kaltura/app/alpha/apps/kaltura/lib/kPermissionManager.php:731
Stack trace:
#0 /opt/kaltura/app/alpha/apps/kaltura/lib/kPermissionManager.php(485): kPermissionManager::errorIfKsNotValid()

Ramifications?
Thanks!


#2

Hi @merp,

"[KalturaFrontController->getExceptionObject] ERR: exception ‘KalturaAPIException’ with message ‘Action “trackevent” does not exists for service “analytics”’ in /opt/kaltura/app/api_v3/lib/KalturaDispatcher.php:57
Stack trace: "
Can be ignored, the service indeed does not exist in CE nor is it needed.

As for the KS errors, it’s hard to tell from the snippet you posted but one possible reason is actually browser side cache.
Is there an actual issue you’re seeing when working with the system or did you just run through the logs to ensure everything is in order and saw these?


#3

As I have a Content Provider testing adding content, I have kaltlog running.
I see he has tried uploading 4 content samples, and they are still on “Uploading”.
However, I just chatted with him, and all 4 uploads timed out/failed. They were about 1-2 GB each.


#4

Seeing these errors to:

==> /opt/kaltura/log/batch/bulkupload-0-2016-12-02.err.log <==
PHP Warning: Invalid argument supplied for foreach() in /opt/kaltura/app/plugins/bulk_upload/xml/batch/BulkUploadEngineXml.php on line 1027
PHP Warning: Invalid argument supplied for foreach() in /opt/kaltura/app/plugins/bulk_upload/xml/batch/BulkUploadEngineXml.php on line 1027
PHP Warning: Invalid argument supplied for foreach() in /opt/kaltura/app/plugins/bulk_upload/xml/batch/BulkUploadEngineXml.php on line 1027


==> /opt/kaltura/log/batch/import-0-2016-12-02.err.log <==
PHP Notice: curl_setopt(): CURLOPT_SSL_VERIFYHOST no longer accepts the value 1, value 2 will be used instead in /opt/kaltura/app/infra/general/KCurlWrapper.class.php on line 228
PHP Notice: curl_setopt(): CURLOPT_SSL_VERIFYHOST no longer accepts the value 1, value 2 will be used instead in /opt/kaltura/app/infra/general/KCurlWrapper.class.php on line 228
PHP Notice: curl_setopt(): CURLOPT_SSL_VERIFYHOST no longer accepts the value 1, value 2 will be used instead in /opt/kaltura/app/infra/general/KCurlWrapper.class.php on line 228


#5

I’m assuming the SSL error is insignificant, since we’re testing without SSL right now. But the bulk error seems related?


#6

Content Uploader reports this popup appearing every 30 seconds now:


#7

This is most recent from logs while the above happened:

2016-12-03 11:51:48 [0.000975] [96.48.8.194] [1850377346] [10] [API] [KalturaStatement->execute] DEBUG: Sql took - 0.00079584121704102 seconds
2016-12-03 11:51:48 [0.000744] [96.48.8.194] [1850377346] [11] [API] [kPermissionManager::errorIfKsNotValid] ERR: exception ‘Exception’ with message ‘KS Expired [2016-12-03 11:16:14]’ in /opt/kaltura/app/infra/log/KalturaLog.php:83
Stack trace:
#0 /opt/kaltura/app/alpha/apps/kaltura/lib/kPermissionManager.php(715): KalturaLog::err(‘KS Expired [201…’)

#5 {main}
2016-12-03 11:51:48 [0.000238] [96.48.8.194] [1850377346] [12] [API] [kCoreException->__construct] ERR: exception ‘kCoreException’ with message ‘Invalid KS’ in /opt/kaltura/app/alpha/apps/kaltura/lib/kPermissionManager.php:731
Stack trace:
#0 /opt/kaltura/app/alpha/apps/kaltura/lib/kPermissionManager.php(485): kPermissionManager::errorIfKsNotValid()

2016-12-03 11:53:49 [0.000951] [96.48.8.194] [1003014712] [10] [API] [KalturaStatement->execute] DEBUG: Sql took - 0.00076818466186523 seconds
2016-12-03 11:53:49 [0.000792] [96.48.8.194] [1003014712] [11] [API] [kPermissionManager::errorIfKsNotValid] ERR: exception ‘Exception’ with message ‘KS Expired [2016-12-03 11:16:14]’ in /opt/kaltura/app/infra/log/KalturaLog.php:83
Stack trace:
#0 /opt/kaltura/app/alpha/apps/kaltura/lib/kPermissionManager.php(715): KalturaLog::err(‘KS Expired [201…’)

#5 {main}
2016-12-03 11:53:49 [0.000275] [96.48.8.194] [1003014712] [12] [API] [kCoreException->__construct] ERR: exception ‘kCoreException’ with message ‘Invalid KS’ in /opt/kaltura/app/alpha/apps/kaltura/lib/kPermissionManager.php:731
Stack trace:
#0 /opt/kaltura/app/alpha/apps/kaltura/lib/kPermissionManager.php(485): kPermissionManager::errorIfKsNotValid()

        [tmp_name] => /opt/kaltura/web/tmp/phpnLAVqo
        [error] => 0
        [size] => 117368700


[tmp_name] => /opt/kaltura/web/tmp/phpnLAVqo
[error] => 0
[size] => 117368700

2016-12-03 11:54:01 [0.000942] [96.48.8.194] [792028079] [10] [API] [KalturaStatement->execute] DEBUG: Sql took - 0.00074315071105957 seconds
2016-12-03 11:54:01 [0.000692] [96.48.8.194] [792028079] [11] [API] [kPermissionManager::errorIfKsNotValid] ERR: exception ‘Exception’ with message ‘KS Expired [2016-12-03 11:16:14]’ in /opt/kaltura/app/infra/log/KalturaLog.php:83
Stack trace:
#0 /opt/kaltura/app/alpha/apps/kaltura/lib/kPermissionManager.php(715): KalturaLog::err(‘KS Expired [201…’)

#5 {main}
2016-12-03 11:54:01 [0.000283] [96.48.8.194] [792028079] [12] [API] [kCoreException->__construct] ERR: exception ‘kCoreException’ with message ‘Invalid KS’ in /opt/kaltura/app/alpha/apps/kaltura/lib/kPermissionManager.php:731
Stack trace:
#0 /opt/kaltura/app/alpha/apps/kaltura/lib/kPermissionManager.php(485): kPermissionManager::errorIfKsNotValid()


#8

Hello @merp,

When uploading from KMC, there is a limit of 2G per file.
If the files are bigger than that, you will need to either use the bulk upload service to upload a CSV/XML which includes the URL for the source video and some metatada [you can find an example by going to KMC->Upload menu->selecting “Entries CSV/XML” and clicking on “Download CSV/XML Samples”] or by setting up a dropfolder [see https://knowledge.kaltura.com/kaltura-drop-folders-service-content-ingestion-0] or by utilising the chunked upload mechanism and the JS code hosted here:
https://github.com/kaltura/chunked-file-upload-jquery

If the file is smaller than 2G and the batch job failed, I suggest you go to http://jessex/admin_console/index.php/batch/entry-investigation, input the entry ID and see exactly what failed in the process. You can also request an entry reconversion from that view.


#9

I forwarded your recommendations. He confirms they were all under 2 GB.


#10

When I go to: http://media.merp.com/admin_console/index.php/batch/entry-investigation

And enter one of the failed Entry IDs (same result for all 4):, all I get is: Entry not found: Invalid KS “EXPIRED”, Error “-1,INVALID_STR”

Which apparently is the same error he was getting on upload attempt fail.


#11

Please clear the browser’s cache, then re-login to admin console and go to the “Batch Process Control” tab.


#12

He verified that did solve the upload issue. Thanks!
What is the typical timeout for a session? Is it configurable? They have terabytes of video to upload, so having to refresh sessions so completely for every few 2 GB upload will be annoying, so if we can tweak for longer sessions, that would be most helpful. Is it possible?
I’ll post a new thread for the other issues he’s having with the player, embedding, etc.
Thanks again!


#13

I got this error when running ‘kaltlog’

ERR: exception ‘KalturaAPIException’ with message ‘Action “trackevent” does not exists for service “analytics”’ in /opt/kaltura/app/api_v3/lib/KalturaDispatcher.php:58

Then I found this note about an error around the same line.

My question for this is do you have or plan to implement in future versions something to disable the reporting of this error or add this feature to CE?


#14

HI @jess
Assuming this is being sent by the player, is there a way to disable it? It simply does a lot of unnecessary disk writes, on an heavy loaded server.


#15

Hi @oferc1,

See Disable player analytics