Pooles Cavern. Data Transfer

This program was called automatically via CRON

viz: _GET['cron'] == 'yes'

The CRON job sends its output to the Freshen log file. But if the program is called manually, its output goes only to your browser screen.

Debug info added 27-Oct-2026
Function phpversion() reports 7.4.34
allow_url_fopen is reported as Enabled

Data transfer began at 06:13:02 on 08-Nov-2026

Software version 2.3c. File /home/bcra/public_html/data/freshen.php (last modified 20:08:27 on 27-Oct-2026).
PHP time limit set to 5000 seconds.

Received 9,635 bytes from http://pooles.bcra.org.uk:3927/Exports/

That web page has been mined, and found to have 56 CSV files and 56 JF files to transfer.

$file_list is Array
(
    [0] => all_readings/1000930.csv
    [1] => all_readings/1004463.csv
    [2] => all_readings/1004466.csv
    [3] => all_readings/1004467.csv
    [4] => all_readings/1008087.csv
    [5] => all_readings/1008089.csv
    [6] => all_readings/1017216.csv
    [7] => all_readings/1017222.csv
    [8] => all_readings/1017223.csv
    [9] => all_readings/1021454.csv
    [10] => all_readings/1046653.csv
    [11] => all_readings/1046676.csv
    [12] => all_readings/1052724.csv
    [13] => all_readings/1052725.csv
    [14] => all_readings/1066279.csv
    [15] => all_readings/790060.csv
    [16] => all_readings/807565.csv
    [17] => all_readings/807566.csv
    [18] => all_readings/819585.csv
    [19] => all_readings/832627.csv
    [20] => all_readings/840298.csv
    [21] => all_readings/840299.csv
    [22] => all_readings/840300.csv
    [23] => all_readings/840301.csv
    [24] => all_readings/840308.csv
    [25] => all_readings/844534.csv
    [26] => all_readings/844555.csv
    [27] => all_readings/859646.csv
    [28] => all_readings/859658.csv
    [29] => all_readings/859669.csv
    [30] => all_readings/865344.csv
    [31] => all_readings/872568.csv
    [32] => all_readings/872642.csv
    [33] => all_readings/879721.csv
    [34] => all_readings/883599.csv
    [35] => all_readings/883600.csv
    [36] => all_readings/883606.csv
    [37] => all_readings/887883.csv
    [38] => all_readings/888271.csv
    [39] => all_readings/888275.csv
    [40] => all_readings/901903.csv
    [41] => all_readings/903422.csv
    [42] => all_readings/904973.csv
    [43] => all_readings/904993.csv
    [44] => all_readings/926759.csv
    [45] => all_readings/929116.csv
    [46] => all_readings/929117.csv
    [47] => all_readings/929128.csv
    [48] => all_readings/929129.csv
    [49] => all_readings/940875.csv
    [50] => all_readings/940877.csv
    [51] => all_readings/942588.csv
    [52] => all_readings/951585.csv
    [53] => all_readings/954903.csv
    [54] => all_readings/954904.csv
    [55] => all_readings/999986.csv
)
$file_list_raw is Array
(
    [0] => Data/current/1000930.jf
    [1] => Data/current/1004463.jf
    [2] => Data/current/1004466.jf
    [3] => Data/current/1004467.jf
    [4] => Data/current/1008087.jf
    [5] => Data/current/1008089.jf
    [6] => Data/current/1017216.jf
    [7] => Data/current/1017222.jf
    [8] => Data/current/1017223.jf
    [9] => Data/current/1021454.jf
    [10] => Data/current/1046653.jf
    [11] => Data/current/1046676.jf
    [12] => Data/current/1052724.jf
    [13] => Data/current/1052725.jf
    [14] => Data/current/1066279.jf
    [15] => Data/current/790060.jf
    [16] => Data/current/807565.jf
    [17] => Data/current/807566.jf
    [18] => Data/current/819585.jf
    [19] => Data/current/832627.jf
    [20] => Data/current/840298.jf
    [21] => Data/current/840299.jf
    [22] => Data/current/840300.jf
    [23] => Data/current/840301.jf
    [24] => Data/current/840308.jf
    [25] => Data/current/844534.jf
    [26] => Data/current/844555.jf
    [27] => Data/current/859646.jf
    [28] => Data/current/859658.jf
    [29] => Data/current/859669.jf
    [30] => Data/current/865344.jf
    [31] => Data/current/872568.jf
    [32] => Data/current/872642.jf
    [33] => Data/current/879721.jf
    [34] => Data/current/883599.jf
    [35] => Data/current/883600.jf
    [36] => Data/current/883606.jf
    [37] => Data/current/887883.jf
    [38] => Data/current/888271.jf
    [39] => Data/current/888275.jf
    [40] => Data/current/901903.jf
    [41] => Data/current/903422.jf
    [42] => Data/current/904973.jf
    [43] => Data/current/904993.jf
    [44] => Data/current/926759.jf
    [45] => Data/current/929116.jf
    [46] => Data/current/929117.jf
    [47] => Data/current/929128.jf
    [48] => Data/current/929129.jf
    [49] => Data/current/940875.jf
    [50] => Data/current/940877.jf
    [51] => Data/current/942588.jf
    [52] => Data/current/951585.jf
    [53] => Data/current/954903.jf
    [54] => Data/current/954904.jf
    [55] => Data/current/999986.jf
)

