bindService执行成功后，低概率出现onServiceConnected没有被调用

Question

从最近抓取的日志上看，发现重试的次数不固定
Line 99: 01-02 03:24:13.880 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 123: 01-02 03:24:14.010 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 147: 01-02 03:24:14.130 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 171: 01-02 03:24:14.250 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 195: 01-02 03:24:14.370 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 219: 01-02 03:24:14.490 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 243: 01-02 03:24:14.610 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 267: 01-02 03:24:14.730 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 291: 01-02 03:24:14.860 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 316: 01-02 03:24:14.980 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 340: 01-02 03:24:15.100 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 365: 01-02 03:24:15.220 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 388: 01-02 03:24:15.330 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 412: 01-02 03:24:15.450 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 437: 01-02 03:24:15.580 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 461: 01-02 03:24:15.700 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 484: 01-02 03:24:15.820 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 508: 01-02 03:24:15.940 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 534: 01-02 03:24:16.060 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 558: 01-02 03:24:16.180 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 583: 01-02 03:24:16.380 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 607: 01-02 03:24:16.500 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 631: 01-02 03:24:16.620 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 655: 01-02 03:24:16.740 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 679: 01-02 03:24:16.870 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 703: 01-02 03:24:16.990 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 727: 01-02 03:24:17.110 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 751: 01-02 03:24:17.240 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 776: 01-02 03:24:17.360 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 800: 01-02 03:24:17.490 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 824: 01-02 03:24:17.610 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 848: 01-02 03:24:17.730 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 872: 01-02 03:24:17.850 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 896: 01-02 03:24:17.970 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 921: 01-02 03:24:18.100 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 945: 01-02 03:24:18.220 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 968: 01-02 03:24:18.330 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 992: 01-02 03:24:18.450 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 1017: 01-02 03:24:18.580 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 1040: 01-02 03:24:18.690 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 1064: 01-02 03:24:18.810 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 1088: 01-02 03:24:18.930 D/BluetoothAvrcpCtl( 3492): bind successfully //zyf
Line 1099: 01-02 03:24:19.020 D/BluetoothAvrcpCtl( 3492): Proxy object connected //zyf
整个过程持续了约6s

Answer 1

建议debug看看，是不是没有绑定成功，即实际没有bindservice

Answer 2

具体怎么看呢？
绑定的代码如下       if (!context.bindService(new Intent(IBluetoothAvrcpCtl.class.getName()), mConnection, Context.BIND_AUTO_CREATE)) {
               Log.e(TAG, "Could not bind to Bluetooth AVRCP CT Service");
            }
            else
            {
                Log.d(TAG,"bind successfully //zyf");
            }可以肯定，bindService肯定是执行过的

Answer 3

oncreate肯定要执行的，你debug那里看

Answer 4

忘了说了，这个service是startService的方式启动的    Intent in = new Intent();
    in.putExtras(intent);
    in.setClass(context, BluetoothAvrcpCtlService.class);
    String action = intent.getAction();
    in.putExtra("action", action);    Log.d(TAG,"Calling start service!!!! with action = " + in.getAction());
    context.startService(in);我先说说我现在的分析结论
1.service是以startService的方式启动的，所以bindService的过程应该不会触发onCreate和onStartCommand
2.失败后的重试过程中，并没有其他行为调用stopService来结束服务。因为代码中没有stopService；调用startService的地方也只有一个，从日志上看，也只执行了一次正常的日志如下
Line 2511: 01-02 00:06:53.340 D/BluetoothAvrcpCtlReceiver( 3062): Calling start service!!!! with action = null //zyf
Line 2516: 01-02 00:06:53.370 D/BluetoothAvrcpCtlService( 3062): onCreate //zyf
Line 2521: 01-02 00:06:53.380 D/BluetoothAvrcpCtlService( 3062): onStartCommand //zyf
Line 2819: 01-02 00:06:54.070 D/BluetoothAvrcpCtl( 3545): Proxy object connected //zyf
Line 2820: 01-02 00:06:54.070 D/BluetoothAvrcpCtl( 3545): bind successfully //zyf
Line 3280: 01-02 00:07:01.200 D/BluetoothAvrcpCtl( 3545): Proxy object connected //zyf
Line 3281: 01-02 00:07:01.200 D/BluetoothAvrcpCtl( 3545): bind successfully //zyf

Answer 5

无论怎么启动service都需要启动oncreate，你不启动那里来service？？

Answer 6

hello,可能我没说清楚
我的意思是说service最初是以startService的方式启动的，所以后面的bindService后，应该不会再调用onCreate
我这么说没问题吧？

Answer 7

打印service里的日志显示，异常出现时，service并没有block在某一个动作上，应该处于idle状态

Answer 8

哦，启动了service在bind么，那看下bindservice里面是否获取到了service的实例，，，

Answer 9

看来只能这么看看了，刚把debug版本编译出来

Answer 10

在OnBind（）方法中需返回一个IBinder实例，不然onServiceConnected方法不会调用。