Problem with Android Market license checker

nad

Active Member
Licensed User
Longtime User
Hello,

I am getting the following error in the log from one user of one of my apps (he sent it to me) when validating the license with the Android Market license checker


I am not good checking android logs, but have changed some interesting lines to red for easy reading. I think it verifies that there is connection, so it sends a license validation request. it takes really long >15segs and then fails giving back a NotAllowed.

What happens with Basic4Android license checker library if there is a timeout after it has validated that there is connection? Do we get a NotAllowed? Shouldn't the library use the cached license instead?

Just wondering.

Thanks a lot in advance

03-23 18:20:02.882 I/ActivityManager( 331): Starting: Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=****myapp/.main } from pid 716
03-23 18:20:02.898 W/ApplicationContext( 331): Unable to create files directory

03-23 18:20:02.968 D/PhoneWindow( 716): couldn't save which view has focus because the focused view com.jiubang.ggheart.apps.desks.appfunc.XViewFrame@ 4089f430 has no id.
03-23 18:20:02.992 E/dalvikvm(20437): could not disable core file generation for pid 20437: Operation not permitted
03-23 18:20:02.992 I/ActivityManager( 331): Start proc ****myapp for activity ****myapp/.main: pid=20437 uid=10156 gids={3003}
03-23 18:20:03.281 I/B4A (20437): ** Activity (main) Create, isFirst = true **