Flushed OB. Downloading now. This may take some time...

At 0 seconds (key=0)
Array key 0 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 0 mod 4 = group 0)

At 0 seconds (key=1)
Array key 1 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 1 mod 4 = group 1)

At 0 seconds (key=2)
  Starting to fetch data
  Fetched 35,988 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/1004466.csv' in 1 s at rate of 35,988 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/1004466.csv'
  Process reports that it wrote 35,988 bytes. OK.
  Extracted new restart as 8785
  Appended new restart data 8785 to file 'all_readings/1004466.restart.txp'
  Process reports that it wrote 5 bytes. OK.
  (Printed and reset $echo)
At 0 seconds (key=3)
Array key 3 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 3 mod 4 = group 3)

At 0 seconds (key=4)
Array key 4 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 4 mod 4 = group 0)

At 0 seconds (key=5)
Array key 5 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 5 mod 4 = group 1)

At 0 seconds (key=6)
  Starting to fetch data
  Fetched 3,279,949 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/1017216.csv' in 1 s at rate of 3,279,949 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/1017216.csv'
  Process reports that it wrote 3,279,949 bytes. OK.
  Extracted new restart as 1025921
  Appended new restart data 1025921 to file 'all_readings/1017216.restart.txp'
  Process reports that it wrote 8 bytes. OK.
  (Printed and reset $echo)
At 1 seconds (key=7)
Array key 7 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 7 mod 4 = group 3)

At 1 seconds (key=8)
Array key 8 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 8 mod 4 = group 0)

At 1 seconds (key=9)
Array key 9 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 9 mod 4 = group 1)

At 1 seconds (key=10)
  Starting to fetch data
  Fetched 1,857,547 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/1046653.csv' in 1 s at rate of 1,857,547 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/1046653.csv'
  Process reports that it wrote 1,857,547 bytes. OK.
  Extracted new restart as 572115
  Appended new restart data 572115 to file 'all_readings/1046653.restart.txp'
  Process reports that it wrote 7 bytes. OK.
  (Printed and reset $echo)
At 2 seconds (key=11)
Array key 11 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 11 mod 4 = group 3)

At 2 seconds (key=12)
Array key 12 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 12 mod 4 = group 0)

At 2 seconds (key=13)
Array key 13 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 13 mod 4 = group 1)

At 2 seconds (key=14)
  Starting to fetch data
  Fetched 157,026 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/1066279.csv' in 1 s at rate of 157,026 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/1066279.csv'
  Process reports that it wrote 157,026 bytes. OK.
  Extracted new restart as 51595
  Appended new restart data 51595 to file 'all_readings/1066279.restart.txp'
  Process reports that it wrote 6 bytes. OK.
  (Printed and reset $echo)
At 3 seconds (key=15)
Array key 15 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 15 mod 4 = group 3)

At 3 seconds (key=16)
Array key 16 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 16 mod 4 = group 0)

At 3 seconds (key=17)
Array key 17 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 17 mod 4 = group 1)

At 3 seconds (key=18)
  Starting to fetch data
  Fetched 1,140,628 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/819585.csv' in 1 s at rate of 1,140,628 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/819585.csv'
  Process reports that it wrote 1,140,628 bytes. OK.
  Extracted new restart as 364825
  Appended new restart data 364825 to file 'all_readings/819585.restart.txp'
  Process reports that it wrote 7 bytes. OK.
  (Printed and reset $echo)
At 3 seconds (key=19)
Array key 19 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 19 mod 4 = group 3)

At 3 seconds (key=20)
Array key 20 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 20 mod 4 = group 0)

At 3 seconds (key=21)
Array key 21 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 21 mod 4 = group 1)

At 3 seconds (key=22)
  Starting to fetch data
  Fetched 18,746,769 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/840300.csv' in 9 s at rate of 2,082,974 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/840300.csv'
  Process reports that it wrote 18,746,769 bytes. OK.
  Extracted new restart as 6472160
  Appended new restart data 6472160 to file 'all_readings/840300.restart.txp'
  Process reports that it wrote 8 bytes. OK.
  (Printed and reset $echo)
At 12 seconds (key=23)
Array key 23 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 23 mod 4 = group 3)

At 12 seconds (key=24)
Array key 24 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 24 mod 4 = group 0)

At 12 seconds (key=25)
Array key 25 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 25 mod 4 = group 1)

At 12 seconds (key=26)
  Starting to fetch data
  Fetched 3,117,908 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/844555.csv' in 3 s at rate of 1,039,303 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/844555.csv'
  Process reports that it wrote 3,117,908 bytes. OK.
  Extracted new restart as 936279
  Appended new restart data 936279 to file 'all_readings/844555.restart.txp'
  Process reports that it wrote 7 bytes. OK.
  (Printed and reset $echo)
At 15 seconds (key=27)
Array key 27 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 27 mod 4 = group 3)

At 15 seconds (key=28)
Array key 28 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 28 mod 4 = group 0)

At 15 seconds (key=29)
Array key 29 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 29 mod 4 = group 1)

At 15 seconds (key=30)
  Starting to fetch data
  Fetched 3,393,486 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/865344.csv' in 1 s at rate of 3,393,486 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/865344.csv'
  Process reports that it wrote 3,393,486 bytes. OK.
  Extracted new restart as 855038
  Appended new restart data 855038 to file 'all_readings/865344.restart.txp'
  Process reports that it wrote 7 bytes. OK.
  (Printed and reset $echo)
At 16 seconds (key=31)
Array key 31 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 31 mod 4 = group 3)

At 17 seconds (key=32)
Array key 32 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 32 mod 4 = group 0)

At 17 seconds (key=33)
Array key 33 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 33 mod 4 = group 1)

At 17 seconds (key=34)
  Starting to fetch data
  Fetched 7,849,086 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/883599.csv' in 3 s at rate of 2,616,362 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/883599.csv'
  Process reports that it wrote 7,849,086 bytes. OK.
  Extracted new restart as 1867908
  Appended new restart data 1867908 to file 'all_readings/883599.restart.txp'
  Process reports that it wrote 8 bytes. OK.
  (Printed and reset $echo)
At 20 seconds (key=35)
Array key 35 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 35 mod 4 = group 3)

At 20 seconds (key=36)
Array key 36 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 36 mod 4 = group 0)

At 20 seconds (key=37)
Array key 37 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 37 mod 4 = group 1)

At 20 seconds (key=38)
  Starting to fetch data
  Fetched 2,397,302 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/888271.csv' in 1 s at rate of 2,397,302 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/888271.csv'
  Process reports that it wrote 2,397,302 bytes. OK.
  Extracted new restart as 1021861
  Appended new restart data 1021861 to file 'all_readings/888271.restart.txp'
  Process reports that it wrote 8 bytes. OK.
  (Printed and reset $echo)
