2018-05-21:14:00:29.818:info:vcl.schedule:64246:13253801:vcl/source/app/svapp.cxx:460: Enter ImplYield: wait: one event 2018-05-21:14:00:29.818:debug:64246:13253801: DoYield (1): Calling NSApp::nextEventMatchingMask 2018-05-21:14:00:29.818:debug:64246:13253801: ...NSApp::nextEventMatchingMask returned 2018-05-21:14:00:29.818:debug:64246:13253801: DoYield (2): Calling NSApp::nextEventMatchingMask ==> One normal round 2018-05-21:14:00:39.818:debug:64246:13253801: ...NSApp::nextEventMatchingMask returned 2018-05-21:14:00:39.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:372: 605710237 0x7f9d82fbd850 i: 0 Timer a: 1 p: 1 framework::AutoRecovery m_aTimer 600000ms (0x7f9d82fc09a8) 2018-05-21:14:00:39.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:372: 605710237 0x7f9d7be12cb0 i: 0 Timer a: 1 p: 1 graphic::Manager maSwapOutTimer 10000ms (0x7f9d82fbdab0) 2018-05-21:14:00:39.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:426: Calculated minimum timeout as 529790 2018-05-21:14:00:39.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:260: Starting scheduler system timer (529790ms) 2018-05-21:14:00:39.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:432: 605710237 0x7f9d7be12cb0 invoke-in a: 1 p: 1 graphic::Manager maSwapOutTimer 2018-05-21:14:00:39.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:542: 605710237 0x7f9d7be12cb0 restarted a: 1 p: 1 graphic::Manager maSwapOutTimer 2018-05-21:14:00:39.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:260: Starting scheduler system timer (10000ms) 2018-05-21:14:00:39.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:470: 605710237 0x7f9d7be12cb0 invoke-out 2018-05-21:14:00:39.818:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:130: osl_setCondition(0x7f9d7bf4ffa0) 2018-05-21:14:00:39.818:info:vcl.schedule:64246:13253801:vcl/source/app/svapp.cxx:481: Leave ImplYield with return 1 2018-05-21:14:00:39.818:info:vcl.schedule:64246:13253801:vcl/source/app/svapp.cxx:460: Enter ImplYield: wait: one event 2018-05-21:14:00:39.818:debug:64246:13253801: DoYield (1): Calling NSApp::nextEventMatchingMask 2018-05-21:14:00:39.819:debug:64246:13253801: ...NSApp::nextEventMatchingMask returned 2018-05-21:14:00:39.819:debug:64246:13253801: DoYield (2): Calling NSApp::nextEventMatchingMask ==> Next, after 10 s 2018-05-21:14:00:49.818:debug:64246:13253801: ...NSApp::nextEventMatchingMask returned 2018-05-21:14:00:49.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:372: 605720238 0x7f9d82fbd850 i: 0 Timer a: 1 p: 1 framework::AutoRecovery m_aTimer 600000ms (0x7f9d82fc09a8) 2018-05-21:14:00:49.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:372: 605720238 0x7f9d7be12cb0 i: 0 Timer a: 1 p: 1 graphic::Manager maSwapOutTimer 10000ms (0x7f9d82fbdab0) 2018-05-21:14:00:49.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:426: Calculated minimum timeout as 519789 2018-05-21:14:00:49.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:260: Starting scheduler system timer (519789ms) 2018-05-21:14:00:49.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:432: 605720238 0x7f9d7be12cb0 invoke-in a: 1 p: 1 graphic::Manager maSwapOutTimer 2018-05-21:14:00:49.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:542: 605720238 0x7f9d7be12cb0 restarted a: 1 p: 1 graphic::Manager maSwapOutTimer 2018-05-21:14:00:49.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:260: Starting scheduler system timer (10000ms) 2018-05-21:14:00:49.818:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:470: 605720238 0x7f9d7be12cb0 invoke-out 2018-05-21:14:00:49.818:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:130: osl_setCondition(0x7f9d7bf4ffa0) 2018-05-21:14:00:49.818:info:vcl.schedule:64246:13253801:vcl/source/app/svapp.cxx:481: Leave ImplYield with return 1 2018-05-21:14:00:49.819:info:vcl.schedule:64246:13253801:vcl/source/app/svapp.cxx:460: Enter ImplYield: wait: one event 2018-05-21:14:00:49.819:debug:64246:13253801: DoYield (1): Calling NSApp::nextEventMatchingMask 2018-05-21:14:00:49.819:debug:64246:13253801: ...NSApp::nextEventMatchingMask returned 2018-05-21:14:00:49.819:debug:64246:13253801: DoYield (2): Calling NSApp::nextEventMatchingMask ==> And again it goes waiting for 10 s ==> Then I double-click the document 2018-05-21:14:00:52.104:debug:64246:13253801: ...NSApp::nextEventMatchingMask returned 2018-05-21:14:00:52.104:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:130: osl_setCondition(0x7f9d7bf4ffa0) 2018-05-21:14:00:52.104:info:vcl.schedule:64246:13253801:vcl/source/app/svapp.cxx:481: Leave ImplYield with return 1 2018-05-21:14:00:52.104:info:vcl.schedule:64246:13253801:vcl/source/app/svapp.cxx:460: Enter ImplYield: wait: one event 2018-05-21:14:00:52.104:debug:64246:13253801: DoYield (1): Calling NSApp::nextEventMatchingMask 2018-05-21:14:00:52.104:debug:64246:13253801: ...NSApp::nextEventMatchingMask returned 2018-05-21:14:00:52.104:debug:64246:13253801: DoYield (2): Calling NSApp::nextEventMatchingMask ==> And nothing more happens, until the next time the 10 s timer fires. ==> Only then it starts opening the file and whatelse. 2018-05-21:14:00:59.819:debug:64246:13253801: ...NSApp::nextEventMatchingMask returned 2018-05-21:14:00:59.819:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:372: 605730239 0x7f9d82fbd850 i: 0 Timer a: 1 p: 1 framework::AutoRecovery m_aTimer 600000ms (0x7f9d82fc09a8) 2018-05-21:14:00:59.819:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:372: 605730239 0x7f9d7be12cb0 i: 0 Timer a: 1 p: 1 graphic::Manager maSwapOutTimer 10000ms (0x7f9d82fbdab0) 2018-05-21:14:00:59.819:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:426: Calculated minimum timeout as 509788 2018-05-21:14:00:59.819:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:260: Starting scheduler system timer (509788ms) 2018-05-21:14:00:59.819:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:432: 605730239 0x7f9d7be12cb0 invoke-in a: 1 p: 1 graphic::Manager maSwapOutTimer 2018-05-21:14:00:59.819:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:542: 605730239 0x7f9d7be12cb0 restarted a: 1 p: 1 graphic::Manager maSwapOutTimer 2018-05-21:14:00:59.819:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:260: Starting scheduler system timer (10000ms) 2018-05-21:14:00:59.819:info:vcl.schedule:64246:13253801:vcl/source/app/scheduler.cxx:470: 605730239 0x7f9d7be12cb0 invoke-out 2018-05-21:14:00:59.819:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:130: osl_setCondition(0x7f9d7bf4ffa0) 2018-05-21:14:00:59.820:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:161: osl_resetCondition(0x7f9d82df8230) 2018-05-21:14:00:59.820:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:264: osl_checkCondition(0x7f9d82df8230): NO 2018-05-21:14:00:59.820:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:130: osl_setCondition(0x7f9d82df8230) 2018-05-21:14:00:59.820:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:264: osl_checkCondition(0x7f9d82df8230): YES 2018-05-21:14:00:59.820:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:161: osl_resetCondition(0x7f9d82df8230) 2018-05-21:14:00:59.820:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:264: osl_checkCondition(0x7f9d82df8230): NO 2018-05-21:14:00:59.820:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:130: osl_setCondition(0x7f9d82df8230) 2018-05-21:14:00:59.820:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:264: osl_checkCondition(0x7f9d82df8230): YES 2018-05-21:14:00:59.820:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:161: osl_resetCondition(0x7f9d82df8230) 2018-05-21:14:00:59.820:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:264: osl_checkCondition(0x7f9d82df8230): NO 2018-05-21:14:00:59.820:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:130: osl_setCondition(0x7f9d82df8230) 2018-05-21:14:00:59.820:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:264: osl_checkCondition(0x7f9d82df8230): YES 2018-05-21:14:00:59.820:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:161: osl_resetCondition(0x7f9d82df8230) 2018-05-21:14:00:59.821:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:264: osl_checkCondition(0x7f9d82df8230): NO 2018-05-21:14:00:59.821:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:130: osl_setCondition(0x7f9d82df8230) 2018-05-21:14:00:59.821:info:sal.osl.condition:64246:13253801:sal/osl/unx/conditn.cxx:264: osl_checkCondition(0x7f9d82df8230): YES 2018-05-21:14:00:59.825:info:cppuhelper:64246:13253801:cppuhelper/source/servicemanager.cxx:1788: No implementation for com.sun.star.uri.UriSchemeParser_file 2018-05-21:14:00:59.826:info:sal.file:64246:13253801:sal/osl/unx/file.cxx:1033: osl_openFile(/Users/tml/Documents/x/u1.odt, writeable) => 18 2018-05-21:14:00:59.826:info:sal.file:64246:13253801:sal/osl/unx/file.cxx:484: FileHandle_Impl::readFileAt(18, 0, 4)