Scheduled FTP transfer fails, but upload test succeeds

[EdBeavWX]

[3/2/2011 Edited to make the log files more readable]

I've set up FTP transfers as documented, and the upload test succeeds (meteohub-upload.test file shows up in the desired location when pressing save&test).

meteohub ftp settings.jpg (40.71 KiB) Viewed 7563 times

I've set up to upload graphs as shown:

meteohub graph upload.jpg (56.44 KiB) Viewed 7563 times

These files never show up in the destination location. I have logged into meteohub using SSH and the files in the uploads directory seem to have correct permissions (RW for owner, R for everybody else) and I did a manual FTP of the files to the web site. Again, the test file does successfully transfer to the web site.

In a private email, Boris suggested looking in "dmesg log" or "meteohub log" for any hints. Recent logs are attached below this post.

Meteohub 4.7c (Build 343)

Anybody have any ideas?

Thanks,

Ed

Meteohub Log File

Code: Select all

wmr928fulleval(14.02.2011 21:37:32): recomputation of weather data finished: 1412259 records processed in 1651 seconds (855 records per second)
logger (19.02.2011 09:30:26): parent process received termination signal (15).
logger (19.02.2011 09:30:26): child process received termination signal (15).
logger (19.02.2011 09:58:06): data logger (version 4.7b, build 341) started.
logger (19.02.2011 09:58:07): connect station 0 (Vantage via USB serial).
logger (19.02.2011 09:58:07): connect station 1 (System Data via Plug-in).
logger (19.02.2011 09:58:08): station 0 (Vantage), wakeup sent.
logger (19.02.2011 09:58:08): station 0 (Vantage), syncing.
logger (19.02.2011 09:58:08): station 0 (Vantage), gettime request sent.
logger (19.02.2011 09:58:19): station 0 (Vantage), gettime request sent.
logger (19.02.2011 09:58:19): station 0 (Vantage), no Vantage time offset.
logger (19.02.2011 09:58:19): station 0 (Vantage), eeprom request sent.
logger (19.02.2011 09:58:19): station 0 (Vantage), rain collector size 0.01 inch per tick.
logger (19.02.2011 09:58:19): station 0 (Vantage), transmitter 1 active as station type (#0) "ISS".
logger (19.02.2011 09:58:19): station 0 (Vantage), transmitter 2 active as station type (#10) "unknown".
logger (19.02.2011 09:58:19): station 0 (Vantage), transmitter 3 active as station type (#10) "unknown".
logger (19.02.2011 09:58:19): station 0 (Vantage), transmitter 4 active as station type (#10) "unknown".
logger (19.02.2011 09:58:19): station 0 (Vantage), transmitter 5 active as station type (#10) "unknown".
logger (19.02.2011 09:58:19): station 0 (Vantage), transmitter 6 active as station type (#10) "unknown".
logger (19.02.2011 09:58:19): station 0 (Vantage), transmitter 7 active as station type (#10) "unknown".
logger (19.02.2011 09:58:19): station 0 (Vantage), transmitter 8 active as station type (#10) "unknown".
logger (19.02.2011 09:58:24): station 0 (Vantage), data gap of 28 minutes, start reading Vantage's internal logger after 2011-02-19 09:58 (Vantage's local time).
logger (19.02.2011 09:58:26): station 0 (Vantage), internal logger provides 513 pages of data.
logger (19.02.2011 09:59:43): station 0 (Vantage), finished reading internal logger, 1 records inserted.
logger (19.02.2011 13:20:24): parent process received termination signal (15).
logger (19.02.2011 13:20:24): child process received termination signal (15).
logger (19.02.2011 13:20:27): disconnect station 0 (Vantage).
logger (19.02.2011 13:20:27): disconnect station 1 (System Data).
logger (19.02.2011 13:20:27): data logger stopped.
logger (19.02.2011 13:21:41): data logger (version 4.7c, build 343) started.
logger (19.02.2011 13:21:42): connect station 0 (Vantage via USB serial).
logger (19.02.2011 13:21:42): connect station 1 (System Data via Plug-in).
logger (19.02.2011 13:21:43): station 0 (Vantage), wakeup sent.
logger (19.02.2011 13:21:43): station 0 (Vantage), syncing.
logger (19.02.2011 13:21:43): station 0 (Vantage), gettime request sent.
logger (19.02.2011 13:21:43): station 0 (Vantage), no Vantage time offset.
logger (19.02.2011 13:21:43): station 0 (Vantage), eeprom request sent.
logger (19.02.2011 13:21:43): station 0 (Vantage), rain collector size 0.01 inch per tick.
logger (19.02.2011 13:21:43): station 0 (Vantage), transmitter 1 active as station type (#0) "ISS".
logger (19.02.2011 13:21:43): station 0 (Vantage), transmitter 2 active as station type (#10) "unknown".
logger (19.02.2011 13:21:43): station 0 (Vantage), transmitter 3 active as station type (#10) "unknown".
logger (19.02.2011 13:21:43): station 0 (Vantage), transmitter 4 active as station type (#10) "unknown".
logger (19.02.2011 13:21:43): station 0 (Vantage), transmitter 5 active as station type (#10) "unknown".
logger (19.02.2011 13:21:43): station 0 (Vantage), transmitter 6 active as station type (#10) "unknown".
logger (19.02.2011 13:21:43): station 0 (Vantage), transmitter 7 active as station type (#10) "unknown".
logger (19.02.2011 13:21:43): station 0 (Vantage), transmitter 8 active as station type (#10) "unknown".
logger (19.02.2011 13:21:43): station 0 (Vantage), no reading of Vantage's internal logger as data gap is less than 10 minutes: 81 secs
wmr928fulleval(19.02.2011 13:25:01): recomputation of weather data started.
logger (19.02.2011 13:34:27): parent process received termination signal (15).
logger (19.02.2011 13:34:27): child process received termination signal (15).
logger (19.02.2011 13:34:28): disconnect station 0 (Vantage).
logger (19.02.2011 13:34:28): disconnect station 1 (System Data).
logger (19.02.2011 13:34:28): data logger stopped.
logger (19.02.2011 13:35:40): data logger (version 4.7c, build 343) started.
logger (19.02.2011 13:35:41): connect station 0 (Vantage via USB serial).
logger (19.02.2011 13:35:41): connect station 1 (System Data via Plug-in).
logger (19.02.2011 13:35:42): station 0 (Vantage), wakeup sent.
logger (19.02.2011 13:35:42): station 0 (Vantage), syncing.
logger (19.02.2011 13:35:42): station 0 (Vantage), gettime request sent.
logger (19.02.2011 13:35:42): station 0 (Vantage), no Vantage time offset.
logger (19.02.2011 13:35:42): station 0 (Vantage), eeprom request sent.
logger (19.02.2011 13:35:42): station 0 (Vantage), rain collector size 0.01 inch per tick.
logger (19.02.2011 13:35:42): station 0 (Vantage), transmitter 1 active as station type (#0) "ISS".
logger (19.02.2011 13:35:42): station 0 (Vantage), transmitter 2 active as station type (#10) "unknown".
logger (19.02.2011 13:35:42): station 0 (Vantage), transmitter 3 active as station type (#10) "unknown".
logger (19.02.2011 13:35:42): station 0 (Vantage), transmitter 4 active as station type (#10) "unknown".
logger (19.02.2011 13:35:42): station 0 (Vantage), transmitter 5 active as station type (#10) "unknown".
logger (19.02.2011 13:35:42): station 0 (Vantage), transmitter 6 active as station type (#10) "unknown".
logger (19.02.2011 13:35:42): station 0 (Vantage), transmitter 7 active as station type (#10) "unknown".
logger (19.02.2011 13:35:42): station 0 (Vantage), transmitter 8 active as station type (#10) "unknown".
logger (19.02.2011 13:35:42): station 0 (Vantage), no reading of Vantage's internal logger as data gap is less than 10 minutes: 120 secs
logger (19.02.2011 20:49:34): parent process received termination signal (15).
logger (19.02.2011 20:49:34): child process received termination signal (15).
logger (19.02.2011 20:49:36): disconnect station 0 (Vantage).
logger (19.02.2011 20:49:36): disconnect station 1 (System Data).
logger (19.02.2011 20:49:36): data logger stopped.
logger (19.02.2011 20:50:47): data logger (version 4.7c, build 343) started.
logger (19.02.2011 20:50:48): connect station 0 (Vantage via USB serial).
logger (19.02.2011 20:50:48): connect station 1 (System Data via Plug-in).
logger (19.02.2011 20:50:49): station 0 (Vantage), wakeup sent.
logger (19.02.2011 20:50:49): station 0 (Vantage), syncing.
logger (19.02.2011 20:50:49): station 0 (Vantage), gettime request sent.
logger (19.02.2011 20:50:59): station 0 (Vantage), gettime request sent.
logger (19.02.2011 20:50:59): station 0 (Vantage), no Vantage time offset.
logger (19.02.2011 20:50:59): station 0 (Vantage), eeprom request sent.
logger (19.02.2011 20:51:00): station 0 (Vantage), rain collector size 0.01 inch per tick.
logger (19.02.2011 20:51:00): station 0 (Vantage), transmitter 1 active as station type (#0) "ISS".
logger (19.02.2011 20:51:00): station 0 (Vantage), transmitter 2 active as station type (#10) "unknown".
logger (19.02.2011 20:51:00): station 0 (Vantage), transmitter 3 active as station type (#10) "unknown".
logger (19.02.2011 20:51:00): station 0 (Vantage), transmitter 4 active as station type (#10) "unknown".
logger (19.02.2011 20:51:00): station 0 (Vantage), transmitter 5 active as station type (#10) "unknown".
logger (19.02.2011 20:51:00): station 0 (Vantage), transmitter 6 active as station type (#10) "unknown".
logger (19.02.2011 20:51:00): station 0 (Vantage), transmitter 7 active as station type (#10) "unknown".
logger (19.02.2011 20:51:00): station 0 (Vantage), transmitter 8 active as station type (#10) "unknown".
logger (19.02.2011 20:51:00): station 0 (Vantage), no reading of Vantage's internal logger as data gap is less than 10 minutes: 138 secs[/size]

[b][u]DMSG LOG[/u][/b]

[size=50]DMESG:
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 2.6.32-5-kirkwood (Debian 2.6.32-13) (maks@debian.org) (gcc version 4.3.4 (Debian 4.3.4-10) ) #1 Fri May 21 05:44:29 UTC 2010
[    0.000000] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=00053177
[    0.000000] CPU: VIVT data cache, VIVT instruction cache
[    0.000000] Machine: Marvell SheevaPlug Reference Board
[    0.000000] Memory policy: ECC disabled, Data cache writeback
[    0.000000] On node 0 totalpages: 131072
[    0.000000] free_area_init_node: node 0, pgdat c03db168, node_mem_map c0cfa000
[    0.000000]   Normal zone: 1024 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 130048 pages, LIFO batch:31
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 130048
[    0.000000] Kernel command line: console=ttyS0,115200 mtdparts=nand_mtd:0xc0000@0(uboot)ro,0x1ff00000@0x100000(root) root=/dev/mmcblk0p1 rootdelay=10
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Memory: 256MB 256MB = 512MB total
[    0.000000] Memory: 509952KB available (3548K code, 583K data, 120K init, 0K highmem)
[    0.000000] SLUB: Genslabs=11, HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS:114
[    0.000000] Console: colour dummy device 80x30
[   21.474966] Calibrating delay loop... 1192.75 BogoMIPS (lpj=5963776)
[   21.714926] Security Framework initialized
[   21.714945] SELinux:  Disabled at boot.
[   21.714974] Mount-cache hash table entries: 512
[   21.715273] Initializing cgroup subsys ns
[   21.715289] Initializing cgroup subsys cpuacct
[   21.715300] Initializing cgroup subsys devices
[   21.715308] Initializing cgroup subsys freezer
[   21.715316] Initializing cgroup subsys net_cls
[   21.715361] CPU: Testing write buffer coherency: ok
[   21.716085] devtmpfs: initialized
[   21.717949] regulator: core version 0.5
[   21.718164] NET: Registered protocol family 16
[   21.718682] Kirkwood: MV88F6281-A1, TCLK=200000000.
[   21.718695] Feroceon L2: Enabling L2
[   21.718728] Feroceon L2: Cache support initialised.
[   21.719161] initial MPP regs: 01111111 11113322 00001111 00100000 00000000 00000000 00000000
[   21.719183]   final MPP regs: 01111111 11113322 00001111 00000000 00000000 00000000 00000000
[   21.720812] bio: create slab <bio-0> at 0
[   21.721083] vgaarb: loaded
[   21.721551] Switching to clocksource orion_clocksource
[   21.725361] NET: Registered protocol family 2
[   21.725607] IP route cache hash table entries: 4096 (order: 2, 16384 bytes)
[   21.726413] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[   21.726768] TCP bind hash table entries: 16384 (order: 4, 65536 bytes)
[   21.726947] TCP: Hash tables configured (established 16384 bind 16384)
[   21.726957] TCP reno registered
[   21.727113] NET: Registered protocol family 1
[   21.727287] Unpacking initramfs...
[   22.059798] Freeing initrd memory: 5092K
[   22.059896] NetWinder Floating Point Emulator V0.97 (double precision)
[   22.060149] audit: initializing netlink socket (disabled)
[   22.060183] type=2000 audit(0.570:1): initialized
[   22.066930] VFS: Disk quotas dquot_6.5.2
[   22.067201] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[   22.067298] JFFS2 version 2.2. (NAND) (SUMMARY)  © 2001-2006 Red Hat, Inc.
[   22.067937] msgmni has been set to 1006
[   22.069638] alg: No test for stdrng (krng)
[   22.069758] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[   22.069771] io scheduler noop registered
[   22.069779] io scheduler anticipatory registered
[   22.069786] io scheduler deadline registered
[   22.069978] io scheduler cfq registered (default)
[   22.076171] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[   22.076642] serial8250.0: ttyS0 at MMIO 0xf1012000 (irq = 33) is a 16550A
[   22.404063] console [ttyS0] enabled
[   22.408130] NAND device: Manufacturer ID: 0xec, Chip ID: 0xdc (Samsung NAND 512MiB 3,3V 8-bit)
[   22.416826] Scanning device for bad blocks
[   22.431490] Bad eraseblock 283 at 0x000002360000
[   22.445262] Bad eraseblock 529 at 0x000004220000
[   22.450525] Bad eraseblock 546 at 0x000004440000
[   22.586757] Creating 3 MTD partitions on "orion_nand":
[   22.591937] 0x000000000000-0x000000100000 : "u-boot"
[   22.597492] 0x000000100000-0x000000500000 : "uImage"
[   22.602978] 0x000000500000-0x000020000000 : "root"
[   22.609263] mice: PS/2 mouse device common for all mice
[   22.614890] rtc-mv rtc-mv: rtc core: registered rtc-mv as rtc0
[   22.620817] i2c /dev entries driver
[   22.624536] cpuidle: using governor ladder
[   22.628773] cpuidle: using governor menu
[   22.632823] Registered led device: plug:green:health
[   22.637863] mv_xor_shared mv_xor_shared.0: Marvell shared XOR driver
[   22.644280] mv_xor_shared mv_xor_shared.1: Marvell shared XOR driver
[   22.681618] mv_xor mv_xor.0: Marvell XOR: ( xor cpy )
[   22.721617] mv_xor mv_xor.1: Marvell XOR: ( xor fill cpy )
[   22.761614] mv_xor mv_xor.2: Marvell XOR: ( xor cpy )
[   22.801615] mv_xor mv_xor.3: Marvell XOR: ( xor fill cpy )
[   22.808345] TCP cubic registered
[   22.811605] NET: Registered protocol family 17
[   22.816081] Gating clock of unused units
[   22.816089] before: 0x00dfc3dd
[   22.816096]  after: 0x00c701d9
[   22.816401] registered taskstats version 1
[   22.821187] rtc-mv rtc-mv: setting system clock to 2011-02-20 04:50:17 UTC (1298177417)
[   22.829253] Initalizing network drop monitor service
[   22.834308] Freeing init memory: 120K
[   23.210469] mmc0: mvsdio driver initialized, lacking card detect (fall back to polling)
[   23.289698] mmc0: host does not support reading read-only switch. assuming write-enable.
[   23.304617] mmc0: new high speed SDHC card at address b368
[   24.143553] usbcore: registered new interface driver usbfs
[   24.154757] usbcore: registered new interface driver hub
[   24.162565] usbcore: registered new device driver usb
[   24.204056] mmcblk0: mmc0:b368 SDC   3.84 GiB 
[   24.208683]  mmcblk0: p1 p2 p3
[   24.230536] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[   24.242511] orion-ehci orion-ehci.0: Marvell Orion EHCI
[   24.247817] orion-ehci orion-ehci.0: new USB bus registered, assigned bus number 1
[   24.291681] orion-ehci orion-ehci.0: irq 19, io mem 0xf1050000
[   24.311720] orion-ehci orion-ehci.0: USB 2.0 started, EHCI 1.00
[   24.317997] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[   24.324854] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   24.332126] usb usb1: Product: Marvell Orion EHCI
[   24.336852] usb usb1: Manufacturer: Linux 2.6.32-5-kirkwood ehci_hcd
[   24.343245] usb usb1: SerialNumber: orion-ehci.0
[   24.349102] usb usb1: configuration #1 chosen from 1 choice
[   24.359796] hub 1-0:1.0: USB hub found
[   24.376631] hub 1-0:1.0: 1 port detected
[   24.380816] MV-643xx 10/100/1000 ethernet driver version 1.4
[   24.391339] mv643xx_eth smi: probed
[   24.504416] net eth0: port 0 with MAC address f0:ad:4e:00:09:c2
[   24.691598] usb 1-1: new full speed USB device using orion-ehci and address 2
[   24.847574] usb 1-1: New USB device found, idVendor=067b, idProduct=2303
[   24.854322] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[   24.861491] usb 1-1: Product: USB-Serial Controller D
[   24.866582] usb 1-1: Manufacturer: Prolific Technology Inc. 
[   24.873408] usb 1-1: configuration #1 chosen from 1 choice
[   34.640869] md: linear personality registered for level -1
[   34.672459] md: multipath personality registered for level -4
[   34.704279] md: raid0 personality registered for level 0
[   34.742774] md: raid1 personality registered for level 1
[   34.772509] async_tx: api initialized (async)
[   34.785843] xor: measuring software checksum speed
[   34.831577]    arm4regs  :  1084.800 MB/sec
[   34.881574]    8regs     :   804.000 MB/sec
[   34.931576]    32regs    :   900.400 MB/sec
[   34.935777] xor: using function: arm4regs (1084.800 MB/sec)
[   35.121723] raid6: int32x1     78 MB/s
[   35.292113] raid6: int32x2    103 MB/s
[   35.461742] raid6: int32x4    124 MB/s
[   35.631762] raid6: int32x8    110 MB/s
[   35.635530] raid6: using algorithm int32x4 (124 MB/s)
[   35.676407] md: raid6 personality registered for level 6
[   35.681787] md: raid5 personality registered for level 5
[   35.687127] md: raid4 personality registered for level 4
[   35.822874] md: raid10 personality registered for level 10
[   35.908677] end_request: I/O error, dev mtdblock0, sector 0
[   35.914286] Buffer I/O error on device mtdblock0, logical block 0
[   35.920573] uncorrectable error : 
[   35.923827] uncorrectable error : 
[   35.927251] end_request: I/O error, dev mtdblock0, sector 8
[   35.933032] Buffer I/O error on device mtdblock0, logical block 1
[   35.939282] uncorrectable error : 
[   35.942536] uncorrectable error : 
[   35.945964] end_request: I/O error, dev mtdblock0, sector 16
[   35.951833] Buffer I/O error on device mtdblock0, logical block 2
[   35.958082] uncorrectable error : 
[   35.961329] end_request: I/O error, dev mtdblock0, sector 24
[   35.967196] Buffer I/O error on device mtdblock0, logical block 3
[   35.973475] end_request: I/O error, dev mtdblock0, sector 0
[   35.979073] Buffer I/O error on device mtdblock0, logical block 0
[   36.034592] end_request: I/O error, dev mtdblock0, sector 0
[   36.040193] Buffer I/O error on device mtdblock0, logical block 0
[   36.046480] uncorrectable error : 
[   36.049720] uncorrectable error : 
[   36.053158] end_request: I/O error, dev mtdblock0, sector 8
[   36.058941] Buffer I/O error on device mtdblock0, logical block 1
[   36.065191] uncorrectable error : 
[   36.068433] uncorrectable error : 
[   36.071865] end_request: I/O error, dev mtdblock0, sector 16
[   36.077732] Buffer I/O error on device mtdblock0, logical block 2
[   36.083981] uncorrectable error : 
[   36.087228] end_request: I/O error, dev mtdblock0, sector 24
[   36.093096] Buffer I/O error on device mtdblock0, logical block 3
[   36.099373] end_request: I/O error, dev mtdblock0, sector 0
[   36.104973] Buffer I/O error on device mtdblock0, logical block 0
[   36.194246] kjournald starting.  Commit interval 5 seconds
[   36.199780] EXT3-fs: mounted filesystem with ordered data mode.
[   37.418999] udevd version 125 started
[   39.506829] usbcore: registered new interface driver usbserial
[   39.519059] USB Serial support registered for generic
[   39.526898] usbcore: registered new interface driver usbserial_generic
[   39.533509] usbserial: USB Serial Driver core
[   39.552484] USB Serial support registered for pl2303
[   39.562035] pl2303 1-1:1.0: pl2303 converter detected
[   39.579747] usb 1-1: pl2303 converter now attached to ttyUSB0
[   39.585614] usbcore: registered new interface driver pl2303
[   39.591213] pl2303: Prolific PL2303 USB to serial adaptor driver
[   40.686629] Adding 134532k swap on /dev/mmcblk0p2.  Priority:-1 extents:1 across:134532k SS
[   40.805179] EXT3 FS on mmcblk0p1, internal journal
[   42.273310] loop: module loaded
[   42.553975] end_request: I/O error, dev mtdblock0, sector 0
[   42.559581] Buffer I/O error on device mtdblock0, logical block 0
[   42.565870] uncorrectable error : 
[   42.569108] uncorrectable error : 
[   42.572545] end_request: I/O error, dev mtdblock0, sector 8
[   42.578328] Buffer I/O error on device mtdblock0, logical block 1
[   42.584580] uncorrectable error : 
[   42.587822] uncorrectable error : 
[   42.591251] end_request: I/O error, dev mtdblock0, sector 16
[   42.597119] Buffer I/O error on device mtdblock0, logical block 2
[   42.603371] uncorrectable error : 
[   42.606616] end_request: I/O error, dev mtdblock0, sector 24
[   42.612483] Buffer I/O error on device mtdblock0, logical block 3
[   42.618760] end_request: I/O error, dev mtdblock0, sector 0
[   42.624361] Buffer I/O error on device mtdblock0, logical block 0
[   42.929008] kjournald starting.  Commit interval 120 seconds
[   43.012758] EXT3 FS on mmcblk0p3, internal journal
[   43.017583] EXT3-fs: mounted filesystem with ordered data mode.
[   44.751226] NET: Registered protocol family 10
[   44.781708] lo: Disabled Privacy Extensions
[   44.787524] ADDRCONF(NETDEV_UP): eth0: link is not ready
[   45.271759] eth0: link up, 100 Mb/s, full duplex, flow control disabled
[   45.278580] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   47.407642] USB Serial support registered for cp210x
[   47.417428] usbcore: registered new interface driver cp210x
[   47.423064] cp210x: v0.09:Silicon Labs CP210x RS232 serial adaptor driver
[   47.545287] USB Serial support registered for FTDI USB Serial Device
[   47.556550] usbcore: registered new interface driver ftdi_sio
[   47.562399] ftdi_sio: v1.5.0:USB FTDI Serial Converters Driver
[   47.681725] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[   54.888161] warning: `ntpd' uses 32-bit capabilities (legacy support in use)
[   56.061570] eth0: no IPv6 routers present

USB:
Bus 001 Device 002: ID 067b:2303 Prolific Technology, Inc. PL2303 Serial Port
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub

PCI:


Partitions:
Filesystem           1K-blocks      Used Available Use% Mounted on
/dev/mmcblk0p1          858832    667312    147904  82% /
tmpfs                   257768         0    257768   0% /lib/init/rw
udev                     10240       460      9780   5% /dev
tmpfs                   257768         0    257768   0% /dev/shm
/dev/mmcblk0p3         2603128    141892   2329004   6% /data
tmpfs                     8192         0      8192   0% /var/lock
tmpfs                    32768      3320     29448  11% /var/run
tmpfs                    32768        16     32752   1% /var/log

Process:
  PID TTY      STAT   TIME COMMAND
    1 ?        Ss     0:00 init [2]  
    2 ?        S      0:00 [kthreadd]
    3 ?        S      0:00 [ksoftirqd/0]
    4 ?        S      0:00 [watchdog/0]
    5 ?        S      0:00 [events/0]
    6 ?        S      0:00 [cpuset]
    7 ?        S      0:00 [khelper]
    8 ?        S      0:00 [netns]
    9 ?        S      0:00 [async/mgr]
   10 ?        S      0:00 [pm]
   11 ?        S      0:00 [sync_supers]
   12 ?        S      0:00 [bdi-default]
   13 ?        S      0:00 [kintegrityd/0]
   14 ?        S      0:00 [kblockd/0]
   15 ?        S      0:00 [kseriod]
   16 ?        S      0:00 [khungtaskd]
   17 ?        S      0:00 [kswapd0]
   18 ?        SN     0:00 [ksmd]
   19 ?        S      0:00 [aio/0]
   20 ?        S      0:00 [crypto/0]
   25 ?        S      0:00 [mtdblockd]
   26 ?        S      0:00 [orion_spi]
   27 ?        S      0:00 [kpsmoused]
   64 ?        S      0:00 [kmmcd]
  253 ?        S      0:00 [ksuspend_usbd]
  257 ?        S      0:00 [khubd]
  264 ?        S      0:00 [mmcqd]
  369 ?        S      0:00 [kjournald]
  456 ?        S<s    0:00 udevd --daemon
  681 ?        S      0:00 [mv_crypto]
  732 ?        Ss     0:00 logsave -s /var/log/fsck/checkroot fsck -C -y -t ext3 /dev/mmcblk0p1
  769 ?        S      0:00 [flush-179:0]
  842 ?        Ss     0:00 logsave -s /var/log/fsck/checkfs fsck -C -R -A -y
  851 ?        S      0:00 [kjournald]
  947 ?        Ss     0:00 /sbin/portmap
  959 ?        Ss     0:00 /sbin/rpc.statd
 1096 ?        S      0:00 /home/meteohub/meteoschedule /dev/meteohub
 1138 ?        Sl     0:00 /usr/sbin/rsyslogd -f /etc/syslog.conf
 1154 ?        Ss     0:00 /usr/bin/dbus-daemon --system
 1168 ?        Ss     0:00 /usr/sbin/sshd
 1185 ?        S<     0:00 /home/meteohub/loggerd /var/run/loggerd.pid
 1186 ?        S<     0:03 /home/meteohub/loggerd /var/run/loggerd.pid
 1189 ?        S      0:11 /home/meteohub/meteonet -r
 1204 ?        Ss     0:00 /usr/bin/rsync /var/run/rsyncd.pid --daemon
 1253 ?        Ss     0:00 /usr/sbin/nmbd -D
 1255 ?        S<     0:00 /home/meteohub/meteosys
 1256 ?        Ss     0:00 /usr/sbin/smbd -D
 1264 ?        S<s    0:00 /usr/sbin/thttpd -C /etc/thttpd.conf
 1266 ?        S<s    0:00 /usr/sbin/thttpdbackup -C /etc/thttpd-backup.conf
 1274 ?        Ss     0:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -u 105:107 -g
 1282 ?        S      0:00 /usr/sbin/smbd -D
 1285 ?        Ss     0:00 /sbin/mdadm --monitor --pid-file /var/run/mdadm/monitor.pid --daemonise --scan --syslog
 1305 ?        Ss     0:00 /usr/sbin/cron
 1315 ?        SLs    0:00 /usr/sbin/watchdog
 1328 ttyS0    Ss+    0:00 /sbin/getty -L ttyS0 115200 linux
 3789 ?        S      0:00 /usr/sbin/smbd -D
 4585 ?        S<     0:00 meteohub.cgi
 4586 ?        Z<     0:00 [thttpd] <defunct>
 4587 ?        S<     0:00 /usr/sbin/thttpd -C /etc/thttpd.conf
 4620 ?        R<     0:00 ps ax

[d_l]

I can't offer any suggestions as to why Meteohub is failing its scheduled FTP uploads, but have you tried setting an immediate email notification for when the FTP upload fails? That way you would at least know if the Meteohub is recognizing that it has failed in its uploads.

[EdBeavWX]

have you tried setting an immediate email notification for when the FTP upload fails? That way you would at least know if the Meteohub is recognizing that it has failed in its uploads.

Good suggestion. I tried this and didn't receive any emails. Any ideas?

Ed

[d_l]

Ed, if the other email notifications work correctly, then I would think either the Meteohub believes it is FTPing correctly OR perhaps the FTP uploads are simply not being scheduled for some reason? I didn't notice any FTP-related messages in the posted logs, but the print is tiny and I might have missed something scanning through them.

Are there any alarm log messages concerning the FTP uploads?

Dave

[EdBeavWX]

perhaps the FTP uploads are simply not being scheduled for some reason?

I got some hints from viewtopic.php?f=8&t=8086&p=8456&hilit=cron#p8456

Looking at crontab -l I see the following (full output at bottom of post):

Code: Select all

cp /var/run/meteohub/clientrawdaily.out /var/run/meteohub/perm/clientrawdaily.txt; mv /var/run/meteohub/clientrawdaily.out "/var/run/meteohub/uploads/clientrawdaily.txt"
*/1 * * * *     sleep 20; [ -z "`ls /var/run/meteohub/uploads/`" ] || [ -n "`/bin/pidof /usr/bin/ncftpput`" ] || /usr/bin/ncftpput -S .tmp -R -V -DD -u "blah123456%12345a9" -p "Blahefg9hi" -P 21 01234a9.netsolhost.com /htdocs/destination/weather/ /var/run/meteohub/uploads/* 2>&1 | /usr/bin/logger

Following the instructions in the aforementioned post:

/var/run/meteohub/uploads/
has the following files in it (they appear current): allsensors.txt clientrawdaily.txt clientrawextra.txt clientrawhour.txt

/bin/pidof /usr/bin/ncftpput
yields no output, so no ncftpput processing running


Any ideas why the ftp isn't transferring files, and how to get it working again?

Thanks,

Ed


Full crontab -l follows...

Code: Select all

*/1 */1 * * *   /bin/pidof -x histeval0 || /usr/bin/nice /home/meteohub/histeval0
*/5 */1 * * *   /bin/pidof -x histeval1 || /usr/bin/nice /home/meteohub/histeval1
13 */6 * * *   /bin/pidof -x histeval2 || /usr/bin/nice /home/meteohub/histeval2
47 4 * * *   /bin/pidof -x histeval3 || /usr/bin/nice /home/meteohub/histeval3
*/1 * * * * /bin/cat /proc/uptime >/var/run/meteohub/alive
22,52 * * * * echo 'wdnow' >/dev/meteohub
17 */6 * * * rm /var/run/*.idx
*/1 * * * * ([ -n "`/bin/pidof thttpd`" ] && [ -n "`/bin/pidof thttpdbackup`" ]) || rcthttpd restart
26 6 * * * nice /srv/www/meteolog.cgi 0 0
37 2 * * * /usr/bin/test -f /home/meteohub/autoreboot && echo norecompute >/home/meteohub/norecompute && /sbin/shutdown -r -n now
#
# generate history data
#
# synchronize hw clock
44 */2 * * * /sbin/hwclock -w
#
# compute weather data
8,18,28,38,48,58 * * * * /usr/bin/nice /home/meteohub/wmr928eval -x -c min1 -t -3600 -w /data/weather/ -s /home/meteohub/wmr928eval.conf -e /home/meteohub/wswin.conf -E /home/meteohub/wd.conf
1,11,21,31,41,51  * * * * /usr/bin/nice /home/meteohub/wmr928eval -c min5 -t -3600 -w /data/weather/ -s /home/meteohub/wmr928eval.conf
2,22,42 * * * * /usr/bin/nice /home/meteohub/wmr928eval -c min10 -t -3600 -w /data/weather/ -s /home/meteohub/wmr928eval.conf
3,33 * * * * /usr/bin/nice /home/meteohub/wmr928eval -c min30 -t -7200 -w /data/weather/ -s /home/meteohub/wmr928eval.conf
3 * * * * /usr/bin/nice /home/meteohub/wmr928eval -c hour1 -t -7200 -w /data/weather/ -s /home/meteohub/wmr928eval.conf
4 0,6,12,18 * * * /usr/bin/nice /home/meteohub/wmr928eval -c hour6 -t -50000 -w /data/weather/ -s /home/meteohub/wmr928eval.conf
5 2 * * * /usr/bin/nice /home/meteohub/wmr928eval -c day1 -t -200000 -w /data/weather/ -s /home/meteohub/wmr928eval.conf
6 2 1 * * /usr/bin/nice /home/meteohub/wmr928eval -c month1 -t -7000000 -w /data/weather/ -s /home/meteohub/wmr928eval.conf
#
# test for complete recomputation of weather data
*/5 * * * * /home/meteohub/wmr928fullevalwrapper >/dev/null 2>>/data/log/messages
#
# cut wswin import data into monthly chunks
*/3 * * * * /bin/pidof wswinimport || /usr/bin/nice /home/meteohub/wswinimport 2>>/data/log/messages
#
# check for alarms to be mailed to the admin and send them once a day
44 5,11,17,23 * * * /home/meteohub/alarm check "" >/dev/null 2>>/data/log/messages
55 23 * * * /home/meteohub/alarm send "" >/dev/null 2>>/data/log/messages
#
# shorten logfiles
12 0 * * * /usr/bin/tail -n 1000 /data/log/thttpd.log >/data/log/thttpd.log.new; /bin/mv -f /data/log/thttpd.log.new /data/log/thttpd.log
13 0 * * * /usr/bin/tail -n 1000 /data/log/ntp.log >/data/log/ntp.log.new; /bin/mv -f /data/log/ntp.log.new /data/log/ntp.log
14 0 * * * /usr/bin/tail -n 1000 /data/log/smb.log >/data/log/smb.log.new; /bin/mv -f /data/log/smb.log.new /data/log/smb.log
15 0 * * * /usr/bin/tail -n 1000 /data/log/meteohub.log >/data/log/meteohub.log.new; /bin/mv -f /data/log/meteohub.log.new /data/log/meteohub.log
16 0 * * * /usr/bin/tail -n 1000 /data/log/alarm.log >/data/log/alarm.log.new; /bin/mv -f /data/log/alarm.log.new /data/log/alarm.log
17 */6 * * * /usr/bin/tail -n 1000 /var/cron/log >/var/cron/log.new; /bin/mv -f /var/cron/log.new /var/cron/log
#
#
## start section written by meteohub
0 0 1 * * /bin/echo "norecompute" >/home/meteohub/norecompute && /sbin/shutdown -r now
*/5 * * * *     sleep 120; /home/meteohub/meteonet
#- "all-sensors" "*/1 * * * *" "allsensors.txt" 0
*/1 * * * *     sleep 0; /srv/www/meteograph.cgi text all>/var/run/meteohub/all-sensors0.txt; /srv/www/cgi-bin/stamp.cgi cp "/var/run/meteohub/all-sensors0.txt" "/var/run/meteohub/perm/allsensors.txt"; /srv/www/cgi-bin/stamp.cgi cp "/var/run/meteohub/all-sensors0.txt" "/data/myweb/uploads/allsensors.txt"; /srv/www/cgi-bin/stamp.cgi mv "/var/run/meteohub/all-sensors0.txt" "/var/run/meteohub/uploads/allsensors.txt"
#- "WD-live daily" "*/30 * * * *" "clientrawdaily.txt" 0
*/30 * * * *    sleep 0; /srv/www/meteohtml.cgi /home/meteohub/clientrawdaily.conf >/var/run/meteohub/clientrawdaily.out; cp /var/run/meteohub/clientrawdaily.out /data/myweb/uploads/clientrawdaily.txt; cp /var/run/meteohub/clientrawdaily.out /var/run/meteohub/perm/clientrawdaily.txt; mv /var/run/meteohub/clientrawdaily.out "/var/run/meteohub/uploads/clientrawdaily.txt"
#- "WD-live extra" "*/30 * * * *" "clientrawextra.txt" 0
*/30 * * * *    sleep 4; /srv/www/meteohtml.cgi /home/meteohub/clientrawextra.conf >/var/run/meteohub/clientrawextra.out; cp /var/run/meteohub/clientrawextra.out /data/myweb/uploads/clientrawextra.txt; cp /var/run/meteohub/clientrawextra.out /var/run/meteohub/perm/clientrawextra.txt; mv /var/run/meteohub/clientrawextra.out "/var/run/meteohub/uploads/clientrawextra.txt"
#- "WD-live hour" "*/30 * * * *" "clientrawhour.txt" 0
*/30 * * * *    sleep 8; /srv/www/meteohtml.cgi /home/meteohub/clientrawhour.conf >/var/run/meteohub/clientrawhour.out; cp /var/run/meteohub/clientrawhour.out /data/myweb/uploads/clientrawhour.txt; cp /var/run/meteohub/clientrawhour.out /var/run/meteohub/perm/clientrawhour.txt; mv /var/run/meteohub/clientrawhour.out "/var/run/meteohub/uploads/clientrawhour.txt"
#- "WD-live daily" "*/30 * * * *" "clientrawdaily.txt" 0
*/30 * * * *    sleep 12; /srv/www/meteohtml.cgi /home/meteohub/clientrawdaily.conf >/var/run/meteohub/clientrawdaily.out; cp /var/run/meteohub/clientrawdaily.out /data/myweb/uploads/clientrawdaily.txt; cp /var/run/meteohub/clientrawdaily.out /var/run/meteohub/perm/clientrawdaily.txt; mv /var/run/meteohub/clientrawdaily.out "/var/run/meteohub/uploads/clientrawdaily.txt"
*/1 * * * *     sleep 20; [ -z "`ls /var/run/meteohub/uploads/`" ] || [ -n "`/bin/pidof /usr/bin/ncftpput`" ] || /usr/bin/ncftpput -S .tmp -R -V -DD -u "blah123456%12345a9" -p "Blahefg9hi" -P 21 01234a9.netsolhost.com /htdocs/destination/weather/ /var/run/meteohub/uploads/* 2>&1 | /usr/bin/logger
## end section written by meteohub

[pucek]

I've had the same problem. Check your password or login. i've set password with % and it's generated an error.

[EdBeavWX]

I've had the same problem. Check your password or login. i've set password with % and it's generated an error.

Three questions:

Does the upload test succeed for you?

Are you saying you are having problems with passwords that contain the percent character?

What error are you getting? Mine simply fails to upload with no error.

Thanks,

Ed

[Manhart]

The password must be without %. I had to change my password because of this character. The test worked, but i never saw a file uploaded.

Matthias