[Home]

Summary:ASTERISK-27132: Asterisk starts with multiple "undefined symbol" errors in pjsip modules
Reporter:Oleksandr Natalenko (post-factum)Labels:
Date Opened:2017-07-16 08:10:11Date Closed:2017-07-17 07:13:55
Priority:MajorRegression?Yes
Status:Closed/CompleteComponents:Resources/res_pjsip
Versions:14.6.0 Frequency of
Occurrence		Constant
Related
Issues:
duplicatesASTERISK-26518 Error loading module 'res_pjsip.so': undefined symbol: ast_sip_session_register_supplement
Environment:Arch Linux, x86_64, pjproject 2.6.Attachments:
Description:v14.6.0 cannot use pjsip anymore after upgrade from v14.5.0. Configs were untouched. Here is start log:

{code}
čec 16 14:48:45 server.example.com systemd[1]: Started Asterisk PBX and telephony daemon.
čec 16 14:48:46 server.example.com asterisk[2399]: PBX UUID: 2835ed90-e23e-407f-bc61-b97cb5975aa4
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] NOTICE[2399]: dnsmgr.c:494 do_reload: Managed DNS entries will
be refreshed every 300 seconds.
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] NOTICE[2399]: cdr.c:4213 cdr_toggle_runtime_options: CDR simpl
e logging enabled.
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] NOTICE[2399]: loader.c:1367 load_modules: 275 modules will be
loaded.
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading
module 'res_pjsip_one_touch_record_info.so': /usr/lib/asterisk/modules/res_pjsip_one_touch_record_info.so: undefined symbol: ast_sip_
session_register_supplement
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_logger.so': /usr/lib/asterisk/modules/res_pjsip_logger.so: undefined symbol: ast_sip_get_pjsip_endpoint
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_t38.so': /usr/lib/asterisk/modules/res_pjsip_t38.so: undefined symbol: ast_sip_session_resume_reinvite
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_refer.so': /usr/lib/asterisk/modules/res_pjsip_refer.so: undefined symbol: ast_copy_pj_str
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_sdp_rtp.so': /usr/lib/asterisk/modules/res_pjsip_sdp_rtp.so: undefined symbol: ast_copy_pj_str
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'func_pjsip_endpoint.so': /usr/lib/asterisk/modules/func_pjsip_endpoint.so: undefined symbol: ast_sip_get_sorcery
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_pidf_eyebeam_body_supplement.so': /usr/lib/asterisk/modules/res_pjsip_pidf_eyebeam_body_supplement.so: undefined symbol: ast_sip_presence_xml_create_node
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_notify.so': /usr/lib/asterisk/modules/res_pjsip_notify.so: undefined symbol: ast_sip_default_outbound_endpoint
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_transport_management.so': /usr/lib/asterisk/modules/res_pjsip_transport_management.so: undefined symbol: ast_sip_get_pjsip_endpoint
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_outbound_authenticator_digest.so': /usr/lib/asterisk/modules/res_pjsip_outbound_authenticator_digest.so: undefined symbol: ast_sip_unregister_outbound_authenticator
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_mwi.so': /usr/lib/asterisk/modules/res_pjsip_mwi.so: undefined symbol: ast_sip_create_request
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_mwi_body_generator.so': /usr/lib/asterisk/modules/res_pjsip_mwi_body_generator.so: undefined symbol: ast_sip_get_pjsip_endpoint
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip.so': /usr/lib/asterisk/modules/res_pjsip.so: undefined symbol: ast_sip_session_register_supplement
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_dlg_options.so': /usr/lib/asterisk/modules/res_pjsip_dlg_options.so: undefined symbol: ast_sip_session_register_supplement
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_exten_state.so': /usr/lib/asterisk/modules/res_pjsip_exten_state.so: undefined symbol: ast_sip_subscription_is_terminated
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_pidf_digium_body_supplement.so': /usr/lib/asterisk/modules/res_pjsip_pidf_digium_body_supplement.so: undefined symbol: ast_sip_presence_xml_create_node
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_caller_id.so': /usr/lib/asterisk/modules/res_pjsip_caller_id.so: undefined symbol: ast_copy_pj_str
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_registrar.so': /usr/lib/asterisk/modules/res_pjsip_registrar.so: undefined symbol: ast_sip_report_failed_acl
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_endpoint_identifier_anonymous.so': /usr/lib/asterisk/modules/res_pjsip_endpoint_identifier_anonymous.so: undefined symbol: ast_copy_pj_str
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_send_to_voicemail.so': /usr/lib/asterisk/modules/res_pjsip_send_to_voicemail.so: undefined symbol: ast_sip_session_register_supplement
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_nat.so': /usr/lib/asterisk/modules/res_pjsip_nat.so: undefined symbol: ast_sip_session_register_supplement
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_endpoint_identifier_ip.so': /usr/lib/asterisk/modules/res_pjsip_endpoint_identifier_ip.so: undefined symbol: ast_sip_cli_traverse_objects
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_authenticator_digest.so': /usr/lib/asterisk/modules/res_pjsip_authenticator_digest.so: undefined symbol: ast_sip_unregister_authenticator
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_acl.so': /usr/lib/asterisk/modules/res_pjsip_acl.so: undefined symbol: ast_copy_pj_str
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'func_pjsip_aor.so': /usr/lib/asterisk/modules/func_pjsip_aor.so: undefined symbol: ast_sip_location_retrieve_aor_contacts
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_header_funcs.so': /usr/lib/asterisk/modules/res_pjsip_header_funcs.so: undefined symbol: ast_sip_session_register_supplement
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_messaging.so': /usr/lib/asterisk/modules/res_pjsip_messaging.so: undefined symbol: ast_sip_default_outbound_endpoint
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_xpidf_body_generator.so': /usr/lib/asterisk/modules/res_pjsip_xpidf_body_generator.so: undefined symbol: ast_sip_presence_xml_create_attr
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_path.so': /usr/lib/asterisk/modules/res_pjsip_path.so: undefined symbol: ast_copy_pj_str
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_registrar_expire.so': /usr/lib/asterisk/modules/res_pjsip_registrar_expire.so: undefined symbol: ast_sip_location_delete_contact
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_dtmf_info.so': /usr/lib/asterisk/modules/res_pjsip_dtmf_info.so: undefined symbol: ast_sip_session_register_supplement
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_diversion.so': /usr/lib/asterisk/modules/res_pjsip_diversion.so: undefined symbol: ast_copy_pj_str
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_pubsub.so': /usr/lib/asterisk/modules/res_pjsip_pubsub.so: undefined symbol: ast_sip_get_distributor_serializer
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_dialog_info_body_generator.so': /usr/lib/asterisk/modules/res_pjsip_dialog_info_body_generator.so: undefined symbol: ast_sip_presence_xml_create_node
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_history.so': /usr/lib/asterisk/modules/res_pjsip_history.so: undefined symbol: ast_copy_pj_str
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_outbound_publish.so': /usr/lib/asterisk/modules/res_pjsip_outbound_publish.so: undefined symbol: ast_sip_push_task_synchronous
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_endpoint_identifier_user.so': /usr/lib/asterisk/modules/res_pjsip_endpoint_identifier_user.so: undefined symbol: ast_copy_pj_str
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'chan_pjsip.so': /usr/lib/asterisk/modules/chan_pjsip.so: undefined symbol: ast_sip_create_request
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_sips_contact.so': /usr/lib/asterisk/modules/res_pjsip_sips_contact.so: undefined symbol: ast_sip_get_pjsip_endpoint
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_config_wizard.so': /usr/lib/asterisk/modules/res_pjsip_config_wizard.so: undefined symbol: ast_sip_get_sorcery
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_outbound_registration.so': /usr/lib/asterisk/modules/res_pjsip_outbound_registration.so: undefined symbol: ast_sip_cli_traverse_objects
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_publish_asterisk.so': /usr/lib/asterisk/modules/res_pjsip_publish_asterisk.so: undefined symbol: ast_sip_register_publish_handler
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_session.so': /usr/lib/asterisk/modules/res_pjsip_session.so: undefined symbol: ast_sip_get_distributor_serializer
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'func_pjsip_contact.so': /usr/lib/asterisk/modules/func_pjsip_contact.so: undefined symbol: ast_sip_get_contact_status_label
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_pidf_body_generator.so': /usr/lib/asterisk/modules/res_pjsip_pidf_body_generator.so: undefined symbol: ast_sip_get_pjsip_endpoint
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_rfc3326.so': /usr/lib/asterisk/modules/res_pjsip_rfc3326.so: undefined symbol: ast_copy_pj_str
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_transport_websocket.so': /usr/lib/asterisk/modules/res_pjsip_transport_websocket.so: undefined symbol: ast_sip_create_serializer
čec 16 14:48:46 server.example.com asterisk[2399]: [Jul 16 14:48:46] WARNING[2399]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_empty_info.so': /usr/lib/asterisk/modules/res_pjsip_empty_info.so: undefined symbol: ast_sip_session_register_supplement
čec 16 14:48:47 server.example.com asterisk[2399]: [Jul 16 14:48:47] NOTICE[2399]: confbridge/conf_config_parser.c:2127 verify_default_profiles: Adding default_menu menu to app_confbridge
čec 16 14:48:47 server.example.com asterisk[2399]: [Jul 16 14:48:47] NOTICE[2399]: cel_custom.c:97 load_config: No mappings found in cel_custom.conf. Not logging CEL to custom CSVs.
čec 16 14:48:47 server.example.com asterisk[2399]: Asterisk Ready.
{code}

