Bug #2326
closedofono dbus interaction: schedule_scan_register() hits self.dbus == None
100%
Description
see http://jenkins.osmocom.org/jenkins/view/osmo-gsm-tester/job/osmo-gsm-tester_run/597/
and the detailed log at http://jenkins.osmocom.org/jenkins/view/osmo-gsm-tester/job/osmo-gsm-tester_run/597/artifact/trial-597/last_run/log
It is actually a scan started in the sms:trx test run that causes a failure way later during aoip_sms:trx.
i.e. a complete test suite has already finished and concluded, and after a few minutes we still receive a callback result.
At this point the corresponding modem object's dbus member has already been cleaned up and is None.
Updated by neels almost 7 years ago
---------------------------------------------- trial-597 aoip_sms:trx mo_mt_sms.py ---------------------------------------------- [...] 20:09:25 run create_hlr_db: DBG: /home/jenkins/workspace/osmo-gsm-tester_run/trial-597/run.2017-06-13_20-05-36/osmo-hlr_10.42.42_2.2/create_hlr_db/stderr [trial-597↪aoip_sms:trx↪osmo-hlr_10.42.42.2↪create_hlr_db] [process.py:57] 20:09:25 run create_hlr_db(pid=16995): Launched [trial-597↪aoip_sms:trx↪osmo-hlr_10.42.42.2↪create_hlr_db(pid=16995)] [process.py:78] 20:09:25 tst /sierra_1: DBG: scanned operators: [('/sierra_1/operator/26203', {'Status': 'forbidden', 'Name': 'E-Plus', 'MobileCountryCode': '262', 'Technologies': ['gsm', 'umts', 'lte'], 'MobileNetworkCode': '03'}), ('/sierra_1/operator/26201', {'Status': 'forbidden', 'Name': 'TDG', 'MobileCountryCode': '262', 'Technologies': ['gsm', 'umts', 'lte'], 'MobileNetworkCode': '01'}), ('/sierra_1/operator/26202', {'Status': 'forbidden', 'Name': 'Vodafone', 'MobileCountryCode': '262', 'Technologies': ['gsm', 'umts', 'lte'], 'MobileNetworkCode': '02'})] [trial-597↪sms:trx↪/sierra_1] [ofono_client.py:440] 20:09:25 tst /sierra_1: DBG: Failed to find Network Operator {attempts=1, mcc_mnc=('901', '70')} [trial-597↪sms:trx↪/sierra_1] [ofono_client.py:455] 20:09:25 tst mo_mt_sms.py:13: ERR: AttributeError: 'NoneType' object has no attribute 'interface' [trial-597↪aoip_sms:trx↪mo_mt_sms.py:13] [ofono_client.py:403: netreg = self.dbus.interface(I_NETREG)] 20:09:25 tst mo_mt_sms.py:13: TRACEBACK: Traceback (most recent call last): File "/home/jenkins/workspace/osmo-gsm-tester_run/osmo-gsm-tester/src/osmo_gsm_tester/suite.py", line 98, in run self.path) File "/home/jenkins/workspace/osmo-gsm-tester_run/osmo-gsm-tester/src/osmo_gsm_tester/util.py", line 282, in run_python_file SourceFileLoader(module_name, path).load_module() File "<frozen importlib._bootstrap>", line 539, in _check_name_wrapper File "<frozen importlib._bootstrap>", line 1614, in load_module File "<frozen importlib._bootstrap>", line 593, in _load_module_shim File "<frozen importlib._bootstrap>", line 1153, in exec File "<frozen importlib._bootstrap>", line 1129, in _exec File "<frozen importlib._bootstrap>", line 1471, in exec_module File "<frozen importlib._bootstrap>", line 321, in _call_with_frames_removed File "/home/jenkins/workspace/osmo-gsm-tester_run/osmo-gsm-tester/suites/aoip_sms/mo_mt_sms.py", line 13, in <module> hlr.start() File "/home/jenkins/workspace/osmo-gsm-tester_run/osmo-gsm-tester/src/osmo_gsm_tester/osmo_hlr.py", line 59, in start self.run_local('create_hlr_db', ('/bin/sh', '-c', 'sqlite3 %r < %r' % (self.db_file, sql_input))) File "/home/jenkins/workspace/osmo-gsm-tester_run/osmo-gsm-tester/src/osmo_gsm_tester/osmo_hlr.py", line 101, in run_local proc.wait() File "/home/jenkins/workspace/osmo-gsm-tester_run/osmo-gsm-tester/src/osmo_gsm_tester/process.py", line 206, in wait event_loop.wait(self, self.terminated, timeout=timeout) File "/home/jenkins/workspace/osmo-gsm-tester_run/osmo-gsm-tester/src/osmo_gsm_tester/event_loop.py", line 59, in wait if not wait_no_raise(log_obj, condition, condition_args, condition_kwargs, timeout, timestep): File "/home/jenkins/workspace/osmo-gsm-tester_run/osmo-gsm-tester/src/osmo_gsm_tester/event_loop.py", line 50, in wait_no_raise poll() File "/home/jenkins/workspace/osmo-gsm-tester_run/osmo-gsm-tester/src/osmo_gsm_tester/event_loop.py", line 39, in poll func() File "/home/jenkins/workspace/osmo-gsm-tester_run/osmo-gsm-tester/src/osmo_gsm_tester/ofono_client.py", line 74, in poll_glib DeferredHandling.handle_queue() File "/home/jenkins/workspace/osmo-gsm-tester_run/osmo-gsm-tester/src/osmo_gsm_tester/ofono_client.py", line 56, in handle_queue handler(*args, **kwargs) File "/home/jenkins/workspace/osmo-gsm-tester_run/osmo-gsm-tester/src/osmo_gsm_tester/ofono_client.py", line 456, in scan_cb_register self.schedule_scan_register(mcc_mnc) File "/home/jenkins/workspace/osmo-gsm-tester_run/osmo-gsm-tester/src/osmo_gsm_tester/ofono_client.py", line 403, in schedule_scan_register netreg = self.dbus.interface(I_NETREG) AttributeError: 'NoneType' object has no attribute 'interface' [trial-597↪aoip_sms:trx↪mo_mt_sms.py:13] [suite.py:141] 20:09:25 tst mo_mt_sms.py:13: Test FAILED (2.4 sec) [trial-597↪aoip_sms:trx↪mo_mt_sms.py:13] [suite.py:142]
Updated by neels almost 7 years ago
i.e. this line shows the scan request came from the sms:trx suite run:
20:09:25 tst /sierra_1: DBG: Failed to find Network Operator {attempts=1, mcc_mnc=('901', '70')} [trial-597↪sms:trx↪/sierra_1] [ofono_client.py:455]
but the error triggers an exception during poll() of the aoip_sms:trx run:
20:09:25 tst mo_mt_sms.py:13: ERR: AttributeError: 'NoneType' object has no attribute 'interface' [trial-597↪aoip_sms:trx↪mo_mt_sms.py:13] [ofono_client.py:403: netreg = self.dbus.interface(I_NETREG)]
Updated by pespin almost 7 years ago
- Status changed from New to Feedback
Follow patch should fix the issue: https://gerrit.osmocom.org/#/c/2910
Updated by pespin almost 7 years ago
- Status changed from Feedback to Resolved
- % Done changed from 0 to 100
Merged in osmo-gsm-tester fbecf41f0135b9aa714904dea12b7c5fa6cec500