25 Feb
2018
25 Feb
'18
11:08 p.m.
On 7. Feb 2018, at 09:31, Holger Freyther
<holger(a)freyther.de> wrote:
Hey,
Knowing what I know now I would have gone for Go. It
is made for the kind of
concurrency where python seems unusable. I hesitated to build my own event
loop but will build something that looks like our C apps in python (single
select, no async/await).
finally... still plenty of things to improve and do... The next thing is to
improve "mobile" to not add the >30s timeout for first cell selection.
python3 -mosmo_ms_driver
22:59:56.255200 --- -: Going to store files in
{tmp_dir='/tmp/tmp5wbisu2hosmo-ms-driver'}
22:59:56.256180 run lu_test: Starting testcase
22:59:56.256813 run lu_test: Pre-launching all virtphy's
22:59:56.257391 run osmo-ms/00000: Starting virtphy process
[lu_test↪osmo-ms/00000]
22:59:56.258454 run osmo-ms/00000: virtphy
--l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00000 [lu_test↪osmo-ms/00000]
22:59:56.262654 run osmo-ms/00001: Starting virtphy process
[lu_test↪osmo-ms/00001]
22:59:56.262793 run osmo-ms/00001: virtphy
--l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00001 [lu_test↪osmo-ms/00001]
22:59:56.269087 run osmo-ms/00002: Starting virtphy process
[lu_test↪osmo-ms/00002]
22:59:56.269241 run osmo-ms/00002: virtphy
--l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00002 [lu_test↪osmo-ms/00002]
22:59:56.274869 run osmo-ms/00003: Starting virtphy process
[lu_test↪osmo-ms/00003]
22:59:56.275031 run osmo-ms/00003: virtphy
--l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00003 [lu_test↪osmo-ms/00003]
22:59:56.280416 run osmo-ms/00004: Starting virtphy process
[lu_test↪osmo-ms/00004]
22:59:56.280570 run osmo-ms/00004: virtphy
--l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00004 [lu_test↪osmo-ms/00004]
22:59:56.285699 run osmo-ms/00005: Starting virtphy process
[lu_test↪osmo-ms/00005]
22:59:56.285809 run osmo-ms/00005: virtphy
--l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00005 [lu_test↪osmo-ms/00005]
22:59:56.291308 run osmo-ms/00006: Starting virtphy process
[lu_test↪osmo-ms/00006]
22:59:56.291488 run osmo-ms/00006: virtphy
--l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00006 [lu_test↪osmo-ms/00006]
22:59:56.300046 run osmo-ms/00007: Starting virtphy process
[lu_test↪osmo-ms/00007]
22:59:56.300352 run osmo-ms/00007: virtphy
--l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00007 [lu_test↪osmo-ms/00007]
22:59:56.306980 run osmo-ms/00008: Starting virtphy process
[lu_test↪osmo-ms/00008]
22:59:56.307134 run osmo-ms/00008: virtphy
--l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00008 [lu_test↪osmo-ms/00008]
22:59:56.312373 run osmo-ms/00009: Starting virtphy process
[lu_test↪osmo-ms/00009]
22:59:56.312526 run osmo-ms/00009: virtphy
--l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00009 [lu_test↪osmo-ms/00009]
22:59:56.319714 run lu_test: Checking if sockets are in the
filesystem
22:59:56.539503 run osmo-ms/00000: Starting process
[lu_test↪osmo-ms/00000]
22:59:56.539977 run osmo-ms/00000: mobile -c
/tmp/tmp5wbisu2hosmo-ms-driver/mob_00000.cfg --vty-port=0 [lu_test↪osmo-ms/00000]
22:59:56.607714 run lu_test: MS start registered
{at=282743.473796635, delay=0.06289598299190402, ms=00000}
23:00:09.926047 run osmo-ms/00001: Starting process
[lu_test↪osmo-ms/00001]
23:00:09.926563 run osmo-ms/00001: mobile -c
/tmp/tmp5wbisu2hosmo-ms-driver/mob_00001.cfg --vty-port=0 [lu_test↪osmo-ms/00001]
23:00:09.994826 run lu_test: MS start registered
{at=282756.860905894, delay=0.06362233200343326, ms=00001}
23:00:15.424151 run osmo-ms/00002: Starting process
[lu_test↪osmo-ms/00002]
23:00:15.425052 run osmo-ms/00002: mobile -c
/tmp/tmp5wbisu2hosmo-ms-driver/mob_00002.cfg --vty-port=0 [lu_test↪osmo-ms/00002]
23:00:15.488956 run lu_test: MS start registered
{at=282762.355019533, delay=0.05711088899988681, ms=00002}
23:00:19.724246 run osmo-ms/00003: Starting process
[lu_test↪osmo-ms/00003]
23:00:19.724996 run osmo-ms/00003: mobile -c
/tmp/tmp5wbisu2hosmo-ms-driver/mob_00003.cfg --vty-port=0 [lu_test↪osmo-ms/00003]
23:00:19.785684 run lu_test: MS start registered
{at=282766.651760042, delay=0.05416889599291608, ms=00003}
23:00:23.325812 run osmo-ms/00004: Starting process
[lu_test↪osmo-ms/00004]
23:00:23.326760 run osmo-ms/00004: mobile -c
/tmp/tmp5wbisu2hosmo-ms-driver/mob_00004.cfg --vty-port=0 [lu_test↪osmo-ms/00004]
23:00:23.393305 run lu_test: MS start registered
{at=282770.259381962, delay=0.06159330299124122, ms=00004}
23:00:26.427752 run osmo-ms/00005: Starting process
[lu_test↪osmo-ms/00005]
23:00:26.428336 run osmo-ms/00005: mobile -c
/tmp/tmp5wbisu2hosmo-ms-driver/mob_00005.cfg --vty-port=0 [lu_test↪osmo-ms/00005]
23:00:26.494263 run lu_test: MS start registered
{at=282773.360336693, delay=0.059708705986849964, ms=00005}
23:00:29.627894 run osmo-ms/00006: Starting process
[lu_test↪osmo-ms/00006]
23:00:29.628553 run osmo-ms/00006: mobile -c
/tmp/tmp5wbisu2hosmo-ms-driver/mob_00006.cfg --vty-port=0 [lu_test↪osmo-ms/00006]
23:00:29.683842 run lu_test: MS start registered
{at=282776.54991861, delay=0.04898471699561924, ms=00006}
23:00:33.226115 run osmo-ms/00007: Starting process
[lu_test↪osmo-ms/00007]
23:00:33.226723 run osmo-ms/00007: mobile -c
/tmp/tmp5wbisu2hosmo-ms-driver/mob_00007.cfg --vty-port=0 [lu_test↪osmo-ms/00007]
23:00:33.288238 run lu_test: MS start registered
{at=282780.154311069, delay=0.055712109024170786, ms=00007}
23:00:34.052813 run lu_test: MS performed LU
{at=282780.918918862, lu_delay=37.44512222701451, ms=00000}
23:00:37.525812 run osmo-ms/00008: Starting process
[lu_test↪osmo-ms/00008]
23:00:37.526370 run osmo-ms/00008: mobile -c
/tmp/tmp5wbisu2hosmo-ms-driver/mob_00008.cfg --vty-port=0 [lu_test↪osmo-ms/00008]
23:00:37.590594 run lu_test: MS start registered
{at=282784.456672404, delay=0.05611523304833099, ms=00008}
23:00:43.028214 run osmo-ms/00009: Starting process
[lu_test↪osmo-ms/00009]
23:00:43.028898 run osmo-ms/00009: mobile -c
/tmp/tmp5wbisu2hosmo-ms-driver/mob_00009.cfg --vty-port=0 [lu_test↪osmo-ms/00009]
23:00:43.087549 run lu_test: MS start registered
{at=282789.95362104, delay=0.05322436196729541, ms=00009}
23:00:43.087727 run lu_test: All started...
{duration=46.56737156602321, too_slow=0}
23:00:47.231257 run lu_test: MS performed LU
{at=282794.097388755, lu_delay=37.23648286098614, ms=00001}
23:00:52.878843 run lu_test: MS performed LU
{at=282799.744935023, lu_delay=37.38991548999911, ms=00002}
23:00:56.661687 run lu_test: MS performed LU
{at=282803.527769994, lu_delay=36.87600995198591, ms=00003}
23:01:00.411273 run lu_test: MS performed LU
{at=282807.277505226, lu_delay=37.01812326401705, ms=00004}
23:01:04.194030 run lu_test: MS performed LU
{at=282811.06022426, lu_delay=37.699887567025144, ms=00005}
23:01:06.060154 run lu_test: MS performed LU
{at=282812.926287126, lu_delay=36.37636851600837, ms=00006}
23:01:09.847484 run lu_test: MS performed LU
{at=282816.713489132, lu_delay=36.559178063005675, ms=00007}
23:01:15.475567 run lu_test: MS performed LU
{at=282822.341696909, lu_delay=37.8850245049689, ms=00008}
23:01:21.124497 run lu_test: MS performed LU
{at=282827.990630504, lu_delay=38.037009463994764, ms=00009}
23:01:56.525619 run lu_test: Tests done {all_completed=True,
max=38.037009463994764, min=36.37636851600837}