preset nr missing in log. nr woul be helpful to debug missing graphics..

[ozett]

hi, i have stalled pixelcontroller-images, which i wanted to debug.
i found warnings about missing graphics, but i cannot find the number of the preset in the log. only three dots [...]. is this by design? i would be helpful to see the preset-nr in the log to correct the preset-settings ...

my log:

INFO: Framerate: 10.165, Framecount: 69,460,000
Oct 06, 2014 10:54:36 AM com.neophob.sematrix.core.api.impl.PixelControllerServerImpl run
INFO: Framerate: 10.152, Framecount: 69,465,000
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.osc.PixelControllerOscServer handleOscMessage
INFO: Recieved OSC message: CHANGE_PRESET
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.osc.PixelControllerOscServer handleOscMessage
INFO: Recieved OSC message: LOAD_PRESET
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.listener.MessageProcessor loadActivePreset
INFO: Load Preset ...
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.visual.fader.TransitionManager <init>
INFO: Transition Manager created, saved 1 visual output buffer(s)
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.preset.PresetServiceImpl removeObsoleteCommands
INFO: No Blinkengenerator found, remove loading blinken resource
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.visual.fader.Fader <init>
WARNING: Invalid fadeTime 50 fixed to 98
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.listener.MessageProcessor processMsg
INFO: Update Transition Manager, output 0 should have new visual 0
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.listener.MessageProcessor processMsg
INFO: Update Transition Manager, output 0 should have new visual 1
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.visual.generator.Image loadFile
INFO: Filename circle.jpg not found in list
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.visual.generator.Image loadFile
INFO: load image /tmp/win/pixelcontroller-distribution-2.1.0-RC2/data/pics/circle.jpg
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.visual.generator.Image loadFile
WARNING: Failed to load image circle.jpg
javax.imageio.IIOException: Can't read input file!
    at javax.imageio.ImageIO.read(ImageIO.java:1301)
    at com.neophob.sematrix.core.visual.generator.Image.loadFile(Image.java:131)
    at com.neophob.sematrix.core.visual.generator.PixelControllerGenerator.setFileImageSimple(PixelControllerGenerator.java:363)
    at com.neophob.sematrix.core.listener.MessageProcessor.processMsg(MessageProcessor.java:415)
    at com.neophob.sematrix.core.listener.MessageProcessor.loadActivePreset(MessageProcessor.java:771)
    at com.neophob.sematrix.core.listener.MessageProcessor.processMsg(MessageProcessor.java:373)
    at com.neophob.sematrix.core.osc.PixelControllerOscServer.handleOscMessage(PixelControllerOscServer.java:131)
    at com.neophob.sematrix.osc.server.OscMessageHandler.update(OscMessageHandler.java:46)
    at java.util.Observable.notifyObservers(Observable.java:159)
    at com.neophob.sematrix.osc.server.impl.AbstractOscServer.notifyOscClients(AbstractOscServer.java:66)
    at com.neophob.sematrix.osc.server.impl.OscServerImpl.messageReceived(OscServerImpl.java:121)
    at de.sciss.net.OSCReceiver.dispatchMessage(OSCReceiver.java:875)
    at de.sciss.net.OSCReceiver.dispatchPacket(OSCReceiver.java:855)
    at de.sciss.net.OSCReceiver.dispatchPacket(OSCReceiver.java:860)
    at de.sciss.net.OSCReceiver.flipDecodeDispatch(OSCReceiver.java:843)
    at de.sciss.net.OSCReceiver$UDPOSCReceiver.run(OSCReceiver.java:1052)
    at java.lang.Thread.run(Thread.java:745)

Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.listener.MessageProcessor processMsg
INFO: Unknown attribute ignored <COLOR_SCROLL_LENGTH>
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.listener.MessageProcessor processMsg
INFO: Unknown attribute ignored <COLOR_FADE_LENGTH>
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.listener.MessageProcessor processMsg
INFO: Shuffler select: 011111111111111
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.listener.MessageProcessor processMsg
INFO: Unknown attribute ignored <CHANGE_PRESENT>
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.listener.MessageProcessor loadActivePreset
INFO: Preset loaded in 55ms
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.visual.fader.Fader startFade
INFO: Started preset fader CROSSFADE, duration 500, steps 5, new visual 1
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.visual.fader.TransitionManager startCrossfader
INFO: Started fader for output 0, new Visual: 1
Oct 06, 2014 10:59:57 AM com.neophob.sematrix.core.visual.VisualState updateSystem
INFO: Cleanup fader CROSSFADE, new visual: 1, output screen: 0
Oct 06, 2014 11:02:48 AM com.neophob.sematrix.core.api.impl.PixelControllerServerImpl run
INFO: Framerate: 10.204, Framecount: 69,470,000
Oct 06, 2014 11:10:58 AM com.neophob.sematrix.core.api.impl.PixelControllerServerImpl run
INFO: Framerate: 10.204, Framecount: 69,475,000
Oct 06, 2014 11:19:08 AM com.neophob.sematrix.core.api.impl.PixelControllerServerImpl run
INFO: Framerate: 10.204, Framecount: 69,480,000
Oct 06, 2014 11:27:18 AM com.neophob.sematrix.core.api.impl.PixelControllerServerImpl run
INFO: Framerate: 10.204, Framecount: 69,485,000
Oct 06, 2014 11:29:57 AM com.neophob.sematrix.core.osc.PixelControllerOscServer handleOscMessage
INFO: Recieved OSC message: CHANGE_PRESET
Oct 06, 2014 11:29:57 AM com.neophob.sematrix.core.osc.PixelControllerOscServer handleOscMessage
INFO: Recieved OSC message: LOAD_PRESET
Oct 06, 2014 11:29:57 AM com.neophob.sematrix.core.listener.MessageProcessor loadActivePreset
INFO: Load Preset ...
Oct 06, 2014 11:29:57 AM com.neophob.sematrix.core.visual.fader.TransitionManager <init>
INFO: Transition Manager created, saved 1 visual output buffer(s)
Oct 06, 2014 11:29:57 AM com.neophob.sematrix.core.preset.PresetServiceImpl removeObsoleteCommands
INFO: No Blinkengenerator found, remove loading blinken resource
Oct 06, 2014 11:29:57 AM com.neophob.sematrix.core.preset.PresetServiceImpl removeObsoleteCommands
INFO: No Imagegenerator found, remove loading image resource
Oct 06, 2014 11:29:57 AM com.neophob.sematrix.core.visual.fader.Fader <init>
WARNING: Invalid fadeTime 50 fixed to 98
Oct 06, 2014 11:29:57 AM com.neophob.sematrix.core.listener.MessageProcessor processMsg
INFO: Update Transition Manager, output 0 should have new visual 0
Oct 06, 2014 11:29:57 AM com.neophob.sematrix.core.visual.fader.Fader <init> 