At 21 seconds (key=39)
Array key 39 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 39 mod 4 = group 3)

At 21 seconds (key=40)
Array key 40 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 40 mod 4 = group 0)

At 21 seconds (key=41)
Array key 41 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 41 mod 4 = group 1)

At 21 seconds (key=42)
  Starting to fetch data
  Fetched 2,978,753 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/904973.csv' in 3 s at rate of 992,918 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/904973.csv'
  Process reports that it wrote 2,978,753 bytes. OK.
  Extracted new restart as 881351
  Appended new restart data 881351 to file 'all_readings/904973.restart.txp'
  Process reports that it wrote 7 bytes. OK.
  (Printed and reset $echo)
At 24 seconds (key=43)
Array key 43 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 43 mod 4 = group 3)

At 24 seconds (key=44)
Array key 44 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 44 mod 4 = group 0)

At 24 seconds (key=45)
Array key 45 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 45 mod 4 = group 1)

At 24 seconds (key=46)
  Starting to fetch data
  Fetched 5,411,335 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/929117.csv' in 2 s at rate of 2,705,668 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/929117.csv'
  Process reports that it wrote 5,411,335 bytes. OK.
  Extracted new restart as 1773402
  Appended new restart data 1773402 to file 'all_readings/929117.restart.txp'
  Process reports that it wrote 8 bytes. OK.
  (Printed and reset $echo)
At 26 seconds (key=47)
Array key 47 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 47 mod 4 = group 3)

At 26 seconds (key=48)
Array key 48 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 48 mod 4 = group 0)

At 26 seconds (key=49)
Array key 49 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 49 mod 4 = group 1)

At 26 seconds (key=50)
  Starting to fetch data
  Fetched 1,958,004 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/940877.csv' in 1 s at rate of 1,958,004 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/940877.csv'
  Process reports that it wrote 1,958,004 bytes. OK.
  Extracted new restart as 587884
  Appended new restart data 587884 to file 'all_readings/940877.restart.txp'
  Process reports that it wrote 7 bytes. OK.
  (Printed and reset $echo)
At 27 seconds (key=51)
Array key 51 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 51 mod 4 = group 3)

At 28 seconds (key=52)
Array key 52 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 52 mod 4 = group 0)

At 28 seconds (key=53)
Array key 53 skipped. (Time is 06:13. Hour mod 4 is 2, so looking for group 2. But array key 53 mod 4 = group 1)

At 28 seconds (key=54)
  Starting to fetch data
  Fetched 5,473,038 bytes from 'http://pooles.bcra.org.uk:3927/Exports/all_readings/954904.csv' in 2 s at rate of 2,736,519 B/s
Experimental mining for Vbatt_chk. Code not finished.
  Saved file to 'all_readings/954904.csv'
  Process reports that it wrote 5,473,038 bytes. OK.
  Extracted new restart as 1356965
  Appended new restart data 1356965 to file 'all_readings/954904.restart.txp'
  Process reports that it wrote 8 bytes. OK.
  (Printed and reset $echo)

FINISHED first processing pass. Now looking at *.jf files ...

Now scanning for *.jf files ...

At 30 seconds(key=0)
array key 0 skipped. (Looking for group 2. 0 mod 4 = 0)

At 30 seconds(key=1)
array key 1 skipped. (Looking for group 2. 1 mod 4 = 1)

At 30 seconds(key=2)
  Starting to fetch data
  Fetched 9,331 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/1004466.jf' in 1 s at rate of 9,331 B/s
  Saved file to 'all_readings/1004466.jf'
  Process reports that it wrote 9,331 bytes. OK.
  (Printed and reset $echo)
At 30 seconds(key=3)
array key 3 skipped. (Looking for group 2. 3 mod 4 = 3)

At 30 seconds(key=4)
array key 4 skipped. (Looking for group 2. 4 mod 4 = 0)

At 30 seconds(key=5)
array key 5 skipped. (Looking for group 2. 5 mod 4 = 1)

