2018-08-07T18:22:55,673 INFO [main] sqlstd.SQLStdHiveAccessController: Current user : hive, Current Roles : [public[hive:USER]] 2018-08-07T18:22:55,818 INFO [main] ql.Driver: Completed compiling command(queryId=hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2); Time taken: 2.678 seconds 2018-08-07T18:22:55,822 INFO [main] lockmgr.DbTxnManager: Setting lock request transaction to txnid:0 for queryId=hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2 2018-08-07T18:22:55,826 INFO [main] lockmgr.DbLockManager: Requesting: queryId=hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2 LockRequest(component:[LockComponent(type:EXCLUSIVE, level:TABLE, dbname:default, tablename:temp_tro1, operationType:INSERT, isAcid:false), LockComponent(type:SHARED_READ, level:TABLE, dbname:bddbm_db, tablename:temp_large_table3, operationType:SELECT)], txnid:0, user:hive/bdtst1a.server@server, hostname:bdtst1b.server, agentInfo:hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2) 2018-08-07T18:22:55,954 INFO [main] lockmgr.DbLockManager: Response to queryId=hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2 LockResponse(lockid:25858, state:ACQUIRED) 2018-08-07T18:22:55,955 INFO [main] lockmgr.DbTxnManager: Started heartbeat with delay/interval = 150000/150000 MILLISECONDS for query: hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2 2018-08-07T18:22:55,956 INFO [main] ql.Driver: Executing command(queryId=hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2): insert into default.temp_tro1 (str1,str2,str3) select str1,str2,str3 from bddbm_db.temp_large_table3 2018-08-07T18:22:55,957 INFO [main] ql.Driver: Query ID = hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2 2018-08-07T18:22:55,957 INFO [main] ql.Driver: Total jobs = 1 2018-08-07T18:22:55,969 INFO [main] ql.Driver: Launching Job 1 out of 1 2018-08-07T18:22:55,969 INFO [main] ql.Driver: Starting task [Stage-1:MAPRED] in serial mode 2018-08-07T18:22:55,971 INFO [main] tez.TezSessionPoolManager: The current user: hive, session user: hive 2018-08-07T18:22:55,971 INFO [main] tez.TezSessionPoolManager: Current queue name is null incoming queue name is null 2018-08-07T18:22:56,001 INFO [a501276d-2015-435b-85c5-4d40534ac162 main] Configuration.deprecation: mapred.committer.job.setup.cleanup.needed is deprecated. Instead, use mapreduce.job.committer.setup.cleanup.needed 2018-08-07T18:22:56,020 INFO [main] ql.Context: New scratch dir is hdfs://edhcluster/tmp/hive/scratch/hive/a501276d-2015-435b-85c5-4d40534ac162/hive_2018-08-07_18-22-53_167_2618699013418541798-1 2018-08-07T18:22:56,036 INFO [a501276d-2015-435b-85c5-4d40534ac162 main] CliDriver: Waiting for Tez session and AM to be ready... 2018-08-07T18:22:56,476 INFO [main] exec.Task: Session is already open 2018-08-07T18:22:56,477 INFO [main] tez.DagUtils: Resource modification time: 1533637365055 for hdfs://edhcluster/tmp/hive/scratch/hive/_tez_session_dir/a501276d-2015-435b-85c5-4d40534ac162/zookeeper-3.4.10.jar 2018-08-07T18:22:56,477 INFO [main] tez.DagUtils: Resource modification time: 1533637365240 for hdfs://edhcluster/tmp/hive/scratch/hive/_tez_session_dir/a501276d-2015-435b-85c5-4d40534ac162/hive-hbase-handler-2.1.1.jar 2018-08-07T18:22:56,478 INFO [main] tez.DagUtils: Resource modification time: 1533637365777 for hdfs://edhcluster/tmp/hive/scratch/hive/_tez_session_dir/a501276d-2015-435b-85c5-4d40534ac162/guava-14.0.1.jar 2018-08-07T18:22:56,479 INFO [main] tez.DagUtils: Resource modification time: 1533637366150 for hdfs://edhcluster/tmp/hive/scratch/hive/_tez_session_dir/a501276d-2015-435b-85c5-4d40534ac162/hbase-client-1.2.5.jar 2018-08-07T18:22:56,480 INFO [main] tez.DagUtils: Resource modification time: 1533637366361 for hdfs://edhcluster/tmp/hive/scratch/hive/_tez_session_dir/a501276d-2015-435b-85c5-4d40534ac162/hbase-common-1.2.5.jar 2018-08-07T18:22:56,480 INFO [main] tez.DagUtils: Resource modification time: 1533637367327 for hdfs://edhcluster/tmp/hive/scratch/hive/_tez_session_dir/a501276d-2015-435b-85c5-4d40534ac162/hbase-protocol-1.2.5.jar 2018-08-07T18:22:56,481 INFO [main] tez.DagUtils: Resource modification time: 1533637368321 for hdfs://edhcluster/tmp/hive/scratch/hive/_tez_session_dir/a501276d-2015-435b-85c5-4d40534ac162/hbase-server-1.2.5.jar 2018-08-07T18:22:56,482 INFO [main] tez.DagUtils: Resource modification time: 1533637368460 for hdfs://edhcluster/tmp/hive/scratch/hive/_tez_session_dir/a501276d-2015-435b-85c5-4d40534ac162/hadoop-distcp-2.7.3.jar 2018-08-07T18:22:56,482 INFO [main] exec.Task: Dag name: insert into default.t...db.temp_large_table3(Stage-1) 2018-08-07T18:22:56,493 INFO [main] ql.Context: New scratch dir is hdfs://edhcluster/tmp/hive/scratch/hive/a501276d-2015-435b-85c5-4d40534ac162/hive_2018-08-07_18-22-53_167_2618699013418541798-1 2018-08-07T18:22:56,532 INFO [main] tez.DagUtils: Vertex has custom input? false 2018-08-07T18:22:56,589 INFO [main] exec.SerializationUtilities: Serializing MapWork using kryo 2018-08-07T18:22:56,601 INFO [main] exec.Utilities: Setting plan: /tmp/hive/scratch/hive/a501276d-2015-435b-85c5-4d40534ac162/hive_2018-08-07_18-22-53_167_2618699013418541798-1/hive/_tez_scratch_dir/d5cc1718-38b1-49ba-a97e-ab9f78415b62/map.xml 2018-08-07T18:22:56,669 INFO [main] fs.FSStatsPublisher: created : hdfs://edhcluster/user/hive/staging_hive_2018-08-07_18-22-53_167_2618699013418541798-1/-ext-10001 2018-08-07T18:22:56,686 INFO [main] client.TezClient: Submitting dag to TezSession, sessionName=HIVE-a501276d-2015-435b-85c5-4d40534ac162, applicationId=application_1533623337748_0376, dagName=insert into default.t...db.temp_large_table3(Stage-1), callerContext={ context=HIVE, callerType=HIVE_QUERY_ID, callerId=hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2 } 2018-08-07T18:22:57,206 INFO [main] client.TezClient: Submitted dag to TezSession, sessionName=HIVE-a501276d-2015-435b-85c5-4d40534ac162, applicationId=application_1533623337748_0376, dagId=dag_1533623337748_0376_1, dagName=insert into default.t...db.temp_large_table3(Stage-1) 2018-08-07T18:22:57,277 INFO [main] SessionState: 2018-08-07T18:22:57,719 INFO [main] SessionState: Status: Running (Executing on YARN cluster with App id application_1533623337748_0376) 2018-08-07T18:22:57,721 INFO [main] SessionState: Map 1: 0/165 2018-08-07T18:23:00,542 INFO [main] SessionState: Map 1: 0(+1)/165 2018-08-07T18:23:01,551 INFO [main] SessionState: Map 1: 0(+2)/165 2018-08-07T18:23:02,770 INFO [main] SessionState: Map 1: 0(+3)/165 2018-08-07T18:23:03,983 INFO [main] SessionState: Map 1: 0(+4)/165 2018-08-07T18:23:04,591 INFO [main] SessionState: Map 1: 0(+10)/165 2018-08-07T18:23:04,793 INFO [main] SessionState: Map 1: 0(+13)/165 2018-08-07T18:23:04,995 INFO [main] SessionState: Map 1: 0(+14)/165 2018-08-07T18:23:05,197 INFO [main] SessionState: Map 1: 0(+15)/165 2018-08-07T18:23:05,399 INFO [main] SessionState: Map 1: 0(+18)/165 2018-08-07T18:23:05,601 INFO [main] SessionState: Map 1: 0(+20)/165 2018-08-07T18:23:05,803 INFO [main] SessionState: Map 1: 0(+21)/165 2018-08-07T18:23:06,005 INFO [main] SessionState: Map 1: 0(+23)/165 2018-08-07T18:23:06,207 INFO [main] SessionState: Map 1: 0(+24)/165 2018-08-07T18:23:07,015 INFO [main] SessionState: Map 1: 0(+25)/165 2018-08-07T18:23:07,218 INFO [main] SessionState: Map 1: 0(+27)/165 2018-08-07T18:23:07,622 INFO [main] SessionState: Map 1: 0(+28)/165 2018-08-07T18:23:08,433 INFO [main] SessionState: Map 1: 0(+29)/165 2018-08-07T18:23:09,046 INFO [main] SessionState: Map 1: 0(+32)/165 2018-08-07T18:23:09,458 INFO [main] SessionState: Map 1: 0(+33)/165 2018-08-07T18:23:10,267 INFO [main] SessionState: Map 1: 0(+34)/165 2018-08-07T18:23:11,285 INFO [main] SessionState: Map 1: 0(+38)/165 2018-08-07T18:23:11,688 INFO [main] SessionState: Map 1: 0(+39)/165 2018-08-07T18:23:12,303 INFO [main] SessionState: Map 1: 0(+40)/165 2018-08-07T18:23:12,707 INFO [main] SessionState: Map 1: 0(+41)/165 2018-08-07T18:23:12,918 INFO [main] SessionState: Map 1: 0(+42)/165 2018-08-07T18:23:13,121 INFO [main] SessionState: Map 1: 0(+44)/165 2018-08-07T18:23:13,525 INFO [main] SessionState: Map 1: 0(+45)/165 2018-08-07T18:23:14,135 INFO [main] SessionState: Map 1: 0(+46)/165 2018-08-07T18:23:14,548 INFO [main] SessionState: Map 1: 0(+47)/165 2018-08-07T18:23:14,760 INFO [main] SessionState: Map 1: 0(+48)/165 2018-08-07T18:23:14,966 INFO [main] SessionState: Map 1: 0(+49)/165 2018-08-07T18:23:15,389 INFO [main] SessionState: Map 1: 0(+50)/165 2018-08-07T18:23:15,791 INFO [main] SessionState: Map 1: 0(+52)/165 2018-08-07T18:23:15,995 INFO [main] SessionState: Map 1: 0(+53)/165 2018-08-07T18:23:16,811 INFO [main] SessionState: Map 1: 0(+56)/165 2018-08-07T18:23:17,425 INFO [main] SessionState: Map 1: 0(+57)/165 2018-08-07T18:23:17,627 INFO [main] SessionState: Map 1: 0(+58)/165 2018-08-07T18:23:18,443 INFO [main] SessionState: Map 1: 0(+59)/165 2018-08-07T18:23:18,645 INFO [main] SessionState: Map 1: 0(+60)/165 2018-08-07T18:23:19,049 INFO [main] SessionState: Map 1: 0(+62)/165 2018-08-07T18:23:19,498 INFO [main] SessionState: Map 1: 0(+63)/165 2018-08-07T18:23:19,903 INFO [main] SessionState: Map 1: 0(+64)/165 2018-08-07T18:23:20,515 INFO [main] SessionState: Map 1: 0(+67)/165 2018-08-07T18:23:20,919 INFO [main] SessionState: Map 1: 0(+68)/165 2018-08-07T18:23:21,122 INFO [main] SessionState: Map 1: 0(+69)/165 2018-08-07T18:23:21,747 INFO [main] SessionState: Map 1: 0(+71)/165 2018-08-07T18:23:21,958 INFO [main] SessionState: Map 1: 0(+73)/165 2018-08-07T18:23:22,572 INFO [main] SessionState: Map 1: 0(+74)/165 2018-08-07T18:23:22,774 INFO [main] SessionState: Map 1: 0(+75)/165 2018-08-07T18:23:23,382 INFO [main] SessionState: Map 1: 0(+76)/165 2018-08-07T18:23:24,801 INFO [main] SessionState: Map 1: 0(+78)/165 2018-08-07T18:23:25,205 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:23:28,267 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:23:31,547 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:23:34,574 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:23:37,822 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:23:40,878 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:23:43,928 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:23:46,960 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:23:50,070 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:23:53,147 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:23:56,201 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:23:59,274 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:02,307 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:05,342 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:08,388 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:11,423 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:14,502 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:17,546 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:20,599 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:23,643 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:26,691 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:29,753 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:32,861 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:35,947 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:38,983 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:42,042 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:45,070 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:48,107 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:51,151 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:54,199 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:24:57,242 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:25:00,294 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:25:03,325 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:25:06,369 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:25:09,423 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:25:12,450 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:25:15,479 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:25:18,544 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:25:21,786 INFO [main] SessionState: Map 1: 0(+79)/165 2018-08-07T18:25:23,817 INFO [main] SessionState: Map 1: 1(+79)/165 2018-08-07T18:25:25,956 INFO [Heartbeater-0] lockmgr.DbTxnManager: Sending heartbeat for txnid:0 and lockid:25858 queryId=hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2 txnid:0 2018-08-07T18:25:25,957 INFO [Heartbeater-0] hive.metastore: Trying to connect to metastore with URI thrift://bdtst1c.server:9083 2018-08-07T18:25:25,960 WARN [Heartbeater-0] hive.metastore: Failed to connect to the MetaStore Server... 2018-08-07T18:25:25,960 INFO [Heartbeater-0] hive.metastore: Trying to connect to metastore with URI thrift://bdtst1b.server:9083 2018-08-07T18:25:25,980 INFO [Heartbeater-0] hive.metastore: Opened a connection to metastore, current connections: 3 2018-08-07T18:25:25,980 INFO [Heartbeater-0] hive.metastore: Connected to metastore. 2018-08-07T18:25:26,979 INFO [main] SessionState: Map 1: 1(+79)/165 2018-08-07T18:25:27,808 INFO [main] SessionState: Map 1: 2(+79)/165 2018-08-07T18:25:28,629 INFO [main] SessionState: Map 1: 2(+80)/165 2018-08-07T18:25:31,678 INFO [main] SessionState: Map 1: 2(+80)/165 2018-08-07T18:25:34,742 INFO [main] SessionState: Map 1: 2(+80)/165 2018-08-07T18:25:37,771 INFO [main] SessionState: Map 1: 2(+80)/165 2018-08-07T18:25:38,980 INFO [main] SessionState: Map 1: 3(+80)/165 2018-08-07T18:25:42,019 INFO [main] SessionState: Map 1: 3(+80)/165 2018-08-07T18:25:45,081 INFO [main] SessionState: Map 1: 3(+80)/165 2018-08-07T18:25:46,929 INFO [main] SessionState: Map 1: 4(+80)/165 2018-08-07T18:25:48,961 INFO [main] SessionState: Map 1: 5(+80)/165 2018-08-07T18:25:49,162 INFO [main] SessionState: Map 1: 6(+79)/165 2018-08-07T18:25:49,364 INFO [main] SessionState: Map 1: 6(+80)/165 2018-08-07T18:25:51,385 INFO [main] SessionState: Map 1: 7(+79)/165 2018-08-07T18:25:51,586 INFO [main] SessionState: Map 1: 7(+80)/165 2018-08-07T18:25:51,788 INFO [main] SessionState: Map 1: 9(+80)/165 2018-08-07T18:25:53,212 INFO [main] SessionState: Map 1: 10(+80)/165 2018-08-07T18:25:55,047 INFO [main] SessionState: Map 1: 11(+79)/165 2018-08-07T18:25:55,255 INFO [main] SessionState: Map 1: 13(+79)/165 2018-08-07T18:25:55,456 INFO [main] SessionState: Map 1: 13(+80)/165 2018-08-07T18:25:56,284 INFO [main] SessionState: Map 1: 14(+80)/165 2018-08-07T18:25:57,088 INFO [main] SessionState: Map 1: 17(+79)/165 2018-08-07T18:25:57,504 INFO [main] SessionState: Map 1: 17(+80)/165 2018-08-07T18:25:57,705 INFO [main] SessionState: Map 1: 18(+79)/165 2018-08-07T18:25:57,906 INFO [main] SessionState: Map 1: 19(+79)/165 2018-08-07T18:25:58,108 INFO [main] SessionState: Map 1: 19(+80)/165 2018-08-07T18:25:59,727 INFO [main] SessionState: Map 1: 20(+79)/165 2018-08-07T18:25:59,928 INFO [main] SessionState: Map 1: 21(+80)/165 2018-08-07T18:26:02,806 INFO [main] SessionState: Map 1: 22(+79)/165 2018-08-07T18:26:03,007 INFO [main] SessionState: Map 1: 22(+80)/165 2018-08-07T18:26:03,417 INFO [main] SessionState: Map 1: 23(+80)/165 2018-08-07T18:26:06,055 INFO [main] SessionState: Map 1: 24(+79)/165 2018-08-07T18:26:06,257 INFO [main] SessionState: Map 1: 24(+80)/165 2018-08-07T18:26:07,675 INFO [main] SessionState: Map 1: 25(+80)/165 2018-08-07T18:26:07,876 INFO [main] SessionState: Map 1: 26(+79)/165 2018-08-07T18:26:08,078 INFO [main] SessionState: Map 1: 26(+80)/165 2018-08-07T18:26:09,694 INFO [main] SessionState: Map 1: 27(+80)/165 2018-08-07T18:26:10,096 INFO [main] SessionState: Map 1: 28(+79)/165 2018-08-07T18:26:10,499 INFO [main] SessionState: Map 1: 28(+80)/165 2018-08-07T18:26:10,701 INFO [main] SessionState: Map 1: 30(+80)/165 2018-08-07T18:26:11,104 INFO [main] SessionState: Map 1: 31(+79)/165 2018-08-07T18:26:11,305 INFO [main] SessionState: Map 1: 31(+80)/165 2018-08-07T18:26:12,951 INFO [main] SessionState: Map 1: 32(+80)/165 2018-08-07T18:26:13,757 INFO [main] SessionState: Map 1: 33(+80)/165 2018-08-07T18:26:15,290 INFO [main] SessionState: Map 1: 34(+80)/165 2018-08-07T18:26:16,708 INFO [main] SessionState: Map 1: 35(+80)/165 2018-08-07T18:26:16,909 INFO [main] SessionState: Map 1: 36(+79)/165 2018-08-07T18:26:17,111 INFO [main] SessionState: Map 1: 36(+80)/165 2018-08-07T18:26:18,162 INFO [main] SessionState: Map 1: 37(+79)/165 2018-08-07T18:26:18,371 INFO [main] SessionState: Map 1: 37(+80)/165 2018-08-07T18:26:19,608 INFO [main] SessionState: Map 1: 38(+79)/165 2018-08-07T18:26:19,809 INFO [main] SessionState: Map 1: 39(+79)/165 2018-08-07T18:26:20,014 INFO [main] SessionState: Map 1: 39(+80)/165 2018-08-07T18:26:20,431 INFO [main] SessionState: Map 1: 40(+80)/165 2018-08-07T18:26:21,646 INFO [main] SessionState: Map 1: 41(+80)/165 2018-08-07T18:26:23,475 INFO [main] SessionState: Map 1: 42(+79)/165 2018-08-07T18:26:23,676 INFO [main] SessionState: Map 1: 42(+80)/165 2018-08-07T18:26:25,310 INFO [main] SessionState: Map 1: 43(+79)/165 2018-08-07T18:26:25,712 INFO [main] SessionState: Map 1: 44(+80)/165 2018-08-07T18:26:28,741 INFO [main] SessionState: Map 1: 45(+79)/165 2018-08-07T18:26:29,144 INFO [main] SessionState: Map 1: 45(+80)/165 2018-08-07T18:26:30,571 INFO [main] SessionState: Map 1: 46(+80)/165 2018-08-07T18:26:31,577 INFO [main] SessionState: Map 1: 47(+79)/165 2018-08-07T18:26:31,779 INFO [main] SessionState: Map 1: 47(+80)/165 2018-08-07T18:26:33,188 INFO [main] SessionState: Map 1: 48(+79)/165 2018-08-07T18:26:33,389 INFO [main] SessionState: Map 1: 48(+80)/165 2018-08-07T18:26:36,224 INFO [main] SessionState: Map 1: 49(+80)/165 2018-08-07T18:26:38,043 INFO [main] SessionState: Map 1: 51(+78)/165 2018-08-07T18:26:38,244 INFO [main] SessionState: Map 1: 52(+80)/165 2018-08-07T18:26:38,445 INFO [main] SessionState: Map 1: 53(+80)/165 2018-08-07T18:26:40,888 INFO [main] SessionState: Map 1: 54(+80)/165 2018-08-07T18:26:44,126 INFO [main] SessionState: Map 1: 55(+80)/165 2018-08-07T18:26:46,994 INFO [main] SessionState: Map 1: 56(+79)/165 2018-08-07T18:26:47,396 INFO [main] SessionState: Map 1: 56(+80)/165 2018-08-07T18:26:50,434 INFO [main] SessionState: Map 1: 56(+80)/165 2018-08-07T18:26:51,041 INFO [main] SessionState: Map 1: 57(+80)/165 2018-08-07T18:26:51,242 INFO [main] SessionState: Map 1: 58(+79)/165 2018-08-07T18:26:51,443 INFO [main] SessionState: Map 1: 58(+80)/165 2018-08-07T18:26:51,854 INFO [main] SessionState: Map 1: 59(+80)/165 2018-08-07T18:26:53,262 INFO [main] SessionState: Map 1: 60(+80)/165 2018-08-07T18:26:53,463 INFO [main] SessionState: Map 1: 61(+80)/165 2018-08-07T18:26:54,287 INFO [main] SessionState: Map 1: 62(+80)/165 2018-08-07T18:26:57,336 INFO [main] SessionState: Map 1: 62(+80)/165 2018-08-07T18:26:57,537 INFO [main] SessionState: Map 1: 63(+79)/165 2018-08-07T18:26:57,738 INFO [main] SessionState: Map 1: 63(+80)/165 2018-08-07T18:26:58,141 INFO [main] SessionState: Map 1: 64(+80)/165 2018-08-07T18:26:58,342 INFO [main] SessionState: Map 1: 66(+80)/165 2018-08-07T18:27:00,774 INFO [main] SessionState: Map 1: 67(+79)/165 2018-08-07T18:27:00,975 INFO [main] SessionState: Map 1: 67(+80)/165 2018-08-07T18:27:02,802 INFO [main] SessionState: Map 1: 68(+80)/165 2018-08-07T18:27:05,711 INFO [main] SessionState: Map 1: 69(+79)/165 2018-08-07T18:27:05,913 INFO [main] SessionState: Map 1: 69(+80)/165 2018-08-07T18:27:06,525 INFO [main] SessionState: Map 1: 70(+80)/165 2018-08-07T18:27:07,143 INFO [main] SessionState: Map 1: 71(+79)/165 2018-08-07T18:27:07,345 INFO [main] SessionState: Map 1: 71(+80)/165 2018-08-07T18:27:09,384 INFO [main] SessionState: Map 1: 72(+79)/165 2018-08-07T18:27:09,585 INFO [main] SessionState: Map 1: 72(+80)/165 2018-08-07T18:27:09,987 INFO [main] SessionState: Map 1: 73(+80)/165 2018-08-07T18:27:12,426 INFO [main] SessionState: Map 1: 74(+80)/165 2018-08-07T18:27:14,647 INFO [main] SessionState: Map 1: 75(+80)/165 2018-08-07T18:27:17,691 INFO [main] SessionState: Map 1: 75(+80)/165 2018-08-07T18:27:18,102 INFO [main] SessionState: Map 1: 76(+80)/165 2018-08-07T18:27:20,559 INFO [main] SessionState: Map 1: 78(+80)/165 2018-08-07T18:27:23,944 INFO [main] SessionState: Map 1: 78(+80)/165 2018-08-07T18:27:25,801 INFO [main] SessionState: Map 1: 79(+80)/165 2018-08-07T18:27:28,848 INFO [main] SessionState: Map 1: 79(+80)/165 2018-08-07T18:27:31,881 INFO [main] SessionState: Map 1: 79(+80)/165 2018-08-07T18:27:34,916 INFO [main] SessionState: Map 1: 79(+80)/165 2018-08-07T18:27:37,934 INFO [main] SessionState: Map 1: 79(+80)/165 2018-08-07T18:27:41,151 INFO [main] SessionState: Map 1: 79(+80)/165 2018-08-07T18:27:44,241 INFO [main] SessionState: Map 1: 79(+80)/165 2018-08-07T18:27:45,457 INFO [main] SessionState: Map 1: 80(+79)/165 2018-08-07T18:27:45,658 INFO [main] SessionState: Map 1: 80(+80)/165 2018-08-07T18:27:48,796 INFO [main] SessionState: Map 1: 80(+80)/165 2018-08-07T18:27:51,839 INFO [main] SessionState: Map 1: 80(+80)/165 2018-08-07T18:27:54,888 INFO [main] SessionState: Map 1: 80(+80)/165 2018-08-07T18:27:55,955 INFO [Heartbeater-0] lockmgr.DbTxnManager: Sending heartbeat for txnid:0 and lockid:25858 queryId=hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2 txnid:0 2018-08-07T18:27:57,919 INFO [main] SessionState: Map 1: 80(+80)/165 2018-08-07T18:27:58,925 INFO [main] SessionState: Map 1: 81(+80)/165 2018-08-07T18:28:00,343 INFO [main] SessionState: Map 1: 82(+79)/165 2018-08-07T18:28:00,544 INFO [main] SessionState: Map 1: 82(+80)/165 2018-08-07T18:28:03,900 INFO [main] SessionState: Map 1: 82(+80)/165 2018-08-07T18:28:06,940 INFO [main] SessionState: Map 1: 82(+80)/165 2018-08-07T18:28:09,973 INFO [main] SessionState: Map 1: 83(+79)/165 2018-08-07T18:28:10,579 INFO [main] SessionState: Map 1: 83(+80)/165 2018-08-07T18:28:13,827 INFO [main] SessionState: Map 1: 83(+80)/165 2018-08-07T18:28:16,903 INFO [main] SessionState: Map 1: 83(+80)/165 2018-08-07T18:28:18,713 INFO [main] SessionState: Map 1: 84(+80)/165 2018-08-07T18:28:19,727 INFO [main] SessionState: Map 1: 85(+79)/165 2018-08-07T18:28:20,341 INFO [main] SessionState: Map 1: 85(+80)/165 2018-08-07T18:28:23,389 INFO [main] SessionState: Map 1: 85(+80)/165 2018-08-07T18:28:24,611 INFO [main] SessionState: Map 1: 86(+79)/165 2018-08-07T18:28:27,644 INFO [main] SessionState: Map 1: 87(+78)/165 2018-08-07T18:28:28,057 INFO [main] SessionState: Map 1: 88(+77)/165 2018-08-07T18:28:28,866 INFO [main] SessionState: Map 1: 89(+76)/165 2018-08-07T18:28:31,943 INFO [main] SessionState: Map 1: 89(+76)/165 2018-08-07T18:28:34,983 INFO [main] SessionState: Map 1: 90(+75)/165 2018-08-07T18:28:36,803 INFO [main] SessionState: Map 1: 91(+74)/165 2018-08-07T18:28:40,078 INFO [main] SessionState: Map 1: 91(+74)/165 2018-08-07T18:28:41,300 INFO [main] SessionState: Map 1: 92(+73)/165 2018-08-07T18:28:42,941 INFO [main] SessionState: Map 1: 93(+72)/165 2018-08-07T18:28:45,973 INFO [main] SessionState: Map 1: 93(+72)/165 2018-08-07T18:28:47,809 INFO [main] SessionState: Map 1: 95(+70)/165 2018-08-07T18:28:49,217 INFO [main] SessionState: Map 1: 96(+69)/165 2018-08-07T18:28:52,243 INFO [main] SessionState: Map 1: 96(+69)/165 2018-08-07T18:28:54,272 INFO [main] SessionState: Map 1: 97(+68)/165 2018-08-07T18:28:57,311 INFO [main] SessionState: Map 1: 97(+68)/165 2018-08-07T18:28:58,126 INFO [main] SessionState: Map 1: 98(+67)/165 2018-08-07T18:28:58,336 INFO [main] SessionState: Map 1: 99(+66)/165 2018-08-07T18:29:01,367 INFO [main] SessionState: Map 1: 99(+66)/165 2018-08-07T18:29:01,568 INFO [main] SessionState: Map 1: 100(+65)/165 2018-08-07T18:29:04,591 INFO [main] SessionState: Map 1: 100(+65)/165 2018-08-07T18:29:07,781 INFO [main] SessionState: Map 1: 100(+65)/165 2018-08-07T18:29:08,183 INFO [main] SessionState: Map 1: 102(+63)/165 2018-08-07T18:29:10,405 INFO [main] SessionState: Map 1: 103(+62)/165 2018-08-07T18:29:11,209 INFO [main] SessionState: Map 1: 104(+61)/165 2018-08-07T18:29:12,616 INFO [main] SessionState: Map 1: 105(+60)/165 2018-08-07T18:29:15,652 INFO [main] SessionState: Map 1: 105(+60)/165 2018-08-07T18:29:17,078 INFO [main] SessionState: Map 1: 106(+59)/165 2018-08-07T18:29:17,288 INFO [main] SessionState: Map 1: 107(+58)/165 2018-08-07T18:29:19,317 INFO [main] SessionState: Map 1: 108(+57)/165 2018-08-07T18:29:20,929 INFO [main] SessionState: Map 1: 110(+55)/165 2018-08-07T18:29:21,331 INFO [main] SessionState: Map 1: 113(+52)/165 2018-08-07T18:29:22,546 INFO [main] SessionState: Map 1: 115(+50)/165 2018-08-07T18:29:22,747 INFO [main] SessionState: Map 1: 116(+49)/165 2018-08-07T18:29:23,553 INFO [main] SessionState: Map 1: 117(+48)/165 2018-08-07T18:29:23,754 INFO [main] SessionState: Map 1: 119(+46)/165 2018-08-07T18:29:23,955 INFO [main] SessionState: Map 1: 120(+45)/165 2018-08-07T18:29:24,358 INFO [main] SessionState: Map 1: 121(+44)/165 2018-08-07T18:29:25,367 INFO [main] SessionState: Map 1: 123(+42)/165 2018-08-07T18:29:26,373 INFO [main] SessionState: Map 1: 124(+41)/165 2018-08-07T18:29:29,001 INFO [main] SessionState: Map 1: 125(+40)/165 2018-08-07T18:29:29,202 INFO [main] SessionState: Map 1: 126(+39)/165 2018-08-07T18:29:30,611 INFO [main] SessionState: Map 1: 128(+37)/165 2018-08-07T18:29:31,013 INFO [main] SessionState: Map 1: 129(+36)/165 2018-08-07T18:29:31,416 INFO [main] SessionState: Map 1: 132(+33)/165 2018-08-07T18:29:31,617 INFO [main] SessionState: Map 1: 133(+32)/165 2018-08-07T18:29:31,819 INFO [main] SessionState: Map 1: 134(+31)/165 2018-08-07T18:29:32,020 INFO [main] SessionState: Map 1: 136(+29)/165 2018-08-07T18:29:32,422 INFO [main] SessionState: Map 1: 137(+28)/165 2018-08-07T18:29:34,432 INFO [main] SessionState: Map 1: 138(+27)/165 2018-08-07T18:29:35,035 INFO [main] SessionState: Map 1: 139(+26)/165 2018-08-07T18:29:35,437 INFO [main] SessionState: Map 1: 140(+25)/165 2018-08-07T18:29:35,839 INFO [main] SessionState: Map 1: 142(+23)/165 2018-08-07T18:29:36,040 INFO [main] SessionState: Map 1: 143(+22)/165 2018-08-07T18:29:36,241 INFO [main] SessionState: Map 1: 144(+21)/165 2018-08-07T18:29:36,442 INFO [main] SessionState: Map 1: 145(+20)/165 2018-08-07T18:29:36,643 INFO [main] SessionState: Map 1: 146(+19)/165 2018-08-07T18:29:37,045 INFO [main] SessionState: Map 1: 147(+18)/165 2018-08-07T18:29:38,452 INFO [main] SessionState: Map 1: 148(+17)/165 2018-08-07T18:29:38,653 INFO [main] SessionState: Map 1: 149(+16)/165 2018-08-07T18:29:38,855 INFO [main] SessionState: Map 1: 150(+15)/165 2018-08-07T18:29:39,457 INFO [main] SessionState: Map 1: 151(+14)/165 2018-08-07T18:29:40,261 INFO [main] SessionState: Map 1: 152(+13)/165 2018-08-07T18:29:41,668 INFO [main] SessionState: Map 1: 153(+12)/165 2018-08-07T18:29:42,271 INFO [main] SessionState: Map 1: 154(+11)/165 2018-08-07T18:29:43,075 INFO [main] SessionState: Map 1: 155(+10)/165 2018-08-07T18:29:43,276 INFO [main] SessionState: Map 1: 156(+9)/165 2018-08-07T18:29:45,889 INFO [main] SessionState: Map 1: 157(+8)/165 2018-08-07T18:29:47,898 INFO [main] SessionState: Map 1: 158(+7)/165 2018-08-07T18:29:49,908 INFO [main] SessionState: Map 1: 159(+6)/165 2018-08-07T18:29:50,511 INFO [main] SessionState: Map 1: 160(+5)/165 2018-08-07T18:29:52,521 INFO [main] SessionState: Map 1: 161(+4)/165 2018-08-07T18:29:53,726 INFO [main] SessionState: Map 1: 162(+3)/165 2018-08-07T18:29:56,338 INFO [main] SessionState: Map 1: 163(+2)/165 2018-08-07T18:29:58,746 INFO [main] SessionState: Map 1: 164(+1)/165 2018-08-07T18:30:01,688 INFO [main] SessionState: Map 1: 165/165 2018-08-07T18:30:01,713 INFO [main] counters.Limits: Counter limits initialized with parameters: GROUP_NAME_MAX=256, MAX_GROUPS=500, COUNTER_NAME_MAX=64, MAX_COUNTERS=1200 2018-08-07T18:30:01,726 INFO [main] exec.FileSinkOperator: Moving tmp dir: hdfs://edhcluster/user/hive/staging_hive_2018-08-07_18-22-53_167_2618699013418541798-1/_tmp.-ext-10000 to: hdfs://edhcluster/user/hive/staging_hive_2018-08-07_18-22-53_167_2618699013418541798-1/-ext-10000 2018-08-07T18:30:01,796 INFO [main] ql.Driver: Starting task [Stage-2:DEPENDENCY_COLLECTION] in serial mode 2018-08-07T18:30:01,796 INFO [main] ql.Driver: Starting task [Stage-0:MOVE] in serial mode 2018-08-07T18:30:01,797 INFO [main] exec.Task: Loading data to table default.temp_tro1 from hdfs://edhcluster/user/hive/staging_hive_2018-08-07_18-22-53_167_2618699013418541798-1/-ext-10000 2018-08-07T18:30:11,683 WARN [main] serde2.AbstractEncodingAwareSerDe: The data may not be properly converted to target charset ISO-8859-1 2018-08-07T18:30:11,759 INFO [main] ql.Driver: Starting task [Stage-3:STATS] in serial mode 2018-08-07T18:30:11,759 INFO [main] exec.StatsTask: Executing stats task 2018-08-07T18:30:11,891 INFO [main] fs.FSStatsPublisher: created : hdfs://edhcluster/user/hive/staging_hive_2018-08-07_18-22-53_167_2618699013418541798-1/-ext-10001 2018-08-07T18:30:11,985 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428373, numRows=789517}} 2018-08-07T18:30:12,003 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,012 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,022 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,031 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,041 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,048 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,056 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,064 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,074 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428373, numRows=789517}} 2018-08-07T18:30:12,083 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,091 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,100 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,108 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,118 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,130 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,139 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,147 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,156 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,166 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,175 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,197 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,206 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,214 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,222 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,229 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,238 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,247 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,258 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,268 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,277 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,287 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,296 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428373, numRows=789517}} 2018-08-07T18:30:12,307 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,317 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,326 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,338 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,348 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,357 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,365 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,374 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,383 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,394 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,401 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,409 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,432 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,439 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,445 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,451 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,458 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,464 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,470 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,477 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,484 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,491 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,497 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,503 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428373, numRows=789517}} 2018-08-07T18:30:12,508 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,516 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,523 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,529 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,536 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,542 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,549 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,555 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,560 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,566 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,572 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,577 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,583 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,589 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,594 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,600 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,605 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=109729672, numRows=649288}} 2018-08-07T18:30:12,612 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,617 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,638 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,644 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,650 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,656 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,662 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428373, numRows=789517}} 2018-08-07T18:30:12,667 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,672 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,678 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,683 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,688 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,693 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,698 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,703 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,707 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,712 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,718 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,723 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,728 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,732 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,738 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,743 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,748 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,753 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,759 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,763 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428373, numRows=789517}} 2018-08-07T18:30:12,768 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,774 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,779 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,783 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,789 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,794 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,799 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,804 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,809 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,813 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,818 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,823 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,843 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,849 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,855 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,861 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,868 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,874 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,880 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,885 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,889 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,895 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,900 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,905 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428373, numRows=789517}} 2018-08-07T18:30:12,911 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,916 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,924 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,929 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,934 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,938 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,970 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,976 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,981 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,985 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,990 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,994 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:12,999 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,004 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,008 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,013 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,017 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,021 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,026 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,030 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,035 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,039 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,044 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,048 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428373, numRows=789517}} 2018-08-07T18:30:13,053 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,058 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,062 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,066 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,070 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,075 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,094 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,098 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,103 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,107 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,111 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,116 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,121 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,125 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,129 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,133 INFO [main] fs.FSStatsAggregator: Read stats : {default.temp_tro1/={rawDataSize=133428204, numRows=789516}} 2018-08-07T18:30:13,152 INFO [main] fs.FSStatsAggregator: Read stats for : default.temp_tro1/ numRows 130129920 2018-08-07T18:30:13,152 INFO [main] fs.FSStatsAggregator: Read stats for : default.temp_tro1/ rawDataSize 21991956480 2018-08-07T18:30:13,153 WARN [main] serde2.AbstractEncodingAwareSerDe: The data may not be properly converted to target charset ISO-8859-1 2018-08-07T18:30:13,328 INFO [main] exec.StatsTask: Table default.temp_tro1 stats: [numFiles=165, numRows=130129920, totalSize=22122086400, rawDataSize=21991956480] 2018-08-07T18:30:13,348 INFO [main] ql.Driver: Completed executing command(queryId=hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2); Time taken: 437.393 seconds 2018-08-07T18:30:13,348 INFO [main] ql.Driver: OK 2018-08-07T18:30:13,348 INFO [main] lockmgr.DbTxnManager: Stopped heartbeat for query: hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2 2018-08-07T18:30:13,348 INFO [main] lockmgr.DbLockManager: releaseLocks: [lockid:25858 queryId=hive_20180807182253_52487095-48c1-4847-92cd-6e60121e8ae2 txnid:0] 2018-08-07T18:30:13,488 INFO [main] CliDriver: Time taken: 440.311 seconds 2018-08-07T18:30:13,489 INFO [a501276d-2015-435b-85c5-4d40534ac162 main] conf.HiveConf: Using the default value passed in for log id: a501276d-2015-435b-85c5-4d40534ac162 2018-08-07T18:30:13,489 INFO [a501276d-2015-435b-85c5-4d40534ac162 main] session.SessionState: Resetting thread name to main 2018-08-07T18:30:13,489 INFO [main] conf.HiveConf: Using the default value passed in for log id: a501276d-2015-435b-85c5-4d40534ac162 2018-08-07T18:30:13,495 INFO [main] tez.TezSessionPoolManager: Closing tez session if not default: sessionId=a501276d-2015-435b-85c5-4d40534ac162, queueName=null, user=hive, doAs=true, isOpen=true, isDefault=false 2018-08-07T18:30:13,495 INFO [main] tez.TezSessionState: Closing Tez Session 2018-08-07T18:30:13,495 INFO [main] client.TezClient: Shutting down Tez Session, sessionName=HIVE-a501276d-2015-435b-85c5-4d40534ac162, applicationId=application_1533623337748_0376