RRD files not being created with Spine running

Post support questions that directly relate to Linux/Unix operating systems.

Moderators: Developers, Moderators

mrheavydee95
Posts: 30
Joined: Tue Mar 05, 2024 12:51 pm

RRD files not being created with Spine running

Post by mrheavydee95 »

This is a brand new installation for both Cacti (1.2.26) and the latest version of Spine. The localhost RRD files were created with cmd.php poller and are being updated with Spine. I just can't get Spine to create new RRD files.
User avatar
macan
Cacti Guru User
Posts: 1137
Joined: Tue Mar 18, 2008 2:30 am
Location: Czech

Re: RRD files not being created with Spine running

Post by macan »

which user run spine? Has this user rights to your_cacti/rra directory?
Let the Cacti grow!
mrheavydee95
Posts: 30
Joined: Tue Mar 05, 2024 12:51 pm

Re: RRD files not being created with Spine running

Post by mrheavydee95 »

We have root as the owner of spine and www-data is the owner of the RRA folder (see below).

local-admin@vmcactiscus001:/usr/local/spine/bin$ ll
total 560
drwxr-xr-x 2 root root 4096 Mar 5 13:50 ./
drwxr-xr-x 5 root root 4096 Mar 5 13:13 ../
-rwsr-xr-x 1 root root 561880 Mar 5 13:13 spine*


drwxrwxr-x 2 www-data www-data 4096 Mar 5 18:00 rra/
User avatar
macan
Cacti Guru User
Posts: 1137
Joined: Tue Mar 18, 2008 2:30 am
Location: Czech

Re: RRD files not being created with Spine running

Post by macan »

so user www-data or root started spine in cron?
Let the Cacti grow!
mrheavydee95
Posts: 30
Joined: Tue Mar 05, 2024 12:51 pm

Re: RRD files not being created with Spine running

Post by mrheavydee95 »

running it as www-data in cron, see below:

*/1 * * * * www-data php /var/www/html/cacti/poller.php > /dev/null 2>&1
bike
Posts: 5
Joined: Sun Feb 11, 2024 12:58 pm

Re: RRD files not being created with Spine running

Post by bike »

You clearly have a problem with access rights, check if SeLinux is enabled, disable it for testing, try running Spine using cactid.
mrheavydee95
Posts: 30
Joined: Tue Mar 05, 2024 12:51 pm

Re: RRD files not being created with Spine running

Post by mrheavydee95 »

I don't know how to disable selinux but I was able to run "cat /proc/mounts" and I don't see it.