At 30 seconds(key=6)
  Starting to fetch data
  Fetched 1,035,267 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/1017216.jf' in 1 s at rate of 1,035,267 B/s
  Saved file to 'all_readings/1017216.jf'
  Process reports that it wrote 1,035,267 bytes. OK.
  (Printed and reset $echo)
At 31 seconds(key=7)
array key 7 skipped. (Looking for group 2. 7 mod 4 = 3)

At 31 seconds(key=8)
array key 8 skipped. (Looking for group 2. 8 mod 4 = 0)

At 31 seconds(key=9)
array key 9 skipped. (Looking for group 2. 9 mod 4 = 1)

At 31 seconds(key=10)
  Starting to fetch data
  Fetched 575,896 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/1046653.jf' in 1 s at rate of 575,896 B/s
  Saved file to 'all_readings/1046653.jf'
  Process reports that it wrote 575,896 bytes. OK.
  (Printed and reset $echo)
At 31 seconds(key=11)
array key 11 skipped. (Looking for group 2. 11 mod 4 = 3)

At 31 seconds(key=12)
array key 12 skipped. (Looking for group 2. 12 mod 4 = 0)

At 31 seconds(key=13)
array key 13 skipped. (Looking for group 2. 13 mod 4 = 1)

At 31 seconds(key=14)
  Starting to fetch data
  Fetched 52,041 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/1066279.jf' in 1 s at rate of 52,041 B/s
  Saved file to 'all_readings/1066279.jf'
  Process reports that it wrote 52,041 bytes. OK.
  (Printed and reset $echo)
At 31 seconds(key=15)
array key 15 skipped. (Looking for group 2. 15 mod 4 = 3)

At 31 seconds(key=16)
array key 16 skipped. (Looking for group 2. 16 mod 4 = 0)

At 31 seconds(key=17)
array key 17 skipped. (Looking for group 2. 17 mod 4 = 1)

At 31 seconds(key=18)
  Starting to fetch data
  Fetched 365,789 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/819585.jf' in 1 s at rate of 365,789 B/s
  Saved file to 'all_readings/819585.jf'
  Process reports that it wrote 365,789 bytes. OK.
  (Printed and reset $echo)
At 32 seconds(key=19)
array key 19 skipped. (Looking for group 2. 19 mod 4 = 3)

At 32 seconds(key=20)
array key 20 skipped. (Looking for group 2. 20 mod 4 = 0)

At 32 seconds(key=21)
array key 21 skipped. (Looking for group 2. 21 mod 4 = 1)

At 32 seconds(key=22)
  Starting to fetch data
  Fetched 6,047,715 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/840300.jf' in 2 s at rate of 3,023,858 B/s
  Saved file to 'all_readings/840300.jf'
  Process reports that it wrote 6,047,715 bytes. OK.
  (Printed and reset $echo)
At 34 seconds(key=23)
array key 23 skipped. (Looking for group 2. 23 mod 4 = 3)

At 34 seconds(key=24)
array key 24 skipped. (Looking for group 2. 24 mod 4 = 0)

At 34 seconds(key=25)
array key 25 skipped. (Looking for group 2. 25 mod 4 = 1)

At 34 seconds(key=26)
  Starting to fetch data
  Fetched 944,956 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/844555.jf' in 1 s at rate of 944,956 B/s
  Saved file to 'all_readings/844555.jf'
  Process reports that it wrote 944,956 bytes. OK.
  (Printed and reset $echo)
At 34 seconds(key=27)
array key 27 skipped. (Looking for group 2. 27 mod 4 = 3)

At 34 seconds(key=28)
array key 28 skipped. (Looking for group 2. 28 mod 4 = 0)

At 34 seconds(key=29)
array key 29 skipped. (Looking for group 2. 29 mod 4 = 1)

At 34 seconds(key=30)
  Starting to fetch data
  Fetched 861,163 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/865344.jf' in 1 s at rate of 861,163 B/s
  Saved file to 'all_readings/865344.jf'
  Process reports that it wrote 861,163 bytes. OK.
  (Printed and reset $echo)
