hss log

nano@nano:~/openair-cn/scripts$ ./run_hss
OPENAIRCN_DIR   = /home/nano/openair-cn
[sudo] nano 的密码：
==== EURECOM OPENAIR-HSS vBranch: develop Abrev. Hash: 67180ca Date: Tue May 30 10:16:31 2017 +0200 ====
Please report any bug to: [e-mail]

Parsing configuration file: /usr/local/etc/oai/hss.conf
Configuration
* Global:
    - File .............: /usr/local/etc/oai/hss.conf
* MYSQL:
    - Server ...........: 127.0.0.1
    - Database .........: oai_db
    - User .............: root
    - Password .........: *****
* FreeDiameter:
    - Conf file ........: /usr/local/etc/oai/freeDiameter/hss_fd.conf
* Security:
    - Operator key......: None
    - Random      ......: true
Initializing db layer
Initializing db layer: DONE
Initialized random
Initializing s6a layer
07/19/17,17:54:53.459762  NOTI   libfdproto '1.2.0' initialized.
07/19/17,17:54:53.459862  NOTI   libgnutls '3.5.6' initialized.
07/19/17,17:54:53.460494   DBG   Core state: 0 -> 1
07/19/17,17:54:53.460510  NOTI   libfdcore '1.2.0' initialized.
07/19/17,17:54:53.530154   DBG   Generating fresh Diffie-Hellman parameters of size 1024 (this takes some time)...
07/19/17,17:54:53.580751   DBG   Loading : /usr/lib/freeDiameter/acl_wl.fdx
07/19/17,17:54:53.586498  NOTI   Extension ACL_wl initialized with configuration: '/usr/local/etc/oai/freeDiameter/acl.conf'
07/19/17,17:54:53.586563   DBG   Loading : /usr/lib/freeDiameter/dict_nas_mipv6.fdx
07/19/17,17:54:53.594298   DBG   Dictionary Extension 'MIPv6 NAS-to-HAAA Interaction' initialized
07/19/17,17:54:53.594378   DBG   Loading : /usr/lib/freeDiameter/dict_s6a.fdx
07/19/17,17:54:53.603455  NOTI   Dictionary Extension 'S6A from 3GPP standard v.10.5' initialized
07/19/17,17:54:53.603527  NOTI   All extensions loaded.
07/19/17,17:54:53.603575  NOTI   freeDiameter configuration:
07/19/17,17:54:53.603595  NOTI     Default trace level .... : +1
07/19/17,17:54:53.603612  NOTI     Configuration file ..... : /usr/local/etc/oai/freeDiameter/hss_fd.conf
07/19/17,17:54:53.603629  NOTI     Diameter Identity ...... : hss.OpenAir5G.Alliance (l:22)
07/19/17,17:54:53.603646  NOTI     Diameter Realm ......... : OpenAir5G.Alliance (l:18)
07/19/17,17:54:53.603663  NOTI     Tc Timer ............... : 30
07/19/17,17:54:53.603679  NOTI     Tw Timer ............... : 30
07/19/17,17:54:53.603696  NOTI     Local port ............. : 3868
07/19/17,17:54:53.603713  NOTI     Local secure port ...... : 5868
07/19/17,17:54:53.603729  NOTI     Number of SCTP streams . : 3
07/19/17,17:54:53.603746  NOTI     Number of clients thr .. : 5
07/19/17,17:54:53.603763  NOTI     Number of app threads .. : 4
07/19/17,17:54:53.603779  NOTI     Local endpoints ........ : Default (use all available)
07/19/17,17:54:53.603796  NOTI     Local applications ..... : (none)
07/19/17,17:54:53.603812  NOTI     Flags : - IP ........... : Enabled
07/19/17,17:54:53.603829  NOTI             - IPv6 ......... : DISABLED
07/19/17,17:54:53.603845  NOTI             - Relay app .... : DISABLED
07/19/17,17:54:53.603862  NOTI             - TCP .......... : Enabled
07/19/17,17:54:53.603879  NOTI             - SCTP ......... : DISABLED
07/19/17,17:54:53.603895  NOTI             - Pref. proto .. : TCP
07/19/17,17:54:53.603912  NOTI             - TLS method ... : Separate port
07/19/17,17:54:53.603928  NOTI     TLS :   - Certificate .. : /usr/local/etc/oai/freeDiameter/hss.cert.pem
07/19/17,17:54:53.603945  NOTI             - Private key .. : /usr/local/etc/oai/freeDiameter/hss.key.pem
07/19/17,17:54:53.603962  NOTI             - CA (trust) ... : /usr/local/etc/oai/freeDiameter/hss.cacert.pem (1 certs)
07/19/17,17:54:53.603979  NOTI             - CRL .......... : (none)
07/19/17,17:54:53.603996  NOTI             - Priority ..... : (default: 'NORMAL')
07/19/17,17:54:53.604012  NOTI             - DH bits ...... : 1024
07/19/17,17:54:53.604032  NOTI     Origin-State-Id ........ : 1500458093
07/19/17,17:54:53.604053  NOTI   Loaded extensions: '/usr/lib/freeDiameter/acl_wl.fdx'[/usr/local/etc/oai/freeDiameter/acl.conf], loaded
07/19/17,17:54:53.604071  NOTI   Loaded extensions: '/usr/lib/freeDiameter/dict_nas_mipv6.fdx'[(no config file)], loaded
07/19/17,17:54:53.604088  NOTI   Loaded extensions: '/usr/lib/freeDiameter/dict_s6a.fdx'[(no config file)], loaded
07/19/17,17:54:53.604120   DBG   Core state: 1 -> 2
07/19/17,17:54:53.604921  NOTI   Local server address(es): 192.168.1.143{---L-}
07/19/17,17:54:53.605004   DBG   Core state: 2 -> 3
Initializing s6a layer: DONE
07/19/17,17:55:08.936221   DBG   Incoming connection: '{----} TCP srv [0.0.0.0]:3868 (5)' <- 'localhost'   {{----} TCP from [127.0.0.1]:47280 (5<-6)}
07/19/17,17:55:08.936450   DBG   RCV from '<unknown>': (no model)0/257 f:R--- src:'(nil)' len:208 {C:264/l:30,C:296/l:26,C:278/l:12,C:257/l:14,C:266/l:12,C:269/l:20,C:267/l:12,C:299/l:12,C:260/l:32,C:265/l:12}
07/19/17,17:55:08.936481   DBG   Created new peer object for incoming CER: mme.OpenAir5G.Alliance
Query: SELECT idmmeidentity FROM mmeidentity WHERE mmeidentity.mmehost='mme.OpenAir5G.Alliance'
Accepting mme.OpenAir5G.Alliance peer
07/19/17,17:55:08.991030  NOTI   Connected to 'mme.OpenAir5G.Alliance' (TCP,soc#6), remote capabilities:
07/19/17,17:55:08.991052  NOTI      'Capabilities-Exchange-Request'
07/19/17,17:55:08.991060  NOTI        Version: 0x01
07/19/17,17:55:08.991066  NOTI        Length: 208
07/19/17,17:55:08.991072  NOTI        Flags: 0x80 (R---)
07/19/17,17:55:08.991078  NOTI        Command Code: 257
07/19/17,17:55:08.991084  NOTI        ApplicationId: 0
07/19/17,17:55:08.991090  NOTI        Hop-by-Hop Identifier: 0x5267D52E
07/19/17,17:55:08.991096  NOTI        End-to-End Identifier: 0xC7CC5DD4
07/19/17,17:55:08.991103  NOTI         {internal data}: src:(nil)(0) rwb:(nil) rt:0 cb:(nil),(nil)((nil)) qry:(nil) asso:1 sess:(nil)
07/19/17,17:55:08.991110  NOTI         AVP: 'Origin-Host'(264) l=30 f=-M val="mme.OpenAir5G.Alliance"
07/19/17,17:55:08.991114  NOTI         AVP: 'Origin-Realm'(296) l=26 f=-M val="OpenAir5G.Alliance"
07/19/17,17:55:08.991120  NOTI         AVP: 'Origin-State-Id'(278) l=12 f=-M val=1500458108 (0x596f2c7c)
07/19/17,17:55:08.991125  NOTI         AVP: 'Host-IP-Address'(257) l=14 f=-M val=192.168.1.143
07/19/17,17:55:08.991131  NOTI         AVP: 'Vendor-Id'(266) l=12 f=-M val=0 (0x0)
07/19/17,17:55:08.991135  NOTI         AVP: 'Product-Name'(269) l=20 f=-- val="freeDiameter"
07/19/17,17:55:08.991141  NOTI         AVP: 'Firmware-Revision'(267) l=12 f=-- val=10200 (0x27d8)
07/19/17,17:55:08.991148  NOTI         AVP: 'Inband-Security-Id'(299) l=12 f=-M val='NO_INBAND_SECURITY' (0 (0x0))
07/19/17,17:55:08.991154  NOTI         AVP: 'Vendor-Specific-Application-Id'(260) l=32 f=-M val=(grouped)
07/19/17,17:55:08.991161  NOTI            AVP: 'Auth-Application-Id'(258) l=12 f=-M val=16777251 (0x1000023)
07/19/17,17:55:08.991167  NOTI            AVP: 'Vendor-Id'(266) l=12 f=-M val=10415 (0x28af)
07/19/17,17:55:08.991173  NOTI         AVP: 'Supported-Vendor-Id'(265) l=12 f=-M val=10415 (0x28af)
07/19/17,17:55:08.991201   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Capabilities-Exchange-Answer'0/257 f:---- src:'(nil)' len:208 {C:268/l:12,C:264/l:30,C:296/l:26,C:278/l:12,C:257/l:14,C:266/l:12,C:269/l:20,C:267/l:12,C:260/l:32,C:265/l:12}
07/19/17,17:55:08.991242  NOTI   No TLS protection negotiated with peer 'mme.OpenAir5G.Alliance'.
07/19/17,17:55:08.991385  NOTI   'STATE_CLOSED'    -> 'STATE_OPEN'    'mme.OpenAir5G.Alliance'
^A07/19/17,17:55:40.472802   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Device-Watchdog-Request'0/280 f:R--- src:'(nil)' len:92 {C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:55:40.473537   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)0/280 f:---- src:'mme.OpenAir5G.Alliance' len:104 {C:268/l:12,C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:56:08.583816   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Device-Watchdog-Request'0/280 f:R--- src:'(nil)' len:92 {C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:56:08.584422   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)0/280 f:---- src:'mme.OpenAir5G.Alliance' len:104 {C:268/l:12,C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:56:36.726811   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Device-Watchdog-Request'0/280 f:R--- src:'(nil)' len:92 {C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:56:36.727035   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)0/280 f:---- src:'mme.OpenAir5G.Alliance' len:104 {C:268/l:12,C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:57:07.376917   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)0/280 f:R--- src:'mme.OpenAir5G.Alliance' len:92 {C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:57:07.376982   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Device-Watchdog-Answer'0/280 f:---- src:'(nil)' len:104 {C:268/l:12,C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:57:32.241851   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)16777251/318 f:RP-- src:'mme.OpenAir5G.Alliance' len:288 {C:263/l:50,C:277/l:12,C:264/l:30,C:296/l:26,C:293/l:30,C:283/l:26,C:1/l:23,V:10415/C:1407/l:15,V:10415/C:1408/l:44}
07/19/17,17:57:32.241898   DBG   DISPATCHING: (no model)16777251/318 f:RP-- src:'mme.OpenAir5G.Alliance' len:288 {C:263/l:50,C:277/l:12,C:264/l:30,C:296/l:26,C:293/l:30,C:283/l:26,C:1/l:23,V:10415/C:1407/l:15,V:10415/C:1408/l:44,C:282/l:8}
Converted 02f829 to plmn 208.92
Query: SELECT `key`,`sqn`,`rand`,`OPc` FROM `users` WHERE `users`.`imsi`='208920100001100'
Key: 68.74.73.69.69.20.20.73.79.6d.4b.20.79.65.0a.73.
Received SQN 00000000000000000831 converted to 831
SQN: 00.00.00.00.03.3f.
RAND: 5c.c6.6b.00.d8.85.aa.49.d1.65.80.45.a4.6a.85.7c.
OPc: 50.4f.20.63.4f.63.20.50.4f.50.20.63.63.50.0a.4f.
Generated random
RijndaelKeySchedule: K 6874736969202073796D4B2079650A73
MAC_A   : 23.b3.82.88.33.33.3f.aa.
SQN     : 00.00.00.00.03.3f.
RAND    : cf.58.d4.40.93.0d.6b.0b.f2.64.8c.4f.08.7e.53.47.
RijndaelKeySchedule: K 6874736969202073796D4B2079650A73
AK      : 2f.63.7f.04.e3.4f.
CK      : 11.b2.90.a3.b8.90.6d.5d.7b.16.9b.96.04.69.b5.01.
IK      : f0.54.ca.0c.37.29.c4.c4.5b.d6.1d.21.3c.20.9e.ac.
XRES    : b5.6f.4b.61.89.73.2e.6d.
AUTN    : 2f.63.7f.04.e0.70.80.00.23.b3.82.88.33.33.3f.aa.
0x11 0xb2 0x90 0xa3 0xb8 0x90 0x6d 0x5d 0x7b 0x16 0x9b 0x96 0x04 0x69 0xb5 0x01 0xf0 0x54 0xca 0x0c 0x37 0x29 0xc4 0xc4 0x5b 0xd6 0x1d 0x21 0x3c 0x20 0x9e 0xac
0x10 0x02 0xf8 0x29 0x00 0x03 0x2f 0x63 0x7f 0x04 0xe0 0x70 0x00 0x06
KASME   : 2a.a8.69.ce.2a.66.93.a1.37.10.4a.a1.a7.8a.48.6d.b1.9b.f7.dc.7c.3c.29.75.63.d3.00.8c.9f.ff.48.7c.
Query: UPDATE `users` SET `rand`=UNHEX('cf58d440930d6b0bf2648c4f087e5347'),`sqn`=831 WHERE `users`.`imsi`='208920100001100'
1 rows affected
Query: UPDATE `users` SET `sqn` = `sqn` + 32 WHERE `users`.`imsi`='208920100001100'
1 rows affected
07/19/17,17:57:32.350974   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Authentication-Information-Answer'16777251/318 f:-P-- src:'(nil)' len:300 {C:263/l:50,V:10415/C:1413/l:144,C:277/l:12,C:264/l:30,C:296/l:26,C:268/l:12}
07/19/17,17:57:32.482016   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)16777251/316 f:RP-- src:'mme.OpenAir5G.Alliance' len:276 {C:263/l:50,C:277/l:12,C:264/l:30,C:296/l:26,C:293/l:30,C:283/l:26,C:1/l:23,V:10415/C:1407/l:15,V:10415/C:1032/l:16,V:10415/C:1405/l:16}
07/19/17,17:57:32.482053   DBG   DISPATCHING: (no model)16777251/316 f:RP-- src:'mme.OpenAir5G.Alliance' len:276 {C:263/l:50,C:277/l:12,C:264/l:30,C:296/l:26,C:293/l:30,C:283/l:26,C:1/l:23,V:10415/C:1407/l:15,V:10415/C:1032/l:16,V:10415/C:1405/l:16,C:282/l:8}
Received new update location request
Query: SELECT `access_restriction`,`mmeidentity_idmmeidentity`,`msisdn`,`ue_ambr_ul`,`ue_ambr_dl`,`rau_tau_timer` FROM `users` WHERE `users`.`imsi`='208920100001100'
Query: SELECT mmehost,mmerealm FROM mmeidentity WHERE mmeidentity.idmmeidentity='1'
Query: INSERT INTO `mmeidentity` (`mmehost`,`mmerealm`) SELECT 'mme.OpenAir5G.Alliance','OpenAir5G.Alliance' FROM `mmeidentity` WHERE NOT EXISTS (SELECT * FROM `mmeidentity` WHERE `mmehost`='mme.OpenAir5G.Alliance' AND `mmerealm`='OpenAir5G.Alliance') LIMIT 1;UPDATE `users`,`mmeidentity` SET `users`.`mmeidentity_idmmeidentity`=`mmeidentity`.`idmmeidentity`, `users`.`ms_ps_status`="NOT_PURGED" WHERE `users`.`imsi`='208920100001100' AND `mmeidentity`.`mmehost`='mme.OpenAir5G.Alliance' AND `mmeidentity`.`mmerealm`='OpenAir5G.Alliance'
0 rows affected
0 rows affected
Query: SELECT * FROM `pdn` WHERE `pdn`.`users_imsi`=208920100001100 LIMIT 10;
07/19/17,17:57:32.483561   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Update-Location-Answer'16777251/316 f:-P-- src:'(nil)' len:552 {C:263/l:50,V:10415/C:1406/l:16,V:10415/C:1400/l:380,C:277/l:12,C:264/l:30,C:296/l:26,C:268/l:12}
07/19/17,17:58:03.748024   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Device-Watchdog-Request'0/280 f:R--- src:'(nil)' len:92 {C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:58:03.748266   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)0/280 f:---- src:'mme.OpenAir5G.Alliance' len:104 {C:268/l:12,C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:58:32.288978   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)0/280 f:R--- src:'mme.OpenAir5G.Alliance' len:92 {C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:58:32.289065   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Device-Watchdog-Answer'0/280 f:---- src:'(nil)' len:104 {C:268/l:12,C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:59:00.592811   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)0/280 f:R--- src:'mme.OpenAir5G.Alliance' len:92 {C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:59:00.593006   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Device-Watchdog-Answer'0/280 f:---- src:'(nil)' len:104 {C:268/l:12,C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:59:28.932049   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Device-Watchdog-Request'0/280 f:R--- src:'(nil)' len:92 {C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:59:28.932298   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)0/280 f:---- src:'mme.OpenAir5G.Alliance' len:104 {C:268/l:12,C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:59:57.691130   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Device-Watchdog-Request'0/280 f:R--- src:'(nil)' len:92 {C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,17:59:57.691349   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)0/280 f:---- src:'mme.OpenAir5G.Alliance' len:104 {C:268/l:12,C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,18:00:28.306754   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Device-Watchdog-Request'0/280 f:R--- src:'(nil)' len:92 {C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,18:00:28.306960   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)0/280 f:---- src:'mme.OpenAir5G.Alliance' len:104 {C:268/l:12,C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,18:00:58.540779   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)0/280 f:R--- src:'mme.OpenAir5G.Alliance' len:92 {C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,18:00:58.540824   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Device-Watchdog-Answer'0/280 f:---- src:'(nil)' len:104 {C:268/l:12,C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,18:01:28.371107   DBG   SENT to 'mme.OpenAir5G.Alliance': 'Device-Watchdog-Request'0/280 f:R--- src:'(nil)' len:92 {C:264/l:30,C:296/l:26,C:278/l:12}
07/19/17,18:01:28.371305   DBG   RCV from 'mme.OpenAir5G.Alliance': (no model)0/280 f:---- src:'mme.OpenAir5G.Alliance' len:104 {C:268/l:12,C:264/l:30,C:296/l:26,C:278/l:12}