03-23 18:20:03.421 D/dalvikvm(20437): GC_EXTERNAL_ALLOC freed 78K, 43% free 3107K/5379K, external 1837K/2122K, paused 135ms
03-23 18:20:04.093 D/Finsky (20104): [1] ContentSyncService.getAccountsToSync: Checking account to sync; Syncing 73 packages (127 versions) and skipped 172 system packages.
03-23 18:20:04.140 D/Finsky (20104): [1] ContentSyncService.attemptContentSync: Syncing account to sync; Syncing 73 packages (127 versions) and skipped 172 system packages.
03-23 18:20:04.289 I/LicenseChecker(20437): Binding to licensing service.
03-23 18:20:04.484 I/TelephonyRegistry( 331): notifyServiceState: 0 home domestic Globe Telecom-PH GLOBE 51502 (manual) HSDPA CSS not supported -1 -1RoamInd: -1DefRoamInd: -1EmergOnly: false
03-23 18:20:04.492 D/PollingManager( 484): phone state: 0 home domestic Globe Telecom-PH GLOBE 51502 (manual) HSDPA CSS not supported -1 -1RoamInd: -1DefRoamInd: -1EmergOnly: false
03-23 18:20:04.492 D/RadioSignalLevelVzwGlobalPhone( 451): umts level: 0
03-23 18:20:04.492 D/StatusBarPolicy( 451): ERI alert sound is disabled.
03-23 18:20:04.492 D/StatusBarPolicy( 451): iconLevel=0
03-23 18:20:04.500 D/StatusBarPolicy( 451): mOperatorName_string is @0, net= 8
03-23 18:20:04.500 I/TelephonyRegistry( 331): notifyDataConnection: state=2 isDataConnectivityPossible=true reason=nwTypeChanged interfaceName=ppp0 networkType=8
03-23 18:20:04.507 V/StatusBarPolicy( 451): state= 2, networkType= 8
03-23 18:20:04.507 D/StatusBarPolicy( 451): mOperatorName_string is @0, net= 8
03-23 18:20:04.515 D/NetStatUtils(18188): Receive a broadcast: android.intent.action.ANY_DATA_STATE
03-23 18:20:04.515 D/NetStatUtils(18188): Received android.intent.action.ANY_DATA_STATE broadcast - state = CONNECTED, unavailable = false
03-23 18:20:04.515 D/NetStatUtils(18188): The APN name is: http.globe.com.ph and the interface is: ppp0
03-23 18:20:04.515 D/NetStatUtils(18188): This APN has been recorded before yet.
03-23 18:20:04.515 D/NetStatUtils(18188): This APN has been active yet. the corresponding interface is: ppp0
03-23 18:20:04.523 D/PhoneApp( 482): [NotificationMgr] updateNetworkSelection()...state = 0 new network Globe Telecom-PH 3G
03-23 18:20:04.523 D/PhoneApp( 482): mIsInRoaming :false isInRoaming :false hasService :true
03-23 18:20:04.523 D/PhoneApp( 482): mReceiver: ACTION_ANY_DATA_CONNECTION_STATE_CHANGED
03-23 18:20:04.523 I/ActivityManager( 331): Displayed ****myapp/.main: +1s553ms
03-23 18:20:04.523 D/PhoneApp( 482): - state: CONNECTED
03-23 18:20:04.523 D/PhoneApp( 482): - reason: nwTypeChanged
03-23 18:20:04.523 D/CallNotifier( 482): CallNotifier()-> rcvd: EVENT_SERVICE_STATE_CHANGED
03-23 18:20:04.523 D/CallNotifier( 482): CallNotifier()-> state is STATE_IN_SERVICE
03-23 18:20:04.523 D/PhoneApp( 482): [NotificationMgr] hideDataDisconnectedRoaming()...
03-23 18:20:04.531 I/LicenseChecker(20437): Calling checkLicense on service for ****myapp
03-23 18:20:04.531 I/LicenseChecker(20437): Start monitoring timeout.
03-23 18:20:04.539 D/MobileDataStateTracker( 331): replacing old mInterfaceName (ppp0) with ppp0 for hipri
03-23 18:20:04.546 D/ConnectivityService( 331): Radio access technology (RAT) changeupdating network settings
03-23 18:20:04.546 W/NetworkStateTracker( 331): net.tcp.buffersize.hsdpa not found in system properties. Using defaults
03-23 18:20:04.546 V/NetworkStateTracker( 331): Setting TCP values: [65536,131072,262144,4096,16384,131072] which comes from [net.tcp.buffersize.default]
03-23 18:20:04.546 D/MobileDataStateTracker( 331): replacing old mInterfaceName (ppp0) with ppp0 for cbs
03-23 18:20:04.546 D/MobileDataStateTracker( 331): replacing old mInterfaceName (ppp0) with ppp0 for ims
03-23 18:20:04.554 D/MobileDataStateTracker( 331): replacing old mInterfaceName (ppp0) with ppp0 for fota
03-23 18:20:04.554 D/MobileDataStateTracker( 331): replacing old mInterfaceName (ppp0) with ppp0 for agps
03-23 18:20:04.554 D/MobileDataStateTracker( 331): replacing old mInterfaceName (ppp0) with ppp0 for dun
03-23 18:20:04.554 D/MobileDataStateTracker( 331): replacing old mInterfaceName (ppp0) with ppp0 for supl
03-23 18:20:04.554 D/MobileDataStateTracker( 331): replacing old mInterfaceName (ppp0) with ppp0 for mms
03-23 18:20:04.554 D/MobileDataStateTracker( 331): default Received state= CONNECTED, old= CONNECTED, reason= nwTypeChanged, apnTypeList= *
03-23 18:20:04.554 D/NetworkStateTracker( 331): setDetailed state, old =CONNECTED and new state=CONNECTED
03-23 18:20:04.554 D/VzwGpsLocationProvider( 331): state: CONNECTED apnName: http.globe.com.ph reason: nwTypeChanged
03-23 18:20:04.554 I/B4A (20437): ** Activity (main) Resume **
03-23 18:20:04.554 I/LicenseChecker(20437): Calling checkLicense on service for ****myapp
03-23 18:20:04.554 I/LicenseChecker(20437): Start monitoring timeout.
03-23 18:20:04.570 I/ggheart ( 716): onStop
03-23 18:20:05.851 D/Volley (20104): [14] BasicNetwork.logSlowRequests: HTTP response for request=<[ ] https://android.clients.google.com/fdfe/log NORMAL 101> [lifetime=5594], , [rc=200], [retryCount=1]
03-23 18:20:05.882 D/Volley (20104): [1] Request.finish: 5627 ms: [ ] https://android.clients.google.com/fdfe/log NORMAL 101
03-23 18:20:08.750 D/dalvikvm(20437): GC_EXTERNAL_ALLOC freed 590K, 46% free 3343K/6087K, external 2722K/2727K, paused 34ms
03-23 18:20:09.656 D/dalvikvm(10070): GC_EXPLICIT freed 361K, 47% free 3343K/6279K, external 1807K/2122K, paused 93ms
03-23 18:20:10.054 V/MOT_DEVICE_STATS( 494): [ID=EventLogs;ver=3.6;time=1332498009;tz=28800000;][dchrg;1332496491587;73;23258658;]
03-23 18:20:10.054 V/MOT_DEVICE_STATS_L1( 494): [ID=EventLogs;ver=3.6;time=1332498009;tz=28800000;][scrst;1332491440867;1;][scrst;1332491504710;0;][scrst;1332491515695;1;][scrst;1332491577906;0;][scrst;1332491670757;1;][scrst;1332491732523;0;][scrst;1332491860703;1;][scrst;1332491923554;0;][scrst;1332492403781;1;][scrst;1332492595476;0;][scrst;1332492831523;1;][scrst;1332492893382;0;][scrst;1332493000906;1;][scrst;1332493016914;0;][scrst;1332493319562;1;][scrst;1332493325148;0;][scrst;1332493465773;1;][scrst;1332493484328;0;][scrst;1332493853245;1;][scrst;1332494704292;0;][scrst;1332495455804;1;][scrst;1332495578531;0;][scrst;1332496008773;1;][scrst;1332496084328;0;][scrst;1332496094515;1;][scrst;1332496096625;0;][scrst;1332496399531;1;][scrst;1332496438546;0;][scrst;1332496491695;1;][scrst;1332496493609;0;][scrst;1332496515765;1;][scrst;1332496549796;0;][scrst;1332496755218;1;][blvl;1332491279633;62;4122;390;][blvl;1332491870835;52;4122;390;][blvl;1332492379695;52;3719;360;][blvl;1332494025273;42;3719;360;][blvl;1332494501705;42;3719;390;][blvl;1332495126768;32;3719;390;][blvl;1332495497767;32;3719;360;][blvl;1332496959693;32;3719;400;][blvl;1332497659247;32;3719;370;][bsts;1332496491587;1;26;][bsts;1332497928470;0;24;][bsts;1332497929241;1;24;]
03-23 18:20:10.054 V/MOT_DEVICE_STATS_L2( 494): [ID=EventLogs;ver=3.6;time=1332498009;tz=28800000;][apres;1332491440882;180;][appau;1332491504726;180;][apres;1332491515757;180;][appau;1332491577921;180;][apres;1332491670804;180;][appau;1332491732531;180;][apres;1332491860757;180;][appau;1332491923570;180;][apres;1332492364554;180;][appau;1332492364578;180;][apres;1332492381695;180;][appau;1332492381703;180;][apres;1332492403789;180;][appau;1332492411343;180;][apres;1332492411390;116;][appau;1332492414335;116;][apres;1332492414492;118;][appau;1332492560742;118;][apres;1332492560828;116;][appau;1332492563578;116;][apres;1332492563648;180;][appau;1332492600289;180;][apres;1332492831531;180;][appau;1332492893390;180;][apres;1332493000968;180;][appau;1332493016921;180;][apres;1332493307312;180;][appau;1332493307328;180;][apres;1332493319570;180;][appau;1332493325156;180;][apres;1332493465835;180;][appau;1332493484343;180;][apres;1332493774453;180;][appau;1332493774460;180;][apres;1332493853308;180;][appau;1332494332097;180;][apres;1332494332105;116;][appau;1332494336401;116;][apres;1332494336487;120;][appau;1332494354659;120;][apres;1332494354675;116;][appau;1332494360855;116;][apres;1332494361206;167;][appau;1332494463464;167;][apres;1332494463503;116;][appau;1332494484620;116;][apres;1332494484800;136;][appau;1332494698823;136;][apres;1332494698901;116;][appau;1332494705730;116;][apres;1332494760460;116;][appau;1332494760460;116;][apres;1332495455843;116;][appau;1332495459539;116;][apres;1332495459562;180;][appau;1332495461820;180;][apres;1332495462070;116;][appau;1332495468742;116;][apres;1332495468937;199;][appau;1332495502101;199;][apres;1332495502140;116;][appau;1332495511929;116;][apres;1332495511976;120;][appau;1332495575437;120;][apres;1332495575445;116;][appau;1332495578570;116;][apres;1332495602054;116;][appau;1332495602054;116;][apres;1332495607132;116;][appau;1332495607140;116;][apres;1332496008804;116;][appau;1332496013117;116;][apres;1332496013179;180;][appau;1332496016054;180;][apres;1332496016085;116;][appau;1332496035242;116;][apres;1332496035421;146;][appau;1332496056156;146;][apres;1332496056171;116;][appau;1332496084343;116;][apres;1332496094523;116;][appau;1332496096664;116;][apres;1332496399531;116;][appau;1332496402992;116;][apres;1332496403039;180;][appau;1332496435562;180;][apres;1332496435570;116;][appau;1332496438554;116;][apres;1332496491703;116;][appau;1332496493984;116;][apres;1332496515789;116;][appau;1332496550648;116;][apres;1332496755234;116;][appau;1332496759148;116;][apres;1332496759210;180;][appau;1332496763492;180;][apres;1332496763523;116;][appau;1332496764968;116;][apres;1332496765148;156;][appau;1332496794406;156;][apres;1332496794492;105;][appau;1332496795898;105;][apres;1332496796101;109;][appau;1332496894250;109;][apres;1332496894367;156;][appau;1332496911773;156;][apres;1332496911828;116;][appau;1332496914203;116;][apres;1332496914257;180;][appau;1332496915882;180;][apres;1332496915906;116;][appau;1332496919828;116;][apres;1332496919867;180;][appau;1332496923289;180;][apres;1332496923335;116;][appau;1332496929093;116;][apres;1332496929203;180;][appau;1332497140195;180;][apres;1332497154640;180;][appau;1332497871507;180;][apres;1332497871539;116;][appau;1332497886820;116;][apres;1332497887109;24;][appau;1332497908937;24;][apres;1332497908968;116;][appau;1332497912273;116;][apres;1332497912406;18;][appau;1332497993656;18;][apres;1332497993687;116;][appau;1332498002898;116;][apres;1332498003039;212;]
03-23 18:20:10.101 D/dalvikvm( 494): GC_CONCURRENT freed 757K, 45% free 3870K/6919K, external 1831K/2122K, paused 5ms+2ms
03-23 18:20:11.062 D/dalvikvm(20437): GC_EXTERNAL_ALLOC freed 274K, 47% free 3259K/6087K, external 3429K/3445K, paused 60ms
03-23 18:20:11.117 D/dalvikvm(20437): GC_EXTERNAL_ALLOC freed 16K, 47% free 3244K/6087K, external 4312K/4420K, paused 32ms
03-23 18:20:14.531 I/LicenseChecker(20437): Check timed out.
03-23 18:20:14.539 I/dalvikvm(20437): Total arena pages for JIT: 11
03-23 18:20:14.562 I/LicenseChecker(20437): Check timed out.
03-23 18:20:14.562 I/B4A (20437): DontAllow
03-23 18:20:14.632 I/B4A (20437): DontAllow