At 34 seconds(key=31)
array key 31 skipped. (Looking for group 2. 31 mod 4 = 3)

At 34 seconds(key=32)
array key 32 skipped. (Looking for group 2. 32 mod 4 = 0)

At 34 seconds(key=33)
array key 33 skipped. (Looking for group 2. 33 mod 4 = 1)

At 34 seconds(key=34)
  Starting to fetch data
  Fetched 1,885,921 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/883599.jf' in 1 s at rate of 1,885,921 B/s
  Saved file to 'all_readings/883599.jf'
  Process reports that it wrote 1,885,921 bytes. OK.
  (Printed and reset $echo)
At 35 seconds(key=35)
array key 35 skipped. (Looking for group 2. 35 mod 4 = 3)

At 35 seconds(key=36)
array key 36 skipped. (Looking for group 2. 36 mod 4 = 0)

At 35 seconds(key=37)
array key 37 skipped. (Looking for group 2. 37 mod 4 = 1)

At 35 seconds(key=38)
  Starting to fetch data
  Fetched 899,493 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/888271.jf' in 1 s at rate of 899,493 B/s
  Saved file to 'all_readings/888271.jf'
  Process reports that it wrote 899,493 bytes. OK.
  (Printed and reset $echo)
At 35 seconds(key=39)
array key 39 skipped. (Looking for group 2. 39 mod 4 = 3)

At 35 seconds(key=40)
array key 40 skipped. (Looking for group 2. 40 mod 4 = 0)

At 35 seconds(key=41)
array key 41 skipped. (Looking for group 2. 41 mod 4 = 1)

At 35 seconds(key=42)
  Starting to fetch data
  Fetched 889,690 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/904973.jf' in 1 s at rate of 889,690 B/s
  Saved file to 'all_readings/904973.jf'
  Process reports that it wrote 889,690 bytes. OK.
  (Printed and reset $echo)
At 36 seconds(key=43)
array key 43 skipped. (Looking for group 2. 43 mod 4 = 3)

At 36 seconds(key=44)
array key 44 skipped. (Looking for group 2. 44 mod 4 = 0)

At 36 seconds(key=45)
array key 45 skipped. (Looking for group 2. 45 mod 4 = 1)

At 36 seconds(key=46)
  Starting to fetch data
  Fetched 1,789,486 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/929117.jf' in 1 s at rate of 1,789,486 B/s
  Saved file to 'all_readings/929117.jf'
  Process reports that it wrote 1,789,486 bytes. OK.
  (Printed and reset $echo)
At 36 seconds(key=47)
array key 47 skipped. (Looking for group 2. 47 mod 4 = 3)

At 36 seconds(key=48)
array key 48 skipped. (Looking for group 2. 48 mod 4 = 0)

At 36 seconds(key=49)
array key 49 skipped. (Looking for group 2. 49 mod 4 = 1)

At 36 seconds(key=50)
  Starting to fetch data
  Fetched 592,392 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/940877.jf' in 1 s at rate of 592,392 B/s
  Saved file to 'all_readings/940877.jf'
  Process reports that it wrote 592,392 bytes. OK.
  (Printed and reset $echo)
At 37 seconds(key=51)
array key 51 skipped. (Looking for group 2. 51 mod 4 = 3)

At 37 seconds(key=52)
array key 52 skipped. (Looking for group 2. 52 mod 4 = 0)

At 37 seconds(key=53)
array key 53 skipped. (Looking for group 2. 53 mod 4 = 1)

At 37 seconds(key=54)
  Starting to fetch data
  Fetched 1,366,009 bytes from 'http://pooles.bcra.org.uk:3927/Data/current/954904.jf' in 1 s at rate of 1,366,009 B/s
  Saved file to 'all_readings/954904.jf'
  Process reports that it wrote 1,366,009 bytes. OK.
  (Printed and reset $echo)
A total of 75,112,069 file bytes were written in 38 seconds.

At 38 seconds(key=55) array key 55 skipped. (Looking for group 2. 55 mod 4 = 3)

FINISHED