My test script restart app (driver.close & driver.launchapp) after creating 10 records. After 3 hour the instrument reset and Command number starting from #1. script failed with error Command duration or timed out 1.75 seconds. My driver settings for the simulatory is listed below:
capabilities = new DesiredCapabilities();
capabilities.setCapability(“platformName”, “iOS”);
capabilities.setCapability(“deviceName”, “iPhone 6”);
capabilities.setCapability(“appWaitActivity”, “”);
capabilities.setCapability(“newCommandTimeout”, 400); //Appium Server Time Out(3 min)
capabilities.setCapability(“launchTimeout”,30000);
capabilities.setCapability(“waitForAppScript”,"$.delay(9000); true");
try {
_driver = new IOSDriver(new URL(IP), capabilities);
driver=((IOSDriver)_driver);
driver.manage().timeouts().implicitlyWait(0, TimeUnit.SECONDS);
driver.context("NATIVE_APP");
decorator = new AppiumFieldDecorator(driver);
PageFactory.initElements(decorator, this /* refers to current page object class*/);
} catch (MalformedURLException e) {
e.printStackTrace();
// Print the statistics from graphwalker
actualResult = modelhandler.getStatistics();
System.out.println(actualResult);
}
Here is the appium log:
2015-12-22 16:58:15:196 - info: [debug] Instruments didn’t terminate after 3 seconds; trying to kill it
2015-12-22 16:58:15:197 - info: [debug] Killall instruments
2015-12-22 16:58:15:569 - info: [debug] [INSTSERVER] Instruments exited with code null
2015-12-22 16:58:15:570 - info: [debug] Cleaning up after instruments exit
2015-12-22 16:58:15:570 - info: [debug] Stopping iOS log capture
2015-12-22 16:58:15:570 - info: [debug] Reset not set, not ending sim or cleaning up app state
2015-12-22 16:58:15:570 - info: [debug] Responding to client with success: {“status”:0,“value”:“Successfully closed the [/Users/admin/Documents/AITiOS.app] app.”,“sessionId”:“d09b491f-4d64-4490-a99d-cf06aad00fcf”}
2015-12-22 16:58:15:571 - info: <-- POST /wd/hub/session/d09b491f-4d64-4490-a99d-cf06aad00fcf/appium/app/close 200 3407.178 ms - 138 {“status”:0,“value”:“Successfully closed the [/Users/admin/Documents/AITiOS.app] app.”,“sessionId”:“d09b491f-4d64-4490-a99d-cf06aad00fcf”}
2015-12-22 16:58:15:582 - info: --> POST /wd/hub/session/d09b491f-4d64-4490-a99d-cf06aad00fcf/appium/app/launch {}
2015-12-22 16:58:15:582 - info: [debug] Removing any remaining instruments sockets
2015-12-22 16:58:15:582 - info: [debug] Cleaned up instruments socket /tmp/instruments_sock
2015-12-22 16:58:15:583 - info: [debug] Setting Xcode version
2015-12-22 16:58:15:583 - info: [debug] Xcode version set to 7.1
2015-12-22 16:58:15:583 - info: [debug] Setting iOS SDK Version
2015-12-22 16:58:15:583 - info: [debug] iOS SDK Version set to 9.1
2015-12-22 16:58:15:584 - info: [debug] Checking whether instruments supports our device string
2015-12-22 16:58:15:584 - info: [debug] Getting list of devices instruments supports
2015-12-22 16:58:15:704 - info: [debug] Instruments is at: /Applications/Xcode.app/Contents/Developer/usr/bin/instruments
2015-12-22 16:58:25:051 - info: [debug] Getting device string from opts: {“forceIphone”:false,“forceIpad”:false,“xcodeVersion”:“7.1”,“iOSSDKVersion”:“9.1”,“deviceName”:“iPhone 6”,“platformVersion”:“9.1”}
2015-12-22 16:58:25:052 - info: [debug] fixDevice is on
2015-12-22 16:58:25:052 - info: [debug] Fixing device. Changed from: “iPhone 6 (9.1)” to: “iPhone 6 (9.1) [”
2015-12-22 16:58:25:052 - info: [debug] Final device string is: ‘iPhone 6 (9.1) [’
2015-12-22 16:58:25:052 - info: [debug] iOS sim UDID is C4832C97-F663-4C14-8B93-E5D996373091
2015-12-22 16:58:25:052 - info: [debug] Auto-detecting iOS udid…
2015-12-22 16:58:25:052 - info: [debug] Not auto-detecting udid, running on sim
2015-12-22 16:58:25:053 - info: [debug] Could not parse plist file (as binary) at /Users/admin/Documents/AITiOS.app/en.lproj/Localizable.strings
2015-12-22 16:58:25:053 - info: Will try to parse the plist file as XML
2015-12-22 16:58:25:053 - info: [debug] Could not parse plist file (as XML) at /Users/admin/Documents/AITiOS.app/en.lproj/Localizable.strings
2015-12-22 16:58:25:053 - warn: Could not parse app Localizable.strings assuming it doesn’t exist
2015-12-22 16:58:25:054 - info: [debug] Creating instruments
2015-12-22 16:58:25:054 - info: [debug] Preparing uiauto bootstrap
2015-12-22 16:58:25:054 - info: [debug] Dynamic bootstrap dir: /Users/admin/Library/Application Support/appium/bootstrap
2015-12-22 16:58:25:054 - info: [debug] Dynamic env: {“nodePath”:"/Applications/Appium.app/Contents/Resources/node/bin/node",“commandProxyClientPath”:"/Applications/Appium.app/Contents/Resources/node_modules/appium/submodules/appium-uiauto/bin/command-proxy-client.js",“instrumentsSock”:"/tmp/instruments_sock",“interKeyDelay”:null,“justLoopInfinitely”:false,“autoAcceptAlerts”:false,“autoDismissAlerts”:false,“sendKeyStrategy”:“oneByOne”}
2015-12-22 16:58:25:120 - info: [debug] Dynamic bootstrap code: // This file is automatically generated. Do not manually modify!
…
2015-12-22 16:58:25:120 - info: [debug] Dynamic bootstrap path: /Users/admin/Library/Application Support/appium/bootstrap/bootstrap-f062718992d59810.js
2015-12-22 16:58:25:138 - info: [debug] Reusing dynamic bootstrap: /Users/admin/Library/Application Support/appium/bootstrap/bootstrap-f062718992d59810.js
2015-12-22 16:58:25:138 - info: [debug] Getting device string from opts: {“forceIphone”:false,“forceIpad”:false,“xcodeVersion”:“7.1”,“iOSSDKVersion”:“9.1”,“deviceName”:“iPhone 6”,“platformVersion”:“9.1”}
2015-12-22 16:58:25:138 - info: [debug] fixDevice is on
2015-12-22 16:58:25:139 - info: [debug] Fixing device. Changed from: “iPhone 6 (9.1)” to: “iPhone 6 (9.1) [”
2015-12-22 16:58:25:139 - info: [debug] Final device string is: ‘iPhone 6 (9.1) [’
2015-12-22 16:58:25:139 - info: [debug] We’re on iOS7.1+ so forcing defaultDevice on
2015-12-22 16:58:25:139 - info: [debug] Getting device string from opts: {“forceIphone”:false,“forceIpad”:false,“xcodeVersion”:“7.1”,“iOSSDKVersion”:“9.1”,“deviceName”:“iPhone 6”,“platformVersion”:“9.1”}
2015-12-22 16:58:25:139 - info: [debug] fixDevice is on
2015-12-22 16:58:25:139 - info: [debug] Fixing device. Changed from: “iPhone 6 (9.1)” to: “iPhone 6 (9.1) [”
2015-12-22 16:58:25:139 - info: [debug] Final device string is: ‘iPhone 6 (9.1) [’
2015-12-22 16:58:25:163 - info: [debug] Parsed app Info.plist (as binary)
2015-12-22 16:58:25:177 - info: [debug] Wrote new app Info.plist with device type
2015-12-22 16:58:25:177 - info: [debug] Checking whether we need to set app preferences
2015-12-22 16:58:25:178 - info: [debug] Reset not set, not ending sim or cleaning up app state
2015-12-22 16:58:25:178 - info: [debug] Not setting locale
2015-12-22 16:58:25:178 - info: [debug] No iOS / app preferences to set
2015-12-22 16:58:25:178 - info: [debug] Starting iOS 8.* simulator log capture
2015-12-22 16:58:25:218 - info: [debug] Not pre-launching simulator
2015-12-22 16:58:25:218 - info: [debug] Starting command proxy.
2015-12-22 16:58:25:219 - info: [debug] Instruments socket server started at /tmp/instruments_sock
2015-12-22 16:58:25:219 - info: [debug] Starting instruments
2015-12-22 16:58:25:225 - info: [debug] Instruments is at: /Applications/Xcode.app/Contents/Developer/usr/bin/instruments
2015-12-22 16:58:25:226 - info: Launching instruments
2015-12-22 16:58:25:227 - info: [debug] Attempting to run app on iPhone 6 (9.1) [
2015-12-22 16:58:25:227 - info: [debug] Spawning instruments with command: /Applications/Xcode.app/Contents/Developer/usr/bin/instruments -t /Applications/Xcode.app/Contents/Applications/Instruments.app/Contents/PlugIns/AutomationInstrument.xrplugin/Contents/Resources/Automation.tracetemplate -D /tmp/appium-instruments/instrumentscli0.trace -w “iPhone 6 (9.1) [” /Users/admin/Documents/AITiOS.app -e UIASCRIPT “/Users/admin/Library/Application Support/appium/bootstrap/bootstrap-f062718992d59810.js” -e UIARESULTSPATH /tmp/appium-instruments
2015-12-22 16:58:25:227 - info: [debug] And extra without-delay env: {}
2015-12-22 16:58:25:228 - info: [debug] And launch timeouts (in ms): {“global”:30000}
2015-12-22 16:58:34:465 - info: Instruments is ready to receive commands
2015-12-22 16:58:34:465 - info: [debug] Instruments launched. Starting poll loop for new commands.
2015-12-22 16:58:34:465 - info: [debug] Setting bootstrap config keys/values
2015-12-22 16:58:34:466 - info: [debug] Pushing command to appium work queue: “target = $.target();\nau = $;\n$.isVerbose = true;\n”
2015-12-22 16:58:34:468 - info: [debug] Socket data received (2 bytes)
2015-12-22 16:58:34:469 - info: [debug] Socket data being routed.
2015-12-22 16:58:34:469 - info: [debug] Sending command to instruments: target = $.target();
au = $;
$.isVerbose = true;
2015-12-22 16:58:35:351 - info: [debug] [INST] 2015-12-22 21:58:35 +0000 Debug: evaluation finished
2015-12-22 16:58:35:354 - info: [debug] [INST] 2015-12-22 21:58:35 +0000 Debug: responding with:
2015-12-22 16:58:35:355 - info: [debug] [INST] 2015-12-22 21:58:35 +0000 Debug: Running system command #1: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/submodules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:true}…
2015-12-22 16:58:35:472 - info: [debug] Socket data received (27 bytes)
2015-12-22 16:58:35:473 - info: [debug] Socket data being routed.
2015-12-22 16:58:35:473 - info: [debug] Got result from instruments: {“status”:0,“value”:true}
2015-12-22 16:58:35:473 - info: [debug] Setting initial orientation to PORTRAIT
2015-12-22 16:58:35:473 - info: [debug] Pushing command to appium work queue: “au.setScreenOrientation(‘PORTRAIT’)”
2015-12-22 16:58:35:474 - info: [debug] Sending command to instruments: au.setScreenOrientation(‘PORTRAIT’)
2015-12-22 16:58:36:474 - info: [debug] [INST] 2015-12-22 21:58:36 +0000 Debug: Got new command 1 from instruments: au.setScreenOrientation(‘PORTRAIT’)
2015-12-22 16:58:36:487 - info: [debug] [INST] 2015-12-22 21:58:36 +0000 Debug: evaluating au.setScreenOrientation(‘PORTRAIT’)
2015-12-22 16:58:36:488 - info: [debug] [INST] 2015-12-22 21:58:36 +0000 Debug: target.setDeviceOrientation(“1”)
2015-12-22 16:58:37:432 - info: [debug] [INST] 2015-12-22 21:58:37 +0000 Debug: evaluation finished
2015-12-22 16:58:37:433 - info: [debug] [INST] 2015-12-22 21:58:37 +0000 Debug: responding with:
2015-12-22 16:58:37:434 - info: [debug] [INST] 2015-12-22 21:58:37 +0000 Debug: Running system command #2: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/submodules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:“PORTRAIT”}…
2015-12-22 16:58:37:519 - info: [debug] Socket data received (33 bytes)
2015-12-22 16:58:37:520 - info: [debug] Socket data being routed.
2015-12-22 16:58:37:520 - info: [debug] Got result from instruments: {“status”:0,“value”:“PORTRAIT”}
2015-12-22 16:58:37:520 - info: [debug] Using custom script to wait for app start:$.delay(9000); true
2015-12-22 16:58:37:520 - info: [debug] Pushing command to appium work queue: “try{\n$.delay(9000); true\n} catch(err) { $.log(“waitForAppScript err: " + error); false; };”
2015-12-22 16:58:37:520 - info: [debug] Sending command to instruments: try{
$.delay(9000); true
} catch(err) { $.log(“waitForAppScript err: " + error); false; };
2015-12-22 16:58:38:431 - info: [debug] [INST] 2015-12-22 21:58:38 +0000 Debug: Got new command 2 from instruments: try{
$.delay(9000); true
} catch(err) { $.log(“waitForAppScript err: " + error); false; };
2015-12-22 16:58:38:432 - info: [debug] [INST] 2015-12-22 21:58:38 +0000 Debug: evaluating try{
$.delay(9000); true
} catch(err) { $.log(“waitForAppScript err: " + error); false; };
2015-12-22 16:58:47:410 - info: [debug] [INST] 2015-12-22 21:58:47 +0000 Debug: evaluation finished
2015-12-22 16:58:47:434 - info: [debug] [INST] 2015-12-22 21:58:47 +0000 Debug: responding with:
2015-12-22 16:58:47:435 - info: [debug] [INST] 2015-12-22 21:58:47 +0000 Debug: Running system command #3: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/submodules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:true}…
2015-12-22 16:58:47:499 - info: [debug] Socket data received (27 bytes)
2015-12-22 16:58:47:500 - info: [debug] Socket data being routed.
2015-12-22 16:58:47:500 - info: [debug] Got result from instruments: {“status”:0,“value”:true}
2015-12-22 16:58:47:500 - info: [debug] Responding to client with success: {“status”:0,“value”:“Successfully launched the app.”,“sessionId”:“d09b491f-4d64-4490-a99d-cf06aad00fcf”}
2015-12-22 16:58:47:501 - info: <-- POST /wd/hub/session/d09b491f-4d64-4490-a99d-cf06aad00fcf/appium/app/launch 200 31918.231 ms - 104 {“status”:0,“value”:“Successfully launched the app.”,“sessionId”:“d09b491f-4d64-4490-a99d-cf06aad00fcf”}
2015-12-22 16:58:47:516 - info: --> POST /wd/hub/session/d09b491f-4d64-4490-a99d-cf06aad00fcf/element {“using”:“class name”,“value”:“UIATableView”}
2015-12-22 16:58:47:516 - info: [debug] Waiting up to 0ms for condition
2015-12-22 16:58:47:516 - info: [debug] Pushing command to appium work queue: “au.getElementByType(‘UIATableView’)”
2015-12-22 16:58:47:516 - info: [debug] Sending command to instruments: au.getElementByType(‘UIATableView’)
2015-12-22 16:58:48:386 - info: [debug] [INST] 2015-12-22 21:58:48 +0000 Debug: Got new command 3 from instruments: au.getElementByType(‘UIATableView’)
2015-12-22 16:58:48:408 - info: [debug] [INST] 2015-12-22 21:58:48 +0000 Debug: evaluating au.getElementByType(‘UIATableView’)
2015-12-22 16:58:48:409 - info: [debug] [INST] 2015-12-22 21:58:48 +0000 Debug: evaluation finished
2015-12-22 16:58:48:410 - info: [debug] [INST] 2015-12-22 21:58:48 +0000 Debug: responding with:
2015-12-22 16:58:48:412 - info: [debug] [INST] 2015-12-22 21:58:48 +0000 Debug: Running system command #4: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/submodules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:””}…
2015-12-22 16:58:48:475 - info: [debug] Socket data received (25 bytes)
2015-12-22 16:58:48:475 - info: [debug] Socket data being routed.
2015-12-22 16:58:48:476 - info: [debug] Got result from instruments: {“status”:0,“value”:””}
2015-12-22 16:58:48:476 - info: [debug] Condition unmet after 960ms. Timing out.
2015-12-22 16:58:48:476 - info: [debug] Responding to client with error: {“status”:7,“value”:{“message”:“An element could not be located on the page using the given search parameters.”,“origValue”:""},“sessionId”:“d09b491f-4d64-4490-a99d-cf06aad00fcf”}
2015-12-22 16:58:48:485 - info: <-- POST /wd/hub/session/d09b491f-4d64-4490-a99d-cf06aad00fcf/element 500 968.896 ms - 179
2015-12-22 16:58:48:530 - info: --> POST /wd/hub/session/d09b491f-4d64-4490-a99d-cf06aad00fcf/element {“using”:“id”,“value”:“Add”}
2015-12-22 16:58:48:531 - info: [debug] Waiting up to 0ms for condition
2015-12-22 16:58:48:531 - info: [debug] Pushing command to appium work queue: “au.getElementByAccessibilityId(‘Add’)”
2015-12-22 16:58:48:531 - info: [debug] Sending command to instruments: au.getElementByAccessibilityId(‘Add’)
2015-12-22 16:58:49:514 - info: [debug] [INST] 2015-12-22 21:58:49 +0000 Debug: Got new command 4 from instruments: au.getElementByAccessibilityId(‘Add’)
2015-12-22 16:58:49:516 - info: [debug] [INST] 2015-12-22 21:58:49 +0000 Debug: evaluating au.getElementByAccessibilityId(‘Add’)
2015-12-22 16:58:49:518 - info: [debug] [INST] 2015-12-22 21:58:49 +0000 Debug: evaluation finished
2015-12-22 16:58:49:518 - info: [debug] [INST] 2015-12-22 21:58:49 +0000 Debug: responding with:
2015-12-22 16:58:49:520 - info: [debug] [INST] 2015-12-22 21:58:49 +0000 Debug: Running system command #5: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/submodules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:""}…
2015-12-22 16:58:49:602 - info: [debug] Socket data received (25 bytes)
2015-12-22 16:58:49:603 - info: [debug] Socket data being routed.
2015-12-22 16:58:49:603 - info: [debug] Got result from instruments: {“status”:0,“value”:""}
2015-12-22 16:58:49:603 - info: [debug] Id selector, ‘Add’, not found in Localizable.strings.
2015-12-22 16:58:49:604 - info: [debug] Pushing command to appium work queue: “au.getElementById(‘Add’)”
2015-12-22 16:58:49:604 - info: [debug] Sending command to instruments: au.getElementById(‘Add’)
2015-12-22 16:58:50:777 - info: [debug] Socket data received (25 bytes)
2015-12-22 16:58:50:778 - info: [debug] Socket data being routed.
2015-12-22 16:58:50:778 - info: [debug] Got result from instruments: {“status”:0,“value”:""}
2015-12-22 16:58:50:778 - info: [debug] Condition unmet after 2247ms. Timing out.
2015-12-22 16:58:50:778 - info: [debug] Responding to client with error: {“status”:7,“value”:{“message”:“An element could not be located on the page using the given search parameters.”,“origValue”:""},“sessionId”:“d09b491f-4d64-4490-a99d-cf06aad00fcf”}
2015-12-22 16:58:50:779 - info: <-- POST /wd/hub/session/d09b491f-4d64-4490-a99d-cf06aad00fcf/element 500 2248.455 ms - 179
2015-12-22 16:58:50:794 - info: --> POST /wd/hub/session/d09b491f-4d64-4490-a99d-cf06aad00fcf/element {“using”:“id”,“value”:“Add”}
2015-12-22 16:58:50:794 - info: [debug] Waiting up to 0ms for condition
2015-12-22 16:58:50:794 - info: [debug] Pushing command to appium work queue: “au.getElementByAccessibilityId(‘Add’)”
2015-12-22 16:58:50:794 - info: [debug] Sending command to instruments: au.getElementByAccessibilityId(‘Add’)
2015-12-22 16:58:50:818 - info: [debug] [INST] 2015-12-22 21:58:50 +0000 Debug: Got new command 5 from instruments: au.getElementById(‘Add’)
2015-12-22 16:58:50:886 - info: [debug] [INST] 2015-12-22 21:58:50 +0000 Debug: evaluating au.getElementById(‘Add’)
2015-12-22 16:58:50:926 - info: [debug] [INST] 2015-12-22 21:58:50 +0000 Debug: evaluation finished
2015-12-22 16:58:50:984 - info: [debug] [INST] 2015-12-22 21:58:50 +0000 Debug: responding with:
2015-12-22 16:58:51:098 - info: [debug] [INST] 2015-12-22 21:58:50 +0000 Debug: Running system command #6: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/submodules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:""}…
2015-12-22 16:58:51:496 - info: [debug] [INST] 2015-12-22 21:58:51 +0000 Debug: Got new command 6 from instruments: au.getElementByAccessibilityId(‘Add’)
2015-12-22 16:58:51:552 - info: [debug] [INST] 2015-12-22 21:58:51 +0000 Debug: evaluating au.getElementByAccessibilityId(‘Add’)
2015-12-22 16:58:51:561 - info: [debug] Socket data received (25 bytes)
2015-12-22 16:58:51:561 - info: [debug] Socket data being routed.
2015-12-22 16:58:51:561 - info: [debug] Got result from instruments: {“status”:0,“value”:""}
2015-12-22 16:58:51:561 - info: [debug] Id selector, ‘Add’, not found in Localizable.strings.
2015-12-22 16:58:51:562 - info: [debug] Pushing command to appium work queue: “au.getElementById(‘Add’)”
2015-12-22 16:58:51:562 - info: [debug] Sending command to instruments: au.getElementById(‘Add’)
2015-12-22 16:58:51:634 - info: [debug] [INST] 2015-12-22 21:58:51 +0000 Debug: evaluation finished
2015-12-22 16:58:51:842 - info: [debug] [INST] 2015-12-22 21:58:51 +0000 Debug: responding with:
2015-12-22 16:58:51:951 - info: [debug] [INST] 2015-12-22 21:58:51 +0000 Debug: Running system command #7: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/submodules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:""}…
2015-12-22 16:58:52:531 - info: [debug] Socket data received (25 bytes)
2015-12-22 16:58:52:531 - info: [debug] Socket data being routed.
2015-12-22 16:58:52:532 - info: [debug] Got result from instruments: {“status”:0,“value”:""}
2015-12-22 16:58:52:532 - info: [debug] Condition unmet after 1738ms. Timing out.
2015-12-22 16:58:52:532 - info: [debug] Responding to client with error: {“status”:7,“value”:{“message”:“An element could not be located on the page using the given search parameters.”,“origValue”:""},“sessionId”:“d09b491f-4d64-4490-a99d-cf06aad00fcf”}
2015-12-22 16:58:52:533 - info: <-- POST /wd/hub/session/d09b491f-4d64-4490-a99d-cf06aad00fcf/element 500 1738.817 ms - 179
2015-12-22 16:58:52:653 - info: [debug] [INST] 2015-12-22 21:58:52 +0000 Debug: Got new command 7 from instruments: au.getElementById(‘Add’)
2015-12-22 16:58:52:657 - info: [debug] [INST] 2015-12-22 21:58:52 +0000 Debug: evaluating au.getElementById(‘Add’)
2015-12-22 16:58:52:725 - info: [debug] [INST] 2015-12-22 21:58:52 +0000 Debug: evaluation finished