[neophob]

does the image /tmp/win/pixelcontroller-distribution-2.1.0-RC2/data/pics/circle.jpg exist?

[ozett]

no. the circle.jpg was request by an preset.
in the distribution directory existed a file call circle.png.
i converted it to .jpg and saved it to die pics-dir, to prevent future error-messages.

i think, this filename comes from a default-preset an must be checked...but cant find out preset-nr.

(by the way: this was not the reason for my stalled pixelmatrix. pixelcontroller is running perfectly 24/7 since months. i check it running and it is correctly sending data to my artnet client. i think the client stalls. because pixelcontroller outputs the data to an itead iboard 1.1, on which an art-net client-sketch is running. i think the board shows after a productive and errorfree year now sometimes temperatur problems... i will investigate that board)

[13rac1]

Seems like there is already a call to log this data:

    private synchronized void loadActivePreset(VisualState visualState) {
        LOG.log(Level.INFO, "Load Preset ...");
        visualState.setLoadingPresent(true);

        // save current visual buffer
        transitionManager = new TransitionManager(visualState);

        // save current selections
        int currentVisual = visualState.getCurrentVisual();
        int currentOutput = visualState.getCurrentOutput();

        List<String> presetToLoad = presetService.getActivePreset();

        // load preset
        long start = System.currentTimeMillis();
        for (String s : presetToLoad) {
            s = StringUtils.trim(s);
            s = StringUtils.removeEnd(s, ";");
            LOG.log(Level.FINEST, "LOAD PRESET: " + s);
            this.processMsg(StringUtils.split(s, ' '), false, null);
        }
}

PixelController/pixelcontroller-core/src/main/java/com/neophob/sematrix/core/listener/MessageProcessor.java

Line 770 in d1fda8a

LOG.log(Level.FINEST, "LOAD PRESET: " + s);

[ozett]

i understand: you looked after this issue.... fantastic, but:
i dont really understand the comment...
the logging of the preset-nr is already coded but not in the log ....
...means: a bug?
waiting for version 2.2 ..?

[13rac1]

The log level needs to be set to level.FINEST for this message to show. It isn't a bug.

[13rac1]

Ah. Edit sys/logging.properties to temporarily change your logging level to FINEST. Note: this makes for extremely verbose log files and makes the system very slow. Change:

.level=INFO

to:

.level=FINEST