Hi, Just check the logs of datanode, it looks like this:
2015-05-20 11:42:14,605 INFO org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace: src: / 10.9.0.48:50676, dest: /10.9.0.17:50010, bytes: 134217728, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_804680172_54, offset: 0, srvID: 39fb78d5-828a-4319-8303-c704fab526e3, blockid: BP-436159032-10.9.0.16-1431330007172:blk_1073742096_1273, duration: 16994466261 2015-05-20 11:42:14,606 INFO org.apache.hadoop.hdfs.server.datanode.DataNode: PacketResponder: BP-436159032-10.9.0.16-1431330007172:blk_1073742096_1273, type=LAST_IN_PIPELINE, downstreams=0:[] terminating 2015-05-20 11:42:17,788 INFO org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace: src: / 10.9.0.17:49046, dest: /10.9.0.17:50010, bytes: 134217728, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_102926009_54, offset: 0, srvID: 39fb78d5-828a-4319-8303-c704fab526e3, blockid: BP-436159032-10.9.0.16-1431330007172:blk_1073742099_1276, duration: 17829554438 2015-05-20 11:42:17,788 INFO org.apache.hadoop.hdfs.server.datanode.DataNode: PacketResponder: BP-436159032-10.9.0.16-1431330007172:blk_1073742099_1276, type=HAS_DOWNSTREAM_IN_PIPELINE terminating 2015-05-20 11:42:17,904 INFO org.apache.hadoop.hdfs.server.datanode.DataNode: Receiving BP-436159032-10.9.0.16-1431330007172:blk_1073742103_1280 src: / 10.9.0.17:49049 dest: /10.9.0.17:50010 2015-05-20 11:42:17,904 WARN org.apache.hadoop.hdfs.server.datanode.DataNode: IOException in BlockReceiver constructor. Cause is 2015-05-20 11:42:17,904 INFO org.apache.hadoop.hdfs.server.datanode.DataNode: opWriteBlock BP-436159032-10.9.0.16-1431330007172:blk_1073742103_1280 received exception org.apache.hadoop.util.DiskChecker$DiskOutOfSpaceException: Out of space: The volume with the most available space (=114409472 B) is less than the block size (=134217728 B). 2015-05-20 11:42:17,905 ERROR org.apache.hadoop.hdfs.server.datanode.DataNode: HadoopV26Slave1:50010:DataXceiver error processing WRITE_BLOCK operation src: /10.9.0.17:49049 dst: /10.9.0.17:50010 org.apache.hadoop.util.DiskChecker$DiskOutOfSpaceException: Out of space: The volume with the most available space (=114409472 B) is less than the block size (=134217728 B). at org.apache.hadoop.hdfs.server.datanode.fsdataset.RoundRobinVolumeChoosingPolicy.chooseVolume(RoundRobinVolumeChoosingPolicy.java:67) at org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsVolumeList.getNextVolume(FsVolumeList.java:69) at org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl.createRbw(FsDatasetImpl.java:1084) at org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl.createRbw(FsDatasetImpl.java:114) at org.apache.hadoop.hdfs.server.datanode.BlockReceiver.<init>(BlockReceiver.java:183) at org.apache.hadoop.hdfs.server.datanode.DataXceiver.writeBlock(DataXceiver.java:615) at org.apache.hadoop.hdfs.protocol.datatransfer.Receiver.opWriteBlock(Receiver.java:137) at org.apache.hadoop.hdfs.protocol.datatransfer.Receiver.processOp(Receiver.java:74) at org.apache.hadoop.hdfs.server.datanode.DataXceiver.run(DataXceiver.java:235) at java.lang.Thread.run(Thread.java:745) 2015-05-20 11:43:59,669 INFO org.apache.hadoop.hdfs.server.datanode.BlockPoolSliceScanner: Verification succeeded for BP-436159032-10.9.0.16-1431330007172:blk_1073741999_1176 2015-05-20 11:46:10,214 INFO org.apache.hadoop.hdfs.server.datanode.BlockPoolSliceScanner: Verification succeeded for BP-436159032-10.9.0.16-1431330007172:blk_1073742000_1177 2015-05-20 11:48:35,445 INFO org.apache.hadoop.hdfs.server.datanode.BlockPoolSliceScanner: Verification succeeded for BP-436159032-10.9.0.16-1431330007172:blk_1073741990_1167 2015-05-20 11:50:04,043 INFO org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetAsyncDiskService: Scheduling blk_1073742080_1257 file /tmp/hadoop-hduser/dfs/data/current/BP-436159032-10.9.0.16-1431330007172/current/finalized/subdir0/subdir1/blk_1073742080 for deletion 2015-05-20 11:50:04,136 INFO org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetAsyncDiskService: Scheduling blk_1073742081_1258 file /tmp/hadoop-hduser/dfs/data/current/BP-436159032-10.9.0.16-1431330007172/current/finalized/subdir0/subdir1/blk_1073742081 for deletion 2015-05-20 11:50:04,136 INFO org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetAsyncDiskService: Scheduling blk_1073742082_1259 file /tmp/hadoop-hduser/dfs/data/current/BP-436159032-10.9.0.16-1431330007172/current/finalized/subdir0/subdir1/blk_1073742082 for deletion 2015-05-20 11:50:04,136 INFO org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetAsyncDiskService: Scheduling blk_1073742083_1260 file /tmp/hadoop-hduser/dfs/data/current/BP-436159032-10.9.0.16-1431330007172/current/finalized/subdir0/subdir1/blk_1073742083 for deletion 2015-05-20 11:50:04,136 INFO org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetAsyncDiskService: Scheduling blk_1073742084_1261 file /tmp/hadoop-hduser/dfs/data/current/BP-436159032-10.9.0.16-1431330007172/current/finalized/subdir0/subdir1/blk_1073742084 for deletion But why this is a hdfs issue, because I think spark broadcast the variable in memory. BTW, the datanode logs seems like I don't have any space to save the storage. So the question comes back, originally I have 50GB for HDFS, why I broadcast a variable and then that variable will dominate the whole hdfs? P.S. when I reduce the data size to only 10 lines (200kb), it works without problem. Best Regards, Allan On 21 May 2015 at 01:30, Akhil Das <ak...@sigmoidanalytics.com> wrote: > This is more like an issue with your HDFS setup, can you check in the > datanode logs? Also try putting a new file in HDFS and see if that works. > > Thanks > Best Regards > > On Wed, May 20, 2015 at 11:47 AM, allanjie <allanmcgr...@gmail.com> wrote: > >> Hi All, >> The variable I need to broadcast is just 468 MB. >> >> >> When broadcasting, it just “stop” at here: >> >> * >> 15/05/20 11:36:14 INFO Configuration.deprecation: mapred.tip.id is >> deprecated. Instead, use mapreduce.task.id >> 15/05/20 11:36:14 INFO Configuration.deprecation: mapred.task.id is >> deprecated. Instead, use mapreduce.task.attempt.id >> 15/05/20 11:36:14 INFO Configuration.deprecation: mapred.task.is.map is >> deprecated. Instead, use mapreduce.task.ismap >> 15/05/20 11:36:14 INFO Configuration.deprecation: mapred.task.partition is >> deprecated. Instead, use mapreduce.task.partition >> 15/05/20 11:36:14 INFO Configuration.deprecation: mapred.job.id is >> deprecated. Instead, use mapreduce.job.id >> 15/05/20 11:36:14 INFO mapred.FileInputFormat: Total input paths to >> process >> : 1 >> 15/05/20 11:36:14 INFO spark.SparkContext: Starting job: saveAsTextFile at >> Test1.java:90 >> 15/05/20 11:36:15 INFO scheduler.DAGScheduler: Got job 0 (saveAsTextFile >> at >> Test1.java:90) with 4 output partitions (allowLocal=false) >> 15/05/20 11:36:15 INFO scheduler.DAGScheduler: Final stage: Stage >> 0(saveAsTextFile at Test1.java:90) >> 15/05/20 11:36:15 INFO scheduler.DAGScheduler: Parents of final stage: >> List() >> 15/05/20 11:36:15 INFO scheduler.DAGScheduler: Missing parents: List() >> 15/05/20 11:36:15 INFO scheduler.DAGScheduler: Submitting Stage 0 >> (MapPartitionsRDD[3] at saveAsTextFile at Test1.java:90), which has no >> missing parents >> 15/05/20 11:36:15 INFO storage.MemoryStore: ensureFreeSpace(129264) called >> with curMem=988453294, maxMem=2061647216 >> 15/05/20 11:36:15 INFO storage.MemoryStore: Block broadcast_2 stored as >> values in memory (estimated size 126.2 KB, free 1023.4 MB) >> 15/05/20 11:36:15 INFO storage.MemoryStore: ensureFreeSpace(78190) called >> with curMem=988582558, maxMem=2061647216 >> 15/05/20 11:36:15 INFO storage.MemoryStore: Block broadcast_2_piece0 >> stored >> as bytes in memory (estimated size 76.4 KB, free 1023.3 MB) >> 15/05/20 11:36:15 INFO storage.BlockManagerInfo: Added broadcast_2_piece0 >> in >> memory on HadoopV26Master:44855 (size: 76.4 KB, free: 1492.4 MB) >> 15/05/20 11:36:15 INFO storage.BlockManagerMaster: Updated info of block >> broadcast_2_piece0 >> 15/05/20 11:36:15 INFO spark.SparkContext: Created broadcast 2 from >> broadcast at DAGScheduler.scala:839 >> 15/05/20 11:36:15 INFO scheduler.DAGScheduler: Submitting 4 missing tasks >> from Stage 0 (MapPartitionsRDD[3] at saveAsTextFile at Test1.java:90) >> 15/05/20 11:36:15 INFO scheduler.TaskSchedulerImpl: Adding task set 0.0 >> with >> 4 tasks >> 15/05/20 11:36:15 INFO scheduler.TaskSetManager: Starting task 0.0 in >> stage >> 0.0 (TID 0, HadoopV26Slave5, NODE_LOCAL, 1387 bytes) >> 15/05/20 11:36:15 INFO scheduler.TaskSetManager: Starting task 1.0 in >> stage >> 0.0 (TID 1, HadoopV26Slave3, NODE_LOCAL, 1387 bytes) >> 15/05/20 11:36:15 INFO scheduler.TaskSetManager: Starting task 2.0 in >> stage >> 0.0 (TID 2, HadoopV26Slave4, NODE_LOCAL, 1387 bytes) >> 15/05/20 11:36:15 INFO scheduler.TaskSetManager: Starting task 3.0 in >> stage >> 0.0 (TID 3, HadoopV26Slave1, NODE_LOCAL, 1387 bytes) >> 15/05/20 11:36:15 INFO storage.BlockManagerInfo: Added broadcast_2_piece0 >> in >> memory on HadoopV26Slave5:45357 (size: 76.4 KB, free: 2.1 GB) >> 15/05/20 11:36:15 INFO storage.BlockManagerInfo: Added broadcast_2_piece0 >> in >> memory on HadoopV26Slave3:57821 (size: 76.4 KB, free: 2.1 GB) >> ……. >> 15/05/20 11:36:28 INFO storage.BlockManagerInfo: Added broadcast_1_piece1 >> in >> memory on HadoopV26Slave5:45357 (size: 4.0 MB, free: 1646.3 MB) >> * >> >> And didn’t go forward as I still waiting, basically not stop, but more >> like >> stuck. >> >> I have 6 workers/VMs: each of them has 8GB memory and 12GB disk storage. >> After a few mins pass, the program stopped and showed something like this: >> >> >> 15/05/20 11:42:45 WARN scheduler.TaskSetManager: Lost task 1.0 in stage >> 0.0 >> (TID 1, HadoopV26Slave3): >> org.apache.hadoop.ipc.RemoteException(java.io.IOException): File >> >> /user/output/_temporary/0/_temporary/attempt_201505201136_0000_m_000001_1/part-00001 >> could only be replicated to 0 nodes instead of minReplication (=1). There >> are 6 datanode(s) running and no node(s) are excluded in this operation. >> at >> >> org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.chooseTarget4NewBlock(BlockManager.java:1549) >> at >> >> org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getAdditionalBlock(FSNamesystem.java:3200) >> at >> >> org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer.addBlock(NameNodeRpcServer.java:641) >> at >> >> org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolServerSideTranslatorPB.addBlock(ClientNamenodeProtocolServerSideTranslatorPB.java:482) >> at >> >> org.apache.hadoop.hdfs.protocol.proto.ClientNamenodeProtocolProtos$ClientNamenodeProtocol$2.callBlockingMethod(ClientNamenodeProtocolProtos.java) >> at >> >> org.apache.hadoop.ipc.ProtobufRpcEngine$Server$ProtoBufRpcInvoker.call(ProtobufRpcEngine.java:619) >> at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:962) >> at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:2039) >> at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:2035) >> at java.security.AccessController.doPrivileged(Native Method) >> at javax.security.auth.Subject.doAs(Subject.java:415) >> at >> >> org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1628) >> at org.apache.hadoop.ipc.Server$Handler.run(Server.java:2033) >> >> at org.apache.hadoop.ipc.Client.call(Client.java:1468) >> at org.apache.hadoop.ipc.Client.call(Client.java:1399) >> at >> >> org.apache.hadoop.ipc.ProtobufRpcEngine$Invoker.invoke(ProtobufRpcEngine.java:232) >> at com.sun.proxy.$Proxy13.addBlock(Unknown Source) >> at >> >> org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolTranslatorPB.addBlock(ClientNamenodeProtocolTranslatorPB.java:399) >> at sun.reflect.GeneratedMethodAccessor7.invoke(Unknown Source) >> at >> >> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) >> at java.lang.reflect.Method.invoke(Method.java:606) >> at >> >> org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:187) >> at >> >> org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:102) >> at com.sun.proxy.$Proxy14.addBlock(Unknown Source) >> at >> >> org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.locateFollowingBlock(DFSOutputStream.java:1532) >> at >> >> org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.nextBlockOutputStream(DFSOutputStream.java:1349) >> at >> >> org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:588) >> >> And then I check the volume of each slave, seems that almost all the >> storage >> has been dominated. But the variable I broadcast is just 468MB. >> >> Originally it is saved in HDFS. And In java program I read it from hdfs >> and >> then broadcast that variable. >> >> Anyone can help? Really thanks. >> >> >> >> -- >> View this message in context: >> http://apache-spark-user-list.1001560.n3.nabble.com/java-program-Get-Stuck-at-broadcasting-tp22953.html >> Sent from the Apache Spark User List mailing list archive at Nabble.com. >> >> --------------------------------------------------------------------- >> To unsubscribe, e-mail: user-unsubscr...@spark.apache.org >> For additional commands, e-mail: user-h...@spark.apache.org >> >> > -- PhD student, Social Media Laboratory <http://smedia.ust.hk/>, Department of Electronic & Computer Engineering <http://www.ece.ust.hk/ece.php>, The Hong Kong University of Science and Technology <http://www.ust.hk/>. Website: http://www.allanjie.net
