This discussion has been locked.
You can no longer post new replies to this discussion. If you have a question you can start a new discussion

ap50 is inactive

Hi

My astaro tells me our new ap50 is inactive. (On the Wireless Security Tab: AP50 is inactive, Location: IT)

On the log I get the following:



2012:06:22-14:36:22 fw001 awed[16912]: access point firmware available: AP10:2015 AP50:2015 AP30:2015
2012:06:22-14:36:23 fw001 awed[16912]: configuration: 1 APs, 1 networks, 1 network groups
2012:06:22-14:36:23 fw001 awed[16912]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:36:47 fw001 awed[16912]: new connection from 10.64.32.1:52042
2012:06:22-14:36:47 fw001 awed[16912]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:37:03 fw001 awed[17052]: Awed version 1.4.2.57.2.19 starting
2012:06:22-14:37:03 fw001 awed[17052]: access point firmware available: AP10:2015 AP50:2015 AP30:2015
2012:06:22-14:37:04 fw001 awed[17052]: configuration: 1 APs, 1 networks, 1 network groups
2012:06:22-14:37:04 fw001 awed[17052]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:37:30 fw001 awed[17052]: new connection from 10.64.32.1:39322
2012:06:22-14:37:30 fw001 awed[17052]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:37:45 fw001 awed[17198]: Awed version 1.4.2.57.2.19 starting
2012:06:22-14:37:45 fw001 awed[17198]: access point firmware available: AP10:2015 AP50:2015 AP30:2015
2012:06:22-14:37:46 fw001 awed[17198]: configuration: 1 APs, 1 networks, 1 network groups
2012:06:22-14:37:46 fw001 awed[17198]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:38:10 fw001 awed[17198]: new connection from 10.64.32.1:43572
2012:06:22-14:38:10 fw001 awed[17198]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:38:26 fw001 awed[17326]: Awed version 1.4.2.57.2.19 starting
2012:06:22-14:38:26 fw001 awed[17326]: access point firmware available: AP10:2015 AP50:2015 AP30:2015
2012:06:22-14:38:27 fw001 awed[17326]: configuration: 1 APs, 1 networks, 1 network groups
2012:06:22-14:38:27 fw001 awed[17326]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:38:49 fw001 awed[17326]: new connection from 10.64.32.1:39322
2012:06:22-14:38:49 fw001 awed[17326]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:39:02 fw001 awed[17457]: Awed version 1.4.2.57.2.19 starting
2012:06:22-14:39:02 fw001 awed[17457]: access point firmware available: AP10:2015 AP50:2015 AP30:2015
2012:06:22-14:39:03 fw001 awed[17457]: configuration: 1 APs, 1 networks, 1 network groups
2012:06:22-14:39:03 fw001 awed[17457]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:39:27 fw001 awed[17457]: new connection from 10.64.32.1:37371
2012:06:22-14:39:27 fw001 awed[17457]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:39:43 fw001 awed[17598]: Awed version 1.4.2.57.2.19 starting
2012:06:22-14:39:43 fw001 awed[17598]: access point firmware available: AP10:2015 AP50:2015 AP30:2015
2012:06:22-14:39:44 fw001 awed[17598]: configuration: 1 APs, 1 networks, 1 network groups
2012:06:22-14:39:44 fw001 awed[17598]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:40:06 fw001 awed[17598]: new connection from 10.64.32.1:32852
2012:06:22-14:40:06 fw001 awed[17598]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:40:19 fw001 awed[17698]: Awed version 1.4.2.57.2.19 starting
2012:06:22-14:40:19 fw001 awed[17698]: access point firmware available: AP10:2015 AP50:2015 AP30:2015
2012:06:22-14:40:20 fw001 awed[17698]: configuration: 1 APs, 1 networks, 1 network groups
2012:06:22-14:40:20 fw001 awed[17698]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:40:46 fw001 awed[17698]: new connection from 10.64.32.1:44935
2012:06:22-14:40:46 fw001 awed[17698]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:41:00 fw001 awed[17847]: Awed version 1.4.2.57.2.19 starting
2012:06:22-14:41:00 fw001 awed[17847]: access point firmware available: AP10:2015 AP50:2015 AP30:2015
2012:06:22-14:41:01 fw001 awed[17847]: configuration: 1 APs, 1 networks, 1 network groups
2012:06:22-14:41:01 fw001 awed[17847]: AP 'A40002977CC4733': configuration change detected
2012:06:22-14:41:25 fw001 awed[17847]: new connection from 10.64.32.1:43572
2012:06:22-14:41:25 fw001 awed[17847]: AP 'A40002977CC4733': configuration change detected 