local-admin@vmcactiscus001:/etc/selinux$ cat /proc/mounts
/dev/root / ext4 rw,relatime,discard,errors=remount-ro 0 0
devtmpfs /dev devtmpfs rw,nosuid,noexec,relatime,size=8152664k,nr_inodes=2038166,mode=755,inode64 0 0
proc /proc proc rw,nosuid,nodev,noexec,relatime 0 0
sysfs /sys sysfs rw,nosuid,nodev,noexec,relatime 0 0
securityfs /sys/kernel/security securityfs rw,nosuid,nodev,noexec,relatime 0 0
tmpfs /dev/shm tmpfs rw,nosuid,nodev,inode64 0 0
devpts /dev/pts devpts rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000 0 0
tmpfs /run tmpfs rw,nosuid,nodev,size=3262316k,nr_inodes=819200,mode=755,inode64 0 0
tmpfs /run/lock tmpfs rw,nosuid,nodev,noexec,relatime,size=5120k,inode64 0 0
cgroup2 /sys/fs/cgroup cgroup2 rw,nosuid,nodev,noexec,relatime,nsdelegate,memory_recursiveprot 0 0
pstore /sys/fs/pstore pstore rw,nosuid,nodev,noexec,relatime 0 0
efivarfs /sys/firmware/efi/efivars efivarfs rw,nosuid,nodev,noexec,relatime 0 0
bpf /sys/fs/bpf bpf rw,nosuid,nodev,noexec,relatime,mode=700 0 0
systemd-1 /proc/sys/fs/binfmt_misc autofs rw,relatime,fd=29,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=2525 0 0
hugetlbfs /dev/hugepages hugetlbfs rw,relatime,pagesize=2M 0 0
mqueue /dev/mqueue mqueue rw,nosuid,nodev,noexec,relatime 0 0
debugfs /sys/kernel/debug debugfs rw,nosuid,nodev,noexec,relatime 0 0
tracefs /sys/kernel/tracing tracefs rw,nosuid,nodev,noexec,relatime 0 0
configfs /sys/kernel/config configfs rw,nosuid,nodev,noexec,relatime 0 0
fusectl /sys/fs/fuse/connections fusectl rw,nosuid,nodev,noexec,relatime 0 0
ramfs /run/credentials/systemd-sysusers.service ramfs ro,nosuid,nodev,noexec,relatime,mode=700 0 0
/dev/loop0 /snap/core20/2182 squashfs ro,nodev,relatime,errors=continue,threads=single 0 0
/dev/loop1 /snap/lxd/27037 squashfs ro,nodev,relatime,errors=continue,threads=single 0 0
/dev/loop2 /snap/snapd/20671 squashfs ro,nodev,relatime,errors=continue,threads=single 0 0
/dev/sda15 /boot/efi vfat rw,relatime,fmask=0077,dmask=0077,codepage=437,iocharset=iso8859-1,shortname=mixed,errors=remount-ro 0 0
binfmt_misc /proc/sys/fs/binfmt_misc binfmt_misc rw,nosuid,nodev,noexec,relatime 0 0
/dev/sdb1 /mnt ext4 rw,relatime 0 0
tracefs /sys/kernel/debug/tracing tracefs rw,nosuid,nodev,noexec,relatime 0 0
tmpfs /run/snapd/ns tmpfs rw,nosuid,nodev,size=3262316k,nr_inodes=819200,mode=755,inode64 0 0
nsfs /run/snapd/ns/lxd.mnt nsfs rw 0 0
/dev/loop3 /snap/lxd/27428 squashfs ro,nodev,relatime,errors=continue,threads=single 0 0
/dev/loop4 /snap/snapd/21184 squashfs ro,nodev,relatime,errors=continue,threads=single 0 0
tmpfs /run/user/1000 tmpfs rw,nosuid,nodev,relatime,size=1631156k,nr_inodes=407789,mode=700,uid=1000,gid=1000,inode64 0 0
mrheavydee95
Posts: 30
Joined: Tue Mar 05, 2024 12:51 pm

Re: RRD files not being created with Spine running

Post by mrheavydee95 »

I've looked online on how to disable SELinux but it doesn't seem to be installed on my machine. Any other ideas?
User avatar
macan
Cacti Guru User
Posts: 1137
Joined: Tue Mar 18, 2008 2:30 am
Location: Czech

Re: RRD files not being created with Spine running

Post by macan »

sudo www-data
touch /path/to/your/directory/rra/test.txt

Is it working? If not, you have permission problem.
Let the Cacti grow!
User avatar
macan
Cacti Guru User
Posts: 1137
Joined: Tue Mar 18, 2008 2:30 am
Location: Czech

Re: RRD files not being created with Spine running

Post by macan »

try run
spine --first=X --last=X -V=5 -R
where X is id of any problematic device and show result
Let the Cacti grow!
mrheavydee95
Posts: 30
Joined: Tue Mar 05, 2024 12:51 pm

Re: RRD files not being created with Spine running

Post by mrheavydee95 »

Its telling me Spine is not installed but as you can see from the below I'm running the command from the spine folder.

local-admin@vmcactiscus001:/usr/local/spine/bin$ spine --first=5 --last=5 -V=5 -R
Command 'spine' not found, but can be installed with:
sudo apt install cacti-spine
local-admin@vmcactiscus001:/usr/local/spine/bin$ ll
total 560
drwxr-xr-x 2 root root 4096 Mar 5 13:50 ./
drwxr-xr-x 5 root root 4096 Mar 5 13:13 ../
-rwsr-xr-x 1 root root 561880 Mar 5 13:13 spine*
local-admin@vmcactiscus001:/usr/local/spine/bin$
User avatar
macan
Cacti Guru User
Posts: 1137
Joined: Tue Mar 18, 2008 2:30 am
Location: Czech

Re: RRD files not being created with Spine running

Post by macan »

you need to configure spine:
https://docs.cacti.net/Spine.md
Let the Cacti grow!
mrheavydee95
Posts: 30
Joined: Tue Mar 05, 2024 12:51 pm

Re: RRD files not being created with Spine running

Post by mrheavydee95 »

My apologies, I didn't put the ./spine in my last post. I just ran it and here are my results.