03-23 18:20:14.632 W/ActivityManager( 331): Duplicate finish request for HistoryRecord{40584b50 ****myapp/.main}
03-23 18:20:14.640 I/B4A (20437): ** Activity (main) Pause, UserClosed = true **
03-23 18:20:14.648 I/DemoService( 716): DiyScheduer.onStart
03-23 18:20:14.648 I/ggheart ( 716): onStart
03-23 18:20:16.460 D/Volley (20104): [13] BasicNetwork.logSlowRequests: HTTP response for request=<[ ] https://android.clients.google.com/v...api/ApiRequest NORMAL 102 ContentSyncRequestProto> [lifetime=12310], , [rc=200], [retryCount=0]
03-23 18:20:16.476 D/Finsky (20104): [1] 1.onResponse: Phonesky checkin succeeded for account
03-23 18:20:16.492 D/Finsky (20104): [1] 1.attemptNextSync: Done checking in.
03-23 18:20:16.531 D/Volley (20104): [1] Request.finish: 12388 ms: [ ] https://android.clients.google.com/v...api/ApiRequest NORMAL 102 ContentSyncRequestProto
03-23 18:20:16.609 D/dalvikvm(20104): GC_CONCURRENT freed 3173K, 58% free 4603K/10759K, external 7094K/7094K, paused 6ms+4ms
03-23 18:20:17.750 D/Volley (20104): [1] Request.finish: 13217 ms: [ ] https://android.clients.google.com/v...api/ApiRequest NORMAL 103 CheckLicenseRequestProto
03-23 18:20:17.750 I/LicenseChecker(20437): Received response.
03-23 18:20:19.257 I/LicenseChecker(20437): Received response.

