can't load chan_celliax.so

Hello together,

I just installed celliax as I read in the README but I always get this error:

loading module 'chan_celliax.so': libspeexdsp.so.1: cannot open shared object file: No such file or directory

But the libspeexdsk.so.1 exists in /usr/local/lib!

I made a symbolic link from /usr/lib/asterisk/modules to it but this didn't help.

What can I do?


Comment viewing options

Select your preferred way to display the comments and click "Save settings" to activate your changes.

In my distribution I had

In my distribution I had similar problem with another library, I found a workaround just setting the environment variable:
export LD_LIBRARY_PATH=/usr/local/lib

I don't knoy whether this is correct or not.

Another possibility would be to make a symlink directly in /usr/lib/, but I prefer not to manually mess files or directories outside /usr/local/.


ldconfig!

after installing new libraries, for ex libspeexdsp etc, you have to run: " ldconfig /usr/local/lib ", so the system knows that new libraries are there to be used.

Maybe this was the problem.

Please write again if still does not work

Giovanni


The hint with ldconfig

The hint with ldconfig worked. THANKS!

But I have a segmentation fault on trixbox 2.6.1.10 while starting asterisk with following options:

-U asterisk -G asterisk

It is working with user root and group asterisk. It seems to be any kind of right problem in chan_celliax. I just chowned /dev/snd and /dev/ttyACM0 - without success.

Any idea?


trixbox revert the ownership

trixbox revert the ownership of devices at each reboot
so, try to insert the following lines in the script that starts asterisk:

chown -R asterisk.asterisk /dev/snd/*
chown -R asterisk.asterisk /dev/ttyACM*


took your freepbx_engine script

Ok it's starting now. I took the freepbx_engine script.

But I got the next problem ;-)

I'm using mobigater hardware. I just can place ONE call, it's ringing on the other end but and I can hook up - but celliax doesn't recognize that the call is answered. After it asterisk can't place another call on celliax. I don't see any messages from chan_celliax any longer.

Is it a celliax.conf problem? Or just another permission problem?


After call no response from modem

I just tested some things:

After a fresh reboot I get response from command celliax_at ati0 as I can see in the asterisk full log file.

When I answer a call (I hear nothing) I get the response "Ringing stopped and I have not answered. Why?" and the celliax_at command doesn't receive any responses no longer.

[ 4, 4, 4] sending: ATA, expecting: OK
[ 4, 4, 4] sent data... (A)
[ 4, 4, 4] sent data... (T)
[ 4, 4, 4] sent data... (A)
[ 4, 4, 4] sent (carriage return)
4, 4, 4] Read line 0: |ATA|
4, 4, 4] Read line 1: |OK|
[ 4, 4, 4] |OK| got what EXPECTED
[ 4, 4, 4] CALLFLOW_CALL_INCOMING
[ 4, 4, 4] AT: call answered

[ 4, 4, 4] Ringing stopped and I have not answered. Why?

Does celliax lost communication?


Still accepting at commands

After answering a call and celliax don't get's any responses from the modem I can still place call with celliax_at atd...

BTW: I can't stop asterisk after all. I hade to do a reboot of the system.


mobigater

Ciao Stefan
unfortunately I don't have a mobigater device to test, so you have to debug it yourself, at the moment.
Do you have the aT command documentation for it?

Anyway, one of the German Mobigater reseller/distributor told me he will send me one Mobigater-4 so I can better support it. I will have it in firstr week of November, he told me, when I'll be back from India.

Is the Mobigater-4 the model you are trying to use?

Ciao for now,

Giovanni


Hello Giovanni, I have a

Hello Giovanni,

I have a Mobigater Pro/SIP. This one has worked together with celliax and trixbox some months ago. But the server crashed...
As far as I know, the Mobigater-4 is the same hardware.
I will try to look further into it.

Hm, german distributor? Do you have a link?

Have a nice time in India!

Ciao,

Stefan


can you send debugging log?

are you using the last version from svn?

please, can you send a full debugging log?

You have to "set debug=100" from CLI, activate the at debugging from celliax.conf, and activate debugging in console and in full in the logger.conf


My log...

Hi Giovanni,

yes, I use the latest svn. Here comes my log. I cut and deleted a lot of lines (debug_locks and debug_monitoring locks). Can I attach files? Or how can I send the log in a better way?

[Oct 29 08:12:03] NOTICE[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][NOTIOBCE 1132 ][none ][-1,-1,-1] ENTERING FUNC
[Oct 29 08:12:03] VERBOSE[2771] logger.c: == Registered application 'CelliaxSendsms'
[Oct 29 08:12:03] VERBOSE[2771] logger.c: == Manager registered action CELLIAXsendsms
[Oct 29 08:12:03] DEBUG[2771] channel.c: Registered handler for 'Celliax' (Celliax, Audio-Serial Driver)
[Oct 29 08:12:03] VERBOSE[2771] logger.c: == Registered channel type 'Celliax' (Celliax, Audio-Serial Driver)
[Oct 29 08:12:03] VERBOSE[2771] logger.c: == Parsing '/etc/asterisk/celliax.conf': [Oct 29 08:12:03] DEBUG[2771] config.c: Parsing /etc/asterisk/celliax.conf
[Oct 29 08:12:03] VERBOSE[2771] logger.c: Found
[Oct 29 08:12:03] NOTICE[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][NOTICE 1563 ][mobigater1][-1, 0, 0] DEBUG_ALL activated.
[Oct 29 08:12:03] NOTICE[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][NOTICE 1573 ][mobigater1][-1, 0, 0] DEBUG_AT activated.
[Oct 29 08:12:03] NOTICE[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][NOTICE 1584 ][mobigater1][-1, 0, 0] DEBUG_FBUS2 activated.
[Oct 29 08:12:03] NOTICE[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][NOTICE 1595 ][mobigater1][-1, 0, 0] DEBUG_SERIAL activated.
[Oct 29 08:12:03] NOTICE[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][NOTICE 1606 ][mobigater1][-1, 0, 0] DEBUG_SOUND activated.
[Oct 29 08:12:03] NOTICE[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][NOTICE 1617 ][mobigater1][-1, 0, 0] DEBUG_PBX activated.
[Oct 29 08:12:03] NOTICE[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][NOTICE 1628 ][mobigater1][-1, 0, 0] DEBUG_CALL activated.
[Oct 29 08:12:03] NOTICE[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][NOTICE 1661 ][mobigater1][-1, 0, 0] DEBUG_CVM activated.
[Oct 29 08:12:03] DEBUG[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][DEBUG_SOUND 1667 ][mobigater1][-1, 0, 0] playback_boost is 2879.187585
[Oct 29 08:12:03] DEBUG[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][DEBUG_SOUND 1668 ][mobigater1][-1, 0, 0] capture_boost is 512.000000
[Oct 29 08:12:03] NOTICE[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][NOTICE 26 ][mobigater1][-1, 0, 0] Found PORTAUDIO device: id=0 name=C-Media USB Headphone Set : USB Audio (hw:0,0) max input channels=1 max output channels=2
[Oct 29 08:12:03] NOTICE[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][NOTICE 26 ][mobigater1][-1, 0, 0] Found PORTAUDIO device: id=1 name=front max input channels=0 max output channels=2
[Oct 29 08:12:03] NOTICE[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][NOTICE 26 ][mobigater1][-1, 0, 0] Found PORTAUDIO device: id=2 name=surround40 max input channels=0 max output channels=2
[Oct 29 08:12:03] NOTICE[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][NOTICE 26 ][mobigater1][-1, 0, 0] Found PORTAUDIO device: id=3 name=iec958 max input channels=0 max output channels=2
[Oct 29 08:12:03] NOTICE[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][NOTICE 26 ][mobigater1][-1, 0, 0] Found PORTAUDIO device: id=4 name=spdif max input channels=1 max output channels=2
[Oct 29 08:12:03] NOTICE[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][NOTICE 26 ][mobigater1][-1, 0, 0] Found PORTAUDIO device: id=5 name=default max input channels=128 max output channels=128
[Oct 29 08:12:03] NOTICE[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][NOTICE 26 ][mobigater1][-1, 0, 0] Found PORTAUDIO device: id=6 name=dmix max input channels=0 max output channels=2
[Oct 29 08:12:03] NOTICE[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][NOTICE 26 ][mobigater1][-1, 0, 0] Found PORTAUDIO device: id=7 name=/dev/dsp max input channels=16 max output channels=16
[Oct 29 08:12:03] NOTICE[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][NOTICE 69 ][mobigater1][-1, 0, 0] Using INPUT PORTAUDIO device: id=5 name=default max input channels=128 max output channels=128
[Oct 29 08:12:03] NOTICE[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][NOTICE 93 ][mobigater1][-1, 0, 0] Using OUTPUT PORTAUDIO device: id=5 name=default max input channels=128 max output channels=128
[Oct 29 08:12:03] DEBUG[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][DEBUG_SERIAL 2464 ][mobigater1][-1, 0, 0] Syncing Serial
[Oct 29 08:12:03] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1757 ][mobigater1][-1, 0, 0] sleeping for 500000 usec
[Oct 29 08:12:04] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1674 ][mobigater1][-1, 0, 0] sending: atciapa, expecting: OK
[Oct 29 08:12:04] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (a)
[Oct 29 08:12:04] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (t)
[Oct 29 08:12:04] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (c)
[Oct 29 08:12:04] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (i)
[Oct 29 08:12:04] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (a)
[Oct 29 08:12:04] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (p)
[Oct 29 08:12:04] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (a)
[Oct 29 08:12:04] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:04] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |ERROR|
[Oct 29 08:12:04] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1293 ][mobigater1][-1, 0, 0] got ERROR
[Oct 29 08:12:04] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1768 ][mobigater1][-1, 0, 0] atciapa does not get OK from the phone. Continuing.
[Oct 29 08:12:04] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1819 ][mobigater1][-1, 0, 0] sleeping for 1000000 usec
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |OK|
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+mode=0
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (m)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (o)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (d)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (e)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (0)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |ERROR|
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1293 ][mobigater1][-1, 0, 0] got ERROR
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1833 ][mobigater1][-1, 0, 0] AT+mode=0 does not get OK from the phone. If it is NOT Motorola, no problem.
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: ATZ
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (Z)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |OK|
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: ATE0
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (E)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (0)
[Oct 29 08:12:05] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1847 ][mobigater1][-1, 0, 0] ATE0 failed
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CMEE=0
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (M)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (E)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (E)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (0)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CMEE=0|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |ERROR|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1293 ][mobigater1][-1, 0, 0] got ERROR
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1853 ][mobigater1][-1, 0, 0] AT+CMEE failed
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: ATI0
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (0)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |ATI0|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |SIMCOM_Ltd|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |SIMCOM_SIM300|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 3: |Revision:1008B10SIM300M32_SPANSION|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 4: |OK|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: ATI1
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (1)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |ATI1|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |SIMCOM_Ltd|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |SIMCOM_SIM300|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 3: |Revision:1008B10SIM300M32_SPANSION|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 4: |OK|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: ATI2
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (2)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |ATI2|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |SIMCOM_Ltd|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |SIMCOM_SIM300|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 3: |Revision:1008B10SIM300M32_SPANSION|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 4: |OK|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: ATI3
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (3)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |ATI3|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |SIMCOM_Ltd|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |SIMCOM_SIM300|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 3: |Revision:1008B10SIM300M32_SPANSION|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 4: |OK|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: ATI4
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (4)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |ATI4|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |SIMCOM_Ltd|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |SIMCOM_SIM300|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 3: |Revision:1008B10SIM300M32_SPANSION|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 4: |OK|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: ATI5
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (5)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |ATI5|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |SIMCOM_Ltd|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |SIMCOM_SIM300|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 3: |Revision:1008B10SIM300M32_SPANSION|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 4: |OK|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: ATI6
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (6)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |ATI6|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |SIMCOM_Ltd|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |SIMCOM_SIM300|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 3: |Revision:1008B10SIM300M32_SPANSION|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 4: |OK|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: ATI7
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (7)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |ATI7|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |SIMCOM_Ltd|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |SIMCOM_SIM300|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 3: |Revision:1008B10SIM300M32_SPANSION|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 4: |OK|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: ATI8
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (8)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |ATI8|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |SIMCOM_Ltd|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |SIMCOM_SIM300|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 3: |Revision:1008B10SIM300M32_SPANSION|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 4: |OK|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: ATI9
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (9)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |ATI9|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |SIMCOM_Ltd|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |SIMCOM_SIM300|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 3: |Revision:1008B10SIM300M32_SPANSION|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 4: |OK|
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CGMI
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (G)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (M)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:08] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CGMI|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |SIMCOM_Ltd|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |OK|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CGMM
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (G)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (M)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (M)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CGMM|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |SIMCOM_SIM300|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |OK|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CGSN
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (G)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (S)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (N)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CGSN|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |353579016734515|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |OK|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CNMI=3,1,0,0,0
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (N)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (M)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (3)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (,)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (1)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (,)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (0)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (,)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (0)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (,)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (0)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CNMI=3,1,0,0,0|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |OK|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CSCA?
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (S)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (?)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CSCA?|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |+CSCA: "+491710760000",145|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 775 ][mobigater1][-1, 0, 0] |+CSCA: "+491710760000",145| +CSCA: Message Center Address!
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |OK|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CMGF?
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (M)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (G)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (F)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (?)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CMGF?|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |+CMGF: 0|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 781 ][mobigater1][-1, 0, 0] |+CMGF: 0| +CMGF: Message Format!
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |OK|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CMGF=1
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (M)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (G)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (F)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (1)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CMGF=1|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |OK|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CSCS?
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (S)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (S)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (?)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CSCS?|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |+CSCS: "IRA"|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |OK|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CSCS="ASCII"
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (S)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (S)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (")
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (S)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (")
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CSCS="ASCII"|
[Oct 29 08:12:09] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |+CME ERROR: 738|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1922 ][mobigater1][-1, 0, 0] AT+CSCS="ASCII" failed, continue
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CMER=?
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (M)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (E)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (R)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (?)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CMER=?|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |ERROR|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1927 ][mobigater1][-1, 0, 0] AT+CMER=? failed, continue
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CMER=3,0,0,1
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (M)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (E)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (R)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (3)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (,)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (0)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (,)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (0)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (,)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (1)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CMER=3,0,0,1|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |ERROR|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1293 ][mobigater1][-1, 0, 0] got ERROR
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1932 ][mobigater1][-1, 0, 0] AT+CMER=? failed, continue
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CIND=?
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (N)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (D)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (?)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CIND=?|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |+CIND: |
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |OK|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT*ECAM=?
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (*)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (E)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (M)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (?)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |ERROR|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1293 ][mobigater1][-1, 0, 0] got ERROR
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1945 ][mobigater1][-1, 0, 0] AT*ECAM=? failed, continue
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT*ECAM=1
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (*)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (E)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (M)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (1)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT*ECAM=1|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |ERROR|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1293 ][mobigater1][-1, 0, 0] got ERROR
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1950 ][mobigater1][-1, 0, 0] AT*ECAM=1 failed, continue
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CLCC=0
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (L)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (0)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CLCC=0|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |ERROR|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1293 ][mobigater1][-1, 0, 0] got ERROR
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1959 ][mobigater1][-1, 0, 0] AT+CLCC=0 failed, continue
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+CLIP=1
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (L)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (I)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (P)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (1)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CLIP=1|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |OK|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT+MCST=1
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (M)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (S)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (1)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+MCST=1|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |ERROR|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1293 ][mobigater1][-1, 0, 0] got ERROR
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1976 ][mobigater1][-1, 0, 0] AT+MCST=1 does not get OK from the phone. If it is NOT Motorola, no problem.
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1674 ][mobigater1][-1, 0, 0] sending: atcucu, expecting: OK
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (a)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (t)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (c)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (u)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (c)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (u)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |atcucu|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |ERROR|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1293 ][mobigater1][-1, 0, 0] got ERROR
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1987 ][mobigater1][-1, 0, 0] atcucu does not get OK from the phone. Continuing.
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1674 ][mobigater1][-1, 0, 0] sending: AT+CKPD="EEE", expecting: OK
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (K)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (P)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (D)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (=)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (")
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (E)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (E)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (E)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (")
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CKPD="EEE"|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |OK|
[Oct 29 08:12:12] DEBUG[2771] celliax_additional.c: rev 1095M[(nil)|b7fd48d0][DEBUG_AT 1281 ][mobigater1][-1, 0, 0] |OK| got what EXPECTED
[Oct 29 08:12:12] DEBUG[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][DEBUG_PBX 1164 ][none ][-1,-1,-1] Created channel Celliax: celliax.conf category '[mobigater1]', channel name 'mobigater1' control_device_name '/dev/ttyACM0'
[Oct 29 08:12:12] NOTICE[2771] chan_celliax.c: rev 1095M[(nil)|b7fd48d0][NOTICE 1212 ][none ][-1,-1,-1] EXITING FUNC
[Oct 29 08:12:12] VERBOSE[2771] logger.c: chan_celliax.so => (Celliax, Audio-Serial Driver)
[Oct 29 08:12:12] VERBOSE[2771] logger.c: == Registered custom function BLACKLIST
[Oct 29 08:12:12] VERBOSE[2771] logger.c: == Registered application 'LookupBlacklist'
[Oct 29 08:12:12] VERBOSE[2771] logger.c: app_lookupblacklist.so => (Look up Caller*ID name/number from blacklist database)
[Oct 29 08:12:12] VERBOSE[2771] logger.c: == Registered custom function LANGUAGE
[Oct 29 08:12:12] VERBOSE[2771] logger.c: func_language.so => (Channel language dialplan function)
[Oct 29 08:12:13] VERBOSE[2771] logger.c: == Registered application 'RxFAX'
[Oct 29 08:12:13] VERBOSE[2771] logger.c: app_rxfax.so => (Trivial FAX Receive Application)
[Oct 29 08:12:13] VERBOSE[2771] logger.c: == Registered application 'Playback'
[Oct 29 08:12:13] VERBOSE[2771] logger.c: app_playback.so => (Sound File Playback Application)
[Oct 29 08:12:13] VERBOSE[2771] logger.c: == Registered application 'Dial'
[Oct 29 08:12:13] VERBOSE[2771] logger.c: == Registered application 'RetryDial'
[Oct 29 08:12:13] VERBOSE[2771] logger.c: app_dial.so => (Dialing Application)
[Oct 29 08:12:13] VERBOSE[2771] logger.c: == Registered application 'DISA'
[Oct 29 08:12:13] VERBOSE[2771] logger.c: app_disa.so => (DISA (Direct Inward System Access) Application)
[Oct 29 08:12:13] VERBOSE[2771] logger.c: == Registered application 'ForkCDR'
[Oct 29 08:12:13] VERBOSE[2771] logger.c: app_forkcdr.so => (Fork The CDR into 2 separate entities)
[Oct 29 08:12:13] VERBOSE[2771] logger.c: == Registered application 'LookupCIDName'
[Oct 29 08:12:13] VERBOSE[2771] logger.c: app_lookupcidname.so => (Look up CallerID Name from local database)
[Oct 29 08:12:13] VERBOSE[2771] logger.c: == Registered custom function SHA1
[Oct 29 08:12:13] VERBOSE[2771] logger.c: func_sha1.so => (SHA-1 computation dialplan function)
[Oct 29 08:12:13] VERBOSE[2771] logger.c: == Registered application 'DumpChan'
[Oct 29 08:12:13] VERBOSE[2771] logger.c: app_dumpchan.so => (Dump Info About The Calling Channel)
[Oct 29 08:12:13] VERBOSE[2771] logger.c: Asterisk Ready.
[Oct 29 08:12:13] DEBUG[2842] ^y 1885 ][none ][-1,-1,-1] locked 0x1cb85f8 (MONLOCK)
[Oct 29 08:12:13] DEBUG[2842] ^e 1888 ][none ][-1,-1,-1] locked 0x1cb8610 (IFLOCK)
[Oct 29 08:12:34] DEBUG[2842] chan_celliax.c: rev 1095M[(nil)|b6f62b90][DEBUG_SERIAL 1912 ][mobigater1][-1, 0, 0] Syncing Serial
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT|
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |OK|
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1674 ][mobigater1][-1, 0, 0] sending: AT+CSQ, expecting: OK
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (S)
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (Q)
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CSQ|
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |+CSQ: 21,0|
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 670 ][mobigater1][-1, 0, 0] |+CSQ: 21,0| +CSQ: Signal Quality: 21, Error Rate=0
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |OK|
[Oct 29 08:12:34] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1281 ][mobigater1][-1, 0, 0] |OK| got what EXPECTED
[Oct 29 08:13:03] DEBUG[2779] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Oct 29 08:13:03] DEBUG[2779] chan_sip.c: = Found Their Call ID: 6f4c4ac4446c61336a00c628044d4eac@192.168.250.20 Their Tag Our tag: as367ac2b6
[Oct 29 08:13:03] DEBUG[2779] chan_sip.c: Stopping retransmission on '6f4c4ac4446c61336a00c628044d4eac@192.168.250.20' of Request 102: Match Found
[Oct 29 08:13:03] VERBOSE[2779] logger.c: Really destroying SIP dialog '6f4c4ac4446c61336a00c628044d4eac@192.168.250.20' Method: OPTIONS
[Oct 29 08:13:05] DEBUG[2842] chan_celliax.c: rev 1095M[(nil)|b6f62b90][DEBUG_SERIAL 1912 ][mobigater1][-1, 0, 0] Syncing Serial
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1629 ][mobigater1][-1, 0, 0] sending: AT
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT|
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |OK|
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1287 ][mobigater1][-1, 0, 0] got OK
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1674 ][mobigater1][-1, 0, 0] sending: AT+CSQ, expecting: OK
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (A)
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (T)
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (+)
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (C)
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (S)
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1525 ][mobigater1][-1, 0, 0] sent data... (Q)
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1555 ][mobigater1][-1, 0, 0] sent (carriage return)
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |AT+CSQ|
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 1: |+CSQ: 21,0|
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 670 ][mobigater1][-1, 0, 0] |+CSQ: 21,0| +CSQ: Signal Quality: 21, Error Rate=0
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 2: |OK|
[Oct 29 08:13:05] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1281 ][mobigater1][-1, 0, 0] |OK| got what EXPECTED
[Oct 29 08:13:33] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 471 ][mobigater1][-1, 0, 0] Read line 0: |RING|
[Oct 29 08:13:33] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 502 ][mobigater1][-1, 0, 0] |RING| got first RING, but not sending AT+CLCC? as this device seems not to support
[Oct 29 08:13:33] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 471 ][mobigater1][-1, 0, 4] Read line 1: |+CLIP: "+491234567890",145,"",,"",0|
[Oct 29 08:13:33] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 576 ][mobigater1][-1, 4, 4] |+CLIP: "+491234567890",145,"",,"",0| CLIP INCOMING CALLID: name is , number is +491234567890
[Oct 29 08:13:33] DEBUG[2842] celliax_additional.c: rev 1095M[(nil)|b6f62b90][DEBUG_AT 1471 ][mobigater1][-1, 4, 4] CALLFLOW_CALL_INCOMING call_incoming_time.tv_sec=0, call_incoming_timeout.tv_sec=1225264403
[Oct 29 08:13:33] DEBUG[2842] chan_celliax.c: rev 1095M[(nil)|b6f62b90][DEBUG_PBX 2003 ][mobigater1][-1, 4, 4] INCOMING RING
[Oct 29 08:13:33] NOTICE[2842] chan_celliax.c: rev 1095M[(nil)|b6f62b90][NOTICE 990 ][mobigater1][-1, 4, 4] ENTERING FUNC
[Oct 29 08:13:33] DEBUG[2842] chan_celliax.c: rev 1095M[(nil)|b6f62b90][DEBUG_PBX 1012 ][mobigater1][-1, 4, 4] new channel: name=Celliax/mobigater1 requested_state=4
[Oct 29 08:13:33] DEBUG[2842] chan_celliax.c: rev 1095M[0x87d0f68|b6f62b90][DEBUG_PBX 1067 ][mobigater1][ 4, 4, 4] Try to start PBX on Celliax/mobigater1, state=4
[Oct 29 08:13:33] DEBUG[2842] chan_celliax.c: rev 1095M[0x87d0f68|b6f62b90][DEBUG_SERIAL 1088 ][mobigater1][ 4, 4, 4] STARTED controldev_thread=3068603280 STOP=4294967294 NULL=4294967295
[Oct 29 08:13:33] NOTICE[2842] chan_celliax.c: rev 1095M[0x87d0f68|b6f62b90][NOTICE 1098 ][mobigater1][ 4, 4, 4] EXITING FUNC
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'NoOp'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [mobigater1@from-trunk:1] NoOp("Celliax/mobigater1", "Catch-All DID Match - Found mobigater1 - You probably want a DID for this.") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'Goto'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [mobigater1@from-trunk:2] Goto("Celliax/mobigater1", "ext-did|s|1") in new stack
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Goto (ext-did,s,1)
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@ext-did:1] Set("Celliax/mobigater1", "__FROM_DID=s") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'Gosub'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@ext-did:2] Gosub("Celliax/mobigater1", "app-blacklist-check|s|1") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'LookupBlacklist'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@app-blacklist-check:1] LookupBlacklist("Celliax/mobigater1", "") in new stack
[Oct 29 08:13:33] WARNING[2845] app_lookupblacklist.c: LookupBlacklist is deprecated. Please use ${BLACKLIST()} instead.
[Oct 29 08:13:33] DEBUG[2845] db.c: Unable to find key '+491234567890' in family 'blacklist'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Expression result is '0'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'GotoIf'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@app-blacklist-check:2] GotoIf("Celliax/mobigater1", "0?blacklisted") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Not taking any branch
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'Return'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@app-blacklist-check:3] Return("Celliax/mobigater1", "") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Function result is ''
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Expression result is '0'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'GotoIf'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@ext-did:3] GotoIf("Celliax/mobigater1", "0 ?cidok") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Not taking any branch
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Function result is '+491234567890'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@ext-did:4] Set("Celliax/mobigater1", "CALLERID(name)=+491234567890") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Function result is '"+491234567890" <+491234567890>'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'NoOp'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@ext-did:5] NoOp("Celliax/mobigater1", "CallerID is "+491234567890" <+491234567890>") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@ext-did:6] Set("Celliax/mobigater1", "FAX_RX=disabled") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@ext-did:7] Set("Celliax/mobigater1", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'SetCallerPres'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@ext-did:8] SetCallerPres("Celliax/mobigater1", "allowed_not_screened") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'Goto'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@ext-did:9] Goto("Celliax/mobigater1", "from-did-direct|21|1") in new stack
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Goto (from-did-direct,21,1)
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [21@from-did-direct:1] Set("Celliax/mobigater1", "__RINGTIMER=120") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'Macro'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [21@from-did-direct:2] Macro("Celliax/mobigater1", "exten-vm|novm|21") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'Macro'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@macro-exten-vm:1] Macro("Celliax/mobigater1", "user-callerid") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Function result is '+491234567890'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Function result is '+491234567890'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'NoOp'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("Celliax/mobigater1", "user-callerid: +491234567890 +491234567890") in new stack
[Oct 29 08:13:33] DEBUG[2845] app_macro.c: Executed application: Noop
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Expression result is '1'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Function result is '+491234567890'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Function result is '+491234567890'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@macro-user-callerid:2] Set("Celliax/mobigater1", "AMPUSER=+491234567890") in new stack
[Oct 29 08:13:33] DEBUG[2845] app_macro.c: Executed application: Set
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Expression result is '0'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'GotoIf'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("Celliax/mobigater1", "0?report") in new stack
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Not taking any branch
[Oct 29 08:13:33] DEBUG[2845] app_macro.c: Executed application: GotoIf
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Expression result is '1'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Function result is '+491234567890'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'ExecIf'
[Oct 29 08:13:33] VERBOSE[2845] logger.c: -- Executing [s@macro-user-callerid:4] ExecIf("Celliax/mobigater1", "1|Set|REALCALLERIDNUM=+491234567890") in new stack
[Oct 29 08:13:33] DEBUG[2845] app_macro.c: Executed application: ExecIf
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Expression result is '0'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Function result is '+491234567890'
[Oct 29 08:13:33] DEBUG[2845] pbx.c: Launching 'NoOp'
[Oct 29 08:13:33] DEBUG[2846] chan_celliax.c: rev 1095M[0x87d0f68|b6e72b90][DEBUG_SERIAL 2314 ][mobigater1][ 4, 4, 4] In celliax_do_controldev_thread: started, p=0xb79de008
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-user-callerid:5] NoOp("Celliax/mobigater1", "REALCALLERIDNUM is +491234567890") in new stack
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Noop
[Oct 29 08:13:34] DEBUG[2845] db.c: Unable to find key '+491234567890/user' in family 'DEVICE'
[Oct 29 08:13:34] DEBUG[2845] func_db.c: DB: DEVICE/+491234567890/user not found in database.
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Function result is ''
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-user-callerid:6] Set("Celliax/mobigater1", "AMPUSER=") in new stack
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Set
[Oct 29 08:13:34] DEBUG[2845] db.c: Unable to find key '/cidname' in family 'AMPUSER'
[Oct 29 08:13:34] DEBUG[2845] func_db.c: DB: AMPUSER//cidname not found in database.
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Function result is ''
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-user-callerid:7] Set("Celliax/mobigater1", "AMPUSERCIDNAME=") in new stack
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Set
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Expression result is '1'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'GotoIf'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-user-callerid:8] GotoIf("Celliax/mobigater1", "1?report") in new stack
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Goto (macro-user-callerid,s,13)
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: GotoIf
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'NoOp'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("Celliax/mobigater1", "TTL: ARG1: novm") in new stack
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Noop
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Expression result is '0'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'GotoIf'

[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("Celliax/mobigater1", "0?continue") in new stack
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Not taking any branch
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: GotoIf
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Expression result is '1'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Expression result is '-1'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Function result is '64'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-user-callerid:15] Set("Celliax/mobigater1", "__TTL=64") in new stack
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Set
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Expression result is '1'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'GotoIf'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-user-callerid:16] GotoIf("Celliax/mobigater1", "1?continue") in new stack
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Goto (macro-user-callerid,s,23)
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: GotoIf
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Function result is '"+491234567890" <+491234567890>'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'NoOp'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("Celliax/mobigater1", "Using CallerID "+491234567890" <+491234567890>") in new stack
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Noop
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Macro
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-exten-vm:2] Set("Celliax/mobigater1", "RingGroupMethod=none") in new stack
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Set
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-exten-vm:3] Set("Celliax/mobigater1", "VMBOX=novm") in new stack
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Set
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-exten-vm:4] Set("Celliax/mobigater1", "EXTTOCALL=21") in new stack
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Set
[Oct 29 08:13:34] DEBUG[2845] db.c: Unable to find key '21' in family 'CFU'
[Oct 29 08:13:34] DEBUG[2845] func_db.c: DB: CFU/21 not found in database.
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Function result is ''
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-exten-vm:5] Set("Celliax/mobigater1", "CFUEXT=") in new stack
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Set
[Oct 29 08:13:34] DEBUG[2845] db.c: Unable to find key '21' in family 'CFB'
[Oct 29 08:13:34] DEBUG[2845] func_db.c: DB: CFB/21 not found in database.
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Function result is ''
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-exten-vm:6] Set("Celliax/mobigater1", "CFBEXT=") in new stack
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Set
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Expression result is '0'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Expression result is '0'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Expression result is '0'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Function result is '""'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'Set'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-exten-vm:7] Set("Celliax/mobigater1", "RT=""") in new stack
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Set
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'Macro'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-exten-vm:8] Macro("Celliax/mobigater1", "record-enable|21|IN") in new stack
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Function result is '0'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Expression result is '0'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'GotoIf'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("Celliax/mobigater1", "0?2:4") in new stack
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Goto (macro-record-enable,s,4)
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: GotoIf
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Function result is '20081029-081334'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'AGI'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-record-enable:4] AGI("Celliax/mobigater1", "recordingcheck|20081029-081334|1225264413.0") in new stack
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Oct 29 08:13:34] DEBUG[2845] chan_celliax.c: rev 1095M[0x87d0f68|b6eaeb90][DEBUG_SOUND 767 ][mobigater1][ 4, 4, 4] DTMF recognition inited
[Oct 29 08:13:34] DEBUG[2846] celliax_additional.c: rev 1095M[0x87d0f68|b6e72b90][DEBUG_AT 1471 ][mobigater1][ 4, 4, 4] CALLFLOW_CALL_INCOMING call_incoming_time.tv_sec=0, call_incoming_timeout.tv_sec=1225264404
[Oct 29 08:13:34] DEBUG[2846] celliax_additional.c: rev 1095M[0x87d0f68|b6e72b90][DEBUG_AT 1471 ][mobigater1][ 4, 4, 4] CALLFLOW_CALL_INCOMING call_incoming_time.tv_sec=0, call_incoming_timeout.tv_sec=1225264404
[Oct 29 08:13:34] DEBUG[2846] celliax_additional.c: rev 1095M[0x87d0f68|b6e72b90][DEBUG_AT 1471 ][mobigater1][ 4, 4, 4] CALLFLOW_CALL_INCOMING call_incoming_time.tv_sec=0, call_incoming_timeout.tv_sec=1225264404
[Oct 29 08:13:34] VERBOSE[2845] logger.c: recordingcheck|20081029-081334|1225264413.0: Inbound recording not enabled
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- AGI Script recordingcheck completed, returning 0
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: AGI
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'NoOp'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-record-enable:5] NoOp("Celliax/mobigater1", "No recording needed") in new stack
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Noop
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: Macro
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'Macro'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-exten-vm:9] Macro("Celliax/mobigater1", "dial||tr|21") in new stack
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Expression result is '1'
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'GotoIf'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-dial:1] GotoIf("Celliax/mobigater1", "1?dial") in new stack
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Goto (macro-dial,s,3)
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: GotoIf
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'AGI'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-dial:3] AGI("Celliax/mobigater1", "dialparties.agi") in new stack
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Oct 29 08:13:34] DEBUG[2846] celliax_additional.c: rev 1095M[0x87d0f68|b6e72b90][DEBUG_AT 1471 ][mobigater1][ 4, 4, 4] CALLFLOW_CALL_INCOMING call_incoming_time.tv_sec=0, call_incoming_timeout.tv_sec=1225264404
[Oct 29 08:13:34] DEBUG[2846] celliax_additional.c: rev 1095M[0x87d0f68|b6e72b90][DEBUG_AT 1471 ][mobigater1][ 4, 4, 4] CALLFLOW_CALL_INCOMING call_incoming_time.tv_sec=0, call_incoming_timeout.tv_sec=1225264404
[Oct 29 08:13:34] VERBOSE[2845] logger.c: dialparties.agi: Starting New Dialparties.agi
[Oct 29 08:13:34] DEBUG[2849] manager.c: Manager received command 'login'
[Oct 29 08:13:34] VERBOSE[2849] logger.c: == Parsing '/etc/asterisk/manager.conf': [Oct 29 08:13:34] DEBUG[2849] config.c: Parsing /etc/asterisk/manager.conf
[Oct 29 08:13:34] VERBOSE[2849] logger.c: Found
[Oct 29 08:13:34] VERBOSE[2849] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': [Oct 29 08:13:34] DEBUG[2849] config.c: Parsing /etc/asterisk/manager_additional.conf
[Oct 29 08:13:34] VERBOSE[2849] logger.c: Found
[Oct 29 08:13:34] VERBOSE[2849] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [Oct 29 08:13:34] DEBUG[2849] config.c: Parsing /etc/asterisk/manager_custom.conf
[Oct 29 08:13:34] VERBOSE[2849] logger.c: Found
[Oct 29 08:13:34] DEBUG[2849] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
[Oct 29 08:13:34] DEBUG[2849] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
[Oct 29 08:13:34] DEBUG[2849] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
[Oct 29 08:13:34] DEBUG[2849] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
[Oct 29 08:13:34] VERBOSE[2849] logger.c: == Manager 'admin' logged on from 127.0.0.1
[Oct 29 08:13:34] VERBOSE[2845] logger.c: dialparties.agi: Caller ID name is '+491234567890' number is '+491234567890'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: dialparties.agi: Methodology of ring is 'none'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- dialparties.agi: Added extension 21 to extension map
[Oct 29 08:13:34] DEBUG[2845] db.c: Unable to find key '21' in family 'CF'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- dialparties.agi: Extension 21 cf is disabled
[Oct 29 08:13:34] DEBUG[2845] db.c: Unable to find key '21' in family 'DND'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- dialparties.agi: Extension 21 do not disturb is disabled
[Oct 29 08:13:34] DEBUG[2845] db.c: Unable to find key '21' in family 'CW'
[Oct 29 08:13:34] DEBUG[2845] db.c: Unable to find key '21' in family 'CFB'
[Oct 29 08:13:34] DEBUG[2845] db.c: Unable to find key '21' in family 'CFU'
[Oct 29 08:13:34] DEBUG[2849] manager.c: Manager received command 'ExtensionState'
[Oct 29 08:13:34] DEBUG[2849] devicestate.c: No provider found, checking channel drivers for SIP - 21
[Oct 29 08:13:34] DEBUG[2849] chan_sip.c: Checking device state for peer 21
[Oct 29 08:13:34] VERBOSE[2845] logger.c: dialparties.agi: Extension 21 has ExtensionState: 0
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 21
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- dialparties.agi: dbset CALLTRACE/21 to +491234567890
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- dialparties.agi: Filtered ARG3: 21
[Oct 29 08:13:34] DEBUG[2849] manager.c: Manager received command 'Logoff'
[Oct 29 08:13:34] VERBOSE[2849] logger.c: == Manager 'admin' logged off from 127.0.0.1
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- AGI Script dialparties.agi completed, returning 0
[Oct 29 08:13:34] DEBUG[2845] app_macro.c: Executed application: AGI
[Oct 29 08:13:34] DEBUG[2845] pbx.c: Launching 'Dial'
[Oct 29 08:13:34] VERBOSE[2845] logger.c: -- Executing [s@macro-dial:7] Dial("Celliax/mobigater1", "SIP/21||tr") in new stack
[Oct 29 08:13:34] DEBUG[2845] chan_sip.c: Asked to create a SIP channel with formats: 0x40 (slin)
[Oct 29 08:13:34] DEBUG[2845] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
[Oct 29 08:13:34] DEBUG[2846] celliax_additional.c: rev 1095M[0x87d0f68|b6e72b90][DEBUG_AT 1471 ][mobigater1][ 4, 4, 4] CALLFLOW_CALL_INCOMING call_incoming_time.tv_sec=0, call_incoming_timeout.tv_sec=1225264404
[Oct 29 08:13:34] DEBUG[2845] chan_sip.c: Setting NAT on RTP to On
[Oct 29 08:13:34] DEBUG[2845] chan_sip.c: *** Our native formats are 0x4 (ulaw)
[Oct 29 08:13:34] DEBUG[2845] chan_sip.c: *** Joint capabilities are 0x0 (nothing)
[Oct 29 08:13:34] DEBUG[2845] chan_sip.c: *** Our capabilities are 0x4 (ulaw)
[Oct 29 08:13:34] DEBUG[2845] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw)
[Oct 29 08:13:34] DEBUG[2845] chan_sip.c: *** Our preferred formats from the incoming channel are 0x40 (slin)
[Oct 29 08:13:34] DEBUG[2845] chan_sip.c: This channel will not be able to handle video.
[Oct 29 08:13:34] DEBUG[2845] rtp.c: Channel 'Celliax/mobigater1' has no RTP, not doing anything
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable MACRO_DEPTH.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable AGISTATUS.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable ds.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable FILTERED_DIAL.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable DIALSTATUS_CW.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Copying hard-transferable variable KEEPCID.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable ARG3.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable ARG2.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable ARG1.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable MACRO_PRIORITY.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable MACRO_CONTEXT.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable MACRO_EXTEN.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable RT.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable CFBEXT.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable CFUEXT.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable EXTTOCALL.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable VMBOX.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable RingGroupMethod.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Copying hard-transferable variable TTL.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable AMPUSERCIDNAME.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable AMPUSER.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable REALCALLERIDNUM.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Copying hard-transferable variable RINGTIMER.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Copying hard-transferable variable CALLINGPRES_SV.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable FAX_RX.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Not copying variable LOOKUPBLSTATUS.
[Oct 29 08:13:34] DEBUG[2845] channel.c: Copying hard-transferable variable FROM_DID.
[Oct 29 08:13:34] DEBUG[2845] chan_sip.c: Outgoing Call for 21
[Oct 29 08:13:34] DEBUG[2845] chan_sip.c: Updating call counter for outgoing call
[Oct 29 08:13:34] DEBUG[2845] chan_sip.c: Call to peer '21' is 1 out of 50
[Oct 29 08:13:34] DEBUG[2845] devicestate.c: Notification of state change to be queued on device/channel SIP/21
[Oct 29 08:13:34] DEBUG[2845] chan_sip.c: Our T38 capability (0),