local-admin@vmcactiscus001:/usr/local/spine/bin$ ./spine --first=5 --last=5 -V=5 -R
SPINE: Using spine config file [../etc/spine.conf]
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The path_php_server variable is /var/www/html/cacti/script_server.php
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The path_cactilog variable is /var/www/html/cacti/log/cacti.log
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The version variable is 10.6.16-MariaDB-0ubuntu0.22.04.1
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The log_destination variable is 1 (FILE)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The path_php variable is /usr/bin/php
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The availability_method variable is 4
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The ping_recovery_count variable is 3
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The ping_failure_count variable is 2
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The ping_method variable is 1
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The ping_retries variable is 1
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The ping_timeout variable is 400
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The snmp_retries variable is 3
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The log_perror variable is 1
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The log_pwarn variable is 1
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The boost_redirect variable is 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The boost_rrd_update_enable variable is 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The log_pstats variable is 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The threads variable is 10
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The polling interval is 60 seconds
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The number of concurrent processes is 10
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The script timeout is 25
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The selective_device_debug variable is
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The spine_log_level variable is 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The number of php script servers to run is 1
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The number of active data source profiles is 2
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The number of snmp ports on the system is 1
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: StartDevice='5', EndDevice='5', TotalPHPScripts='1'
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The PHP Script Server is Required
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: The Maximum SNMP OID Get Size is 10
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Total Connections made 1
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Creating Local Connection Pool of 10 threads.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Creating Local Connection 0.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Total Connections made 2
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Creating Local Connection 1.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Total Connections made 3
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Creating Local Connection 2.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Total Connections made 4
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Creating Local Connection 3.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Total Connections made 5
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Creating Local Connection 4.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Total Connections made 6
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Creating Local Connection 5.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Total Connections made 7
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Creating Local Connection 6.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Total Connections made 8
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Creating Local Connection 7.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Total Connections made 9
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Creating Local Connection 8.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Total Connections made 10
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Creating Local Connection 9.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Total Connections made 11
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Version 1.2.26 starting
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] No Device 0 Poller Items found.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: MySQL is Thread Safe!
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Spine running as 1000 UID, 0 EUID
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Spine is running as root.
ERROR: Spine Log File Could Not Be Opened/Created
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Spine has got ICMP
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Initializing Net-SNMP API
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Issues with SNMP Header Version information, assuming old version of Net-SNMP.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Initializing PHP Script Server(s)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: SS[0] PHP Script Server Routine Starting
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: SS[0] PHP Script Server About to FORK Child Process
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: SS[0] PHP Script Server Child FORK Success
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] ERROR: SS[0] Script Server did not start properly return message was: 'System log file is not available for writing, please enable write access
Log: /var/www/html/cacti/log/cacti.log
'2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Initial Value of Available Threads is 10 (0 outstanding)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Device[5] Valid Thread to be Created (139980863174208)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Device[5] Available Threads is 9 (1 outstanding)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] DEBUG: Device[5] HT[1] In Poller, About to Start Polling
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] DEBUG: Traversing Local Connection Pool for free connection.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] DEBUG: Checking Local Pool ID 0.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] DEBUG: Allocating Local Pool ID 0.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Device[5] Valid Thread to be Created (139980854781504)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Device[5] Available Threads is 8 (2 outstanding)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] get_namebyhost(10.120.75.2) - Allocating name_t
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] get_namebyhost(10.120.75.2) - No delimiter, assume full hostname
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] INFO: SNMP Device '10.120.75.2:161' has a timeout of 500000 (500), with 3 retries
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] IPv4 address 10.120.75.2 (10.120.75.2)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] DEBUG: Entering ICMP Ping
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Device[5] Valid Thread to be Created (139980846388800)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Device[5] Available Threads is 7 (3 outstanding)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] DEBUG: Attempting to ping 10.120.75.2, seq 0 (Retry 0 of 1)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] DEBUG: Device[5] HT[2] In Poller, About to Start Polling
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Device[5] Valid Thread to be Created (139980768802368)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Device[5] Available Threads is 6 (4 outstanding)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] DEBUG: Device[5] HT[4] In Poller, About to Start Polling
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] DEBUG: Traversing Local Connection Pool for free connection.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] DEBUG: Checking Local Pool ID 0.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] DEBUG: Checking Local Pool ID 1.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] DEBUG: Allocating Local Pool ID 1.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] DEBUG: Device[5] HT[3] In Poller, About to Start Polling
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] DEBUG: Traversing Local Connection Pool for free connection.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Device[5] Valid Thread to be Created (139980760409664)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Device[5] Available Threads is 5 (5 outstanding)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] DEBUG: Checking Local Pool ID 0.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] DEBUG: Checking Local Pool ID 1.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] get_namebyhost(10.120.75.2) - Allocating name_t
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] DEBUG: Checking Local Pool ID 2.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] get_namebyhost(10.120.75.2) - No delimiter, assume full hostname
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] DEBUG: Allocating Local Pool ID 2.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] Device[5] INFO: SNMP Device '10.120.75.2:161' has a timeout of 500000 (500), with 3 retries
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] DEBUG: Traversing Local Connection Pool for free connection.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] Device[5] IPv4 address 10.120.75.2 (10.120.75.2)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] DEBUG: Checking Local Pool ID 0.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] Device[5] DEBUG: Entering ICMP Ping
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] DEBUG: Checking Local Pool ID 1.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] DEBUG: Checking Local Pool ID 2.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] DEBUG: Checking Local Pool ID 3.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] DEBUG: Allocating Local Pool ID 3.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] get_namebyhost(10.120.75.2) - Allocating name_t
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] get_namebyhost(10.120.75.2) - No delimiter, assume full hostname
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] Device[5] INFO: SNMP Device '10.120.75.2:161' has a timeout of 500000 (500), with 3 retries
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] get_namebyhost(10.120.75.2) - Allocating name_t
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] DEBUG: Received EINTR
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] get_namebyhost(10.120.75.2) - No delimiter, assume full hostname
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] Device[5] INFO: SNMP Device '10.120.75.2:161' has a timeout of 500000 (500), with 3 retries
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] Device[5] IPv4 address 10.120.75.2 (10.120.75.2)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] Device[5] DEBUG: Entering ICMP Ping
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] Device[5] IPv4 address 10.120.75.2 (10.120.75.2)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] Device[5] DEBUG: Entering ICMP Ping
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] DEBUG: Received EINTR
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] Device[5] DEBUG: Attempting to ping 10.120.75.2, seq 1 (Retry 0 of 1)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] DEBUG: Received EINTR
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] Device[5] DEBUG: Attempting to ping 10.120.75.2, seq 2 (Retry 0 of 1)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] Device[5] DEBUG: Attempting to ping 10.120.75.2, seq 3 (Retry 0 of 1)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] DEBUG: Device[5] HT[5] In Poller, About to Start Polling
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] DEBUG: Traversing Local Connection Pool for free connection.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] DEBUG: Checking Local Pool ID 0.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] DEBUG: Checking Local Pool ID 1.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] DEBUG: Checking Local Pool ID 2.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] DEBUG: Checking Local Pool ID 3.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] DEBUG: Checking Local Pool ID 4.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] DEBUG: Allocating Local Pool ID 4.
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] get_namebyhost(10.120.75.2) - Allocating name_t
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] get_namebyhost(10.120.75.2) - No delimiter, assume full hostname
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] Device[5] INFO: SNMP Device '10.120.75.2:161' has a timeout of 500000 (500), with 3 retries
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] Device[5] IPv4 address 10.120.75.2 (10.120.75.2)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] Device[5] DEBUG: Entering ICMP Ping
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] Device[5] DEBUG: Received EINTR
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] Device[5] DEBUG: Received EINTR
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] DEBUG: Received EINTR
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] Device[5] DEBUG: Received EINTR
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] Device[5] DEBUG: Attempting to ping 10.120.75.2, seq 4 (Retry 0 of 1)
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] INFO: ICMP Device Alive, Try Count:1, Time:142.2191 ms
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] Device[5] INFO: ICMP Device Alive, Try Count:1, Time:140.4409 ms
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] Device[5] INFO: ICMP Device Alive, Try Count:1, Time:139.7140 ms
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] Device[5] INFO: ICMP Device Alive, Try Count:1, Time:140.7809 ms
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] Device[5] INFO: ICMP Device Alive, Try Count:1, Time:140.7242 ms
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] PING Result: ICMP: Device is Alive
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] SNMP Result: SNMP not performed due to setting or ping result
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] DEBUG: Device[5] HT[1] RECACHE: Processing 1 items in the auto reindex cache for '10.120.75.2'
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] DEBUG: Device[5] HT[5] RECACHE: Processing 1 items in the auto reindex cache for '10.120.75.2'
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] DEBUG: Device[5] HT[2] RECACHE: Processing 1 items in the auto reindex cache for '10.120.75.2'
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 5 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] DEBUG: Device[5] HT[4] RECACHE: Processing 1 items in the auto reindex cache for '10.120.75.2'
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] DEBUG: Device[5] HT[3] RECACHE: Processing 1 items in the auto reindex cache for '10.120.75.2'
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] ERROR: No such Object for oid '.1.3.6.1.2.1.1.3.0' for Device[5] with Status[1]
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] Device[5] HT[2] DQ[16] Legacy Uptime Result: U, Is Numeric: 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] WARNING: Skipped oid '.1.3.6.1.6.3.10.2.1.3.0' for Device[5] as host ignore flag is active
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] Device[5] HT[2] DQ[16] Extended Uptime Result: , Is Numeric: 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] Device[5] HT[2] DQ[16] RECACHE OID: .1.3.6.1.2.1.1.3.0, (assert: < output: )
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] Device[5] HT[2] NOTE: There are '1' Polling Items for this Device
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] DEBUG: Setting up writes to local database
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] Device[5] HT[2] Total Time: 0.29 Seconds
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] DEBUG: Freeing Local Pool ID 3
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980854781504] DEBUG: Device[5] HT[2] DEBUG: HOST COMPLETE: About to Exit Device Polling Thread Function
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] ERROR: No such Object for oid '.1.3.6.1.2.1.1.3.0' for Device[5] with Status[1]
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] Device[5] HT[4] DQ[16] Legacy Uptime Result: U, Is Numeric: 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] WARNING: Skipped oid '.1.3.6.1.6.3.10.2.1.3.0' for Device[5] as host ignore flag is active
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] Device[5] HT[4] DQ[16] Extended Uptime Result: , Is Numeric: 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] Device[5] HT[4] DQ[16] RECACHE OID: .1.3.6.1.2.1.1.3.0, (assert: < output: )
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] Device[5] HT[4] NOTE: There are '1' Polling Items for this Device
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] DEBUG: Setting up writes to local database
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] Device[5] HT[4] Total Time: 0.3 Seconds
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] DEBUG: Freeing Local Pool ID 1
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980768802368] DEBUG: Device[5] HT[4] DEBUG: HOST COMPLETE: About to Exit Device Polling Thread Function
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980876986176] NOTE: Polling sleeping while waiting for 3 Threads to End
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] ERROR: No such Object for oid '.1.3.6.1.2.1.1.3.0' for Device[5] with Status[1]
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] HT[1] DQ[16] Legacy Uptime Result: U, Is Numeric: 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] WARNING: Skipped oid '.1.3.6.1.6.3.10.2.1.3.0' for Device[5] as host ignore flag is active
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] HT[1] DQ[16] Extended Uptime Result: , Is Numeric: 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] HT[1] DQ[16] RECACHE OID: .1.3.6.1.2.1.1.3.0, (assert: < output: )
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] HT[1] NOTE: There are '1' Polling Items for this Device
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] DEBUG: Setting up writes to local database
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] Device[5] HT[1] Total Time: 0.79 Seconds
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] DEBUG: Freeing Local Pool ID 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980863174208] DEBUG: Device[5] HT[1] DEBUG: HOST COMPLETE: About to Exit Device Polling Thread Function
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] ERROR: No such Object for oid '.1.3.6.1.2.1.1.3.0' for Device[5] with Status[1]
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] Device[5] HT[5] DQ[16] Legacy Uptime Result: U, Is Numeric: 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] WARNING: Skipped oid '.1.3.6.1.6.3.10.2.1.3.0' for Device[5] as host ignore flag is active
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] Device[5] HT[5] DQ[16] Extended Uptime Result: , Is Numeric: 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] Device[5] HT[5] DQ[16] RECACHE OID: .1.3.6.1.2.1.1.3.0, (assert: < output: )
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] Device[5] HT[5] NOTE: There are '1' Polling Items for this Device
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] DEBUG: Setting up writes to local database
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] Device[5] HT[5] Updating Poller Items for Next Poll
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] Device[5] HT[5] Total Time: 0.79 Seconds
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] DEBUG: Freeing Local Pool ID 4
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980760409664] DEBUG: Device[5] HT[5] DEBUG: HOST COMPLETE: About to Exit Device Polling Thread Function
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] ERROR: No such Object for oid '.1.3.6.1.2.1.1.3.0' for Device[5] with Status[1]
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] Device[5] HT[3] DQ[16] Legacy Uptime Result: U, Is Numeric: 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] WARNING: Skipped oid '.1.3.6.1.6.3.10.2.1.3.0' for Device[5] as host ignore flag is active
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] Device[5] HT[3] DQ[16] Extended Uptime Result: , Is Numeric: 0
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] Device[5] HT[3] DQ[16] RECACHE OID: .1.3.6.1.2.1.1.3.0, (assert: < output: )
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] Device[5] HT[3] Total Time: 0.8 Seconds
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] DEBUG: Freeing Local Pool ID 2
2024-03-12 11:08:56 - SPINE: Poller[1] PID[946239] PT[139980846388800] DEBUG: Device[5] HT[3] DEBUG: HOST COMPLETE: About to Exit Device Polling Thread Function
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] The final count of Threads is 0
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] INFO: Device[5] Thread complete and 4 to 5 sources
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Closing Local Connection Pool ID 0
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Closing Local Connection Pool ID 1
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Closing Local Connection Pool ID 2
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Closing Local Connection Pool ID 3
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Closing Local Connection Pool ID 4
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Closing Local Connection Pool ID 5
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Closing Local Connection Pool ID 6
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Closing Local Connection Pool ID 7
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Closing Local Connection Pool ID 8
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Closing Local Connection Pool ID 9
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Thread Cleanup Complete
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: SS[0] Script Server Shutdown Started
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: PHP Script Server Pipes Closed
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Allocated Variable Memory Freed
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: MYSQL Free & Close Completed
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] DEBUG: Net-SNMP Close Completed
2024-03-12 11:08:57 - SPINE: Poller[1] PID[946239] PT[139980876986176] Time: 1.2347 s, Threads: 10, Devices: 1
local-admin@vmcactiscus001:/usr/local/spine/bin$
mrheavydee95
Posts: 30
Joined: Tue Mar 05, 2024 12:51 pm