03-23 18:20:19.257 D/Volley (20104): [1] Request.finish: 14699 ms: [ ] https://android.clients.google.com/v...api/ApiRequest NORMAL 104 CheckLicenseRequestProto
03-23 18:20:20.132 D/dalvikvm(18188): GC_EXPLICIT freed 1267K, 53% free 3107K/6535K, external 1807K/2122K, paused 70ms
03-23 18:20:21.601 I/ActivityManager( 331): No longer want com.avast.android.antitheft (pid 20287): hidden #21
03-23 18:20:21.898 I/ActivityManager( 331): Starting: Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=com.xtralogic.android.logcollector/.SendLogActivity } from pid 716
03-23 18:20:22.023 D/PhoneWindow( 716): couldn't save which view has focus because the focused view com.jiubang.ggheart.apps.desks.appfunc.XViewFrame@ 4089f430 has no id.
03-23 18:20:22.039 E/dalvikvm(20492): could not disable core file generation for pid 20492: Operation not permitted
03-23 18:20:22.046 I/ActivityManager( 331): Start proc com.xtralogic.android.logcollector for activity com.xtralogic.android.logcollector/.SendLogActivity: pid=20492 uid=10157 gids={1007, 1015}
03-23 18:20:22.187 D/dalvikvm(20492): GC_EXTERNAL_ALLOC freed 66K, 44% free 3060K/5379K, external 1846K/2122K, paused 46ms
 
Last edited:
Top