v14.5.0 starts OK with same configs. Here is how Asterisk was built:

{code}
./bootstrap.sh
./configure --prefix=/usr --sysconfdir=/etc --localstatedir=/var --sbindir=/usr/bin --with-libedit=internal
make menuselect.makeopts
menuselect/menuselect --disable BUILD_NATIVE
make
{code}

Unfortunately, checking git log does not give me any hint.
Comments:By: Asterisk Team (asteriskteam) 2017-07-16 08:10:13.834-0500

Thanks for creating a report! The issue has entered the triage process. That means the issue will wait in this status until a Bug Marshal has an opportunity to review the issue. Once the issue has been reviewed you will receive comments regarding the next steps towards resolution.

A good first step is for you to review the [Asterisk Issue Guidelines|https://wiki.asterisk.org/wiki/display/AST/Asterisk+Issue+Guidelines] if you haven't already. The guidelines detail what is expected from an Asterisk issue report.

Then, if you are submitting a patch, please review the [Patch Contribution Process|https://wiki.asterisk.org/wiki/display/AST/Patch+Contribution+Process].
By: Joshua C. Colp (jcolp) 2017-07-16 08:32:55.487-0500

What is your modules.conf? Are you explicitly loading modules in an order?
By: Oleksandr Natalenko (post-factum) 2017-07-16 09:15:17.625-0500

Modules autoload is enabled. I have some noloads as shown below, but unfortunately commenting everything out does not change things.

{code}
» cat /etc/asterisk/modules.conf | grep -vE '^;|^$'
[modules]
autoload=yes
noload => chan_skinny.so
noload => chan_mgcp.so
noload => pbx_dundi.so
noload => chan_unistim.so
noload => chan_iax2.so
noload => chan_sip.so
noload => res_hep.so
noload => res_hep_rtcp.so
noload => res_hep_pjsip.so
noload => res_config_ldap.so
noload => res_ari.so
noload => res_ari_applications.so
noload => res_ari_asterisk.so
noload => res_ari_bridges.so
noload => res_ari_channels.so
noload => res_ari_device_states.so
noload => res_ari_endpoints.so
noload => res_ari_events.so
noload => res_ari_model.so
noload => res_ari_playbacks.so
noload => res_ari_recordings.so
noload => res_ari_sounds.so
noload => res_phoneprov.so
noload => res_pjsip_phoneprov_provider.so
noload => pbx_gtkconsole.so
load => res_musiconhold.so
noload => chan_alsa.so
noload => chan_console.so
{code}
By: Oleksandr Natalenko (post-factum) 2017-07-17 07:08:09.861-0500

Trying to load modules manually.

{code}
server*CLI> module show like res_pjproject.so
Module                         Description                              Use Count  Status      Support Level
res_pjproject.so               PJPROJECT Log and Utility Support        0          Running              core
1 modules loaded

server*CLI> module load res_pjsip.so
Unable to load module res_pjsip.so
Command 'module load res_pjsip.so' failed.
[Jul 17 14:03:51] WARNING[6069]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip.so': /usr/lib/asterisk/modules/res_pjsip.so: undefined symbol: ast_sip_session_register_supplement
[Jul 17 14:03:51] WARNING[6069]: loader.c:1048 load_resource: Module 'res_pjsip.so' could not be loaded.

server*CLI> module load res_pjsip_session.so
Unable to load module res_pjsip_session.so
Command 'module load res_pjsip_session.so' failed.
[Jul 17 14:04:08] WARNING[6069]: loader.c:526 load_dynamic_module: Error loading module 'res_pjsip_session.so': /usr/lib/asterisk/modules/res_pjsip_session.so: undefined symbol: ast_sip_get_distributor_serializer
[Jul 17 14:04:08] WARNING[6069]: loader.c:1048 load_resource: Module 'res_pjsip_session.so' could not be loaded.
{code}

Looks like res_pjsip wants ast_sip_session_register_supplement, which resides in res_pjsip_session, but res_pjsip_session wants ast_sip_get_distributor_serializer, which resides in res_pjsip. Thus, circular dependency occurs. Am I correct?
By: Joshua C. Colp (jcolp) 2017-07-17 07:13:55.801-0500

Based on your analysis this appears to be a duplicate of ASTERISK-26518 which has already been accepted as an issue.