| 3 | | |
| 4 | | == start @ 11:30am == |
| 5 | | |
| 6 | | Bill, Heather & I have been running small-scale tests of the full |
| 7 | | processing -> distribution to ensure all of the steps are working. |
| 8 | | We've ironed out a few bugs and have a solid demonstration of the full |
| 9 | | sequence including automatic diffs, magic, destreak, and distribution. |
| 10 | | |
| 11 | | I've just (at 11:35) launched a full-scale throughput demonstration |
| 12 | | using 611 exposures. These are 3pi exposures from July for the RA,DEC |
| 13 | | range 270.0 - 300.0, 0.0 - 20.0. There are g,r,i exposures, |
| 14 | | supposedly all taken as TTI pairs. Obviously, the fact that 611 is |
| 15 | | odd, and 612 is the closest multiple of 6, says that at least one is |
| 16 | | missing. |
| 17 | | |
| 18 | | I've also modified pantasks.pro (in ~ipp, not yet checked in) to fix |
| 19 | | the problem where we get paths of the form /data/any.0/foo/bar |
| 20 | | (something of a hack, I attempt to replace @HOST@.0 and @HOST@.1 |
| 21 | | before doing just @HOST@). This should help with nfs load during |
| 22 | | camera. |
| 23 | | |
| 24 | | === update 1 === |
| 25 | | |
| 26 | | my fix to the camera paths had no effect: the paths are now of the form: |
| 27 | | |
| 28 | | {{{neb://any/gpc1/throughput.20091104/o4999g0217o.78668/o4999g0217o.78668.cm.29757.smf}}} |
| 29 | | |
| 30 | | but they are still all going to ipp039, who is getting quite a load. |
| 31 | | I'm going to remove it as a processing node so it can deal with the |
| 32 | | camera I/O. |
| 33 | | |
| 34 | | === update 2 === |
| 35 | | |
| 36 | | we are having some trouble in this test because ipp039 is getting |
| 37 | | really slammed. |
| 38 | | |
| 39 | | the situation is that the 'any' neb host problem is affecting more |
| 40 | | than just camera: a number of every diff and warp run are also going |
| 41 | | to 'any', which means ipp039. I'm checking into the cause, but it |
| 42 | | shows we need to fix this nebulous bug. |
| 43 | | |
| 44 | | === update 3 === |
| 45 | | |
| 46 | | i found a bug in the skycell by host pantasks function that allowed |
| 47 | | the range to be larger than count, which in turn set a number of the |
| 48 | | target hosts and nebulous volumes to 'any'. i've fixed this, so |
| 49 | | hopefully the load on ipp039 will fall again... |
| 50 | | |
| 51 | | == report @ 3pm == |
| 52 | | |
| 53 | | looks like the fix I put in for this is now working: we have no |
| 54 | | skycells with path_base including 'any' since the fix, and the load on |
| 55 | | ipp039 has been mostly sensible since then as well. there are |
| 56 | | probably still a few warps sitting there that are being used for |
| 57 | | diffs, so it will be elevated until those are done. |
| 58 | | |
| 59 | | the processing speed is looking mostly reasonable, except for the |
| 60 | | diff_skycells, which were heavily hit by the ipp039 collisions. I'll |
| 61 | | be looking for those times to drop somewhat as well. |
| 62 | | |
| 63 | | So far, we are 3 hours into the test, and we have only processed 1/6 |
| 64 | | of the chips (and somewhat less of the other stages). Since the goal |
| 65 | | is 12 hours, we are running a bit behind. I'm hopeful that the ipp039 |
| 66 | | collisions account for most of the gap... |
| 67 | | |
| 68 | | == report @ 6pm == |
| 69 | | |
| 70 | | It has been 6 hours now, and the chip and diff processing times are |
| 71 | | not much better than before. We've only done about 30% of the data |
| 72 | | set, so it is still going slowly. At this rate, it will be 20 hours |
| 73 | | before we finish this batch, which is kind of slow. I'm going to |
| 74 | | change my plan and try to boost the number of hosts. |
| 75 | | |
| 76 | | At the moment, our limiting steps have used the following number of seconds: |
| 77 | | |
| 78 | | {{{ |
| 79 | | chip: 1,021,709.35 sec |
| 80 | | warp: 610,303.59 sec |
| 81 | | diff: 540,536.4 sec |
| 82 | | destreak: 259,726 sec |
| 83 | | dist process: 177,430.2 sec |
| 84 | | camera: 69,067.08 sec |
| 85 | | }}} |
| 86 | | |
| 87 | | === update 1 === |
| 88 | | |
| 89 | | oops. |
| 90 | | |
| 91 | | I just attempted to load another set of wave 2 nodes and got a |
| 92 | | segfault from pantasks... |
| 93 | | |
| 94 | | I'm going to wait 5 min for jobs to complete, and then I'll restart pantasks... |
| 95 | | |
| 96 | | === update 2 === |
| 97 | | |
| 98 | | OK, I've restarted the stdscience pantasks, and I've bumped up the |
| 99 | | number of hosts to: |
| 100 | | |
| 101 | | 1x1 3x2 4x3 4xC |
| 102 | | |
| 103 | | continuing to exclude ipp005 and ipp039 from processing. |
| 104 | | |
| 105 | | == report @ 9pm == |
| 106 | | |
| 107 | | we've managed to get through 110 chips in the last 3 hours, compared |
| 108 | | with about 100 in the first 3 hours and 72 in the second three hours, |
| 109 | | so the rate is a bit faster now due to the larger number of machines. |
| 110 | | but, compared with the previous setup (1x1, 2x2, 3x3, 3xC) vs (1x1 3x2 |
| 111 | | 4x3 4xC), we have 15 + 15 + 17 = 32 more CPUs out of a previous total |
| 112 | | of 137 CPUs, or 25% more CPUs. I don't think the processing is going |
| 113 | | 25% faster that the first 3 hours (before the ipp039 bottleneck became |
| 114 | | a problem), but it is a little hard to judge. If not, then it is |
| 115 | | evidence that we have still some I/O bottlenecks. I'll look more |
| 116 | | closely at the times reported by the warp, diff, magic, and rc stages |
| 117 | | |
| 118 | | == report @ 9 am == |
| 119 | | |
| 120 | | processing effectively stopped around 6am this morning, though the |
| 121 | | full analysis was not complete: |
| 122 | | |
| 123 | | * there are a number of chipRuns that have not finished: 37 exposures |
| 124 | | are still pending. This is basically because of point 2: |
| 125 | | |
| 126 | | * ipp014 is unhappy: it is not so unhappy that it has been kicked out |
| 127 | | of nebulous, but files on it are giving I/O errors. The failing chips |
| 128 | | are being caused by this, but coupled to a lingering nebulous error: |
| 129 | | the rules are forcing nebulous to return the ipp014 instance rather |
| 130 | | than randomize among the instances. i'm going to reboot ipp014 and |
| 131 | | kick it out of nebulous for now. |
| 132 | | |
| 133 | | I'm attaching a snapshot of the ganglia load for the past 24 hours. |
| 134 | | you can see where the test started a bit before noon yesterday with |
| 135 | | the initial set of machines, and then the point where I crashed |
| 136 | | pantasks and restarted with and additional set of wave 2, 3, and C |
| 137 | | machines. you can also see the problem machines caused by the |
| 138 | | nebulous distribution (ipp039 and a couple of the ipp04X machines), |
| 139 | | and the ones that have been giving occasional nfs issues (eg ipp016). |
| 140 | | I'm not certain if the latter issues are being cause by glitches in |
| 141 | | those raids, something equivalent to the causes of the ipp005 crashes, |
| 142 | | or because of poor data distribution. this is something we need to |
| 143 | | investigate. |
| 144 | | |
| 145 | | [[Image(ganglia-throughput-20091104.png)]] |
| 146 | | |
| 147 | | === update === |
| 148 | | |
| 149 | | I've gone through most errors and found the following: |
| 150 | | |
| 151 | | * all of the chip failures were caused by the issue with ipp014 -> |
| 152 | | these would be addessed by fixing the nebulous randomization. |
| 153 | | * all but one of the warp failures were caused by the chmod problem -> |
| 154 | | these can be addressed with one of the options I listed |
| 155 | | * the remaining warp failure was a more traditional NFS I/O problem, |
| 156 | | and would be naturally cleaned up with the revert pass |
| 157 | | * the diff failures I checked seemed to be the traditional NFS I/O |
| 158 | | problems, but we do not seem to have a diff revert task -- that is |
| 159 | | surprising! |
| 160 | | |
| 161 | | there are also some destreak failures which I did not investigate. |
| 162 | | also, destreak advance was getting timeouts, which says either the |
| 163 | | query needs to be adjust or the timeout period in pantasks does. |
| 164 | | finally, dist_component.pl had some failures, which are probably NFS |
| 165 | | I/O issues as well. |
| | 37 | |
| | 38 | == start @ 11:30am == |
| | 39 | |
| | 40 | Bill, Heather & I have been running small-scale tests of the full |
| | 41 | processing -> distribution to ensure all of the steps are working. |
| | 42 | We've ironed out a few bugs and have a solid demonstration of the full |
| | 43 | sequence including automatic diffs, magic, destreak, and distribution. |
| | 44 | |
| | 45 | I've just (at 11:35) launched a full-scale throughput demonstration |
| | 46 | using 611 exposures. These are 3pi exposures from July for the RA,DEC |
| | 47 | range 270.0 - 300.0, 0.0 - 20.0. There are g,r,i exposures, |
| | 48 | supposedly all taken as TTI pairs. Obviously, the fact that 611 is |
| | 49 | odd, and 612 is the closest multiple of 6, says that at least one is |
| | 50 | missing. |
| | 51 | |
| | 52 | I've also modified pantasks.pro (in ~ipp, not yet checked in) to fix |
| | 53 | the problem where we get paths of the form /data/any.0/foo/bar |
| | 54 | (something of a hack, I attempt to replace @HOST@.0 and @HOST@.1 |
| | 55 | before doing just @HOST@). This should help with nfs load during |
| | 56 | camera. |
| | 57 | |
| | 58 | === update 1 === |
| | 59 | |
| | 60 | my fix to the camera paths had no effect: the paths are now of the form: |
| | 61 | |
| | 62 | {{{neb://any/gpc1/throughput.20091104/o4999g0217o.78668/o4999g0217o.78668.cm.29757.smf}}} |
| | 63 | |
| | 64 | but they are still all going to ipp039, who is getting quite a load. |
| | 65 | I'm going to remove it as a processing node so it can deal with the |
| | 66 | camera I/O. |
| | 67 | |
| | 68 | === update 2 === |
| | 69 | |
| | 70 | we are having some trouble in this test because ipp039 is getting |
| | 71 | really slammed. |
| | 72 | |
| | 73 | the situation is that the 'any' neb host problem is affecting more |
| | 74 | than just camera: a number of every diff and warp run are also going |
| | 75 | to 'any', which means ipp039. I'm checking into the cause, but it |
| | 76 | shows we need to fix this nebulous bug. |
| | 77 | |
| | 78 | === update 3 === |
| | 79 | |
| | 80 | i found a bug in the skycell by host pantasks function that allowed |
| | 81 | the range to be larger than count, which in turn set a number of the |
| | 82 | target hosts and nebulous volumes to 'any'. i've fixed this, so |
| | 83 | hopefully the load on ipp039 will fall again... |
| | 84 | |
| | 85 | == report @ 3pm == |
| | 86 | |
| | 87 | looks like the fix I put in for this is now working: we have no |
| | 88 | skycells with path_base including 'any' since the fix, and the load on |
| | 89 | ipp039 has been mostly sensible since then as well. there are |
| | 90 | probably still a few warps sitting there that are being used for |
| | 91 | diffs, so it will be elevated until those are done. |
| | 92 | |
| | 93 | the processing speed is looking mostly reasonable, except for the |
| | 94 | diff_skycells, which were heavily hit by the ipp039 collisions. I'll |
| | 95 | be looking for those times to drop somewhat as well. |
| | 96 | |
| | 97 | So far, we are 3 hours into the test, and we have only processed 1/6 |
| | 98 | of the chips (and somewhat less of the other stages). Since the goal |
| | 99 | is 12 hours, we are running a bit behind. I'm hopeful that the ipp039 |
| | 100 | collisions account for most of the gap... |
| | 101 | |
| | 102 | == report @ 6pm == |
| | 103 | |
| | 104 | It has been 6 hours now, and the chip and diff processing times are |
| | 105 | not much better than before. We've only done about 30% of the data |
| | 106 | set, so it is still going slowly. At this rate, it will be 20 hours |
| | 107 | before we finish this batch, which is kind of slow. I'm going to |
| | 108 | change my plan and try to boost the number of hosts. |
| | 109 | |
| | 110 | At the moment, our limiting steps have used the following number of seconds: |
| | 111 | |
| | 112 | {{{ |
| | 113 | chip: 1,021,709.35 sec |
| | 114 | warp: 610,303.59 sec |
| | 115 | diff: 540,536.4 sec |
| | 116 | destreak: 259,726 sec |
| | 117 | dist process: 177,430.2 sec |
| | 118 | camera: 69,067.08 sec |
| | 119 | }}} |
| | 120 | |
| | 121 | === update 1 === |
| | 122 | |
| | 123 | oops. |
| | 124 | |
| | 125 | I just attempted to load another set of wave 2 nodes and got a |
| | 126 | segfault from pantasks... |
| | 127 | |
| | 128 | I'm going to wait 5 min for jobs to complete, and then I'll restart pantasks... |
| | 129 | |
| | 130 | === update 2 === |
| | 131 | |
| | 132 | OK, I've restarted the stdscience pantasks, and I've bumped up the |
| | 133 | number of hosts to: |
| | 134 | |
| | 135 | 1x1 3x2 4x3 4xC |
| | 136 | |
| | 137 | continuing to exclude ipp005 and ipp039 from processing. |
| | 138 | |
| | 139 | == report @ 9pm == |
| | 140 | |
| | 141 | we've managed to get through 110 chips in the last 3 hours, compared |
| | 142 | with about 100 in the first 3 hours and 72 in the second three hours, |
| | 143 | so the rate is a bit faster now due to the larger number of machines. |
| | 144 | but, compared with the previous setup (1x1, 2x2, 3x3, 3xC) vs (1x1 3x2 |
| | 145 | 4x3 4xC), we have 15 + 15 + 17 = 32 more CPUs out of a previous total |
| | 146 | of 137 CPUs, or 25% more CPUs. I don't think the processing is going |
| | 147 | 25% faster that the first 3 hours (before the ipp039 bottleneck became |
| | 148 | a problem), but it is a little hard to judge. If not, then it is |
| | 149 | evidence that we have still some I/O bottlenecks. I'll look more |
| | 150 | closely at the times reported by the warp, diff, magic, and rc stages |
| | 151 | |
| | 152 | == report @ 9 am == |
| | 153 | |
| | 154 | processing effectively stopped around 6am this morning, though the |
| | 155 | full analysis was not complete: |
| | 156 | |
| | 157 | * there are a number of chipRuns that have not finished: 37 exposures |
| | 158 | are still pending. This is basically because of point 2: |
| | 159 | |
| | 160 | * ipp014 is unhappy: it is not so unhappy that it has been kicked out |
| | 161 | of nebulous, but files on it are giving I/O errors. The failing chips |
| | 162 | are being caused by this, but coupled to a lingering nebulous error: |
| | 163 | the rules are forcing nebulous to return the ipp014 instance rather |
| | 164 | than randomize among the instances. i'm going to reboot ipp014 and |
| | 165 | kick it out of nebulous for now. |
| | 166 | |
| | 167 | I'm attaching a snapshot of the ganglia load for the past 24 hours. |
| | 168 | you can see where the test started a bit before noon yesterday with |
| | 169 | the initial set of machines, and then the point where I crashed |
| | 170 | pantasks and restarted with and additional set of wave 2, 3, and C |
| | 171 | machines. you can also see the problem machines caused by the |
| | 172 | nebulous distribution (ipp039 and a couple of the ipp04X machines), |
| | 173 | and the ones that have been giving occasional nfs issues (eg ipp016). |
| | 174 | I'm not certain if the latter issues are being cause by glitches in |
| | 175 | those raids, something equivalent to the causes of the ipp005 crashes, |
| | 176 | or because of poor data distribution. this is something we need to |
| | 177 | investigate. |
| | 178 | |
| | 179 | [[Image(ganglia-throughput-20091104.png)]] |
| | 180 | |
| | 181 | === update === |
| | 182 | |
| | 183 | I've gone through most errors and found the following: |
| | 184 | |
| | 185 | * all of the chip failures were caused by the issue with ipp014 -> |
| | 186 | these would be addessed by fixing the nebulous randomization. |
| | 187 | * all but one of the warp failures were caused by the chmod problem -> |
| | 188 | these can be addressed with one of the options I listed |
| | 189 | * the remaining warp failure was a more traditional NFS I/O problem, |
| | 190 | and would be naturally cleaned up with the revert pass |
| | 191 | * the diff failures I checked seemed to be the traditional NFS I/O |
| | 192 | problems, but we do not seem to have a diff revert task -- that is |
| | 193 | surprising! |
| | 194 | |
| | 195 | there are also some destreak failures which I did not investigate. |
| | 196 | also, destreak advance was getting timeouts, which says either the |
| | 197 | query needs to be adjust or the timeout period in pantasks does. |
| | 198 | finally, dist_component.pl had some failures, which are probably NFS |
| | 199 | I/O issues as well. |
| | 200 | |
| | 201 | == update at 11:35 == |
| | 202 | |
| | 203 | this experiment is winding down slowly. There are a few stragglers |
| | 204 | that have required a little extra intervention to succeed (things like |
| | 205 | removing files with bad permissions -- see the earlier nfs error), and |
| | 206 | there are a few entries that stuck by a bug (3 in warp, 3 in diff so |
| | 207 | far). I'm turning off the warp revert task since the only outstanding |
| | 208 | jobs are ones that require a bug fix. |
| | 209 | |
| | 210 | Of the 611 initial exposures, we have gotten all 611 through chip, |
| | 211 | camera, and fake. 608 made it through warp. 608 warps only yielded |
| | 212 | 294 diffs (10 exposures must not have a corresponding TTI match), of |
| | 213 | which 291 succeeded. I expect to see 582 magicked exposures, but so |
| | 214 | far we have only identified 578 exposures for magic -- there may be |
| | 215 | some errors that need to be cleared in magic (no magic revert?). |
| | 216 | |