Re: RRD files not being created with Spine running

Post by mrheavydee95 »

I fixed the write/access errors.
Here's my latest spine output.

local-admin@vmcactiscus001:/usr/local/spine/bin$ ./spine --first=5 --last=5 -V=5 -R
SPINE: Using spine config file [../etc/spine.conf]
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The path_php_server variable is /var/www/html/cacti/script_server.php
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The path_cactilog variable is /var/www/html/cacti/log/cacti.log
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The version variable is 10.6.16-MariaDB-0ubuntu0.22.04.1
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The log_destination variable is 1 (FILE)
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The path_php variable is /usr/bin/php
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The availability_method variable is 4
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The ping_recovery_count variable is 3
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The ping_failure_count variable is 2
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The ping_method variable is 1
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The ping_retries variable is 1
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The ping_timeout variable is 400
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The snmp_retries variable is 3
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The log_perror variable is 1
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The log_pwarn variable is 1
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The boost_redirect variable is 0
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The boost_rrd_update_enable variable is 0
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The log_pstats variable is 0
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The threads variable is 10
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The polling interval is 60 seconds
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The number of concurrent processes is 16
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The script timeout is 25
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The selective_device_debug variable is
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The spine_log_level variable is 0
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The number of php script servers to run is 1
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The number of active data source profiles is 2
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The number of snmp ports on the system is 1
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: StartDevice='5', EndDevice='5', TotalPHPScripts='1'
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The PHP Script Server is Required
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: The Maximum SNMP OID Get Size is 10
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Total Connections made 1
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Creating Local Connection Pool of 10 threads.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Creating Local Connection 0.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Total Connections made 2
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Creating Local Connection 1.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Total Connections made 3
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Creating Local Connection 2.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Total Connections made 4
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Creating Local Connection 3.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Total Connections made 5
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Creating Local Connection 4.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Total Connections made 6
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Creating Local Connection 5.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Total Connections made 7
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Creating Local Connection 6.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Total Connections made 8
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Creating Local Connection 7.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Total Connections made 9
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Creating Local Connection 8.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Total Connections made 10
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Creating Local Connection 9.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Total Connections made 11
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Version 1.2.26 starting
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] No Device 0 Poller Items found.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: MySQL is Thread Safe!
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Spine running as 1000 UID, 0 EUID
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Spine is running as root.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Spine has got ICMP
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Initializing Net-SNMP API
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Issues with SNMP Header Version information, assuming old version of Net-SNMP.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Initializing PHP Script Server(s)
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: SS[0] PHP Script Server Routine Starting
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: SS[0] PHP Script Server About to FORK Child Process
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: SS[0] PHP Script Server Child FORK Success
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: SS[0] Confirmed PHP Script Server running using readfd[17], writefd[16]
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Initial Value of Available Threads is 10 (0 outstanding)
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Device[5] Valid Thread to be Created (137816973506112)
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Device[5] Available Threads is 9 (1 outstanding)
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] DEBUG: Device[5] HT[1] In Poller, About to Start Polling
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] NOTE: Polling sleeping while waiting for 1 Threads to End
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] DEBUG: Traversing Local Connection Pool for free connection.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] DEBUG: Checking Local Pool ID 0.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] DEBUG: Allocating Local Pool ID 0.
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] get_namebyhost(10.120.75.2) - Allocating name_t
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] get_namebyhost(10.120.75.2) - No delimiter, assume full hostname
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] Device[5] INFO: SNMP Device '10.120.75.2:161' has a timeout of 500000 (500), with 3 retries
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] Device[5] IPv4 address 10.120.75.2 (10.120.75.2)
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] Device[5] DEBUG: Entering ICMP Ping
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] NOTE: Polling sleeping while waiting for 1 Threads to End
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] Device[5] DEBUG: Attempting to ping 10.120.75.2, seq 0 (Retry 0 of 1)
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] Device[5] INFO: ICMP Device Alive, Try Count:1, Time:110.2948 ms
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816986986304] NOTE: Polling sleeping while waiting for 1 Threads to End
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] Device[5] PING Result: ICMP: Device is Alive
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] Device[5] SNMP Result: SNMP not performed due to setting or ping result
2024-03-13 19:03:55 - SPINE: Poller[1] PID[271991] PT[137816973506112] DEBUG: Device[5] HT[1] RECACHE: Processing 1 items in the auto reindex cache for '10.120.75.2'
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816973506112] ERROR: No such Object for oid '.1.3.6.1.2.1.1.3.0' for Device[5] with Status[1]
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816973506112] Device[5] HT[1] DQ[16] Legacy Uptime Result: U, Is Numeric: 0
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816973506112] WARNING: Skipped oid '.1.3.6.1.6.3.10.2.1.3.0' for Device[5] as host ignore flag is active
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816973506112] Device[5] HT[1] DQ[16] Extended Uptime Result: , Is Numeric: 0
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816973506112] Device[5] HT[1] DQ[16] RECACHE OID: .1.3.6.1.2.1.1.3.0, (assert: < output: )
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816973506112] Device[5] HT[1] NOTE: There are '1' Polling Items for this Device
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816973506112] DEBUG: Setting up writes to local database
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816973506112] Device[5] HT[1] Updating Poller Items for Next Poll
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816973506112] Device[5] HT[1] Total Time: 0.23 Seconds
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816973506112] DEBUG: Freeing Local Pool ID 0
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816973506112] DEBUG: Device[5] HT[1] DEBUG: HOST COMPLETE: About to Exit Device Polling Thread Function
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] The final count of Threads is 0
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] INFO: Device[5] Thread complete and 0 to 1 sources
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Closing Local Connection Pool ID 0
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Closing Local Connection Pool ID 1
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Closing Local Connection Pool ID 2
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Closing Local Connection Pool ID 3
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Closing Local Connection Pool ID 4
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Closing Local Connection Pool ID 5
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Closing Local Connection Pool ID 6
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Closing Local Connection Pool ID 7
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Closing Local Connection Pool ID 8
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Closing Local Connection Pool ID 9
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Thread Cleanup Complete
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: SS[0] Script Server Shutdown Started
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: PHP Script Server Pipes Closed
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Allocated Variable Memory Freed
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: MYSQL Free & Close Completed
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] DEBUG: Net-SNMP Close Completed
2024-03-13 19:03:56 - SPINE: Poller[1] PID[271991] PT[137816986986304] Time: 0.7177 s, Threads: 10, Devices: 1
User avatar
macan
Cacti Guru User
Posts: 1137
Joined: Tue Mar 18, 2008 2:30 am
Location: Czech

Re: RRD files not being created with Spine running

Post by macan »

Device doesn't respond to snmp query. Try run snmpwalk from cacti server. How to do it you can find here in forums.

It seems that you haven't defined any graph for device 5?
Let the Cacti grow!
Post Reply

Who is online

Users browsing this forum: No registered users and 2 guests