Skip to content

Instantly share code, notes, and snippets.

@MiLk
Last active August 29, 2015 13:59
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save MiLk/10949600 to your computer and use it in GitHub Desktop.
Save MiLk/10949600 to your computer and use it in GitHub Desktop.
I0417 02:43:52.922135 2018 master.cpp:784] Received registration request from scheduler(1)@172.16.0.180:49120
I0417 02:43:52.922379 2018 master.cpp:802] Registering framework 201404160134-3053457580-5050-1887-0001 at scheduler(1)@172.16.0.180:49120
I0417 02:43:52.923539 2018 hierarchical_allocator_process.hpp:332] Added framework 201404160134-3053457580-5050-1887-0001
I0417 02:43:52.924255 2018 master.cpp:1787] Sending 2 offers to framework 201404160134-3053457580-5050-1887-0001
I0417 02:43:53.262133 2020 master.cpp:2124] Processing reply for offer 201404160134-3053457580-5050-1887-59 on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182) for framework 201404160134-3053457580-5050-1887-0001
I0417 02:43:53.262337 2020 master.hpp:406] Adding task sssp-201404107T024353.123Z-402531b0 with resources cpus(*):0.2; mem(*):256; ports(*):[31000-31000] on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182)
I0417 02:43:53.262433 2020 master.cpp:2248] Launching task sssp-201404107T024353.123Z-402531b0 of framework 201404160134-3053457580-5050-1887-0001 with resources cpus(*):0.2; mem(*):256; ports(*):[31000-31000] on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182)
I0417 02:43:53.263275 2021 hierarchical_allocator_process.hpp:590] Framework 201404160134-3053457580-5050-1887-0001 filtered slave 201404160133-3036680364-5050-7214-2 for 5secs
I0417 02:43:53.980751 2019 http.cpp:373] HTTP request for '/master/state.json'
W0417 02:43:56.393671 2019 master.cpp:1608] Ignoring unknown exited executor sssp-201404107T023820.731Z-0521f33e on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182)
W0417 02:43:56.395493 2017 master.cpp:1608] Ignoring unknown exited executor sssp-201404107T023827.219Z-1ac32497 on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182)
I0417 02:43:56.989820 2016 http.cpp:373] HTTP request for '/master/state.json'
I0417 02:43:57.172775 2021 master.cpp:1787] Sending 1 offers to framework 201404040602-3019903148-5050-16828-0000
I0417 02:43:57.176161 2020 master.cpp:2124] Processing reply for offer 201404160134-3053457580-5050-1887-61 on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182) for framework 201404040602-3019903148-5050-16828-0000
I0417 02:43:57.176430 2020 hierarchical_allocator_process.hpp:590] Framework 201404040602-3019903148-5050-16828-0000 filtered slave 201404160133-3036680364-5050-7214-2 for 5secs
I0417 02:43:58.174399 2018 master.cpp:1787] Sending 1 offers to framework 201404040602-3019903148-5050-16828-0001
I0417 02:43:58.179131 2019 master.cpp:2124] Processing reply for offer 201404160134-3053457580-5050-1887-62 on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182) for framework 201404040602-3019903148-5050-16828-0001
I0417 02:43:58.179515 2019 hierarchical_allocator_process.hpp:590] Framework 201404040602-3019903148-5050-16828-0001 filtered slave 201404160133-3036680364-5050-7214-2 for 5secs
I0417 02:43:59.175756 2017 master.cpp:1787] Sending 1 offers to framework 201404160134-3053457580-5050-1887-0001
I0417 02:43:59.200742 2023 master.cpp:2124] Processing reply for offer 201404160134-3053457580-5050-1887-63 on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182) for framework 201404160134-3053457580-5050-1887-0001
I0417 02:43:59.201254 2023 master.hpp:406] Adding task sssp-201404107T024359.096Z-230b63ff with resources cpus(*):0.2; mem(*):256; ports(*):[31001-31001] on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182)
I0417 02:43:59.201709 2023 master.cpp:2248] Launching task sssp-201404107T024359.096Z-230b63ff of framework 201404160134-3053457580-5050-1887-0001 with resources cpus(*):0.2; mem(*):256; ports(*):[31001-31001] on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182)
I0417 02:43:59.202340 2023 hierarchical_allocator_process.hpp:590] Framework 201404160134-3053457580-5050-1887-0001 filtered slave 201404160133-3036680364-5050-7214-2 for 5secs
I0417 02:44:02.179107 2021 master.cpp:1787] Sending 1 offers to framework 201404040602-3019903148-5050-16828-0000
I0417 02:44:02.184679 2019 master.cpp:2124] Processing reply for offer 201404160134-3053457580-5050-1887-64 on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182) for framework 201404040602-3019903148-5050-16828-0000
I0417 02:44:02.185266 2019 hierarchical_allocator_process.hpp:590] Framework 201404040602-3019903148-5050-16828-0000 filtered slave 201404160133-3036680364-5050-7214-2 for 5secs
I0417 02:44:03.011623 2018 http.cpp:373] HTTP request for '/master/state.json'
I0417 02:44:04.180918 2016 master.cpp:1787] Sending 1 offers to framework 201404040602-3019903148-5050-16828-0001
I0417 02:44:04.186486 2020 master.cpp:2124] Processing reply for offer 201404160134-3053457580-5050-1887-65 on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182) for framework 201404040602-3019903148-5050-16828-0001
I0417 02:44:04.187114 2020 hierarchical_allocator_process.hpp:590] Framework 201404040602-3019903148-5050-16828-0001 filtered slave 201404160133-3036680364-5050-7214-2 for 5secs
I0417 02:44:05.181428 2022 master.cpp:1787] Sending 1 offers to framework 201404160134-3053457580-5050-1887-0001
I0417 02:44:05.189291 2021 master.cpp:2124] Processing reply for offer 201404160134-3053457580-5050-1887-66 on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182) for framework 201404160134-3053457580-5050-1887-0001
I0417 02:44:05.189453 2021 master.hpp:406] Adding task sssp-201404107T024405.085Z-0f6a93b3 with resources cpus(*):0.2; mem(*):256; ports(*):[31002-31002] on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182)
I0417 02:44:05.189623 2021 master.cpp:2248] Launching task sssp-201404107T024405.085Z-0f6a93b3 of framework 201404160134-3053457580-5050-1887-0001 with resources cpus(*):0.2; mem(*):256; ports(*):[31002-31002] on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182)
I0417 02:44:05.189892 2021 hierarchical_allocator_process.hpp:590] Framework 201404160134-3053457580-5050-1887-0001 filtered slave 201404160133-3036680364-5050-7214-2 for 5secs
I0417 02:44:06.022254 2019 http.cpp:373] HTTP request for '/master/state.json'
I0417 02:44:08.184423 2017 master.cpp:1787] Sending 1 offers to framework 201404040602-3019903148-5050-16828-0000
I0417 02:44:08.189296 2023 master.cpp:2124] Processing reply for offer 201404160134-3053457580-5050-1887-67 on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182) for framework 201404040602-3019903148-5050-16828-0000
I0417 02:44:08.189556 2023 hierarchical_allocator_process.hpp:590] Framework 201404040602-3019903148-5050-16828-0000 filtered slave 201404160133-3036680364-5050-7214-2 for 5secs
I0417 02:44:09.031566 2018 http.cpp:373] HTTP request for '/master/state.json'
I0417 02:44:10.185603 2022 master.cpp:1787] Sending 1 offers to framework 201404040602-3019903148-5050-16828-0001
I0417 02:44:10.191586 2021 master.cpp:2124] Processing reply for offer 201404160134-3053457580-5050-1887-68 on slave 201404160133-3036680364-5050-7214-2 (172.16.0.182) for framework 201404040602-3019903148-5050-16828-0001
I0417 02:44:10.191867 2021 hierarchical_allocator_process.hpp:590] Framework 201404040602-3019903148-5050-16828-0001 filtered slave 201404160133-3036680364-5050-7214-2 for 5secs
I0417 02:44:11.186877 2019 master.cpp:1787] Sending 1 offers to framework 201404160134-3053457580-5050-1887-0001
I0417 02:43:53.263538 2458 slave.cpp:728] Got assigned task sssp-201404107T024353.123Z-402531b0 for framework 201404160134-3053457580-5050-1887-0001
I0417 02:43:53.263592 2458 slave.cpp:837] Launching task sssp-201404107T024353.123Z-402531b0 for framework 201404160134-3053457580-5050-1887-0001
I0417 02:43:53.265039 2459 process_isolator.cpp:100] Launching sssp-201404107T024353.123Z-402531b0 (export DIST=http://sssp-dist:the-rain-in-spain@172.16.0.180:9000/ PORT=31000 SSSP_MESOS_MODE=executor &&
curl -sSfL "${DIST-http://downloads.mesosphere.io/sssp/sssp.tgz}" -o dist &&
tar --strip-components 1 -xf dist && rm -f dist RUNNING_PID &&
mkdir -p conf/mesos &&
case "${SSSP_MESOS_MODE-scheduler}" in
executor) touch conf/mesos/executor ;;
scheduler) touch conf/mesos/executor conf/mesos/scheduler ;;
only-scheduler) touch conf/mesos/scheduler ;;
*) echo "Bad mode: '$SSSP_MESOS_MODE'" >&2 ; exit 2 ;;
esac &&
bin/sssp -Dhttp.port=${PORT-9000}
) in /tmp/mesos/slaves/201404160133-3036680364-5050-7214-2/frameworks/201404160134-3053457580-5050-1887-0001/executors/sssp-201404107T024353.123Z-402531b0/runs/98a4bf98-58ef-4684-b032-5744f6a381c5 with resources ' for framework 201404160134-3053457580-5050-1887-0001
I0417 02:43:53.268426 2458 slave.cpp:947] Queuing task 'sssp-201404107T024353.123Z-402531b0' for executor sssp-201404107T024353.123Z-402531b0 of framework '201404160134-3053457580-5050-1887-0001
I0417 02:43:53.269315 2459 process_isolator.cpp:163] Forked executor at 25737
I0417 02:43:53.270392 2457 slave.cpp:2090] Monitoring executor sssp-201404107T024353.123Z-402531b0 of framework 201404160134-3053457580-5050-1887-0001 forked at pid 25737
I0417 02:43:56.153811 2454 slave.cpp:2492] Killing executor 'sssp-201404107T023820.731Z-0521f33e' of framework 201404160134-3053457580-5050-1887-0000
I0417 02:43:56.155303 2458 slave.cpp:2492] Killing executor 'sssp-201404107T023827.219Z-1ac32497' of framework 201404160134-3053457580-5050-1887-0000
I0417 02:43:56.203364 2454 process_isolator.cpp:271] Killed the following process trees:
[
--- 21734 ()
]
I0417 02:43:56.224119 2454 process_isolator.cpp:271] Killed the following process trees:
[
--- 21738 ()
]
I0417 02:43:56.393131 2457 process_isolator.cpp:482] Telling slave of terminated executor 'sssp-201404107T023820.731Z-0521f33e' of framework 201404160134-3053457580-5050-1887-0000
I0417 02:43:56.393452 2456 slave.cpp:2146] Executor 'sssp-201404107T023820.731Z-0521f33e' of framework 201404160134-3053457580-5050-1887-0000 has terminated with signal Killed
I0417 02:43:56.393492 2456 slave.cpp:2281] Cleaning up executor 'sssp-201404107T023820.731Z-0521f33e' of framework 201404160134-3053457580-5050-1887-0000
I0417 02:43:56.393873 2456 gc.cpp:56] Scheduling '/tmp/mesos/slaves/201404160133-3036680364-5050-7214-2/frameworks/201404160134-3053457580-5050-1887-0000/executors/sssp-201404107T023820.731Z-0521f33e/runs/fa20ac78-d8cf-4c18-8c26-a3dbd7291c86' for gc 6.99999544211852days in the future
I0417 02:43:56.395061 2457 process_isolator.cpp:482] Telling slave of terminated executor 'sssp-201404107T023827.219Z-1ac32497' of framework 201404160134-3053457580-5050-1887-0000
I0417 02:43:56.395211 2459 slave.cpp:2146] Executor 'sssp-201404107T023827.219Z-1ac32497' of framework 201404160134-3053457580-5050-1887-0000 has terminated with signal Killed
I0417 02:43:56.395292 2459 slave.cpp:2281] Cleaning up executor 'sssp-201404107T023827.219Z-1ac32497' of framework 201404160134-3053457580-5050-1887-0000
I0417 02:43:56.395895 2459 slave.cpp:2352] Cleaning up framework 201404160134-3053457580-5050-1887-0000
I0417 02:43:56.396909 2459 status_update_manager.cpp:274] Closing status update streams for framework 201404160134-3053457580-5050-1887-0000
I0417 02:43:56.395979 2454 gc.cpp:56] Scheduling '/tmp/mesos/slaves/201404160133-3036680364-5050-7214-2/frameworks/201404160134-3053457580-5050-1887-0000/executors/sssp-201404107T023827.219Z-1ac32497/runs/95da1e7b-96f9-4611-a8ce-53b789e03a15' for gc 6.99999541886815days in the future
I0417 02:43:56.397408 2454 gc.cpp:56] Scheduling '/tmp/mesos/slaves/201404160133-3036680364-5050-7214-2/frameworks/201404160134-3053457580-5050-1887-0000/executors/sssp-201404107T023827.219Z-1ac32497' for gc 6.99999541817185days in the future
I0417 02:43:56.397552 2454 gc.cpp:56] Scheduling '/tmp/mesos/slaves/201404160133-3036680364-5050-7214-2/frameworks/201404160134-3053457580-5050-1887-0000' for gc 6.99999540685333days in the future
I0417 02:43:59.202731 2459 slave.cpp:728] Got assigned task sssp-201404107T024359.096Z-230b63ff for framework 201404160134-3053457580-5050-1887-0001
I0417 02:43:59.203068 2459 slave.cpp:837] Launching task sssp-201404107T024359.096Z-230b63ff for framework 201404160134-3053457580-5050-1887-0001
I0417 02:43:59.205056 2453 process_isolator.cpp:100] Launching sssp-201404107T024359.096Z-230b63ff (export DIST=http://sssp-dist:the-rain-in-spain@172.16.0.180:9000/ PORT=31001 SSSP_MESOS_MODE=executor &&
curl -sSfL "${DIST-http://downloads.mesosphere.io/sssp/sssp.tgz}" -o dist &&
tar --strip-components 1 -xf dist && rm -f dist RUNNING_PID &&
mkdir -p conf/mesos &&
case "${SSSP_MESOS_MODE-scheduler}" in
executor) touch conf/mesos/executor ;;
scheduler) touch conf/mesos/executor conf/mesos/scheduler ;;
only-scheduler) touch conf/mesos/scheduler ;;
*) echo "Bad mode: '$SSSP_MESOS_MODE'" >&2 ; exit 2 ;;
esac &&
bin/sssp -Dhttp.port=${PORT-9000}
) in /tmp/mesos/slaves/201404160133-3036680364-5050-7214-2/frameworks/201404160134-3053457580-5050-1887-0001/executors/sssp-201404107T024359.096Z-230b63ff/runs/6d486e1b-a5e5-4655-ac75-655a7a6c51ee with resources ' for framework 201404160134-3053457580-5050-1887-0001
I0417 02:43:59.212498 2459 slave.cpp:947] Queuing task 'sssp-201404107T024359.096Z-230b63ff' for executor sssp-201404107T024359.096Z-230b63ff of framework '201404160134-3053457580-5050-1887-0001
I0417 02:43:59.213588 2453 process_isolator.cpp:163] Forked executor at 25741
I0417 02:43:59.215529 2454 slave.cpp:2090] Monitoring executor sssp-201404107T024359.096Z-230b63ff of framework 201404160134-3053457580-5050-1887-0001 forked at pid 25741
I0417 02:44:03.740627 2459 slave.cpp:2602] Current usage 69.43%. Max allowed age: 1.440141667947558days
I0417 02:44:04.685724 2453 slave.cpp:1423] Got registration for executor 'sssp-201404107T024353.123Z-402531b0' of framework 201404160134-3053457580-5050-1887-0001
include "application.conf"
mesos.master="zk://172.16.0.180:2181,172.16.0.181:2181,172.16.0.182:2181/mesos"
mesos.workers=1
mesos.mem=256
mesos.cpus=0.2
Play server process ID is 24797
[INFO] 02:43:52.615 application - Mesos master: zk://172.16.0.180:2181,172.16.0.181:2181,172.16.0.182:2181/mesos
[INFO] 02:43:52.669 mesos.scheduler - Starting up...
[INFO] 02:43:52.680 play - Application started (Prod)
2014-04-17 02:43:52,771:24797(0x7fb8458e6700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4
[11:40:22] ok: [node1.mesos1] => {"changed": false, "enabled": true, "item": "", "name": "sssp", "state": "started"}
2014-04-17 02:43:52,771:24797(0x7fb8458e6700):ZOO_INFO@log_env@669: Client environment:os.name=Linux
2014-04-17 02:43:52,771:24797(0x7fb8458e6700):ZOO_INFO@log_env@670: Client environment:os.arch=2.6.32-358.23.2.el6.x86_64
2014-04-17 02:43:52,771:24797(0x7fb8458e6700):ZOO_INFO@log_env@671: Client environment:os.version=#1 SMP Wed Oct 16 18:37:12 UTC 2013
2014-04-17 02:43:52,771:24797(0x7fb8458e6700):ZOO_INFO@log_env@679: Client environment:user.name=vagrant
2014-04-17 02:43:52,771:24797(0x7fb8458e6700):ZOO_INFO@log_env@687: Client environment:user.home=/root
2014-04-17 02:43:52,771:24797(0x7fb8458e6700):ZOO_INFO@log_env@699: Client environment:user.dir=/
2014-04-17 02:43:52,771:24797(0x7fb8458e6700):ZOO_INFO@zookeeper_init@727: Initiating client connection, host=172.16.0.180:2181,172.16.0.181:2181,172.16.0.182:2181 sessionTimeout=10000 watcher=0x7fb852dae8d0 sessionId=0 sessionPasswd=<null> context=0x7fb8481877d0 flags=0
2014-04-17 02:43:52,777:24797(0x7fb83cdfa700):ZOO_INFO@check_events@1585: initiated connection to server [172.16.0.180:2181]
2014-04-17 02:43:52,817:24797(0x7fb83cdfa700):ZOO_INFO@check_events@1632: session establishment complete on server [172.16.0.180:2181], sessionId=0x1456d859b430003, negotiated timeout=10000
I0417 02:43:52.817422 24850 group.cpp:310] Group process ((2)@172.16.0.180:49120) connected to ZooKeeper
I0417 02:43:52.817471 24850 group.cpp:752] Syncing group operations: queue size (joins, cancels, datas) = (0, 0, 0)
I0417 02:43:52.817488 24850 group.cpp:367] Trying to create path '/mesos' in ZooKeeper
I0417 02:43:52.818506 24850 detector.cpp:134] Detected a new leader: (id='56')
I0417 02:43:52.818634 24850 group.cpp:629] Trying to get '/mesos/0000000056' in ZooKeeper
I0417 02:43:52.819315 24850 detector.cpp:354] A new leading master (UPID=master@172.16.0.182:5050) is detected
I0417 02:43:52.819532 24850 sched.cpp:218] No credentials provided. Attempting to register without authentication
I0417 02:43:52.819649 24850 sched.cpp:230] Detecting new master
[INFO] 02:43:52.831 mesos.scheduler - registered as 201404160134-3053457580-5050-1887-0001
[INFO] 02:43:52.882 play - Listening for HTTP on /172.16.0.180:9000
[INFO] 02:43:52.919 mesos.scheduler - handling offer: 201404160134-3053457580-5050-1887-59
[INFO] 02:43:54.203 web - #1 GET / ## Handling administrative request.
[INFO] 02:43:54.259 web - #1 GET / ## Providing tarball of running application.
[INFO] 02:43:56.343 application - Temporary dist tarball created: /tmp/sssp-639d90e671e9985e.tgz
[INFO] 02:43:59.075 mesos.scheduler - handling offer: 201404160134-3053457580-5050-1887-63
[INFO] 02:43:59.223 web - #2 GET / ## Handling administrative request.
[INFO] 02:43:59.225 web - #2 GET / ## Providing tarball of running application.
[INFO] 02:43:59.648 application - Deleting dist tarball /tmp/sssp-639d90e671e9985e.tgz
[INFO] 02:44:01.981 application - Temporary dist tarball created: /tmp/sssp-18450b1c5212730d.tgz
[INFO] 02:44:04.480 application - Deleting dist tarball /tmp/sssp-18450b1c5212730d.tgz
[INFO] 02:44:05.080 mesos.scheduler - handling offer: 201404160134-3053457580-5050-1887-66
[INFO] 02:44:05.258 web - #3 GET / ## Handling administrative request.
[INFO] 02:44:05.259 web - #3 GET / ## Providing tarball of running application.
[INFO] 02:44:06.795 mesos.scheduler - Executor joining: 127.0.0.1:31000
[INFO] 02:44:08.282 application - Temporary dist tarball created: /tmp/sssp-09beebafb11d23b6.tgz
[INFO] 02:44:10.799 application - Deleting dist tarball /tmp/sssp-09beebafb11d23b6.tgz
[INFO] 02:44:12.695 mesos.scheduler - Executor joining: 127.0.0.1:31001
[INFO] 02:44:15.939 mesos.scheduler - Executor joining: 127.0.0.1:31002
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment