i am new yajsw user, after reading reference document, i config & started my application on redhat linux(5.x).
it seems work fine, but when i start my app-process, the console output always display follow message:
Channel not open for writing - cannot extend file to required size
Channel not open for writing - cannot extend file to required size
Channel not open for writing - cannot extend file to required size
...
i was wondering is this a normal message?
and after track the source, it seems happen in org.rzo.yajsw.io.CyclicBufferFileInputStream, as follow code:
-rwxrwxrwx 1 mysql mysql 204800 Jan 6 23:59 err_4880214705375961989$1357487995664
-rw-r--r-- 1 root root 204800 Jan 6 23:59 in_4880214705375961989$1357487995664
-rwxrwxrwx 1 mysql mysql 204800 Jan 6 23:59 out_4880214705375961989$1357487995664
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
this is debug information.
it indicates that the wrapper is waiting for the application to open the shared memory files which are used as buffers to transmit the System.in, System.out and System.err streams.
Ron
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
hello,
i am new yajsw user, after reading reference document, i config & started my application on redhat linux(5.x).
it seems work fine, but when i start my app-process, the console output always display follow message:
i was wondering is this a normal message?
and after track the source, it seems happen in org.rzo.yajsw.io.CyclicBufferFileInputStream, as follow code:
while (!opened && !closed) { lock.lock(); if (file.exists()) try { // System.out.println("open "+file); if (raf == null) raf = new RandomAccessFile(file, "r"); buf = raf.getChannel().map(FileChannel.MapMode.READ_ONLY, 5, CyclicBufferFilePrintStream.length - 5); posBuf = raf.getChannel().map(FileChannel.MapMode.READ_ONLY, 1, 4); lockBuf = raf.getChannel().map(FileChannel.MapMode.READ_ONLY, 0, 1); opened = true; } catch (Exception ex) { System.out.println(ex.getMessage()); } lock.unlock(); if (!opened) try { Thread.sleep(200); } catch (InterruptedException e) { e.printStackTrace(); Thread.currentThread().interrupt(); } }when call raf.getChannel().map(..), nio's FileChanel will throw this exception.
look forward your reply, thanks.
follow is full log in console output:
java version "1.7.0_10" Java(TM) SE Runtime Environment (build 1.7.0_10-b18) Java HotSpot(TM) 64-Bit Server VM (build 23.6-b04, mixed mode) YAJSW: yajsw-stable-11.03 OS : Linux/2.6.18-194.el5/amd64 JVM : Oracle Corporation/1.7.0_10 Jan 06, 2013 11:45:25 PM org.apache.commons.vfs2.VfsLog info INFO: Using "/tmp/vfs_cache" as temporary files store. platform null platform null |WARNING|wrapper|13-01-06 23:45:25|YAJSW: yajsw-stable-11.03 |WARNING|wrapper|13-01-06 23:45:25|OS : Linux/2.6.18-194.el5/amd64 |WARNING|wrapper|13-01-06 23:45:25|JVM : Oracle Corporation/1.7.0_10 Using the autodetected NIO constraint level: 0 |INFO|wrapper|13-01-06 23:45:25|ahessian jmx service bound to port 15792 |INFO|wrapper|13-01-06 23:45:25|exec:/usr/local/agent/jre/bin/java -Dha_agent_home=/usr/local/agent -Dagent_java_io_tmp=/usr/local/agent/wrapper/tmp -Djna_tmpdir=/usr/local/agent/wrapper/tmp -classpath /usr/local/agent/wrapper/wrapper.jar org.rzo.yajsw.tray.TrayIconMainBooter /usr/local/agent/conf/wrapper.conf fork 0 fork 1 fork 2 |INFO|wrapper|13-01-06 23:45:26|started process 19379 |INFO|wrapper|13-01-06 23:45:26|spawned system tray icon process with pid 19379 |INFO|wrapper|13-01-06 23:45:26|start from Thread main |INFO|wrapper|13-01-06 23:45:26|created lock file /usr/local/agent/wrapper/agent.lock |INFO|wrapper|13-01-06 23:45:26|created pid file /usr/local/agent/wrapper/bootstrap.pid |INFO|wrapper|13-01-06 23:45:26|set state IDLE->STARTING |INFO|wrapper|13-01-06 23:45:26|starting Process |INFO|wrapper|13-01-06 23:45:26|starting controller |INFO|wrapper|13-01-06 23:45:26|binding to port 15003 |INFO|wrapper|13-01-06 23:45:26|Controller State: UNKNOWN -> WAITING |INFO|wrapper|13-01-06 23:45:26|binding successfull |INFO|wrapper|13-01-06 23:45:26|controller started |INFO|wrapper|13-01-06 23:45:26|working dir /usr/local/agent |INFO|wrapper|13-01-06 23:45:26|spawning wrapped process |INFO|wrapper|13-01-06 23:45:26|exec:/usr/local/agent/jre/bin/java -classpath /usr/local/agent/wrapper/./wrapperApp.jar:/usr/local/agent/lib/httpclient-4.1.1.jar:/usr/local/agent/lib/grizzly-http-server-2.2.16.jar:/usr/local/agent/lib/jackrabbit-webdav-2.5.2.jar:/usr/local/agent/lib/grizzly-http-2.2.16.jar:/usr/local/agent/lib/cal10n-api-0.7.4.jar:/usr/local/agent/lib/commons-io-2.4.jar:/usr/local/agent/lib/joda-time-2.1.jar:/usr/local/agent/lib/commons-httpclient-3.1.jar:/usr/local/agent/lib/slf4j-ext-1.7.2.jar:/usr/local/agent/lib/log4j-1.2.17.jar:/usr/local/agent/lib/commons-dbutils-1.5.jar:/usr/local/agent/lib/ha-core-1.1.0-SNAPSHOT.jar:/usr/local/agent/lib/xz-1.0.jar:/usr/local/agent/lib/activation-1.1.jar:/usr/local/agent/lib/ha-agent-1.1.0-SNAPSHOT.jar:/usr/local/agent/lib/slf4j-api-1.7.2.jar:/usr/local/agent/lib/slf4j-log4j12-1.7.2.jar:/usr/local/agent/lib/sigar-1.6.6.jar:/usr/local/agent/lib/grizzly-framework-2.2.16.jar:/usr/local/agent/lib/jsr311-api-1.1.1.jar:/usr/local/agent/lib/protobuf-java-2.4.1.jar:/usr/local/agent/lib/jcl-over-slf4j-1.7.2.jar:/usr/local/agent/lib/jaxb-api-2.2.2.jar:/usr/local/agent/lib/guava-13.0.1.jar:/usr/local/agent/lib/commons-lang3-3.1.jar:/usr/local/agent/lib/jersey-apache-client4-1.16.jar:/usr/local/agent/lib/jersey-bundle-1.16.jar:/usr/local/agent/lib/jackson-xc-1.9.2.jar:/usr/local/agent/lib/jettison-1.1.jar:/usr/local/agent/lib/commons-vfs2-2.0.jar:/usr/local/agent/lib/jackson-jaxrs-1.9.2.jar:/usr/local/agent/lib/jackson-core-asl-1.9.2.jar:/usr/local/agent/lib/commons-compress-1.4.1.jar:/usr/local/agent/lib/jersey-json-1.16.jar:/usr/local/agent/lib/commons-exec-1.1.jar:/usr/local/agent/lib/httpcore-4.1.jar:/usr/local/agent/lib/jersey-grizzly2-1.16.jar:/usr/local/agent/lib/stax-api-1.0-2.jar:/usr/local/agent/lib/mysql-connector-java-5.1.22.jar:/usr/local/agent/lib/asm-3.3.1.jar:/usr/local/agent/lib/commons-codec-1.7.jar:/usr/local/agent/lib/jaxb-impl-2.2.3-1.jar:/usr/local/agent/lib/commons-net-3.2.jar:/usr/local/agent/lib/grizzly-rcm-2.2.16.jar:/usr/local/agent/lib/jackson-mapper-asl-1.9.2.jar:/usr/local/agent/conf -Dha.agent.home=/usr/local/agent -Dha.agent.resource.home=/usr/local/agent/resource -Djava.io.tmpdir=/usr/local/agent/wrapper/tmp -Dcom.sun.management.jmxremote -Dcom.sun.management.jmxremote.port=11099 -Dcom.sun.management.jmxremote.authenticate=false -Dcom.sun.management.jmxremote.ssl=false -server -XX:MaxPermSize=256m -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/usr/local/agent/wrapper -Djava.library.path=/usr/local/agent/lib:/usr/local/agent/lib/sigar -Xms128m -Xmx1280m -Dwrapper.port=15003 -Dwrapper.key=3496852879760505870 -Dwrapper.teeName=3496852879760505870$1357487126587 -Dwrapper.tmp.path=/usr/local/agent/wrapper/tmp -Djna_tmpdir=/usr/local/agent/wrapper/tmp -Dwrapper.config=/usr/local/agent/conf/wrapper.conf -Dwrapper.java.monitor.gc="MYGC: {0\,number\,integer}\, {1\,number\,integer}\, {2\,number\,integer}" org.rzo.yajsw.app.WrapperJVMMain fork 0 fork 1 |INFO|wrapper|13-01-06 23:45:26|switch group root -> mysql |INFO|wrapper|13-01-06 23:45:26|switch user root -> mysql fork 2 |WARNING|wrapper|13-01-06 23:45:27|[id: 0x4e24124f, /127.0.0.1:34378 => /127.0.0.1:15792] connected |INFO|wrapper|13-01-06 23:45:27|started process 19392 |INFO|wrapper|13-01-06 23:45:27|waiting for termination of process |INFO|wrapper|13-01-06 23:45:27|created jva.pid file /usr/local/agent/wrapper/agent.pid |INFO|wrapper|13-01-06 23:45:27|started process with pid 19392 Channel not open for writing - cannot extend file to required size |INFO|wrapper|13-01-06 23:45:27|set state STARTING->RUNNING Channel not open for writing - cannot extend file to required size Channel not open for writing - cannot extend file to required size Channel not open for writing - cannot extend file to required size Channel not open for writing - cannot extend file to required size Channel not open for writing - cannot extend file to required size Channel not open for writing - cannot extend file to required size Channel not open for writing - cannot extend file to required size Channel not open for writing - cannot extend file to required size Channel not open for writing - cannot extend file to required size Channel not open for writing - cannot extend file to required size Channel not open for writing - cannot extend file to required size Channel not open for writing - cannot extend file to required size |INFO|19392/0|13-01-06 23:45:29|[INFO] StandardFileSystemManager - Using "/usr/local/agent/wrapper/tmp/vfs_cache" as temporary files store. |INFO|19392/0|13-01-06 23:45:29|platform null |INFO|19392/0|13-01-06 23:45:29|APP user name=mysql |INFO|19392/0|13-01-06 23:45:29|APP working dir=/usr/local/agent |INFO|19392/0|13-01-06 23:45:29|APP java version=1.7.0_10 |INFO|19392/0|13-01-06 23:45:29|APP class path=/usr/local/agent/wrapper/./wrapperApp.jar:/usr/local/agent/lib/httpclient-4.1.1.jar:/usr/local/agent/lib/grizzly-http-server-2.2.16.jar:/usr/local/agent/lib/jackrabbit-webdav-2.5.2.jar:/usr/local/agent/lib/grizzly-http-2.2.16.jar:/usr/local/agent/lib/cal10n-api-0.7.4.jar:/usr/local/agent/lib/commons-io-2.4.jar:/usr/local/agent/lib/joda-time-2.1.jar:/usr/local/agent/lib/commons-httpclient-3.1.jar:/usr/local/agent/lib/slf4j-ext-1.7.2.jar:/usr/local/agent/lib/log4j-1.2.17.jar:/usr/local/agent/lib/commons-dbutils-1.5.jar:/usr/local/agent/lib/ha-core-1.1.0-SNAPSHOT.jar:/usr/local/agent/lib/xz-1.0.jar:/usr/local/agent/lib/activation-1.1.jar:/usr/local/agent/lib/ha-agent-1.1.0-SNAPSHOT.jar:/usr/local/agent/lib/slf4j-api-1.7.2.jar:/usr/local/agent/lib/slf4j-log4j12-1.7.2.jar:/usr/local/agent/lib/sigar-1.6.6.jar:/usr/local/agent/lib/grizzly-framework-2.2.16.jar:/usr/local/agent/lib/jsr311-api-1.1.1.jar:/usr/local/agent/lib/protobuf-java-2.4.1.jar:/usr/local/agent/lib/jcl-over-slf4j-1.7.2.jar:/usr/local/agent/lib/jaxb-api-2.2.2.jar:/usr/local/agent/lib/guava-13.0.1.jar:/usr/local/agent/lib/commons-lang3-3.1.jar:/usr/local/agent/lib/jersey-apache-client4-1.16.jar:/usr/local/agent/lib/jersey-bundle-1.16.jar:/usr/local/agent/lib/jackson-xc-1.9.2.jar:/usr/local/agent/lib/jettison-1.1.jar:/usr/local/agent/lib/commons-vfs2-2.0.jar:/usr/local/agent/lib/jackson-jaxrs-1.9.2.jar:/usr/local/agent/lib/jackson-core-asl-1.9.2.jar:/usr/local/agent/lib/commons-compress-1.4.1.jar:/usr/local/agent/lib/jersey-json-1.16.jar:/usr/local/agent/lib/commons-exec-1.1.jar:/usr/local/agent/lib/httpcore-4.1.jar:/usr/local/agent/lib/jersey-grizzly2-1.16.jar:/usr/local/agent/lib/stax-api-1.0-2.jar:/usr/local/agent/lib/mysql-connector-java-5.1.22.jar:/usr/local/agent/lib/asm-3.3.1.jar:/usr/local/agent/lib/commons-codec-1.7.jar:/usr/local/agent/lib/jaxb-impl-2.2.3-1.jar:/usr/local/agent/lib/commons-net-3.2.jar:/usr/local/agent/lib/grizzly-rcm-2.2.16.jar:/usr/local/agent/lib/jackson-mapper-asl-1.9.2.jar:/usr/local/agent/conf |INFO|19392/0|13-01-06 23:45:29|APP library path=/usr/local/agent/lib:/usr/local/agent/lib/sigar |INFO|19392/0|13-01-06 23:45:29|Application args: no args |INFO|19392/0|13-01-06 23:45:29|mainClass/jar/script: com.actionsky.rds.ha.agent.AgentBootstrap/null/null |INFO|19392/0|13-01-06 23:45:29|external stop false |INFO|19392/0|13-01-06 23:45:29|args: |INFO|19392/0|13-01-06 23:45:29|monitor deadlock: start |INFO|19392/0|13-01-06 23:45:29|monitor heap: start |INFO|19392/0|13-01-06 23:45:29|monitor GC: "MYGC: {0,number,integer}, {1,number,integer}, {2,number,integer}" |INFO|19392/0|13-01-06 23:45:29|monitor gc: minorGCBean/fullGCBean: Copy/MarkSweepCompact |INFO|19392/0|13-01-06 23:45:29|monitor gc: start cycle 60000ms |INFO|19392/0|13-01-06 23:45:29|terminated WrapperManager.init() |INFO|19392/0|13-01-06 23:45:29|"MYGC: 24,750,472, 0, 0" |INFO|19392/0|13-01-06 23:45:29|connecting to port 15003 |INFO|19392/0|13-01-06 23:45:29|[WrapperManager][id: 0x075a6bd5] OPEN |INFO|19392/0|13-01-06 23:45:29|[WrapperManager][id: 0x075a6bd5] CONNECT: /127.0.0.1:15003 |INFO|19392/0|13-01-06 23:45:29|[WrapperManager][id: 0x075a6bd5, /127.0.0.1:43134 => /127.0.0.1:15003] BOUND: /127.0.0.1:43134 |INFO|19392/0|13-01-06 23:45:29|[WrapperManager][id: 0x075a6bd5, /127.0.0.1:43134 => /127.0.0.1:15003] CONNECTED: /127.0.0.1:15003 |INFO|19392/0|13-01-06 23:45:29|WrapperManager: channel connected, sending key |INFO|19392/0|13-01-06 23:45:29|jvm controller set set netty logger |FINE|19392/0|13-01-06 23:45:29|[controller][id: 0x5dd6b6fa, /127.0.0.1:43134 => /127.0.0.1:15003] OPEN |FINE|19392/0|13-01-06 23:45:29|[controller][id: 0x5dd6b6fa, /127.0.0.1:43134 => /127.0.0.1:15003] OPEN |FINE|19392/0|13-01-06 23:45:29|[controller][id: 0x5dd6b6fa, /127.0.0.1:43134 => /127.0.0.1:15003] BOUND: /127.0.0.1:15003 |FINE|19392/0|13-01-06 23:45:29|[controller][id: 0x5dd6b6fa, /127.0.0.1:43134 => /127.0.0.1:15003] BOUND: /127.0.0.1:15003 |FINE|19392/0|13-01-06 23:45:29|[controller][id: 0x5dd6b6fa, /127.0.0.1:43134 => /127.0.0.1:15003] CONNECTED: /127.0.0.1:43134 |FINE|19392/0|13-01-06 23:45:29|[controller][id: 0x5dd6b6fa, /127.0.0.1:43134 => /127.0.0.1:15003] CONNECTED: /127.0.0.1:43134 |INFO|19392/0|13-01-06 23:45:29|Controller State: WAITING -> ESTABLISHEDby the way, the temp folder is permissions is :
and the in/out/err buffer that yajsw created:
in additional, i found the message:
only happen in runConsole.sh, when installed as a daemon, it disappeared.
hello,
this is debug information.
it indicates that the wrapper is waiting for the application to open the shared memory files which are used as buffers to transmit the System.in, System.out and System.err streams.
hi,rzorzorzo
thanks you reply, it's nice project! :)