the rosdep view is empty: call 'sudo rosdep init' and 'rosdep update' [INFO] [WallTime: 1448094602.831329] FBM1W benchmarking node started [INFO] [WallTime: 1448094602.844254] Waiting for client... [INFO] [WallTime: 1448094602.846532] >>> WAITING_CLIENT [INFO] [WallTime: 1448094603.108986] >>> READY Wait for item_instance from the CFH [INFO] [WallTime: 1448094603.110205] >>> WAITING_MANUAL_OPERATION [INFO] [WallTime: 1448094671.009296] >>> COMPLETED_MANUAL_OPERATION Item: AX-03 AX-03 {'instance': 'AX-03', 'trans': [0.016324960187835327, -0.29168258261977625, -0.015764556357028715], 'rot': [0.0008282911283422552, 0.0030224434862668797, -0.02465403021301461, 0.999691131081713], 'class': 'Transmission Parts', 'id': 5} [INFO] [WallTime: 1448094671.010666] Sending goal... [INFO] [WallTime: 1448094671.011496] >>> TRANSMITTING_GOAL [INFO] [WallTime: 1448094671.108839] >>> EXECUTING_GOAL [INFO] [WallTime: 1448094671.110008] Executing goal... [INFO] [WallTime: 1448094671.110789] Waiting for result... [INFO] [WallTime: 1448094671.111594] >>> WAITING_RESULT [INFO] [WallTime: 1448094698.809294] >>> READY [INFO] [WallTime: 1448094698.814342] Received result - Class: AX Instance: 3 X: 0.326119 Y: 0.094460 W: -28.690800 [INFO] [WallTime: 1448094699.819642] Item received - X: 0.32612 Y: 0.09446 W: -28.69 [INFO] [WallTime: 1448094699.820771] Item acquired - X: 0.36736 Y: 0.06734 W: -36.60 [INFO] [WallTime: 1448094699.821791] Error measured - X: -0.04124 Y: 0.02712 W: 7.91 [INFO] [WallTime: 1448094699.822947] Position score - 0.953349 (0.950645 | 0.956053) [INFO] [WallTime: 1448094699.824358] Execution time - 27.699616 Wait for item_instance from the CFH [INFO] [WallTime: 1448094699.825720] >>> WAITING_MANUAL_OPERATION [INFO] [WallTime: 1448094767.909187] >>> COMPLETED_MANUAL_OPERATION Item: AX-01 AX-01 {'instance': 'AX-01', 'trans': [-0.011771168749223456, -0.283139413528425, -0.009678989520638127], 'rot': [-0.009588221743786773, 0.0029440243237564717, -0.010159221472285396, 0.9998980892789275], 'class': 'Bearing Boxes', 'id': 4} [INFO] [WallTime: 1448094767.910345] Sending goal... [INFO] [WallTime: 1448094767.911120] >>> TRANSMITTING_GOAL [INFO] [WallTime: 1448094768.008795] >>> EXECUTING_GOAL [INFO] [WallTime: 1448094768.009953] Executing goal... [INFO] [WallTime: 1448094768.010716] Waiting for result... [INFO] [WallTime: 1448094768.011531] >>> WAITING_RESULT [INFO] [WallTime: 1448094783.609540] >>> READY [INFO] [WallTime: 1448094783.613085] Received result - Class: AX Instance: 1 X: 0.280911 Y: 0.154498 W: -58.842600 [INFO] [WallTime: 1448094784.615715] Item received - X: 0.28091 Y: 0.15450 W: -58.84 [INFO] [WallTime: 1448094784.616567] Item acquired - X: 0.31033 Y: 0.06828 W: 30.63 [INFO] [WallTime: 1448094784.617532] Error measured - X: -0.02942 Y: 0.08621 W: -89.48 [INFO] [WallTime: 1448094784.618376] Position score - 0.705908 (0.908904 | 0.502912) [INFO] [WallTime: 1448094784.619205] Execution time - 43.300047 Wait for item_instance from the CFH [INFO] [WallTime: 1448094784.619804] >>> WAITING_MANUAL_OPERATION [INFO] [WallTime: 1448094864.409193] >>> COMPLETED_MANUAL_OPERATION Item: AX-09 AX-09 {'instance': 'AX-09', 'trans': [0.020717448786317802, -0.24987155363647953, -0.015176506873115236], 'rot': [-0.002928046678956268, 0.002675804151886236, -0.0124857692174273, 0.9999141824085883], 'class': 'Transmission Parts', 'id': 7} [INFO] [WallTime: 1448094864.410397] Sending goal... [INFO] [WallTime: 1448094864.411142] >>> TRANSMITTING_GOAL [INFO] [WallTime: 1448094864.508808] >>> EXECUTING_GOAL [INFO] [WallTime: 1448094864.509904] Executing goal... [INFO] [WallTime: 1448094864.510618] Waiting for result... [INFO] [WallTime: 1448094864.511395] >>> WAITING_RESULT [INFO] [WallTime: 1448094880.709213] >>> READY [INFO] [WallTime: 1448094880.713828] Received result - Class: AX Instance: 09 X: 0.458697 Y: 0.061740 W: -8.491530 [INFO] [WallTime: 1448094881.718417] Item received - X: 0.45870 Y: 0.06174 W: -8.49 [INFO] [WallTime: 1448094881.719329] Item acquired - X: 0.41963 Y: 0.09560 W: -0.84 [INFO] [WallTime: 1448094881.720246] Error measured - X: 0.03907 Y: -0.03386 W: -7.65 [INFO] [WallTime: 1448094881.721315] Position score - 0.952909 (0.948299 | 0.957518) [INFO] [WallTime: 1448094881.722144] Execution time - 59.499576 Wait for item_instance from the CFH [INFO] [WallTime: 1448094881.723837] >>> WAITING_MANUAL_OPERATION [INFO] [WallTime: 1448094952.009026] >>> COMPLETED_MANUAL_OPERATION Item: AX-09 AX-09 {'instance': 'AX-09', 'trans': [0.020717448786317802, -0.24987155363647953, -0.015176506873115236], 'rot': [-0.002928046678956268, 0.002675804151886236, -0.0124857692174273, 0.9999141824085883], 'class': 'Transmission Parts', 'id': 7} [INFO] [WallTime: 1448094952.010245] Sending goal... [INFO] [WallTime: 1448094952.011030] >>> TRANSMITTING_GOAL [INFO] [WallTime: 1448094952.108933] >>> EXECUTING_GOAL [INFO] [WallTime: 1448094952.109851] Executing goal... [INFO] [WallTime: 1448094952.110335] Waiting for result... [INFO] [WallTime: 1448094952.110721] >>> WAITING_RESULT [INFO] [WallTime: 1448094968.409009] >>> READY [INFO] [WallTime: 1448094968.413401] Received result - Class: AX Instance: 09 X: 0.388710 Y: 0.209562 W: 3.332020 [INFO] [WallTime: 1448094969.417121] Item received - X: 0.38871 Y: 0.20956 W: 3.33 [INFO] [WallTime: 1448094969.418108] Item acquired - X: 0.35451 Y: 0.12592 W: 62.19 [INFO] [WallTime: 1448094969.418914] Error measured - X: 0.03420 Y: 0.08364 W: -58.85 [INFO] [WallTime: 1448094969.419803] Position score - 0.791333 (0.909636 | 0.673031) [INFO] [WallTime: 1448094969.420564] Execution time - 75.799445 Wait for item_instance from the CFH [INFO] [WallTime: 1448094969.422500] >>> WAITING_MANUAL_OPERATION [INFO] [WallTime: 1448095008.209051] >>> COMPLETED_MANUAL_OPERATION Item: AX-02 AX-02 {'instance': 'AX-02', 'trans': [-0.003836057407485367, -0.2641597876416293, -0.015484888714543016], 'rot': [-0.00018519313847959238, 0.0037846741524431575, -0.019119746493270143, 0.9998100205734559], 'class': 'Transmission Parts', 'id': 6} [INFO] [WallTime: 1448095008.210391] Sending goal... [INFO] [WallTime: 1448095008.211141] >>> TRANSMITTING_GOAL [INFO] [WallTime: 1448095008.308889] >>> EXECUTING_GOAL [INFO] [WallTime: 1448095008.310062] Executing goal... [INFO] [WallTime: 1448095008.310863] Waiting for result... [INFO] [WallTime: 1448095008.311941] >>> WAITING_RESULT [INFO] [WallTime: 1448095025.409119] >>> READY [INFO] [WallTime: 1448095025.414128] Received result - Class: AX Instance: 2 X: 0.454537 Y: 0.130657 W: -95.267500 [INFO] [WallTime: 1448095026.418332] Item received - X: 0.45454 Y: 0.13066 W: -95.27 [INFO] [WallTime: 1448095026.419324] Item acquired - X: 0.48959 Y: 0.08031 W: 88.94 [INFO] [WallTime: 1448095026.420260] Error measured - X: -0.03506 Y: 0.05035 W: 175.79 [INFO] [WallTime: 1448095026.421629] Position score - 0.481019 (0.938652 | 0.023386) [INFO] [WallTime: 1448095026.422427] Execution time - 92.899060 Wait for item_instance from the CFH [INFO] [WallTime: 1448095026.423179] >>> WAITING_MANUAL_OPERATION [INFO] [WallTime: 1448095069.209052] >>> COMPLETED_MANUAL_OPERATION Item: AX-02 AX-02 {'instance': 'AX-02', 'trans': [-0.003836057407485367, -0.2641597876416293, -0.015484888714543016], 'rot': [-0.00018519313847959238, 0.0037846741524431575, -0.019119746493270143, 0.9998100205734559], 'class': 'Transmission Parts', 'id': 6} [INFO] [WallTime: 1448095069.210247] Sending goal... [INFO] [WallTime: 1448095069.210963] >>> TRANSMITTING_GOAL [INFO] [WallTime: 1448095069.308985] >>> EXECUTING_GOAL [INFO] [WallTime: 1448095069.310142] Executing goal... [INFO] [WallTime: 1448095069.310937] Waiting for result... [INFO] [WallTime: 1448095069.311588] >>> WAITING_RESULT [INFO] [WallTime: 1448095085.309195] >>> READY [INFO] [WallTime: 1448095085.314046] Received result - Class: AX Instance: 2 X: 0.298913 Y: 0.125470 W: -45.375500 [INFO] [WallTime: 1448095086.316991] Item received - X: 0.29891 Y: 0.12547 W: -45.38 [INFO] [WallTime: 1448095086.317903] Item acquired - X: 0.33099 Y: 0.07870 W: -45.93 [INFO] [WallTime: 1448095086.318766] Error measured - X: -0.03208 Y: 0.04677 W: 0.55 [INFO] [WallTime: 1448095086.320390] Position score - 0.970110 (0.943289 | 0.996931) [INFO] [WallTime: 1448095086.321224] Execution time - 108.898992 Wait for item_instance from the CFH [INFO] [WallTime: 1448095086.322092] >>> WAITING_MANUAL_OPERATION [INFO] [WallTime: 1448095136.709028] >>> COMPLETED_MANUAL_OPERATION Item: EM-02 EM-02 {'instance': 'EM-02', 'trans': [-0.02143111920346969, -0.2883062460451405, -0.011437094400024326], 'rot': [-0.00559478256387453, 0.0030181520516779775, -0.006811097125349696, 0.9999565981192409], 'class': 'Containers', 'id': 2} [INFO] [WallTime: 1448095136.710241] Sending goal... [INFO] [WallTime: 1448095136.710986] >>> TRANSMITTING_GOAL [INFO] [WallTime: 1448095136.808881] >>> EXECUTING_GOAL [INFO] [WallTime: 1448095136.810082] Executing goal... [INFO] [WallTime: 1448095136.811011] Waiting for result... [INFO] [WallTime: 1448095136.811853] >>> WAITING_RESULT [INFO] [WallTime: 1448095153.109245] >>> READY [INFO] [WallTime: 1448095153.114354] Received result - Class: EM Instance: 2 X: 0.317764 Y: 0.184677 W: -116.753000 [INFO] [WallTime: 1448095154.117491] Item received - X: 0.31776 Y: 0.18468 W: -116.75 [INFO] [WallTime: 1448095154.118322] Item acquired - X: 0.36389 Y: 0.15874 W: -117.81 [INFO] [WallTime: 1448095154.119088] Error measured - X: -0.04612 Y: 0.02594 W: 1.06 [INFO] [WallTime: 1448095154.119932] Position score - 0.970605 (0.947082 | 0.994129) [INFO] [WallTime: 1448095154.120843] Execution time - 125.198276 Wait for item_instance from the CFH [INFO] [WallTime: 1448095154.121694] >>> WAITING_MANUAL_OPERATION [INFO] [WallTime: 1448095197.409046] >>> COMPLETED_MANUAL_OPERATION Item: AX-16 AX-16 {'instance': 'AX-16', 'trans': [-0.000304264770757446, -0.27834950972503736, -0.011984908474551336], 'rot': [-0.005667760405302253, 0.0028264466456666398, -0.009896205476109352, 0.9999309740219682], 'class': 'Bearing Boxes', 'id': 3} [INFO] [WallTime: 1448095197.410321] Sending goal... [INFO] [WallTime: 1448095197.411071] >>> TRANSMITTING_GOAL [INFO] [WallTime: 1448095197.508865] >>> EXECUTING_GOAL [INFO] [WallTime: 1448095197.510001] Executing goal... [INFO] [WallTime: 1448095197.510725] Waiting for result... [INFO] [WallTime: 1448095197.511559] >>> WAITING_RESULT [INFO] [WallTime: 1448095214.008966] >>> READY [INFO] [WallTime: 1448095214.013336] Received result - Class: AX Instance: 16 X: 0.391592 Y: 0.136613 W: -5.631480 [INFO] [WallTime: 1448095215.017530] Item received - X: 0.39159 Y: 0.13661 W: -5.63 [INFO] [WallTime: 1448095215.018494] Item acquired - X: 0.43072 Y: 0.09625 W: -0.91 [INFO] [WallTime: 1448095215.019292] Error measured - X: -0.03913 Y: 0.04037 W: -4.72 [INFO] [WallTime: 1448095215.020115] Position score - 0.958766 (0.943779 | 0.973752) [INFO] [WallTime: 1448095215.020896] Execution time - 141.697759 Wait for item_instance from the CFH [INFO] [WallTime: 1448095215.021670] >>> WAITING_MANUAL_OPERATION [INFO] [WallTime: 1448095262.408995] >>> COMPLETED_MANUAL_OPERATION Item: AX-01 AX-01 {'instance': 'AX-01', 'trans': [-0.011771168749223456, -0.283139413528425, -0.009678989520638127], 'rot': [-0.009588221743786773, 0.0029440243237564717, -0.010159221472285396, 0.9998980892789275], 'class': 'Bearing Boxes', 'id': 4} [INFO] [WallTime: 1448095262.410338] Sending goal... [INFO] [WallTime: 1448095262.411145] >>> TRANSMITTING_GOAL [INFO] [WallTime: 1448095262.508863] >>> EXECUTING_GOAL [INFO] [WallTime: 1448095262.510000] Executing goal... [INFO] [WallTime: 1448095262.510738] Waiting for result... [INFO] [WallTime: 1448095262.511524] >>> WAITING_RESULT [INFO] [WallTime: 1448095278.709049] >>> READY [INFO] [WallTime: 1448095278.714409] Received result - Class: AX Instance: 1 X: 0.334015 Y: 0.178771 W: -36.348900 [INFO] [WallTime: 1448095279.718491] Item received - X: 0.33402 Y: 0.17877 W: -36.35 [INFO] [WallTime: 1448095279.719569] Item acquired - X: 0.39241 Y: 0.08248 W: 64.36 [INFO] [WallTime: 1448095279.720290] Error measured - X: -0.05839 Y: 0.09629 W: -100.71 [INFO] [WallTime: 1448095279.721113] Position score - 0.663938 (0.887391 | 0.440485) [INFO] [WallTime: 1448095279.721892] Execution time - 157.897160 Wait for item_instance from the CFH [INFO] [WallTime: 1448095279.722813] >>> WAITING_MANUAL_OPERATION [INFO] [WallTime: 1448095314.808946] >>> COMPLETED_MANUAL_OPERATION Item: AX-09 AX-09 {'instance': 'AX-09', 'trans': [0.020717448786317802, -0.24987155363647953, -0.015176506873115236], 'rot': [-0.002928046678956268, 0.002675804151886236, -0.0124857692174273, 0.9999141824085883], 'class': 'Transmission Parts', 'id': 7} [INFO] [WallTime: 1448095314.810417] Sending goal... [INFO] [WallTime: 1448095314.811257] >>> TRANSMITTING_GOAL [INFO] [WallTime: 1448095314.908883] >>> EXECUTING_GOAL [INFO] [WallTime: 1448095314.910082] Executing goal... [INFO] [WallTime: 1448095314.911101] Waiting for result... [INFO] [WallTime: 1448095314.912061] >>> WAITING_RESULT [INFO] [WallTime: 1448095330.409149] >>> READY [INFO] [WallTime: 1448095330.413711] Received result - Class: AX Instance: 09 X: 0.241403 Y: 0.103476 W: 18.618800 [ERROR] [WallTime: 1448095330.508900] State transition not allowed: READY -> READY ###### STOP [ERROR] [WallTime: 1448095330.609082] State transition not allowed: READY -> READY ###### STOP [ERROR] [WallTime: 1448095330.708925] State transition not allowed: READY -> READY ###### STOP [ERROR] [WallTime: 1448095330.809050] State transition not allowed: READY -> READY ###### STOP [ERROR] [WallTime: 1448095330.908952] State transition not allowed: READY -> READY ###### STOP [ERROR] [WallTime: 1448095331.008995] State transition not allowed: READY -> READY ###### STOP [ERROR] [WallTime: 1448095331.108888] State transition not allowed: READY -> READY ###### STOP [ERROR] [WallTime: 1448095331.208977] State transition not allowed: READY -> READY ###### STOP [ERROR] [WallTime: 1448095331.308936] State transition not allowed: READY -> READY ###### STOP [ERROR] [WallTime: 1448095331.409013] State transition not allowed: READY -> READY ###### STOP [INFO] [WallTime: 1448095331.416553] Item received - X: 0.24140 Y: 0.10348 W: 18.62 [INFO] [WallTime: 1448095331.417509] Item acquired - X: 0.28010 Y: 0.09090 W: -19.72 [INFO] [WallTime: 1448095331.418142] Error measured - X: -0.03870 Y: 0.01258 W: 38.34 [INFO] [WallTime: 1448095331.418975] Position score - 0.873158 (0.959311 | 0.787005) [INFO] [WallTime: 1448095331.419612] Execution time - 173.397197 [INFO] [WallTime: 1448095331.423656] >>> TRANSMITTING_SCORE [INFO] [WallTime: 1448095331.509067] >>> END [INFO] [WallTime: 1448095331.510149] >>> END {'execution_time': 173.3971972465515, 'class_accuracy': 0.0, 'instance_accuracy': 0.0, 'pose_score': 0.8321094574408383} Press ENTER to close[INFO] [WallTime: 1448095331.608881] >>> END