It tells me there is a new firmware available, okay nice but what now? I get this message above every 20s.....


This thread was automatically locked due to age.
Parents Reply
  • Could you please run the following when logged into the ASG per SSH:

    /var/mdw/scripts/awed stop
    awed --debug

    Please post /var/log/wireless.log afterwards.
    Having a look into /var/log/selfmon.log might give us a clue as well.

    Thanks,
    Helmut
Children
  • Okay, I probably found the problem. It was not working because my radius server was not ready at this time.
    But the log was really weird, I was thinking the problem was the ap and not the asg. [8-)]



    Please post /var/log/wireless.log afterwards.
    Having a look into /var/log/selfmon.log might give us a clue as well.


    wireless.log

    2012:06:25-08:06:04 fw001 awed[24355]: Awed version 1.4.2.57.2.19 starting
    2012:06:25-08:06:04 fw001 awed[24355]: access point firmware available: AP10:2015 AP50:2015 AP30:2015
    2012:06:25-08:06:05 fw001 awed[24355]: configuration: 1 APs, 2 networks, 1 network groups
    2012:06:25-08:06:05 fw001 awed[24355]: AP 'A40002977CC4733': configuration change detected
    2012:06:25-08:06:09 fw001 awed[24355]: new connection from 10.64.32.1:43575
    2012:06:25-08:06:09 fw001 awed[24355]: AP 'A40002977CC4733': configuration change detected
    2012:06:25-08:06:25 fw001 awed[24431]: Awed version 1.4.2.57.2.19 starting
    2012:06:25-08:06:25 fw001 awed[24431]: access point firmware available: AP10:2015 AP50:2015 AP30:2015
    2012:06:25-08:06:26 fw001 awed[24431]: configuration: 1 APs, 2 networks, 1 network groups
    2012:06:25-08:06:26 fw001 awed[24431]: AP 'A40002977CC4733': configuration change detected
    2012:06:25-08:06:48 fw001 awed[24431]: new connection from 10.64.32.1:43572
    2012:06:25-08:06:48 fw001 awed[24431]: AP 'A40002977CC4733': configuration change detected
    2012:06:25-08:06:56 fw001 awed[24551]: Awed version 1.4.2.57.2.19 starting
    2012:06:25-08:06:56 fw001 awed[24551]: new device handler initiated
    2012:06:25-08:06:56 fw001 awed[24551]: access point firmware available: AP10:2015 AP50:2015 AP30:2015
    2012:06:25-08:06:56 fw001 awed[24551]: reloading configuration
    2012:06:25-08:06:56 fw001 awed[24551]: configuration: 1 APs, 2 networks, 1 network groups
    2012:06:25-08:06:56 fw001 awed[24551]: changed networks: 1 devices: 1 clients: 1 globals: 1
    2012:06:25-08:06:56 fw001 awed[24551]: reviving APs from confd
    2012:06:25-08:06:56 fw001 awed[24551]: aphandler::reconfigure()
    2012:06:25-08:06:56 fw001 awed[24551]: aphandler::new_device() creating from confd input
    2012:06:25-08:06:56 fw001 awed[24551]: ap::reconfigure() AP 'A40002977CC4733'
    2012:06:25-08:06:56 fw001 awed[24551]: checking if we need reconfiguration: 'a88506a4ece0d9f14ca320a440c9006e' (new)   (old) ''
    2012:06:25-08:06:56 fw001 awed[24551]: AP 'A40002977CC4733': configuration change detected
    2012:06:25-08:06:56 fw001 awed[24551]: populating/updating wireless clients from confd
    fw001:/home/login # 
    ##################
    2012-06-25 08:07:27.151 INFORMATIVE MESSAGE awed[24551]: main::top-level:228()
    | new connection from 10.64.32.1:37639

    ##################
    2012-06-25 08:07:27.151 DEBUG INFORMATION awed[24551]: Internal::APHandler::new_device:510()
    | aphandler::new_device() creating from socket input

    ##################
    2012-06-25 08:07:27.164 DEBUG INFORMATION awed[24551]: Internal::APHandler::merge_devices:542()
    | aphandler::merge_devices()

    ##################
    2012-06-25 08:07:27.164 DEBUG INFORMATION awed[24551]: Internal::AP::reconfigure:1466()
    | ap::reconfigure() AP 'A40002977CC4733'

    ##################
    2012-06-25 08:07:27.165 DEBUG INFORMATION awed[24551]: Internal::AP::reconfigure:1593()
    | checking if we need reconfiguration: '2be8a7b436cc6a21d036e4e190a3dc87' (new)   (old) 'a88506a4ece0d9f14ca320a440c9006e'

    ##################
    2012-06-25 08:07:27.165 INFORMATIVE MESSAGE awed[24551]: Internal::AP::reconfigure:1598()
    | AP 'A40002977CC4733': configuration change detected

    ##################
    2012-06-25 08:07:27.165 DEBUG INFORMATION awed[24551]: Internal::AP:[:P]rocess_request:964()
    | received data from A40002977CC4733: $VAR1 = {
    |           'version' => 2015,
    |           'type' => 'AP50',
    |           'id' => 'A40002977CC4733',
    |           'allowed_channels' => '36,40,44,48,1,2,3,4,5,6,7,8,9,10,11,12,13,',
    |           'lan_mac' => '00:1a:8c:0c:e9:7d'
    |         };

    ##################
    2012-06-25 08:07:27.165 DEBUG INFORMATION awed[24551]: Internal::AP:[:P]rocess_request:1001()
    | AP 'A40002977CC4733': update allowed channels (from: ARRAY(0xa033d90) to 17)

    ##################
    2012-06-25 08:07:27.165 DEBUG INFORMATION awed[24551]: Internal::AP::confd_register_ap:1621()
    | AP 'A40002977CC4733' (ref: 'REF_AweDev1') already registered, retaininig data

    ##################
    2012-06-25 08:07:27.166 DEBUG INFORMATION awed[24551]: Internal::AP::confd_register_ap:1644()
    | trying to set object: $VAR1 = {
    |           'lock' => '',
    |           'ref' => 'REF_AweDev1',
    |           'data' => {
    |                       'country' => 'ch',
    |                       'location' => 'Serverraum',
    |                       'name' => 'Serverraum',
    |                       'channel11a' => 0,
    |                       'tunnel_id' => '800',
    |                       'channel' => 0,
    |                       'networks' => [
    |                                       'REF_ItfAweKzn'
    |                                     ],
    |                       'key' => '... stripped ....',
    |                       'lan_mac' => '00:1a:8c:0c:e9:7d',

    |                     },
    |           'class' => 'awe',
    |           'type' => 'device',
    |           'hidden' => 0,
    |           'autoname' => '1',
    |           'nodel' => ''
    |         };

    ##################
    2012-06-25 08:07:27.210 DEBUG INFORMATION awed[24551]: Internal::AP:[:P]rocess_request:1018()
    | device config: $VAR1 = {
    [... stripped ...]

    Wide character in subroutine entry at /usr/local/ap510/site/lib/Digest/HMAC.pm line 28.



    selfmon.log

    2012:06:25-08:06:15 fw001 selfmonng[6783]: I check Failed increment awed_running counter 1 - 3
    2012:06:25-08:06:20 fw001 selfmonng[6783]: I check Failed increment awed_running counter 2 - 3
    2012:06:25-08:06:25 fw001 selfmonng[6783]: W check Failed increment awed_running counter 3 - 3
    2012:06:25-08:06:25 fw001 selfmonng[6783]: W triggerAction: 'cmd'
    2012:06:25-08:06:25 fw001 selfmonng[6783]: W actionCmd(+):  '/var/mdw/scripts/awed restart'
    2012:06:25-08:06:25 fw001 selfmonng[6783]: W child returned status: exit='0' signal='0'
  • Are you using any special characters in your RADIUS key?
    Helmut
  • Are you using any special characters in your RADIUS key?
    Helmut

    Yes I do, my key includes:
    ^@$

    (auto-genrated)
  • Yes I do, my key includes:
    ^@$

    (auto-genrated)


    These shouldn't be a problem. However, it seems as if awed is not able to cope with some wide characters.

    Did you enter the password manually or did you copy'n'paste it?

    Helmut
  • These shouldn't be a problem. However, it seems as if awed is not able to cope with some wide characters.

    Did you enter the password manually or did you copy'n'paste it?

    Helmut

    copy'n'paste, I'll try it manually later.


    The crash however seems to be already fixed in v9. 

    Okay [:)]


    Thanks for your help