giraph-user mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Eric Kimbrel <Eric.Kimb...@soteradefense.com>
Subject Re: Broadcast of large aggregated value is slow.
Date Thu, 16 May 2013 21:00:00 GMT
>From the attached logs in original post, you can see that both workers use about 4 seconds
of compute time on super step 4, but they complete super step 4 about 10 minutes apart.


Eric Kimbrel
Software Engineer I Data Fusion & Analytics
Sotera Defense Solutions, Inc.
o: 360-516-6621
c: 360-990-1873
e: Eric.Kimbrel@soteradefense.com<mailto:First.Last@soteradefense.com>
w: www.potomacfusion.com<http://www.potomacfusion.com/> | www.soteradefense.com<http://www.soteradefense.com/>
Agility. Ingenuity. Integrity.


From: Eric Kimbrel <Eric.Kimbrel@soteradefense.com<mailto:Eric.Kimbrel@soteradefense.com>>
Reply-To: "user@giraph.apache.org<mailto:user@giraph.apache.org>" <user@giraph.apache.org<mailto:user@giraph.apache.org>>
Date: Thursday, May 16, 2013 1:50 PM
To: "user@giraph.apache.org<mailto:user@giraph.apache.org>" <user@giraph.apache.org<mailto:user@giraph.apache.org>>
Subject: Broadcast of large aggregated value is slow.


I have an giraph job in which the Master will read a chunk of a file from HDFS, and then use
an aggregator to broadcast the data to all vertices.  No other messages are sent, and no vertices
aggregate values, only the master.

In the attached logs you can see that the time spent to broadcast the data to all vertices
is slow, and seems to be hanging up somehwere.  It appears that the majority of workers receive
the data in 10-15 seconds, but then nothing happens for around 10 minutes.  Log snippet shown
below

Is there a known reason why transmitting this data during the synchronization is taking so
long, or anything that can be done to speed it up?


2013-05-16 11:09:03,041 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 30 more tasks to send their aggregator data
2013-05-16 11:09:14,444 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 10 more tasks to send their aggregator data, task ids: [13, 20, 22, 34, 38, 50,
48, 52, 58, 56]
2013-05-16 11:09:25,190 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:09:45,191 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:10:05,191 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:10:15,192 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:10:35,193 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:10:55,193 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:11:05,194 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:11:25,195 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:11:45,196 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:12:05,196 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:12:15,197 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:12:35,198 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:12:55,198 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:13:05,199 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:13:25,200 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:13:45,201 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:14:05,201 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:14:15,202 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:14:35,203 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:14:55,204 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:15:15,205 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:15:35,205 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:15:45,206 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:16:05,207 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:16:25,208 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:16:45,208 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:16:55,209 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:17:15,210 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:17:35,210 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:17:45,211 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:18:05,212 INFO org.apache.giraph.utils.TaskIdsPermitsBarrier: waitForRequiredPermits:
Waiting for 8 more tasks to send their aggregator data, task ids: [13, 34, 38, 50, 48, 52,
58, 56]
2013-05-16 11:18:19,841 INFO org.apache.giraph.comm.netty.handler.RequestDecoder: decode:
Server window metrics MBytes/sec sent = 0, MBytes/sec received = 0.027, MBytesSent = 0.0006,
MBytesReceived = 15.4028, ave sent req MBytes = 0, ave received req MBytes = 0.0034, secs
waited = 571.136






Eric Kimbrel
Software Engineer I Data Fusion & Analytics
Sotera Defense Solutions, Inc.
o: 360-516-6621
c: 360-990-1873
e: Eric.Kimbrel@soteradefense.com<mailto:First.Last@soteradefense.com>
w: www.potomacfusion.com<http://www.potomacfusion.com/> | www.soteradefense.com<http://www.soteradefense.com/>
Agility. Ingenuity. Integrity.


Mime
View raw message