builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0168 starttime: 1495095344.98 results: success (0) buildid: 20170517234519 builduid: d606a0d112ad4a99b4296e2d4d930a05 revision: 0884adb687d1b4cf95820feae19939d931053ae7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 01:15:44.984923) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 01:15:44.985349) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 01:15:44.985624) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 01:15:45.009324) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 01:15:45.009670) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dy3MqpbtAR/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-18 01:15:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.64M=0.002s 2017-05-18 01:15:45 (7.64 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090960 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 01:15:45.122854) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 01:15:45.123185) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dy3MqpbtAR/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.021713 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 01:15:45.172556) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 01:15:45.172959) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0884adb687d1b4cf95820feae19939d931053ae7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0884adb687d1b4cf95820feae19939d931053ae7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dy3MqpbtAR/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-18 01:15:45,249 truncating revision to first 12 chars 2017-05-18 01:15:45,250 Setting DEBUG logging. 2017-05-18 01:15:45,250 attempt 1/10 2017-05-18 01:15:45,250 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0884adb687d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-18 01:15:46,818 unpacking tar archive at: mozilla-beta-0884adb687d1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.824512 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 01:15:47.020975) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 01:15:47.021527) ========= script_repo_revision: 0884adb687d1b4cf95820feae19939d931053ae7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 01:15:47.022075) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 01:15:47.022376) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 01:15:47.041850) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-05-18 01:15:47.042494) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dy3MqpbtAR/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 01:15:47 INFO - MultiFileLogger online at 20170518 01:15:47 in /builds/slave/test 01:15:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:15:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:15:47 INFO - {'append_to_log': False, 01:15:47 INFO - 'base_work_dir': '/builds/slave/test', 01:15:47 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:15:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:15:47 INFO - 'branch': 'Mozilla-Beta', 01:15:47 INFO - 'buildbot_json_path': 'buildprops.json', 01:15:47 INFO - 'config_files': ('talos/mac_config.py',), 01:15:47 INFO - 'default_actions': ('clobber', 01:15:47 INFO - 'read-buildbot-config', 01:15:47 INFO - 'download-and-extract', 01:15:47 INFO - 'populate-webroot', 01:15:47 INFO - 'create-virtualenv', 01:15:47 INFO - 'install', 01:15:47 INFO - 'run-tests'), 01:15:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:15:47 INFO - 'download_minidump_stackwalk': True, 01:15:47 INFO - 'download_symbols': 'ondemand', 01:15:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:15:47 INFO - 'tooltool.py': '/tools/tooltool.py', 01:15:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:15:47 INFO - '/tools/misc-python/virtualenv.py')}, 01:15:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:15:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:15:47 INFO - 'gecko_profile': False, 01:15:47 INFO - 'gecko_profile_interval': 0, 01:15:47 INFO - 'installer_path': 'installer.exe', 01:15:47 INFO - 'log_level': 'info', 01:15:47 INFO - 'log_name': 'talos', 01:15:47 INFO - 'log_to_console': True, 01:15:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:15:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:15:47 INFO - 'opt_config_files': (), 01:15:47 INFO - 'pip_index': False, 01:15:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:15:47 INFO - 'cmd': ('bash', 01:15:47 INFO - '-c', 01:15:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:15:47 INFO - 'enabled': True, 01:15:47 INFO - 'halt_on_failure': False, 01:15:47 INFO - 'name': 'check_screen_resolution'},), 01:15:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:15:47 INFO - 'cmd': ('bash', 01:15:47 INFO - '-c', 01:15:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:15:47 INFO - 'enabled': True, 01:15:47 INFO - 'halt_on_failure': False, 01:15:47 INFO - 'name': 'check_screen_resolution'},), 01:15:47 INFO - 'run_cmd_checks_enabled': True, 01:15:47 INFO - 'suite': 'dromaeojs', 01:15:47 INFO - 'system_bits': '32', 01:15:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:15:47 INFO - 'title': 't-yosemite-r7-0168', 01:15:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:15:47 INFO - 'use_talos_json': True, 01:15:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:15:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:15:47 INFO - 'work_dir': 'build'} 01:15:47 INFO - [mozharness: 2017-05-18 08:15:47.243386Z] Running clobber step. 01:15:47 INFO - Running pre-action listener: _resource_record_pre_action 01:15:47 INFO - Running main action method: clobber 01:15:47 INFO - rmtree: /builds/slave/test/build 01:15:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:15:49 INFO - Running post-action listener: _resource_record_post_action 01:15:49 INFO - [mozharness: 2017-05-18 08:15:49.540609Z] Finished clobber step (success) 01:15:49 INFO - [mozharness: 2017-05-18 08:15:49.540811Z] Running read-buildbot-config step. 01:15:49 INFO - Running pre-action listener: _resource_record_pre_action 01:15:49 INFO - Running main action method: read_buildbot_config 01:15:49 INFO - Using buildbot properties: 01:15:49 INFO - { 01:15:49 INFO - "project": "", 01:15:49 INFO - "product": "firefox", 01:15:49 INFO - "script_repo_revision": "production", 01:15:49 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 01:15:49 INFO - "repository": "", 01:15:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 01:15:49 INFO - "buildid": "20170517234519", 01:15:49 INFO - "pgo_build": "False", 01:15:49 INFO - "basedir": "/builds/slave/test", 01:15:49 INFO - "buildnumber": 11, 01:15:49 INFO - "slavename": "t-yosemite-r7-0168", 01:15:49 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 01:15:49 INFO - "platform": "yosemite_r7", 01:15:49 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 01:15:49 INFO - "branch": "mozilla-beta", 01:15:49 INFO - "repo_path": "releases/mozilla-beta", 01:15:49 INFO - "revision": "0884adb687d1b4cf95820feae19939d931053ae7", 01:15:49 INFO - "stage_platform": "macosx64", 01:15:49 INFO - "builduid": "d606a0d112ad4a99b4296e2d4d930a05", 01:15:49 INFO - "slavebuilddir": "test" 01:15:49 INFO - } 01:15:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 01:15:49 INFO - Running post-action listener: _resource_record_post_action 01:15:49 INFO - [mozharness: 2017-05-18 08:15:49.544264Z] Finished read-buildbot-config step (success) 01:15:49 INFO - [mozharness: 2017-05-18 08:15:49.544399Z] Running download-and-extract step. 01:15:49 INFO - Running pre-action listener: _resource_record_pre_action 01:15:49 INFO - Running main action method: download_and_extract 01:15:49 INFO - mkdir: /builds/slave/test/build/tests 01:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:15:49 INFO - trying https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 01:15:49 INFO - Downloading https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 01:15:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 01:15:50 INFO - Downloaded 1287 bytes. 01:15:50 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 01:15:50 INFO - Using the following test package requirements: 01:15:50 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:15:50 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 01:15:50 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 01:15:50 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:15:50 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 01:15:50 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:15:50 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 01:15:50 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 01:15:50 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:15:50 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 01:15:50 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 01:15:50 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:15:50 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 01:15:50 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:15:50 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 01:15:50 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:15:50 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 01:15:50 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:15:50 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 01:15:50 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 01:15:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 01:15:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 01:15:50 INFO - Fetch https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 01:15:52 INFO - Expected file size: 51225431 01:15:52 INFO - Obtained file size: 51225431 01:16:08 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 01:16:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 01:16:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 01:16:08 INFO - Fetch https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 01:16:10 INFO - Expected file size: 51225431 01:16:10 INFO - Obtained file size: 51225431 01:16:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 01:16:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 01:16:26 INFO - Fetch https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 01:16:27 INFO - Expected file size: 14404449 01:16:27 INFO - Obtained file size: 14404449 01:16:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:27 INFO - trying https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.dmg 01:16:27 INFO - Downloading https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 01:16:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 01:16:29 INFO - Downloaded 59543872 bytes. 01:16:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.dmg 01:16:29 INFO - mkdir: /builds/slave/test/properties 01:16:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:16:29 INFO - Writing to file /builds/slave/test/properties/build_url 01:16:29 INFO - Contents: 01:16:29 INFO - build_url:https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.dmg 01:16:30 INFO - Running post-action listener: _resource_record_post_action 01:16:30 INFO - Running post-action listener: set_extra_try_arguments 01:16:30 INFO - [mozharness: 2017-05-18 08:16:30.292206Z] Finished download-and-extract step (success) 01:16:30 INFO - [mozharness: 2017-05-18 08:16:30.292379Z] Running populate-webroot step. 01:16:30 INFO - Running pre-action listener: _resource_record_pre_action 01:16:30 INFO - Running main action method: populate_webroot 01:16:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:16:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:16:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:16:30 INFO - '--tppagecycles', 01:16:30 INFO - '7'], 01:16:30 INFO - 'tests': ['tsvgm']}}, 01:16:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:16:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:16:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:16:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:16:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:16:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:16:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:16:30 INFO - 'talos_options': ['--disable-e10s'], 01:16:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:16:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:16:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:16:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:16:30 INFO - 'talos_options': ['--disable-e10s'], 01:16:30 INFO - 'tests': ['damp', 'tps']}, 01:16:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:16:30 INFO - 'tests': ['damp', 'tps']}, 01:16:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:16:30 INFO - 'tests': ['dromaeo_dom']}, 01:16:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:16:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:16:30 INFO - 'tests': ['basic_compositor_video']}, 01:16:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:16:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:16:30 INFO - 'tests': ['a11yr', 01:16:30 INFO - 'ts_paint', 01:16:30 INFO - 'tpaint', 01:16:30 INFO - 'sessionrestore', 01:16:30 INFO - 'sessionrestore_no_auto_restore', 01:16:30 INFO - 'tabpaint']}, 01:16:30 INFO - 'other-e10s': {'tests': ['a11yr', 01:16:30 INFO - 'ts_paint', 01:16:30 INFO - 'tpaint', 01:16:30 INFO - 'sessionrestore', 01:16:30 INFO - 'sessionrestore_no_auto_restore', 01:16:30 INFO - 'tabpaint']}, 01:16:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:16:30 INFO - 'ts_paint', 01:16:30 INFO - 'tpaint', 01:16:30 INFO - 'sessionrestore', 01:16:30 INFO - 'sessionrestore_no_auto_restore', 01:16:30 INFO - 'tabpaint']}, 01:16:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:16:30 INFO - 'ts_paint', 01:16:30 INFO - 'tpaint', 01:16:30 INFO - 'sessionrestore', 01:16:30 INFO - 'sessionrestore_no_auto_restore', 01:16:30 INFO - 'tabpaint']}, 01:16:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:16:30 INFO - 'tests': ['a11yr', 01:16:30 INFO - 'ts_paint', 01:16:30 INFO - 'tpaint', 01:16:30 INFO - 'sessionrestore', 01:16:30 INFO - 'sessionrestore_no_auto_restore', 01:16:30 INFO - 'tabpaint']}, 01:16:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:16:30 INFO - 'tests': ['a11yr', 01:16:30 INFO - 'ts_paint', 01:16:30 INFO - 'tpaint', 01:16:30 INFO - 'sessionrestore', 01:16:30 INFO - 'sessionrestore_no_auto_restore', 01:16:30 INFO - 'tabpaint']}, 01:16:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:16:30 INFO - 'tests': ['tsvgx', 01:16:30 INFO - 'tsvgr_opacity', 01:16:30 INFO - 'tart', 01:16:30 INFO - 'tscrollx', 01:16:30 INFO - 'cart', 01:16:30 INFO - 'tsvg_static']}, 01:16:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:16:30 INFO - 'tsvgr_opacity', 01:16:30 INFO - 'tart', 01:16:30 INFO - 'tscrollx', 01:16:30 INFO - 'cart', 01:16:30 INFO - 'tsvg_static']}, 01:16:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:16:30 INFO - 'talos_options': ['--disable-e10s'], 01:16:30 INFO - 'tests': ['tp5o']}, 01:16:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:16:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:16:30 INFO - 'talos_options': ['--disable-e10s', 01:16:30 INFO - '--xperf_path', 01:16:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:16:30 INFO - 'tests': ['tp5n']}, 01:16:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:16:30 INFO - 'talos_options': ['--xperf_path', 01:16:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:16:30 INFO - 'tests': ['tp5n']}}, 01:16:30 INFO - 'talos.zip': {'path': '', 01:16:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:16:30 INFO - Running post-action listener: _resource_record_post_action 01:16:30 INFO - [mozharness: 2017-05-18 08:16:30.311553Z] Finished populate-webroot step (success) 01:16:30 INFO - [mozharness: 2017-05-18 08:16:30.311698Z] Running create-virtualenv step. 01:16:30 INFO - Running pre-action listener: _resource_record_pre_action 01:16:30 INFO - Running main action method: create_virtualenv 01:16:30 INFO - Creating virtualenv /builds/slave/test/build/venv 01:16:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:16:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:16:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:16:30 INFO - Using real prefix '/tools/python27' 01:16:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:16:31 INFO - Installing distribute.............................................................................................................................................................................................done. 01:16:33 INFO - Installing pip.................done. 01:16:33 INFO - Return code: 0 01:16:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:16:34 INFO - Reading from file tmpfile_stdout 01:16:34 INFO - Output received: 01:16:34 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 01:16:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1052d2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052d5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9778ca4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050b0d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a5be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:16:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:16:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 01:16:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:16:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:16:34 INFO - 'HOME': '/Users/cltbld', 01:16:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:16:34 INFO - 'LOGNAME': 'cltbld', 01:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:34 INFO - 'MOZ_NO_REMOTE': '1', 01:16:34 INFO - 'NO_EM_RESTART': '1', 01:16:34 INFO - 'PAGER': '/bin/cat', 01:16:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:34 INFO - 'PWD': '/builds/slave/test', 01:16:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:16:34 INFO - 'SHELL': '/bin/bash', 01:16:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 01:16:34 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 01:16:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:16:34 INFO - 'USER': 'cltbld', 01:16:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:16:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:34 INFO - 'XPC_FLAGS': '0x0', 01:16:34 INFO - 'XPC_SERVICE_NAME': '0', 01:16:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:16:34 INFO - Cleaning up... 01:16:34 INFO - Return code: 0 01:16:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1052d2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052d5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9778ca4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050b0d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a5be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:16:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:16:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 01:16:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:16:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:16:34 INFO - 'HOME': '/Users/cltbld', 01:16:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:16:34 INFO - 'LOGNAME': 'cltbld', 01:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:34 INFO - 'MOZ_NO_REMOTE': '1', 01:16:34 INFO - 'NO_EM_RESTART': '1', 01:16:34 INFO - 'PAGER': '/bin/cat', 01:16:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:34 INFO - 'PWD': '/builds/slave/test', 01:16:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:16:34 INFO - 'SHELL': '/bin/bash', 01:16:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 01:16:34 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 01:16:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:16:34 INFO - 'USER': 'cltbld', 01:16:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:16:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:34 INFO - 'XPC_FLAGS': '0x0', 01:16:34 INFO - 'XPC_SERVICE_NAME': '0', 01:16:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:35 INFO - Downloading/unpacking psutil>=3.1.1 01:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:16:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:16:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:16:37 INFO - Installing collected packages: psutil 01:16:37 INFO - Running setup.py install for psutil 01:16:37 INFO - building 'psutil._psutil_osx' extension 01:16:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:16:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:16:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:16:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 01:16:37 INFO - building 'psutil._psutil_posix' extension 01:16:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 01:16:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:16:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:16:37 INFO - ^ 01:16:37 INFO - 1 warning generated. 01:16:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 01:16:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:16:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:16:37 INFO - Successfully installed psutil 01:16:37 INFO - Cleaning up... 01:16:37 INFO - Return code: 0 01:16:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1052d2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052d5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9778ca4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050b0d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a5be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 01:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:16:37 INFO - 'HOME': '/Users/cltbld', 01:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:16:37 INFO - 'LOGNAME': 'cltbld', 01:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:37 INFO - 'MOZ_NO_REMOTE': '1', 01:16:37 INFO - 'NO_EM_RESTART': '1', 01:16:37 INFO - 'PAGER': '/bin/cat', 01:16:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:37 INFO - 'PWD': '/builds/slave/test', 01:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:16:37 INFO - 'SHELL': '/bin/bash', 01:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 01:16:37 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 01:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:16:37 INFO - 'USER': 'cltbld', 01:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:37 INFO - 'XPC_FLAGS': '0x0', 01:16:37 INFO - 'XPC_SERVICE_NAME': '0', 01:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:16:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:16:39 INFO - Installing collected packages: mozsystemmonitor 01:16:39 INFO - Running setup.py install for mozsystemmonitor 01:16:39 INFO - Successfully installed mozsystemmonitor 01:16:39 INFO - Cleaning up... 01:16:39 INFO - Return code: 0 01:16:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1052d2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052d5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9778ca4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050b0d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a5be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:16:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:16:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 01:16:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:16:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:16:39 INFO - 'HOME': '/Users/cltbld', 01:16:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:16:39 INFO - 'LOGNAME': 'cltbld', 01:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:39 INFO - 'MOZ_NO_REMOTE': '1', 01:16:39 INFO - 'NO_EM_RESTART': '1', 01:16:39 INFO - 'PAGER': '/bin/cat', 01:16:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:39 INFO - 'PWD': '/builds/slave/test', 01:16:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:16:39 INFO - 'SHELL': '/bin/bash', 01:16:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 01:16:39 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 01:16:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:16:39 INFO - 'USER': 'cltbld', 01:16:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:16:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:39 INFO - 'XPC_FLAGS': '0x0', 01:16:39 INFO - 'XPC_SERVICE_NAME': '0', 01:16:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:40 INFO - Downloading/unpacking jsonschema==2.5.1 01:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:16:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:41 INFO - Downloading functools32-3.2.3-2.tar.gz 01:16:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:16:41 INFO - warning: no files found matching '*.txt' 01:16:41 INFO - no previously-included directories found matching 'build' 01:16:41 INFO - no previously-included directories found matching 'dist' 01:16:41 INFO - no previously-included directories found matching '.git*' 01:16:41 INFO - Installing collected packages: jsonschema, functools32 01:16:41 INFO - Running setup.py install for functools32 01:16:41 INFO - warning: no files found matching '*.txt' 01:16:41 INFO - no previously-included directories found matching 'build' 01:16:41 INFO - no previously-included directories found matching 'dist' 01:16:41 INFO - no previously-included directories found matching '.git*' 01:16:41 INFO - Successfully installed jsonschema functools32 01:16:41 INFO - Cleaning up... 01:16:42 INFO - Return code: 0 01:16:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1052d2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052d5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9778ca4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050b0d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a5be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:16:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:16:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 01:16:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:16:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:16:42 INFO - 'HOME': '/Users/cltbld', 01:16:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:16:42 INFO - 'LOGNAME': 'cltbld', 01:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:42 INFO - 'MOZ_NO_REMOTE': '1', 01:16:42 INFO - 'NO_EM_RESTART': '1', 01:16:42 INFO - 'PAGER': '/bin/cat', 01:16:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:42 INFO - 'PWD': '/builds/slave/test', 01:16:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:16:42 INFO - 'SHELL': '/bin/bash', 01:16:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 01:16:42 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 01:16:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:16:42 INFO - 'USER': 'cltbld', 01:16:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:16:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:42 INFO - 'XPC_FLAGS': '0x0', 01:16:42 INFO - 'XPC_SERVICE_NAME': '0', 01:16:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:16:43 INFO - Cleaning up... 01:16:43 INFO - Return code: 0 01:16:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1052d2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052d5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9778ca4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050b0d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a5be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:16:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:16:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:16:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 01:16:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:16:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:16:43 INFO - 'HOME': '/Users/cltbld', 01:16:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:16:43 INFO - 'LOGNAME': 'cltbld', 01:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:43 INFO - 'MOZ_NO_REMOTE': '1', 01:16:43 INFO - 'NO_EM_RESTART': '1', 01:16:43 INFO - 'PAGER': '/bin/cat', 01:16:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:43 INFO - 'PWD': '/builds/slave/test', 01:16:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:16:43 INFO - 'SHELL': '/bin/bash', 01:16:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 01:16:43 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 01:16:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:16:43 INFO - 'USER': 'cltbld', 01:16:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:16:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:43 INFO - 'XPC_FLAGS': '0x0', 01:16:43 INFO - 'XPC_SERVICE_NAME': '0', 01:16:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:43 INFO - Downloading/unpacking blobuploader==1.2.4 01:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:45 INFO - Downloading blobuploader-1.2.4.tar.gz 01:16:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:16:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:16:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:46 INFO - Downloading docopt-0.6.1.tar.gz 01:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:16:46 INFO - Installing collected packages: blobuploader, requests, docopt 01:16:46 INFO - Running setup.py install for blobuploader 01:16:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:16:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:16:46 INFO - Running setup.py install for requests 01:16:47 INFO - Running setup.py install for docopt 01:16:47 INFO - Successfully installed blobuploader requests docopt 01:16:47 INFO - Cleaning up... 01:16:47 INFO - Return code: 0 01:16:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1052d2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052d5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9778ca4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050b0d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a5be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:16:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 01:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:16:47 INFO - 'HOME': '/Users/cltbld', 01:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:16:47 INFO - 'LOGNAME': 'cltbld', 01:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:47 INFO - 'MOZ_NO_REMOTE': '1', 01:16:47 INFO - 'NO_EM_RESTART': '1', 01:16:47 INFO - 'PAGER': '/bin/cat', 01:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:47 INFO - 'PWD': '/builds/slave/test', 01:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:16:47 INFO - 'SHELL': '/bin/bash', 01:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 01:16:47 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 01:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:16:47 INFO - 'USER': 'cltbld', 01:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:47 INFO - 'XPC_FLAGS': '0x0', 01:16:47 INFO - 'XPC_SERVICE_NAME': '0', 01:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:16:47 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-xUBMcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:16:47 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-nGbC4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:16:47 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-z28pZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:16:47 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-yPJ86U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:16:48 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-9nnnSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:16:48 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-rQGj2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:16:48 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-1BjT3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:16:48 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-5bLrdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:16:48 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-reE3l1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:16:48 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-v6L_Pn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:16:48 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-UhOGvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:16:48 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-J3s8rC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:16:48 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-c50yuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:16:49 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Ml2XHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:16:49 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-zF5ldJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:16:49 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-tJYTkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:16:49 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-UG4ygc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:16:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:16:49 INFO - Running setup.py install for manifestparser 01:16:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:16:49 INFO - Running setup.py install for mozcrash 01:16:49 INFO - Running setup.py install for mozdebug 01:16:49 INFO - Running setup.py install for mozdevice 01:16:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:16:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:16:50 INFO - Running setup.py install for mozfile 01:16:50 INFO - Running setup.py install for mozhttpd 01:16:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:16:50 INFO - Running setup.py install for mozinfo 01:16:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:16:50 INFO - Running setup.py install for mozInstall 01:16:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:16:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:16:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:16:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:16:50 INFO - Running setup.py install for mozleak 01:16:50 INFO - Running setup.py install for mozlog 01:16:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:16:50 INFO - Running setup.py install for moznetwork 01:16:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:16:51 INFO - Running setup.py install for mozprocess 01:16:51 INFO - Running setup.py install for mozprofile 01:16:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:16:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:16:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:16:51 INFO - Running setup.py install for mozrunner 01:16:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:16:51 INFO - Running setup.py install for mozscreenshot 01:16:51 INFO - Running setup.py install for moztest 01:16:51 INFO - Running setup.py install for mozversion 01:16:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:16:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:16:51 INFO - Cleaning up... 01:16:51 INFO - Return code: 0 01:16:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1052d2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052d5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9778ca4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050b0d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a5be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:16:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 01:16:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:16:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:16:52 INFO - 'HOME': '/Users/cltbld', 01:16:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:16:52 INFO - 'LOGNAME': 'cltbld', 01:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:52 INFO - 'MOZ_NO_REMOTE': '1', 01:16:52 INFO - 'NO_EM_RESTART': '1', 01:16:52 INFO - 'PAGER': '/bin/cat', 01:16:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:52 INFO - 'PWD': '/builds/slave/test', 01:16:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:16:52 INFO - 'SHELL': '/bin/bash', 01:16:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 01:16:52 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 01:16:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:16:52 INFO - 'USER': 'cltbld', 01:16:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:16:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:52 INFO - 'XPC_FLAGS': '0x0', 01:16:52 INFO - 'XPC_SERVICE_NAME': '0', 01:16:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:16:52 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-oeeTEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:16:52 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-60Ofoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:16:52 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-S9ng4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:16:52 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-vRJbxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:16:52 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-2Exi3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:16:52 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-_CUaSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:16:52 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-ptnFPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:16:53 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-tPczIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:16:53 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-aS0foZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:16:53 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-sxEO4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:16:53 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-HbaRY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:16:53 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-ZZbnmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:16:53 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-zI_TWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:16:53 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-QClZDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:16:53 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-j3iHoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:16:53 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-KbzXku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:16:53 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-PAFHI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:16:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:55 INFO - Downloading blessings-1.6.tar.gz 01:16:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:16:55 INFO - Installing collected packages: blessings 01:16:55 INFO - Running setup.py install for blessings 01:16:55 INFO - Successfully installed blessings 01:16:55 INFO - Cleaning up... 01:16:55 INFO - Return code: 0 01:16:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:16:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:16:56 INFO - Reading from file tmpfile_stdout 01:16:56 INFO - Current package versions: 01:16:56 INFO - blessings == 1.6 01:16:56 INFO - blobuploader == 1.2.4 01:16:56 INFO - docopt == 0.6.1 01:16:56 INFO - functools32 == 3.2.3-2 01:16:56 INFO - jsonschema == 2.5.1 01:16:56 INFO - manifestparser == 1.1 01:16:56 INFO - mozInstall == 1.12 01:16:56 INFO - mozcrash == 1.0 01:16:56 INFO - mozdebug == 0.1 01:16:56 INFO - mozdevice == 0.49 01:16:56 INFO - mozfile == 1.2 01:16:56 INFO - mozhttpd == 0.7 01:16:56 INFO - mozinfo == 0.9 01:16:56 INFO - mozleak == 0.1 01:16:56 INFO - mozlog == 3.4 01:16:56 INFO - moznetwork == 0.27 01:16:56 INFO - mozprocess == 0.25 01:16:56 INFO - mozprofile == 0.28 01:16:56 INFO - mozrunner == 6.13 01:16:56 INFO - mozscreenshot == 0.1 01:16:56 INFO - mozsystemmonitor == 0.3 01:16:56 INFO - moztest == 0.8 01:16:56 INFO - mozversion == 1.4 01:16:56 INFO - psutil == 3.1.1 01:16:56 INFO - requests == 1.2.3 01:16:56 INFO - wsgiref == 0.1.2 01:16:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1052d2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052d5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9778ca4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050b0d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a5be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:16:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 01:16:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:16:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:16:56 INFO - 'HOME': '/Users/cltbld', 01:16:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:16:56 INFO - 'LOGNAME': 'cltbld', 01:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:56 INFO - 'MOZ_NO_REMOTE': '1', 01:16:56 INFO - 'NO_EM_RESTART': '1', 01:16:56 INFO - 'PAGER': '/bin/cat', 01:16:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:56 INFO - 'PWD': '/builds/slave/test', 01:16:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:16:56 INFO - 'SHELL': '/bin/bash', 01:16:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 01:16:56 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 01:16:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:16:56 INFO - 'USER': 'cltbld', 01:16:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:16:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:56 INFO - 'XPC_FLAGS': '0x0', 01:16:56 INFO - 'XPC_SERVICE_NAME': '0', 01:16:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:16:56 INFO - Cleaning up... 01:16:56 INFO - Return code: 0 01:16:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1052d2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052d5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9778ca4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050b0d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a5be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:16:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 01:16:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:16:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:16:56 INFO - 'HOME': '/Users/cltbld', 01:16:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:16:56 INFO - 'LOGNAME': 'cltbld', 01:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:56 INFO - 'MOZ_NO_REMOTE': '1', 01:16:56 INFO - 'NO_EM_RESTART': '1', 01:16:56 INFO - 'PAGER': '/bin/cat', 01:16:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:56 INFO - 'PWD': '/builds/slave/test', 01:16:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:16:56 INFO - 'SHELL': '/bin/bash', 01:16:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 01:16:56 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 01:16:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:16:56 INFO - 'USER': 'cltbld', 01:16:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:16:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:56 INFO - 'XPC_FLAGS': '0x0', 01:16:56 INFO - 'XPC_SERVICE_NAME': '0', 01:16:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:16:56 INFO - Cleaning up... 01:16:56 INFO - Return code: 0 01:16:56 INFO - Running post-action listener: _resource_record_post_action 01:16:56 INFO - Running post-action listener: _start_resource_monitoring 01:16:56 INFO - Starting resource monitoring. 01:16:56 INFO - [mozharness: 2017-05-18 08:16:56.623450Z] Finished create-virtualenv step (success) 01:16:56 INFO - [mozharness: 2017-05-18 08:16:56.624421Z] Running install step. 01:16:56 INFO - Running pre-action listener: _resource_record_pre_action 01:16:56 INFO - Running main action method: install 01:16:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:16:56 INFO - Reading from file tmpfile_stdout 01:16:56 INFO - Detecting whether we're running mozinstall >=1.0... 01:16:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:16:56 INFO - Reading from file tmpfile_stdout 01:16:56 INFO - Output received: 01:16:56 INFO - Usage: mozinstall [options] installer 01:16:56 INFO - Options: 01:16:56 INFO - -h, --help show this help message and exit 01:16:56 INFO - -d DEST, --destination=DEST 01:16:56 INFO - Directory to install application into. [default: 01:16:56 INFO - "/builds/slave/test"] 01:16:56 INFO - --app=APP Application being installed. [default: firefox] 01:16:56 INFO - mkdir: /builds/slave/test/build/application 01:16:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 01:17:11 INFO - Reading from file tmpfile_stdout 01:17:11 INFO - Output received: 01:17:11 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 01:17:11 INFO - Running post-action listener: _resource_record_post_action 01:17:11 INFO - [mozharness: 2017-05-18 08:17:11.927507Z] Finished install step (success) 01:17:11 INFO - [mozharness: 2017-05-18 08:17:11.927662Z] Running run-tests step. 01:17:11 INFO - Running pre-action listener: _resource_record_pre_action 01:17:11 INFO - Running main action method: run_tests 01:17:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:17:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:17:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:17:11 INFO - 2017-05-18 01:17:11.942 screenresolution[1968:16189] starting screenresolution argv=screenresolution get 01:17:11 INFO - 2017-05-18 01:17:11.944 screenresolution[1968:16189] Display 0: 1600x1200x32@60 01:17:11 INFO - 2017-05-18 01:17:11.950 screenresolution[1969:16191] starting screenresolution argv=screenresolution list 01:17:11 INFO - Available Modes on Display 0 01:17:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:17:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:17:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:17:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:17:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:17:12 INFO - Intel Iris: 01:17:12 INFO - Chipset Model: Intel Iris 01:17:12 INFO - Type: GPU 01:17:12 INFO - Bus: Built-In 01:17:12 INFO - VRAM (Dynamic, Max): 1536 MB 01:17:12 INFO - Vendor: Intel (0x8086) 01:17:12 INFO - Device ID: 0x0a2e 01:17:12 INFO - Revision ID: 0x0009 01:17:12 INFO - Displays: 01:17:12 INFO - Display: 01:17:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:17:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:17:12 INFO - Display Serial Number: 1600x1200 60 01:17:12 INFO - Main Display: Yes 01:17:12 INFO - Mirror: Off 01:17:12 INFO - Online: Yes 01:17:12 INFO - Rotation: Supported 01:17:12 INFO - Return code: 0 01:17:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:17:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:17:12 INFO - Python 2.7.3 01:17:12 INFO - Return code: 0 01:17:12 INFO - grabbing minidump binary from tooltool 01:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:17:12 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050b0d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050a5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:17:12 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:17:12 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 01:17:12 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:17:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:17:12 INFO - Return code: 0 01:17:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:17:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:17:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:17:12 INFO - ENV: RUST_BACKTRACE is now 1 01:17:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:17:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:17:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:17:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0168 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:17:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dy3MqpbtAR/Render', 01:17:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:17:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:17:12 INFO - 'HOME': '/Users/cltbld', 01:17:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:17:12 INFO - 'LOGNAME': 'cltbld', 01:17:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:17:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:17:12 INFO - 'MOZ_NO_REMOTE': '1', 01:17:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:17:12 INFO - 'NO_EM_RESTART': '1', 01:17:12 INFO - 'PAGER': '/bin/cat', 01:17:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:17:12 INFO - 'PWD': '/builds/slave/test', 01:17:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:17:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:17:12 INFO - 'RUST_BACKTRACE': '1', 01:17:12 INFO - 'SHELL': '/bin/bash', 01:17:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners', 01:17:12 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 01:17:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:17:12 INFO - 'USER': 'cltbld', 01:17:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:17:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:17:12 INFO - 'XPC_FLAGS': '0x0', 01:17:12 INFO - 'XPC_SERVICE_NAME': '0', 01:17:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:17:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:17:12 INFO - mozversion application_buildid: 20170517234519 01:17:12 INFO - mozversion application_changeset: 0884adb687d1b4cf95820feae19939d931053ae7 01:17:12 INFO - mozversion application_display_name: Firefox 01:17:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:17:12 INFO - mozversion application_name: Firefox 01:17:12 INFO - mozversion application_remotingname: firefox 01:17:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:17:12 INFO - mozversion application_vendor: Mozilla 01:17:12 INFO - mozversion application_version: 54.0 01:17:12 INFO - mozversion platform_buildid: 20170517234519 01:17:12 INFO - mozversion platform_changeset: 0884adb687d1b4cf95820feae19939d931053ae7 01:17:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:17:12 INFO - mozversion platform_version: 54.0 01:17:12 INFO - using testdate: 1495095432 01:17:12 INFO - actual date: 1495095432 01:17:12 INFO - starting webserver on 'localhost:52914' 01:17:12 INFO - SUITE-START | Running 2 tests 01:17:12 INFO - TEST-START | dromaeo_css 01:17:12 INFO - operating with platform_type : mac_ 01:17:12 INFO - Initialising browser for dromaeo_css test... 01:17:12 INFO - TEST-INFO | started process 1979 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpgOcLUz/profile http://localhost:52914/getInfo.html) 01:17:14 INFO - PROCESS | 1979 | __metrics Screen width/height:1600/1200 01:17:14 INFO - PROCESS | 1979 | colorDepth:24 01:17:14 INFO - PROCESS | 1979 | Browser inner width/height: 1024/689 01:17:14 INFO - PROCESS | 1979 | __metrics 01:17:15 INFO - PROCESS | 1979 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:17:15 INFO - PROCESS | 1979 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:17:15 INFO - TEST-INFO | 1979: exit 0 01:17:15 INFO - Browser initialized. 01:17:15 INFO - Running cycle 1/1 for dromaeo_css test... 01:17:15 INFO - TEST-INFO | started process 1982 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpgOcLUz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:17:27 INFO - PROCESS | 1982 | RSS: Main: 164880384 01:17:27 INFO - PROCESS | 1982 | 01:20:31 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:52914/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52914/tests/dromaeo/cssquery-ext.html) 01:20:33 INFO - PROCESS | 1982 | RSS: Main: 294084608 01:20:33 INFO - PROCESS | 1982 | 01:23:36 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:52914/tests/dromaeo/cssquery-ext.html (next: http://localhost:52914/tests/dromaeo/cssquery-jquery.html) 01:23:36 INFO - PROCESS | 1982 | RSS: Main: 305696768 01:23:36 INFO - PROCESS | 1982 | 01:26:39 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:52914/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52914/tests/dromaeo/cssquery-mootools.html) 01:26:39 INFO - PROCESS | 1982 | RSS: Main: 296488960 01:26:39 INFO - PROCESS | 1982 | 01:29:42 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:52914/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52914/tests/dromaeo/cssquery-prototype.html) 01:29:42 INFO - PROCESS | 1982 | RSS: Main: 296210432 01:29:42 INFO - PROCESS | 1982 | 01:32:46 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:52914/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52914/tests/dromaeo/cssquery-yui.html) 01:32:47 INFO - PROCESS | 1982 | RSS: Main: 267997184 01:32:47 INFO - PROCESS | 1982 | 01:35:50 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:52914/tests/dromaeo/cssquery-yui.html (next: http://localhost:52914/tests/dromaeo/cssquery-dojo.html) 01:35:50 INFO - PROCESS | 1982 | RSS: Main: 420765696 01:35:50 INFO - PROCESS | 1982 | 01:35:50 INFO - PROCESS | 1982 | __start_tp_report 01:35:50 INFO - PROCESS | 1982 | _x_x_mozilla_page_load 01:35:50 INFO - PROCESS | 1982 | _x_x_mozilla_page_load_details 01:35:50 INFO - PROCESS | 1982 | |i|pagename|runs| 01:35:50 INFO - PROCESS | 1982 | |0;dojo.html;3536.4695295705646;3561.6616421439953;3562.857485700572;3591.138126849557;3631.5460567429072;6275.592086514379;6282.654454005039;6294.276158241798;6307.274663413708;6313.589616674913;6252.687365631723;6269.592476489025;6283;6311.873762524747;6321.841953951142;5632.605717599769;5654.37801841797;5672.17753425753;5729.312482502106;5760.53915686744;5550.72246387681;5570.6657600543895;5613.073842815951;5794.913076303858;5826.126081087828;5669.177969194464;5681.687507187103;5707.571932105088;5713.17159011943;5763.855903602423;5759.1937128802;5777.24895763548;5777.682227477487;5777.942220577825;5780.0751879699465;5569.275994120739;5677.772889084432;5694.373618901917;5698.601097923147;5729.856753581152;6102.359252278536;6119.1739115219325;6158.84602884927;6177.81466556004;6289.905651415233;6638.070670106189;6649.3350664933605;6656;6672.866542669168;6679.76620818269;4871;6672.566283191625;6747.460203183783;6752.763653272112;6775.966120169417;6115.635867915819;6374.872502549923;6380.106785050097;6387.457066149429;6388.456981156607;6580.374864387875;6778.72885084601;6821.658917054127;6856.41720453767;6905.689243984033;6538.313477084933;6540.182477190351;6551.934480655133;6560.737570497222;6570.638614876228;6656.600603963778;6659.167604049494;6739.696713647898;6739.865202695919;6760.661966901635;6340.619562826245;6399.7440102395385;6431.871362572722;6444.194475690539;6451.483881289486;6425.58233714807;6532.542722009509;6594.07682924391;6596.538242323088;6605.207375114921;5345.3852806926925;5378.139497680352;5390.434004429479;5397.190421436816;5434.728263586884;4712.363830882787;4811.687240329367;4827.637927155477;4849.733264670477;4865.270209468537;6181;6184.536159788034;6190.257169139642;6196.039613859859;6198.442140207313;6624.768133115318;6632.602043877287;6673.666316684146;6698.866022679519;6723.562968407038;6298.244210694746;6327.145835312174;6341.334159913143;6347.174867267226;6359.682015899187;5913.083472061838;5917.349091599921;5927.555433342516;5934.6142500737315;5941.940580594225;6583.835404115031;6670.399664030067;6846.7945961621235;6858.177018757781;6979.232284448605;6693.26374098839;6700.731970721116;6737.66311684404;6748.122744043243;6762.985552167018;6928.965355173192;6962.1297337832775;6973.302669732986;6985.79042628722;6996.475264355092;6345.238571371465;6419.35806419373;6451.3548645135115;6611.305812889577;6628.867422651423;5527.170924361217;6443.226812782496;6459.128017717548;6475.09348691193;6566.6388348641285;5670.432956704297;6474.352564743488;6510.804675859732;6525.347465253437;6535.705893234721;6500.609963402211;6508.284088750139;6509.153810004669;6534.509911756732;6543.6400997943665;6194.194754681863;6235.501159907287;6316.399942005502;6371.076193951758;6436.259830119409;6486.643234622141;6513.511486638616;6518.967405162944;6539.803805885831;6564.5076619252795;6343.9365606343345;6370.362963703593;6390;6400.263969643551;6413 01:35:50 INFO - PROCESS | 1982 | |1;ext.html;734.4858598980715;895.8611415230641;904.9547522623867;909.3770767024585;910.9134632209942;39486.407703884426;39864.20271594566;42064.78967605158;42094.78952605233;42210;40327.19345613093;40622.98442538931;40640.57757978472;40659.79670101653;40677.59322406775;8085.636146373398;8348.457350272243;8487.66049358025;8493.278071363942;8506.064332923374;8145.063317718449;11857.762844743143;12043.819342709867;12285.754284914296;12348.75302493954;12051.698707532338;12059.155859089868;12140.3322817245;12141.939290303537;12158.392080395988;12056.63830085099;12079.396030198543;12084.456199471046;12101.818472722916;12118.757624847542;11719.472623731952;11769.588064417705;11778.234414763014;11781.998530124949;11786.764264714744;42008.369874451906;42258.78870605658;42290.788546057076;42317.788411058056;42320.57679423197;3976.7812770297624;4016.357382818764;4026.7583944963103;4088.3049881520215;4096.242195193899;28825.423491530262;29633.555496667566;30290;31133.377332453223;31151;3179.4118088153764;3215.099772063826;3228.2413632796274;3228.8869889553753;3313.8343082845763;362077.18961405026;362139;362407;362460.18769905984;363336;55083;55166.448335516936;55180.724096379265;55218.06736764194;55231.17153242705;57043;57244;57463;57470.7126464365;57472.42527574754;15784.763228551581;15894.76157857633;15903.840961590467;15911.283992220378;15920.920395398181;13394.196348218944;13398;13419.932900335436;13452.5291614793;13505.459781608626;11722.296662200295;12897.806532902014;12907.483700652057;12941.158824676555;13048.151870128411;4896.730679812645;5812.7674893003805;5822.272215973004;5852.531797456193;5863.208466857005;911.2755359489222;935.219092058139;951.8001219743932;952.319091849331;976.1605019683067;25887.611685824726;25997.090101846345;29567.965121221085;30664;30731.846340768152;954.4941181173989;982.6806287956442;1026.4867566216892;1052.3317693264678;1060.5121644043672;15760.369585216464;15824.813139014619;15837.68324633501;15869.761953570705;16098.678026439406;435010;435987;436186;436868;437531;59034.40965590289;59037.70481147653;59090;59199;59243.70378148081;1475.1739026145608;1484.383980648019;1517.211050253862;1519.9164045977577;1525.4279645133074;23368.130549555655;29393.853030735565;29561;30289.24276893095;30352;47740.7612961933;48536;49132.75433622952;50591.73520662013;51008.48991510038;480906.59546703444;485027.5748621234;485477;485812;486041;30969.690303096682;31002;31037;31078.533821992234;31092.067237982897;40167.799161004004;40280.395794062526;40416.39375409312;41452.58547414487;41467;28621.713782861905;28679.569806452502;29317;29849.850750746107;29951.700482995762;28712.99504517332;28732.85633571819;28983.420331593672;29738.702612973593;29858.104256872328;33713.831430842685;33716;34466.82766586151;34531.755435896586;34533.654663453046;28602.57096143518;28986.13041608755;29171;29287.267818304714;29977.550336745404;19241;22100.784456855028;23750.643740344254;23769.762302376756;23786.52426951486 01:35:50 INFO - PROCESS | 1982 | |2;jquery.html;388.2700523016728;431.2324063167561;432.79442264924154;433.61331436478076;434.98260069597217;16996.4051258206;21444.35666929991;22100.668489972664;22593.322200334016;22877.940985311317;23470.88264558679;23637.881810590967;24169.39576510588;24285.51428971424;24531.509369812593;5643.012472817261;6729.427998620124;6980.267071957447;7042.54223475475;7052.61210633415;6904.240533541307;6967.338102880219;7033.437325013987;7035.683394247271;7246.275372462738;7611.010568626071;7709.845803083935;7721.613919304041;7722.652480638357;7726.227377262285;7466.4773465857415;7554.131274903374;7666.118396384431;7676.46264761467;7689.807754806175;7175.964120179366;7406.555606663617;7431.368333691643;7453.291937265951;7469.589172595505;21111.261105861373;25954.740452595423;26302.47395052088;26875.731242687518;26937.191884243504;2972.4649563078638;2986.3728616990456;3099.922501937447;3135.7648176386633;3158.5262210668584;16048.518544443685;16409;16442.75335869963;16449.6710065798;17670.204840782197;2182.71624688791;2197;2232.3414592695194;2237.407087121914;2298.747137814839;165722;170051;172497;176466.11766941083;177648;15180.963807238597;19435.931023793546;19911;19998;20178.065277271086;28501;29874;32182.356352872812;32237.710491580074;32282.83858580739;10506;10576.62981795649;10794.974477424632;10802.189835762349;10900.182486313559;9102.362834601514;9120.315976301805;9145.3598248122;9267.907320926839;9283.48940808262;9056.70505320594;9291;9414.717558473258;9430.3398762086;9468.337216394786;3478.513008178857;3543.8936831895085;3590.312553100091;3985.442038114666;3998.5212864807804;942.7501712046313;1004.1615251265275;1022.5960745505483;1022.6829682798357;1025.220832167558;20467.07898144587;21227.36317910465;21596.892015539823;21842.102632289192;24311.392215194763;864.2006144316482;865.7749583570289;921.2491819167491;974.3033730882453;1034.1984961654778;6536.738530458729;6578;6687.364700353459;6714.731410743516;6931.410830079399;761129;804797.952020484;812009;813895;838711.8064409761;161416.19291903466;173507;173796.262037378;176863;179298.10350948162;1265.9873401266054;1307.3071272225736;1334.4728832111261;1336.5923393365117;1337.2310921220337;19050.571207159795;20695.58608827845;20924.58150837005;21350.893245533673;21363;55838.72080639571;64278.67860660667;67573.66213168902;67627;68353.3164668347;87559;88666;91461;92691;93600.53199733958;11659.18385712992;11665.416729163677;11679.532818687157;11848;12023.819642705192;13146.934265328995;13156.473741050251;13196.406161722554;13309.068365214729;13548.322583870964;11139.275947063416;11636.709082273012;11764.941155296065;11848.585299514476;11887.881121188677;10590.311629744043;10685.465726713477;10695.732606684895;10957.726056848323;11110.722231944266;9717;11900.107491937966;12020.63938081859;12089.81865272004;12170.817437738264;10511.94744026275;11224.494897729459;11643.068554515448;11974.46114924813;11998.64004079879;11468.94265528667;11508.654740357802;11621.360825154698;11659.300441973508;11814.704632384259 01:35:50 INFO - PROCESS | 1982 | |3;mootools.html;1612.8467795559432;1641.343462614954;1668.5911951571866;1670.6575152093826;1672.882898197126;10909.07272881805;11613.825792613097;11631.069514438846;11633.825492617607;11641.825372619407;11057.50241239144;11306.265092768965;11371.919667631582;11597.478113484893;11599.768004639902;2450.0934654177936;2490.987545062272;2496.7253602103756;2498.600223964166;2502.2618327593314;2191.794513017842;2367.538330025646;2438.926832195037;2490.252924122765;2493.2769496845895;2413.698287714036;2469.0370755405406;2477.417806815406;2494.2143224884194;2496.4383013821925;2432.7202371727126;2435.719892212383;2449.4856080223085;2463.334899577113;2487.7263501015054;2430.805535557151;2439.1584903208363;2450.6079027355536;2455.6439316299216;2455.9877200613882;11018.889811101866;11259.493322800412;11266.66200014001;11269.154813389028;11278.266912650652;5670.432956704297;5834.241548598655;5844.824655260349;5853.648781073107;5854.326752423483;3632;3668.578113516953;3750.1562148516637;3758.868439604628;3824.388097904322;1247.5758242197605;1250.0499620288556;1286.4018231522293;1299.3633119771243;1301.5184381778802;14066.718665626733;14311.713765724731;14754.999999999893;15670.451534196249;15863.286152123066;4634.119517291704;4753.287006948985;4783.976080119577;4827.275908613736;4838.879028024257;10411.271211015157;11965.282083075042;11984.58053968107;11997.580084696994;12014.819777703342;4321.719088259316;5692.829215123553;5840.328362238397;5841.299044114733;5843.970780146072;4624.583787459145;5184.5593124584075;5373.274607927958;5385.488378604026;5462.2079798429;3942;3969.226000929849;3990.3615421532418;4019.0354314964197;4047.5750046245307;1039.812833689943;1043.7338478687886;1045.3988956350129;1055.6780182044397;1058.5977328615231;926.3376685669779;949.2406075139999;950.7242899559051;960.8030353777459;1001.0590045357341;6431;6628.668566571652;6648.434883034899;6697.894847350132;6902.240753517109;979.6816034788723;989;1003.6838395905266;1035.53400969564;1040.079529616295;4180.770057646859;4207.137536804948;4219.261629214875;4253.383259427427;4314.7411155330155;13374.598762037154;13993.020488565906;14017.878569714398;14038.227897465737;14062.507812226522;13086.869131308755;13619.659508512168;13850.238236897067;13878.861211387959;13913.443462261395;10273.434961077213;10524.842127368096;10559.577616895238;10564.735881603021;10574.471276436147;6493.448056915026;6719.160104986877;6907.758228461979;6919.550229235084;6938.895916561357;10951.780964380827;11193.496292666687;12773.8083928743;12800.551980680631;12819.423125959167;12599.496020159457;12929.353532323534;12968.416421260878;13101.410436530188;13179.67050823737;8035.116137225017;8138.941937548081;9403.717888463358;9616.807663846825;9624.951875240578;10681.092107170667;10694.465276735977;10800.189985750943;10805.783884322427;10842.403667798348;8936.597853096757;9082.727518174466;9145.176934075966;9150.816983660423;9173.541322933694;8922.776930576787;8936.508492033001;8992.235659968976;9007.77480562965;9023.81952360936;7877.7636670899965;8052.5571093590415;8165.591720414074;8169.428140030141;8178.795530111557;5989.221401217814;6038.879222415615;6115.174451448997;6121.938780612137;6258.468030217483;4672.7663616818345;5523.972380138234;5995.16067750524;6017.15759793638;6166.81499555014 01:35:50 INFO - PROCESS | 1982 | |4;prototype.html;359.39262646128043;373.342916467018;374.78449891312505;391.43242298666934;391.4636947382087;5202.739863006848;5244.501772331632;5247.274435151043;5281.260623512707;5395.325584301962;4669.392978912737;4787.44944331402;4897.804087836491;5047.47001564836;5053.772580233889;3969.6928832217077;4275.657947364203;4299.742015479073;4327.7619730914785;4341;4216.76511448931;4284.6786491013145;4320.719153255044;4331.696781225315;4372.693911426201;3912.608739126079;4141.544430112685;4290.442242508485;4292.720973136752;4300.268954277781;3702.2965636528975;4115.32097203961;4175.728577642443;4257.914841703164;4264.893377665568;3207.454732695424;3494.9379865879255;3949.7755695734413;3966.3257246267917;3968.5316432920004;4601.286800545888;4696.342512048316;4991.750412479398;5061.873453163664;5071.442141364447;4562.520935301779;4703.64722645803;4711.316859055418;4749.786259618326;4778.593819525345;5432.511074003319;5466.751918158533;5485.561155107582;5518.310211223597;5544.528715059225;3191.420246977738;4113.0386121992115;4424.491183513905;4430.379746835446;4438.400815889846;4868.610511159063;4948.0846043481715;5265.894682106336;5582.720863956786;5710.571707121982;3907.925320536875;4465.419495465569;4490.797914093873;4492.011757413364;4507.639388848885;3896.830950714831;4370;4538.2738761797955;4582.656300777455;4739.66822322441;4123.773192474383;4181.958180418218;4227.957720422756;4620.142181278603;4853.878653033632;4983.352164218701;5345.572354211654;5419.729013549307;5442.564594832404;5468.48049435303;1264.5574049082932;1369.9246541440118;1546.6442718174658;1558.3299181352127;1559.6334861307585;1063.9573218246162;1096.1365678346683;1097.067492631257;1169.5497233565138;1180.8132826509302;4348.695591308641;4444.044530425909;4472.74126566082;4828.623842204955;4917.606591472745;5071.290019397287;5571.13647384656;5584.21820945068;5587.413321601173;5623.3289187636365;4069.7965101745385;4328.311958336251;4594.047529799888;5051.646384753032;5053.772580233898;2857.842818644953;2874.6837847836723;2884.8940273600388;2888.6678032026166;2902.3904979954023;5060.7216603086445;5183.611229157828;5320.893582128336;5761.251037365116;5777.88444231113;5185.714785686748;5393.7572809223675;5465.289512363447;5694.886102278014;5697.575606098475;4978.477259887732;5364.8122315718765;5833.812320379858;5925.34821169671;5941.48492134503;5310.698878774725;5588.636738611977;5633.690147041788;5634.971825140848;5649.316574627183;4780.976095119502;5422.539084177889;5447.918281225706;5522.751476183662;5663.716814159358;5655.547556195576;5723.771049157987;5807.328628830785;5831.271091113611;5856.355800861987;4387.341898715091;4588.931166032446;4593.770311484479;4598.402207712976;4617.930731039104;5254.476201901406;5268.341457317835;5439.912017596575;5607.607467477237;5613.971930140323;4882.9755851221935;5265.815696450606;5457;5464.91802622953;5541.639793413416;4308;4870;5474.452554744494;5529.806456773994;5533.229366602649;4582.633389328912;5070;5087.567556757717;5338.57291416678;5418.945810542002;4470.457692096222;4601.44782626087;4828.758562071953;5088.338515992898;5638.37977822431;4148.522919864255;4174.165166966558;4602.502098405169;4621.907561848811;4897.0656590645785 01:35:50 INFO - PROCESS | 1982 | |5;yui.html;777.3314949143739;809.3525179856114;809.6882700160438;854.7649396415981;907.2560500389679;16003.439879604217;17466;17500.21249043793;17532.211050502727;17539.94760314382;16191.676166476664;16214.108224047703;16346.83653163468;16374.345026198938;16455.91772041141;963.2823546457898;1003.4832061488303;1018.1193267823317;1034.9586016559329;1037.2946396450411;1021.4177918586408;1025.8922813104627;1027.193652982409;1028.7582418131733;1028.8250997330472;1057.841323801432;1058.0107599394591;1059.7350662334416;1060.9973574971655;1061.400308825515;1060.0512541275516;1061.5912873543664;1061.75048863517;1062.2935747727752;1062.4846949229618;789.0294937227243;789.6209819286775;789.7078081109969;790.7548659915387;790.8457850719112;17502.578745160237;17589.384371546934;18206.998615076165;18515.629687406312;18792.906035469736;14652.853471465362;14856;14973.401063957428;15042.398304067825;15156.090634561962;11962.461689224006;12244.510219591206;12357;12413.44139513724;12516.874831251662;3596.4245720684567;3824.445455408978;3859.903502412434;3962.920741585181;3994.7003974701724;94533.99999999932;125022;125614;127349;128077;11479.240724420948;11714.765704685859;11715.941420292844;11775.470103845348;11917.165798394202;23474.061037558648;23538;23541.94061267247;23584.29247122589;23729.7627023731;7838.804029899298;7968.083670377865;8292.17078292166;8355;8368.874466883002;4550.863474095716;4782.569568738761;4824.276358546176;4948.579370753526;4951.183054795991;3705.7591256568235;3811.3520701480816;3811.714121440903;3813.580506144322;3814.065553939303;665.6704931059156;677.5697432130534;695.2074634916199;699.3111784891878;699.755085719994;691.8270432391902;701.6246308225075;718.3606590134784;758.6206896551724;776.7941495503696;9374.937713021483;10514.789704206027;10718.303310284806;10780.460976951277;10935.343879367263;671.9563228390139;728.3372131360543;738.5568658804674;741.1550832051465;753.5365750063781;4462.196804575143;4688.132695451352;4900.901981960341;5156.182395973701;5293.470652934676;81813.59093204615;87235;88203;100788.49605752071;102296;87624.56187719021;89253;89384.5530772342;89753;92601;65111.67444162749;67637.3236267631;68788.6560567204;71281;72768.63615681887;11270;11324;11337.546498139982;11351.542042840176;11441.942790285995;44584.885377865816;45230.77384613187;46833.2975005382;48924.77688057827;50250.49749502458;60029.39970600238;60828.6958565222;61036.38963610485;61320;62396.68801655962;11254.324740515594;11546.653600392001;11864.822027669765;12896.871031289567;14444.422223110958;11080.279781814157;13055.934720326717;13319;13339.66650833737;13893.096948698303;14396.99221054516;14460;14730.926345368205;14896.776548351567;15066.32201550911;14514.927425362806;14529.837612991147;14650.19423931694;14955.252237388304;15175;13854.307284635526;14068.577942661737;14836.332365043383;14927.029743066665;15271.541853744406;10530.57877684884;10666.679999600025;10788.298760580537;10871.565137394417;11102.444877756241;11004.779904401707;12220.205686630341;12253.387330633612;12342.44458999365;12797.808032879328 01:35:50 INFO - PROCESS | 1982 | __end_tp_report 01:35:50 INFO - PROCESS | 1982 | __start_cc_report 01:35:50 INFO - PROCESS | 1982 | _x_x_mozilla_cycle_collect,1980 01:35:50 INFO - PROCESS | 1982 | __end_cc_report 01:35:50 INFO - PROCESS | 1982 | __startTimestamp1495096550754__endTimestamp 01:35:50 INFO - PROCESS | 1982 | 01:35:50 INFO - PROCESS | 1982 | ------- Summary: start ------- 01:35:50 INFO - PROCESS | 1982 | Number of tests: 6 01:35:50 INFO - PROCESS | 1982 | 01:35:50 INFO - PROCESS | 1982 | [#0] dojo.html Cycles:165 Average:6178.53 Median:6374.87 stddev:669.16 (10.5%) stddev-sans-first:638.31 01:35:50 INFO - PROCESS | 1982 | Values: 3536.5 3561.7 3562.9 3591.1 3631.5 6275.6 6282.7 6294.3 6307.3 6313.6 6252.7 6269.6 6283.0 6311.9 6321.8 5632.6 5654.4 5672.2 5729.3 5760.5 5550.7 5570.7 5613.1 5794.9 5826.1 5669.2 5681.7 5707.6 5713.2 5763.9 5759.2 5777.2 5777.7 5777.9 5780.1 5569.3 5677.8 5694.4 5698.6 5729.9 6102.4 6119.2 6158.8 6177.8 6289.9 6638.1 6649.3 6656.0 6672.9 6679.8 4871.0 6672.6 6747.5 6752.8 6776.0 6115.6 6374.9 6380.1 6387.5 6388.5 6580.4 6778.7 6821.7 6856.4 6905.7 6538.3 6540.2 6551.9 6560.7 6570.6 6656.6 6659.2 6739.7 6739.9 6760.7 6340.6 6399.7 6431.9 6444.2 6451.5 6425.6 6532.5 6594.1 6596.5 6605.2 5345.4 5378.1 5390.4 5397.2 5434.7 4712.4 4811.7 4827.6 4849.7 4865.3 6181.0 6184.5 6190.3 6196.0 6198.4 6624.8 6632.6 6673.7 6698.9 6723.6 6298.2 6327.1 6341.3 6347.2 6359.7 5913.1 5917.3 5927.6 5934.6 5941.9 6583.8 6670.4 6846.8 6858.2 6979.2 6693.3 6700.7 6737.7 6748.1 6763.0 6929.0 6962.1 6973.3 6985.8 6996.5 6345.2 6419.4 6451.4 6611.3 6628.9 5527.2 6443.2 6459.1 6475.1 6566.6 5670.4 6474.4 6510.8 6525.3 6535.7 6500.6 6508.3 6509.2 6534.5 6543.6 6194.2 6235.5 6316.4 6371.1 6436.3 6486.6 6513.5 6519.0 6539.8 6564.5 6343.9 6370.4 6390.0 6400.3 6413.0 01:35:50 INFO - PROCESS | 1982 | 01:35:50 INFO - PROCESS | 1982 | [#1] ext.html Cycles:180 Average:57302.28 Median:3271.36 stddev:114191.03 (3490.6%) stddev-sans-first:114432.38 01:35:50 INFO - PROCESS | 1982 | Values: 734.5 895.9 905.0 909.4 910.9 39486.4 39864.2 42064.8 42094.8 42210.0 40327.2 40623.0 40640.6 40659.8 40677.6 8085.6 8348.5 8487.7 8493.3 8506.1 8145.1 11857.8 12043.8 12285.8 12348.8 12051.7 12059.2 12140.3 12141.9 12158.4 12056.6 12079.4 12084.5 12101.8 12118.8 11719.5 11769.6 11778.2 11782.0 11786.8 42008.4 42258.8 42290.8 42317.8 42320.6 3976.8 4016.4 4026.8 4088.3 4096.2 28825.4 29633.6 30290.0 31133.4 31151.0 3179.4 3215.1 3228.2 3228.9 3313.8 362077.2 362139.0 362407.0 362460.2 363336.0 55083.0 55166.4 55180.7 55218.1 55231.2 57043.0 57244.0 57463.0 57470.7 57472.4 15784.8 15894.8 15903.8 15911.3 15920.9 13394.2 13398.0 13419.9 13452.5 13505.5 11722.3 12897.8 12907.5 12941.2 13048.2 4896.7 5812.8 5822.3 5852.5 5863.2 911.3 935.2 951.8 952.3 976.2 25887.6 25997.1 29568.0 30664.0 30731.8 954.5 982.7 1026.5 1052.3 1060.5 15760.4 15824.8 15837.7 15869.8 16098.7 435010.0 435987.0 436186.0 436868.0 437531.0 59034.4 59037.7 59090.0 59199.0 59243.7 1475.2 1484.4 1517.2 1519.9 1525.4 23368.1 29393.9 29561.0 30289.2 30352.0 47740.8 48536.0 49132.8 50591.7 51008.5 480906.6 485027.6 485477.0 485812.0 486041.0 30969.7 31002.0 31037.0 31078.5 31092.1 40167.8 40280.4 40416.4 41452.6 41467.0 28621.7 28679.6 29317.0 29849.9 29951.7 28713.0 28732.9 28983.4 29738.7 29858.1 33713.8 33716.0 34466.8 34531.8 34533.7 28602.6 28986.1 29171.0 29287.3 29977.6 19241.0 22100.8 23750.6 23769.8 23786.5 01:35:50 INFO - PROCESS | 1982 | 01:35:50 INFO - PROCESS | 1982 | [#2] jquery.html Cycles:180 Average:45946.97 Median:23903.64 stddev:134997.23 (564.8%) stddev-sans-first:135332.59 01:35:50 INFO - PROCESS | 1982 | Values: 388.3 431.2 432.8 433.6 435.0 16996.4 21444.4 22100.7 22593.3 22877.9 23470.9 23637.9 24169.4 24285.5 24531.5 5643.0 6729.4 6980.3 7042.5 7052.6 6904.2 6967.3 7033.4 7035.7 7246.3 7611.0 7709.8 7721.6 7722.7 7726.2 7466.5 7554.1 7666.1 7676.5 7689.8 7176.0 7406.6 7431.4 7453.3 7469.6 21111.3 25954.7 26302.5 26875.7 26937.2 2972.5 2986.4 3099.9 3135.8 3158.5 16048.5 16409.0 16442.8 16449.7 17670.2 2182.7 2197.0 2232.3 2237.4 2298.7 165722.0 170051.0 172497.0 176466.1 177648.0 15181.0 19435.9 19911.0 19998.0 20178.1 28501.0 29874.0 32182.4 32237.7 32282.8 10506.0 10576.6 10795.0 10802.2 10900.2 9102.4 9120.3 9145.4 9267.9 9283.5 9056.7 9291.0 9414.7 9430.3 9468.3 3478.5 3543.9 3590.3 3985.4 3998.5 942.8 1004.2 1022.6 1022.7 1025.2 20467.1 21227.4 21596.9 21842.1 24311.4 864.2 865.8 921.2 974.3 1034.2 6536.7 6578.0 6687.4 6714.7 6931.4 761129.0 804798.0 812009.0 813895.0 838711.8 161416.2 173507.0 173796.3 176863.0 179298.1 1266.0 1307.3 1334.5 1336.6 1337.2 19050.6 20695.6 20924.6 21350.9 21363.0 55838.7 64278.7 67573.7 67627.0 68353.3 87559.0 88666.0 91461.0 92691.0 93600.5 11659.2 11665.4 11679.5 11848.0 12023.8 13146.9 13156.5 13196.4 13309.1 13548.3 11139.3 11636.7 11764.9 11848.6 11887.9 10590.3 10685.5 10695.7 10957.7 11110.7 9717.0 11900.1 12020.6 12089.8 12170.8 10511.9 11224.5 11643.1 11974.5 11998.6 11468.9 11508.7 11621.4 11659.3 11814.7 01:35:50 INFO - PROCESS | 1982 | 01:35:50 INFO - PROCESS | 1982 | [#3] mootools.html Cycles:180 Average:6844.65 Median:2488.99 stddev:4268.99 (171.5%) stddev-sans-first:4262.86 01:35:50 INFO - PROCESS | 1982 | Values: 1612.8 1641.3 1668.6 1670.7 1672.9 10909.1 11613.8 11631.1 11633.8 11641.8 11057.5 11306.3 11371.9 11597.5 11599.8 2450.1 2491.0 2496.7 2498.6 2502.3 2191.8 2367.5 2438.9 2490.3 2493.3 2413.7 2469.0 2477.4 2494.2 2496.4 2432.7 2435.7 2449.5 2463.3 2487.7 2430.8 2439.2 2450.6 2455.6 2456.0 11018.9 11259.5 11266.7 11269.2 11278.3 5670.4 5834.2 5844.8 5853.6 5854.3 3632.0 3668.6 3750.2 3758.9 3824.4 1247.6 1250.0 1286.4 1299.4 1301.5 14066.7 14311.7 14755.0 15670.5 15863.3 4634.1 4753.3 4784.0 4827.3 4838.9 10411.3 11965.3 11984.6 11997.6 12014.8 4321.7 5692.8 5840.3 5841.3 5844.0 4624.6 5184.6 5373.3 5385.5 5462.2 3942.0 3969.2 3990.4 4019.0 4047.6 1039.8 1043.7 1045.4 1055.7 1058.6 926.3 949.2 950.7 960.8 1001.1 6431.0 6628.7 6648.4 6697.9 6902.2 979.7 989.0 1003.7 1035.5 1040.1 4180.8 4207.1 4219.3 4253.4 4314.7 13374.6 13993.0 14017.9 14038.2 14062.5 13086.9 13619.7 13850.2 13878.9 13913.4 10273.4 10524.8 10559.6 10564.7 10574.5 6493.4 6719.2 6907.8 6919.6 6938.9 10951.8 11193.5 12773.8 12800.6 12819.4 12599.5 12929.4 12968.4 13101.4 13179.7 8035.1 8138.9 9403.7 9616.8 9625.0 10681.1 10694.5 10800.2 10805.8 10842.4 8936.6 9082.7 9145.2 9150.8 9173.5 8922.8 8936.5 8992.2 9007.8 9023.8 7877.8 8052.6 8165.6 8169.4 8178.8 5989.2 6038.9 6115.2 6121.9 6258.5 4672.8 5524.0 5995.2 6017.2 6166.8 01:35:50 INFO - PROCESS | 1982 | 01:35:50 INFO - PROCESS | 1982 | [#4] prototype.html Cycles:180 Average:4513.06 Median:4779.78 stddev:1260.24 (26.4%) stddev-sans-first:1224.60 01:35:50 INFO - PROCESS | 1982 | Values: 359.4 373.3 374.8 391.4 391.5 5202.7 5244.5 5247.3 5281.3 5395.3 4669.4 4787.4 4897.8 5047.5 5053.8 3969.7 4275.7 4299.7 4327.8 4341.0 4216.8 4284.7 4320.7 4331.7 4372.7 3912.6 4141.5 4290.4 4292.7 4300.3 3702.3 4115.3 4175.7 4257.9 4264.9 3207.5 3494.9 3949.8 3966.3 3968.5 4601.3 4696.3 4991.8 5061.9 5071.4 4562.5 4703.6 4711.3 4749.8 4778.6 5432.5 5466.8 5485.6 5518.3 5544.5 3191.4 4113.0 4424.5 4430.4 4438.4 4868.6 4948.1 5265.9 5582.7 5710.6 3907.9 4465.4 4490.8 4492.0 4507.6 3896.8 4370.0 4538.3 4582.7 4739.7 4123.8 4182.0 4228.0 4620.1 4853.9 4983.4 5345.6 5419.7 5442.6 5468.5 1264.6 1369.9 1546.6 1558.3 1559.6 1064.0 1096.1 1097.1 1169.5 1180.8 4348.7 4444.0 4472.7 4828.6 4917.6 5071.3 5571.1 5584.2 5587.4 5623.3 4069.8 4328.3 4594.0 5051.6 5053.8 2857.8 2874.7 2884.9 2888.7 2902.4 5060.7 5183.6 5320.9 5761.3 5777.9 5185.7 5393.8 5465.3 5694.9 5697.6 4978.5 5364.8 5833.8 5925.3 5941.5 5310.7 5588.6 5633.7 5635.0 5649.3 4781.0 5422.5 5447.9 5522.8 5663.7 5655.5 5723.8 5807.3 5831.3 5856.4 4387.3 4588.9 4593.8 4598.4 4617.9 5254.5 5268.3 5439.9 5607.6 5614.0 4883.0 5265.8 5457.0 5464.9 5541.6 4308.0 4870.0 5474.5 5529.8 5533.2 4582.6 5070.0 5087.6 5338.6 5418.9 4470.5 4601.4 4828.8 5088.3 5638.4 4148.5 4174.2 4602.5 4621.9 4897.1 01:35:50 INFO - PROCESS | 1982 | 01:35:50 INFO - PROCESS | 1982 | [#5] yui.html Cycles:180 Average:20585.02 Median:17483.11 stddev:29076.01 (166.3%) stddev-sans-first:29119.53 01:35:50 INFO - PROCESS | 1982 | Values: 777.3 809.4 809.7 854.8 907.3 16003.4 17466.0 17500.2 17532.2 17539.9 16191.7 16214.1 16346.8 16374.3 16455.9 963.3 1003.5 1018.1 1035.0 1037.3 1021.4 1025.9 1027.2 1028.8 1028.8 1057.8 1058.0 1059.7 1061.0 1061.4 1060.1 1061.6 1061.8 1062.3 1062.5 789.0 789.6 789.7 790.8 790.8 17502.6 17589.4 18207.0 18515.6 18792.9 14652.9 14856.0 14973.4 15042.4 15156.1 11962.5 12244.5 12357.0 12413.4 12516.9 3596.4 3824.4 3859.9 3962.9 3994.7 94534.0 125022.0 125614.0 127349.0 128077.0 11479.2 11714.8 11715.9 11775.5 11917.2 23474.1 23538.0 23541.9 23584.3 23729.8 7838.8 7968.1 8292.2 8355.0 8368.9 4550.9 4782.6 4824.3 4948.6 4951.2 3705.8 3811.4 3811.7 3813.6 3814.1 665.7 677.6 695.2 699.3 699.8 691.8 701.6 718.4 758.6 776.8 9374.9 10514.8 10718.3 10780.5 10935.3 672.0 728.3 738.6 741.2 753.5 4462.2 4688.1 4900.9 5156.2 5293.5 81813.6 87235.0 88203.0 100788.5 102296.0 87624.6 89253.0 89384.6 89753.0 92601.0 65111.7 67637.3 68788.7 71281.0 72768.6 11270.0 11324.0 11337.5 11351.5 11441.9 44584.9 45230.8 46833.3 48924.8 50250.5 60029.4 60828.7 61036.4 61320.0 62396.7 11254.3 11546.7 11864.8 12896.9 14444.4 11080.3 13055.9 13319.0 13339.7 13893.1 14397.0 14460.0 14730.9 14896.8 15066.3 14514.9 14529.8 14650.2 14955.3 15175.0 13854.3 14068.6 14836.3 14927.0 15271.5 10530.6 10666.7 10788.3 10871.6 11102.4 11004.8 12220.2 12253.4 12342.4 12797.8 01:35:50 INFO - PROCESS | 1982 | -------- Summary: end -------- 01:35:50 INFO - PROCESS | 1982 | 01:35:51 INFO - TEST-INFO | 1982: exit 0 01:35:51 INFO - TEST-OK | dromaeo_css | took 1118847ms 01:35:51 INFO - TEST-START | kraken 01:35:51 INFO - operating with platform_type : mac_ 01:35:51 INFO - Initialising browser for kraken test... 01:35:51 INFO - TEST-INFO | started process 1987 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpqJLs6M/profile http://localhost:52914/getInfo.html) 01:35:53 INFO - PROCESS | 1987 | __metrics Screen width/height:1600/1200 01:35:53 INFO - PROCESS | 1987 | colorDepth:24 01:35:53 INFO - PROCESS | 1987 | Browser inner width/height: 1024/689 01:35:53 INFO - PROCESS | 1987 | __metrics 01:35:53 INFO - PROCESS | 1987 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:35:53 INFO - PROCESS | 1987 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:35:54 INFO - TEST-INFO | 1987: exit 0 01:35:54 INFO - Browser initialized. 01:35:54 INFO - Running cycle 1/1 for kraken test... 01:35:54 INFO - TEST-INFO | started process 1989 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpqJLs6M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:36:06 INFO - PROCESS | 1989 | RSS: Main: 165203968 01:36:06 INFO - PROCESS | 1989 | 01:36:09 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52914/tests/kraken/driver.html?testName=audio-beat-detection) 01:36:10 INFO - PROCESS | 1989 | RSS: Main: 215674880 01:36:10 INFO - PROCESS | 1989 | 01:36:12 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52914/tests/kraken/driver.html?testName=audio-dft) 01:36:13 INFO - PROCESS | 1989 | RSS: Main: 231440384 01:36:13 INFO - PROCESS | 1989 | 01:36:16 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52914/tests/kraken/driver.html?testName=audio-fft) 01:36:17 INFO - PROCESS | 1989 | RSS: Main: 329183232 01:36:17 INFO - PROCESS | 1989 | 01:36:19 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52914/tests/kraken/driver.html?testName=audio-oscillator) 01:36:19 INFO - PROCESS | 1989 | RSS: Main: 230789120 01:36:19 INFO - PROCESS | 1989 | 01:36:21 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52914/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:36:22 INFO - PROCESS | 1989 | RSS: Main: 231751680 01:36:22 INFO - PROCESS | 1989 | 01:36:25 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52914/tests/kraken/driver.html?testName=imaging-darkroom) 01:36:25 INFO - PROCESS | 1989 | RSS: Main: 273199104 01:36:25 INFO - PROCESS | 1989 | 01:36:28 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52914/tests/kraken/driver.html?testName=imaging-desaturate) 01:36:29 INFO - PROCESS | 1989 | RSS: Main: 273600512 01:36:29 INFO - PROCESS | 1989 | 01:36:32 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52914/tests/kraken/driver.html?testName=json-parse-financial) 01:36:33 INFO - PROCESS | 1989 | RSS: Main: 271523840 01:36:33 INFO - PROCESS | 1989 | 01:36:34 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52914/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:36:35 INFO - PROCESS | 1989 | RSS: Main: 247738368 01:36:35 INFO - PROCESS | 1989 | 01:36:37 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52914/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:36:37 INFO - PROCESS | 1989 | RSS: Main: 253935616 01:36:37 INFO - PROCESS | 1989 | 01:36:39 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52914/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:36:40 INFO - PROCESS | 1989 | RSS: Main: 255639552 01:36:40 INFO - PROCESS | 1989 | 01:36:43 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52914/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:36:44 INFO - PROCESS | 1989 | RSS: Main: 272015360 01:36:44 INFO - PROCESS | 1989 | 01:36:46 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52914/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:36:47 INFO - PROCESS | 1989 | RSS: Main: 266047488 01:36:47 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | Cycle 1(1): loaded http://localhost:52914/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52914/tests/kraken/driver.html?testName=ai-astar) 01:36:49 INFO - PROCESS | 1989 | RSS: Main: 310435840 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | __start_tp_report 01:36:49 INFO - PROCESS | 1989 | _x_x_mozilla_page_load 01:36:49 INFO - PROCESS | 1989 | _x_x_mozilla_page_load_details 01:36:49 INFO - PROCESS | 1989 | |i|pagename|runs| 01:36:49 INFO - PROCESS | 1989 | |0;ai-astar;103;116;114;114;115;115;115;115;113;114 01:36:49 INFO - PROCESS | 1989 | |1;audio-beat-detection;145;108;109;141;110;110;108;90;134;138 01:36:49 INFO - PROCESS | 1989 | |2;audio-dft;184;186;184;185;186;183;181;183;183;185 01:36:49 INFO - PROCESS | 1989 | |3;audio-fft;84;61;70;116;86;83;82;64;75;59 01:36:49 INFO - PROCESS | 1989 | |4;audio-oscillator;89;71;87;68;68;68;82;68;68;83 01:36:49 INFO - PROCESS | 1989 | |5;imaging-gaussian-blur;88;87;86;87;87;86;88;86;91;74 01:36:49 INFO - PROCESS | 1989 | |6;imaging-darkroom;94;95;95;94;95;97;95;95;94;95 01:36:49 INFO - PROCESS | 1989 | |7;imaging-desaturate;87;82;95;123;98;81;84;85;83;86 01:36:49 INFO - PROCESS | 1989 | |8;json-parse-financial;62;63;59;61;65;66;64;64;67;68 01:36:49 INFO - PROCESS | 1989 | |9;json-stringify-tinderbox;56;59;58;62;65;68;61;59;61;59 01:36:49 INFO - PROCESS | 1989 | |10;stanford-crypto-aes;81;83;82;85;80;84;81;65;81;89 01:36:49 INFO - PROCESS | 1989 | |11;stanford-crypto-ccm;137;135;141;127;134;132;136;136;177;146 01:36:49 INFO - PROCESS | 1989 | |12;stanford-crypto-pbkdf2;167;161;162;161;154;161;161;159;161;162 01:36:49 INFO - PROCESS | 1989 | |13;stanford-crypto-sha256-iterative;66;63;64;65;61;64;63;62;64;62 01:36:49 INFO - PROCESS | 1989 | __end_tp_report 01:36:49 INFO - PROCESS | 1989 | __start_cc_report 01:36:49 INFO - PROCESS | 1989 | _x_x_mozilla_cycle_collect,2117 01:36:49 INFO - PROCESS | 1989 | __end_cc_report 01:36:49 INFO - PROCESS | 1989 | __startTimestamp1495096609466__endTimestamp 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | ------- Summary: start ------- 01:36:49 INFO - PROCESS | 1989 | Number of tests: 14 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#0] ai-astar Cycles:10 Average:113.40 Median:115.00 stddev:3.75 (3.3%) stddev-sans-first:0.88 01:36:49 INFO - PROCESS | 1989 | Values: 103.0 116.0 114.0 114.0 115.0 115.0 115.0 115.0 113.0 114.0 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#1] audio-beat-detection Cycles:10 Average:119.30 Median:136.00 stddev:18.53 (13.6%) stddev-sans-first:17.16 01:36:49 INFO - PROCESS | 1989 | Values: 145.0 108.0 109.0 141.0 110.0 110.0 108.0 90.0 134.0 138.0 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:1.56 (0.8%) stddev-sans-first:1.66 01:36:49 INFO - PROCESS | 1989 | Values: 184.0 186.0 184.0 185.0 186.0 183.0 181.0 183.0 183.0 185.0 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#3] audio-fft Cycles:10 Average:78.00 Median:78.50 stddev:16.68 (21.2%) stddev-sans-first:17.55 01:36:49 INFO - PROCESS | 1989 | Values: 84.0 61.0 70.0 116.0 86.0 83.0 82.0 64.0 75.0 59.0 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:76.50 stddev:8.90 (11.6%) stddev-sans-first:7.92 01:36:49 INFO - PROCESS | 1989 | Values: 89.0 71.0 87.0 68.0 68.0 68.0 82.0 68.0 68.0 83.0 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:4.47 (5.1%) stddev-sans-first:4.68 01:36:49 INFO - PROCESS | 1989 | Values: 88.0 87.0 86.0 87.0 87.0 86.0 88.0 86.0 91.0 74.0 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:95.00 stddev:0.88 (0.9%) stddev-sans-first:0.87 01:36:49 INFO - PROCESS | 1989 | Values: 94.0 95.0 95.0 94.0 95.0 97.0 95.0 95.0 94.0 95.0 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#7] imaging-desaturate Cycles:10 Average:90.40 Median:85.50 stddev:12.72 (14.9%) stddev-sans-first:13.43 01:36:49 INFO - PROCESS | 1989 | Values: 87.0 82.0 95.0 123.0 98.0 81.0 84.0 85.0 83.0 86.0 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:64.50 stddev:2.77 (4.3%) stddev-sans-first:2.85 01:36:49 INFO - PROCESS | 1989 | Values: 62.0 63.0 59.0 61.0 65.0 66.0 64.0 64.0 67.0 68.0 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:61.00 stddev:3.52 (5.8%) stddev-sans-first:3.28 01:36:49 INFO - PROCESS | 1989 | Values: 56.0 59.0 58.0 62.0 65.0 68.0 61.0 59.0 61.0 59.0 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:82.50 stddev:6.24 (7.6%) stddev-sans-first:6.62 01:36:49 INFO - PROCESS | 1989 | Values: 81.0 83.0 82.0 85.0 80.0 84.0 81.0 65.0 81.0 89.0 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#11] stanford-crypto-ccm Cycles:10 Average:140.10 Median:136.50 stddev:13.91 (10.2%) stddev-sans-first:14.71 01:36:49 INFO - PROCESS | 1989 | Values: 137.0 135.0 141.0 127.0 134.0 132.0 136.0 136.0 177.0 146.0 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.90 Median:161.00 stddev:3.18 (2.0%) stddev-sans-first:2.49 01:36:49 INFO - PROCESS | 1989 | Values: 167.0 161.0 162.0 161.0 154.0 161.0 161.0 159.0 161.0 162.0 01:36:49 INFO - PROCESS | 1989 | 01:36:49 INFO - PROCESS | 1989 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:64.00 stddev:1.51 (2.4%) stddev-sans-first:1.27 01:36:49 INFO - PROCESS | 1989 | Values: 66.0 63.0 64.0 65.0 61.0 64.0 63.0 62.0 64.0 62.0 01:36:49 INFO - PROCESS | 1989 | -------- Summary: end -------- 01:36:49 INFO - PROCESS | 1989 | 01:36:50 INFO - TEST-INFO | 1989: exit 0 01:36:50 INFO - TEST-OK | kraken | took 58934ms 01:36:50 INFO - SUITE-END | took 1177s 01:36:50 INFO - Completed test suite (00:19:38) 01:36:50 INFO - javascript benchmark 01:36:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7900.864259095164, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3536.4695295705646, 3561.6616421439953, 3562.857485700572, 3591.138126849557, 3631.5460567429072, 6275.592086514379, 6282.654454005039, 6294.276158241798, 6307.274663413708, 6313.589616674913, 6252.687365631723, 6269.592476489025, 6283.0, 6311.873762524747, 6321.841953951142, 5632.605717599769, 5654.37801841797, 5672.17753425753, 5729.312482502106, 5760.53915686744, 5550.72246387681, 5570.6657600543895, 5613.073842815951, 5794.913076303858, 5826.126081087828, 5669.177969194464, 5681.687507187103, 5707.571932105088, 5713.17159011943, 5763.855903602423, 5759.1937128802, 5777.24895763548, 5777.682227477487, 5777.942220577825, 5780.0751879699465, 5569.275994120739, 5677.772889084432, 5694.373618901917, 5698.601097923147, 5729.856753581152, 6102.359252278536, 6119.1739115219325, 6158.84602884927, 6177.81466556004, 6289.905651415233, 6638.070670106189, 6649.3350664933605, 6656.0, 6672.866542669168, 6679.76620818269, 4871.0, 6672.566283191625, 6747.460203183783, 6752.763653272112, 6775.966120169417, 6115.635867915819, 6374.872502549923, 6380.106785050097, 6387.457066149429, 6388.456981156607, 6580.374864387875, 6778.72885084601, 6821.658917054127, 6856.41720453767, 6905.689243984033, 6538.313477084933, 6540.182477190351, 6551.934480655133, 6560.737570497222, 6570.638614876228, 6656.600603963778, 6659.167604049494, 6739.696713647898, 6739.865202695919, 6760.661966901635, 6340.619562826245, 6399.7440102395385, 6431.871362572722, 6444.194475690539, 6451.483881289486, 6425.58233714807, 6532.542722009509, 6594.07682924391, 6596.538242323088, 6605.207375114921, 5345.3852806926925, 5378.139497680352, 5390.434004429479, 5397.190421436816, 5434.728263586884, 4712.363830882787, 4811.687240329367, 4827.637927155477, 4849.733264670477, 4865.270209468537, 6181.0, 6184.536159788034, 6190.257169139642, 6196.039613859859, 6198.442140207313, 6624.768133115318, 6632.602043877287, 6673.666316684146, 6698.866022679519, 6723.562968407038, 6298.244210694746, 6327.145835312174, 6341.334159913143, 6347.174867267226, 6359.682015899187, 5913.083472061838, 5917.349091599921, 5927.555433342516, 5934.6142500737315, 5941.940580594225, 6583.835404115031, 6670.399664030067, 6846.7945961621235, 6858.177018757781, 6979.232284448605, 6693.26374098839, 6700.731970721116, 6737.66311684404, 6748.122744043243, 6762.985552167018, 6928.965355173192, 6962.1297337832775, 6973.302669732986, 6985.79042628722, 6996.475264355092, 6345.238571371465, 6419.35806419373, 6451.3548645135115, 6611.305812889577, 6628.867422651423, 5527.170924361217, 6443.226812782496, 6459.128017717548, 6475.09348691193, 6566.6388348641285, 5670.432956704297, 6474.352564743488, 6510.804675859732, 6525.347465253437, 6535.705893234721, 6500.609963402211, 6508.284088750139, 6509.153810004669, 6534.509911756732, 6543.6400997943665, 6194.194754681863, 6235.501159907287, 6316.399942005502, 6371.076193951758, 6436.259830119409, 6486.643234622141, 6513.511486638616, 6518.967405162944, 6539.803805885831, 6564.5076619252795, 6343.9365606343345, 6370.362963703593, 6390.0, 6400.263969643551, 6413.0], "value": 6137.360729930226, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.4858598980715, 895.8611415230641, 904.9547522623867, 909.3770767024585, 910.9134632209942, 39486.407703884426, 39864.20271594566, 42064.78967605158, 42094.78952605233, 42210.0, 40327.19345613093, 40622.98442538931, 40640.57757978472, 40659.79670101653, 40677.59322406775, 8085.636146373398, 8348.457350272243, 8487.66049358025, 8493.278071363942, 8506.064332923374, 8145.063317718449, 11857.762844743143, 12043.819342709867, 12285.754284914296, 12348.75302493954, 12051.698707532338, 12059.155859089868, 12140.3322817245, 12141.939290303537, 12158.392080395988, 12056.63830085099, 12079.396030198543, 12084.456199471046, 12101.818472722916, 12118.757624847542, 11719.472623731952, 11769.588064417705, 11778.234414763014, 11781.998530124949, 11786.764264714744, 42008.369874451906, 42258.78870605658, 42290.788546057076, 42317.788411058056, 42320.57679423197, 3976.7812770297624, 4016.357382818764, 4026.7583944963103, 4088.3049881520215, 4096.242195193899, 28825.423491530262, 29633.555496667566, 30290.0, 31133.377332453223, 31151.0, 3179.4118088153764, 3215.099772063826, 3228.2413632796274, 3228.8869889553753, 3313.8343082845763, 362077.18961405026, 362139.0, 362407.0, 362460.18769905984, 363336.0, 55083.0, 55166.448335516936, 55180.724096379265, 55218.06736764194, 55231.17153242705, 57043.0, 57244.0, 57463.0, 57470.7126464365, 57472.42527574754, 15784.763228551581, 15894.76157857633, 15903.840961590467, 15911.283992220378, 15920.920395398181, 13394.196348218944, 13398.0, 13419.932900335436, 13452.5291614793, 13505.459781608626, 11722.296662200295, 12897.806532902014, 12907.483700652057, 12941.158824676555, 13048.151870128411, 4896.730679812645, 5812.7674893003805, 5822.272215973004, 5852.531797456193, 5863.208466857005, 911.2755359489222, 935.219092058139, 951.8001219743932, 952.319091849331, 976.1605019683067, 25887.611685824726, 25997.090101846345, 29567.965121221085, 30664.0, 30731.846340768152, 954.4941181173989, 982.6806287956442, 1026.4867566216892, 1052.3317693264678, 1060.5121644043672, 15760.369585216464, 15824.813139014619, 15837.68324633501, 15869.761953570705, 16098.678026439406, 435010.0, 435987.0, 436186.0, 436868.0, 437531.0, 59034.40965590289, 59037.70481147653, 59090.0, 59199.0, 59243.70378148081, 1475.1739026145608, 1484.383980648019, 1517.211050253862, 1519.9164045977577, 1525.4279645133074, 23368.130549555655, 29393.853030735565, 29561.0, 30289.24276893095, 30352.0, 47740.7612961933, 48536.0, 49132.75433622952, 50591.73520662013, 51008.48991510038, 480906.59546703444, 485027.5748621234, 485477.0, 485812.0, 486041.0, 30969.690303096682, 31002.0, 31037.0, 31078.533821992234, 31092.067237982897, 40167.799161004004, 40280.395794062526, 40416.39375409312, 41452.58547414487, 41467.0, 28621.713782861905, 28679.569806452502, 29317.0, 29849.850750746107, 29951.700482995762, 28712.99504517332, 28732.85633571819, 28983.420331593672, 29738.702612973593, 29858.104256872328, 33713.831430842685, 33716.0, 34466.82766586151, 34531.755435896586, 34533.654663453046, 28602.57096143518, 28986.13041608755, 29171.0, 29287.267818304714, 29977.550336745404, 19241.0, 22100.784456855028, 23750.643740344254, 23769.762302376756, 23786.52426951486], "value": 19317.95857600266, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.2700523016728, 431.2324063167561, 432.79442264924154, 433.61331436478076, 434.98260069597217, 16996.4051258206, 21444.35666929991, 22100.668489972664, 22593.322200334016, 22877.940985311317, 23470.88264558679, 23637.881810590967, 24169.39576510588, 24285.51428971424, 24531.509369812593, 5643.012472817261, 6729.427998620124, 6980.267071957447, 7042.54223475475, 7052.61210633415, 6904.240533541307, 6967.338102880219, 7033.437325013987, 7035.683394247271, 7246.275372462738, 7611.010568626071, 7709.845803083935, 7721.613919304041, 7722.652480638357, 7726.227377262285, 7466.4773465857415, 7554.131274903374, 7666.118396384431, 7676.46264761467, 7689.807754806175, 7175.964120179366, 7406.555606663617, 7431.368333691643, 7453.291937265951, 7469.589172595505, 21111.261105861373, 25954.740452595423, 26302.47395052088, 26875.731242687518, 26937.191884243504, 2972.4649563078638, 2986.3728616990456, 3099.922501937447, 3135.7648176386633, 3158.5262210668584, 16048.518544443685, 16409.0, 16442.75335869963, 16449.6710065798, 17670.204840782197, 2182.71624688791, 2197.0, 2232.3414592695194, 2237.407087121914, 2298.747137814839, 165722.0, 170051.0, 172497.0, 176466.11766941083, 177648.0, 15180.963807238597, 19435.931023793546, 19911.0, 19998.0, 20178.065277271086, 28501.0, 29874.0, 32182.356352872812, 32237.710491580074, 32282.83858580739, 10506.0, 10576.62981795649, 10794.974477424632, 10802.189835762349, 10900.182486313559, 9102.362834601514, 9120.315976301805, 9145.3598248122, 9267.907320926839, 9283.48940808262, 9056.70505320594, 9291.0, 9414.717558473258, 9430.3398762086, 9468.337216394786, 3478.513008178857, 3543.8936831895085, 3590.312553100091, 3985.442038114666, 3998.5212864807804, 942.7501712046313, 1004.1615251265275, 1022.5960745505483, 1022.6829682798357, 1025.220832167558, 20467.07898144587, 21227.36317910465, 21596.892015539823, 21842.102632289192, 24311.392215194763, 864.2006144316482, 865.7749583570289, 921.2491819167491, 974.3033730882453, 1034.1984961654778, 6536.738530458729, 6578.0, 6687.364700353459, 6714.731410743516, 6931.410830079399, 761129.0, 804797.952020484, 812009.0, 813895.0, 838711.8064409761, 161416.19291903466, 173507.0, 173796.262037378, 176863.0, 179298.10350948162, 1265.9873401266054, 1307.3071272225736, 1334.4728832111261, 1336.5923393365117, 1337.2310921220337, 19050.571207159795, 20695.58608827845, 20924.58150837005, 21350.893245533673, 21363.0, 55838.72080639571, 64278.67860660667, 67573.66213168902, 67627.0, 68353.3164668347, 87559.0, 88666.0, 91461.0, 92691.0, 93600.53199733958, 11659.18385712992, 11665.416729163677, 11679.532818687157, 11848.0, 12023.819642705192, 13146.934265328995, 13156.473741050251, 13196.406161722554, 13309.068365214729, 13548.322583870964, 11139.275947063416, 11636.709082273012, 11764.941155296065, 11848.585299514476, 11887.881121188677, 10590.311629744043, 10685.465726713477, 10695.732606684895, 10957.726056848323, 11110.722231944266, 9717.0, 11900.107491937966, 12020.63938081859, 12089.81865272004, 12170.817437738264, 10511.94744026275, 11224.494897729459, 11643.068554515448, 11974.46114924813, 11998.64004079879, 11468.94265528667, 11508.654740357802, 11621.360825154698, 11659.300441973508, 11814.704632384259], "value": 11819.571852830251, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1612.8467795559432, 1641.343462614954, 1668.5911951571866, 1670.6575152093826, 1672.882898197126, 10909.07272881805, 11613.825792613097, 11631.069514438846, 11633.825492617607, 11641.825372619407, 11057.50241239144, 11306.265092768965, 11371.919667631582, 11597.478113484893, 11599.768004639902, 2450.0934654177936, 2490.987545062272, 2496.7253602103756, 2498.600223964166, 2502.2618327593314, 2191.794513017842, 2367.538330025646, 2438.926832195037, 2490.252924122765, 2493.2769496845895, 2413.698287714036, 2469.0370755405406, 2477.417806815406, 2494.2143224884194, 2496.4383013821925, 2432.7202371727126, 2435.719892212383, 2449.4856080223085, 2463.334899577113, 2487.7263501015054, 2430.805535557151, 2439.1584903208363, 2450.6079027355536, 2455.6439316299216, 2455.9877200613882, 11018.889811101866, 11259.493322800412, 11266.66200014001, 11269.154813389028, 11278.266912650652, 5670.432956704297, 5834.241548598655, 5844.824655260349, 5853.648781073107, 5854.326752423483, 3632.0, 3668.578113516953, 3750.1562148516637, 3758.868439604628, 3824.388097904322, 1247.5758242197605, 1250.0499620288556, 1286.4018231522293, 1299.3633119771243, 1301.5184381778802, 14066.718665626733, 14311.713765724731, 14754.999999999893, 15670.451534196249, 15863.286152123066, 4634.119517291704, 4753.287006948985, 4783.976080119577, 4827.275908613736, 4838.879028024257, 10411.271211015157, 11965.282083075042, 11984.58053968107, 11997.580084696994, 12014.819777703342, 4321.719088259316, 5692.829215123553, 5840.328362238397, 5841.299044114733, 5843.970780146072, 4624.583787459145, 5184.5593124584075, 5373.274607927958, 5385.488378604026, 5462.2079798429, 3942.0, 3969.226000929849, 3990.3615421532418, 4019.0354314964197, 4047.5750046245307, 1039.812833689943, 1043.7338478687886, 1045.3988956350129, 1055.6780182044397, 1058.5977328615231, 926.3376685669779, 949.2406075139999, 950.7242899559051, 960.8030353777459, 1001.0590045357341, 6431.0, 6628.668566571652, 6648.434883034899, 6697.894847350132, 6902.240753517109, 979.6816034788723, 989.0, 1003.6838395905266, 1035.53400969564, 1040.079529616295, 4180.770057646859, 4207.137536804948, 4219.261629214875, 4253.383259427427, 4314.7411155330155, 13374.598762037154, 13993.020488565906, 14017.878569714398, 14038.227897465737, 14062.507812226522, 13086.869131308755, 13619.659508512168, 13850.238236897067, 13878.861211387959, 13913.443462261395, 10273.434961077213, 10524.842127368096, 10559.577616895238, 10564.735881603021, 10574.471276436147, 6493.448056915026, 6719.160104986877, 6907.758228461979, 6919.550229235084, 6938.895916561357, 10951.780964380827, 11193.496292666687, 12773.8083928743, 12800.551980680631, 12819.423125959167, 12599.496020159457, 12929.353532323534, 12968.416421260878, 13101.410436530188, 13179.67050823737, 8035.116137225017, 8138.941937548081, 9403.717888463358, 9616.807663846825, 9624.951875240578, 10681.092107170667, 10694.465276735977, 10800.189985750943, 10805.783884322427, 10842.403667798348, 8936.597853096757, 9082.727518174466, 9145.176934075966, 9150.816983660423, 9173.541322933694, 8922.776930576787, 8936.508492033001, 8992.235659968976, 9007.77480562965, 9023.81952360936, 7877.7636670899965, 8052.5571093590415, 8165.591720414074, 8169.428140030141, 8178.795530111557, 5989.221401217814, 6038.879222415615, 6115.174451448997, 6121.938780612137, 6258.468030217483, 4672.7663616818345, 5523.972380138234, 5995.16067750524, 6017.15759793638, 6166.81499555014], "value": 5235.036978299443, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.39262646128043, 373.342916467018, 374.78449891312505, 391.43242298666934, 391.4636947382087, 5202.739863006848, 5244.501772331632, 5247.274435151043, 5281.260623512707, 5395.325584301962, 4669.392978912737, 4787.44944331402, 4897.804087836491, 5047.47001564836, 5053.772580233889, 3969.6928832217077, 4275.657947364203, 4299.742015479073, 4327.7619730914785, 4341.0, 4216.76511448931, 4284.6786491013145, 4320.719153255044, 4331.696781225315, 4372.693911426201, 3912.608739126079, 4141.544430112685, 4290.442242508485, 4292.720973136752, 4300.268954277781, 3702.2965636528975, 4115.32097203961, 4175.728577642443, 4257.914841703164, 4264.893377665568, 3207.454732695424, 3494.9379865879255, 3949.7755695734413, 3966.3257246267917, 3968.5316432920004, 4601.286800545888, 4696.342512048316, 4991.750412479398, 5061.873453163664, 5071.442141364447, 4562.520935301779, 4703.64722645803, 4711.316859055418, 4749.786259618326, 4778.593819525345, 5432.511074003319, 5466.751918158533, 5485.561155107582, 5518.310211223597, 5544.528715059225, 3191.420246977738, 4113.0386121992115, 4424.491183513905, 4430.379746835446, 4438.400815889846, 4868.610511159063, 4948.0846043481715, 5265.894682106336, 5582.720863956786, 5710.571707121982, 3907.925320536875, 4465.419495465569, 4490.797914093873, 4492.011757413364, 4507.639388848885, 3896.830950714831, 4370.0, 4538.2738761797955, 4582.656300777455, 4739.66822322441, 4123.773192474383, 4181.958180418218, 4227.957720422756, 4620.142181278603, 4853.878653033632, 4983.352164218701, 5345.572354211654, 5419.729013549307, 5442.564594832404, 5468.48049435303, 1264.5574049082932, 1369.9246541440118, 1546.6442718174658, 1558.3299181352127, 1559.6334861307585, 1063.9573218246162, 1096.1365678346683, 1097.067492631257, 1169.5497233565138, 1180.8132826509302, 4348.695591308641, 4444.044530425909, 4472.74126566082, 4828.623842204955, 4917.606591472745, 5071.290019397287, 5571.13647384656, 5584.21820945068, 5587.413321601173, 5623.3289187636365, 4069.7965101745385, 4328.311958336251, 4594.047529799888, 5051.646384753032, 5053.772580233898, 2857.842818644953, 2874.6837847836723, 2884.8940273600388, 2888.6678032026166, 2902.3904979954023, 5060.7216603086445, 5183.611229157828, 5320.893582128336, 5761.251037365116, 5777.88444231113, 5185.714785686748, 5393.7572809223675, 5465.289512363447, 5694.886102278014, 5697.575606098475, 4978.477259887732, 5364.8122315718765, 5833.812320379858, 5925.34821169671, 5941.48492134503, 5310.698878774725, 5588.636738611977, 5633.690147041788, 5634.971825140848, 5649.316574627183, 4780.976095119502, 5422.539084177889, 5447.918281225706, 5522.751476183662, 5663.716814159358, 5655.547556195576, 5723.771049157987, 5807.328628830785, 5831.271091113611, 5856.355800861987, 4387.341898715091, 4588.931166032446, 4593.770311484479, 4598.402207712976, 4617.930731039104, 5254.476201901406, 5268.341457317835, 5439.912017596575, 5607.607467477237, 5613.971930140323, 4882.9755851221935, 5265.815696450606, 5457.0, 5464.91802622953, 5541.639793413416, 4308.0, 4870.0, 5474.452554744494, 5529.806456773994, 5533.229366602649, 4582.633389328912, 5070.0, 5087.567556757717, 5338.57291416678, 5418.945810542002, 4470.457692096222, 4601.44782626087, 4828.758562071953, 5088.338515992898, 5638.37977822431, 4148.522919864255, 4174.165166966558, 4602.502098405169, 4621.907561848811, 4897.0656590645785], "value": 4146.159248492028, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [777.3314949143739, 809.3525179856114, 809.6882700160438, 854.7649396415981, 907.2560500389679, 16003.439879604217, 17466.0, 17500.21249043793, 17532.211050502727, 17539.94760314382, 16191.676166476664, 16214.108224047703, 16346.83653163468, 16374.345026198938, 16455.91772041141, 963.2823546457898, 1003.4832061488303, 1018.1193267823317, 1034.9586016559329, 1037.2946396450411, 1021.4177918586408, 1025.8922813104627, 1027.193652982409, 1028.7582418131733, 1028.8250997330472, 1057.841323801432, 1058.0107599394591, 1059.7350662334416, 1060.9973574971655, 1061.400308825515, 1060.0512541275516, 1061.5912873543664, 1061.75048863517, 1062.2935747727752, 1062.4846949229618, 789.0294937227243, 789.6209819286775, 789.7078081109969, 790.7548659915387, 790.8457850719112, 17502.578745160237, 17589.384371546934, 18206.998615076165, 18515.629687406312, 18792.906035469736, 14652.853471465362, 14856.0, 14973.401063957428, 15042.398304067825, 15156.090634561962, 11962.461689224006, 12244.510219591206, 12357.0, 12413.44139513724, 12516.874831251662, 3596.4245720684567, 3824.445455408978, 3859.903502412434, 3962.920741585181, 3994.7003974701724, 94533.99999999932, 125022.0, 125614.0, 127349.0, 128077.0, 11479.240724420948, 11714.765704685859, 11715.941420292844, 11775.470103845348, 11917.165798394202, 23474.061037558648, 23538.0, 23541.94061267247, 23584.29247122589, 23729.7627023731, 7838.804029899298, 7968.083670377865, 8292.17078292166, 8355.0, 8368.874466883002, 4550.863474095716, 4782.569568738761, 4824.276358546176, 4948.579370753526, 4951.183054795991, 3705.7591256568235, 3811.3520701480816, 3811.714121440903, 3813.580506144322, 3814.065553939303, 665.6704931059156, 677.5697432130534, 695.2074634916199, 699.3111784891878, 699.755085719994, 691.8270432391902, 701.6246308225075, 718.3606590134784, 758.6206896551724, 776.7941495503696, 9374.937713021483, 10514.789704206027, 10718.303310284806, 10780.460976951277, 10935.343879367263, 671.9563228390139, 728.3372131360543, 738.5568658804674, 741.1550832051465, 753.5365750063781, 4462.196804575143, 4688.132695451352, 4900.901981960341, 5156.182395973701, 5293.470652934676, 81813.59093204615, 87235.0, 88203.0, 100788.49605752071, 102296.0, 87624.56187719021, 89253.0, 89384.5530772342, 89753.0, 92601.0, 65111.67444162749, 67637.3236267631, 68788.6560567204, 71281.0, 72768.63615681887, 11270.0, 11324.0, 11337.546498139982, 11351.542042840176, 11441.942790285995, 44584.885377865816, 45230.77384613187, 46833.2975005382, 48924.77688057827, 50250.49749502458, 60029.39970600238, 60828.6958565222, 61036.38963610485, 61320.0, 62396.68801655962, 11254.324740515594, 11546.653600392001, 11864.822027669765, 12896.871031289567, 14444.422223110958, 11080.279781814157, 13055.934720326717, 13319.0, 13339.66650833737, 13893.096948698303, 14396.99221054516, 14460.0, 14730.926345368205, 14896.776548351567, 15066.32201550911, 14514.927425362806, 14529.837612991147, 14650.19423931694, 14955.252237388304, 15175.0, 13854.307284635526, 14068.577942661737, 14836.332365043383, 14927.029743066665, 15271.541853744406, 10530.57877684884, 10666.679999600025, 10788.298760580537, 10871.565137394417, 11102.444877756241, 11004.779904401707, 12220.205686630341, 12253.387330633612, 12342.44458999365, 12797.808032879328], "value": 7996.410104274887, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1411.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 116.0, 114.0, 114.0, 115.0, 115.0, 115.0, 115.0, 113.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 109.0, 141.0, 110.0, 110.0, 108.0, 90.0, 134.0, 138.0], "value": 119.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 184.0, 185.0, 186.0, 183.0, 181.0, 183.0, 183.0, 185.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 61.0, 70.0, 116.0, 86.0, 83.0, 82.0, 64.0, 75.0, 59.0], "value": 78.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 87.0, 68.0, 68.0, 68.0, 82.0, 68.0, 68.0, 83.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 86.0, 87.0, 87.0, 86.0, 88.0, 86.0, 91.0, 74.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 97.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 82.0, 95.0, 123.0, 98.0, 81.0, 84.0, 85.0, 83.0, 86.0], "value": 90.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 59.0, 61.0, 65.0, 66.0, 64.0, 64.0, 67.0, 68.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 58.0, 62.0, 65.0, 68.0, 61.0, 59.0, 61.0, 59.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 82.0, 85.0, 80.0, 84.0, 81.0, 65.0, 81.0, 89.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 141.0, 127.0, 134.0, 132.0, 136.0, 136.0, 177.0, 146.0], "value": 140.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 161.0, 162.0, 161.0, 154.0, 161.0, 161.0, 159.0, 161.0, 162.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 64.0, 65.0, 61.0, 64.0, 63.0, 62.0, 64.0, 62.0], "value": 63.4, "unit": "score"}]}]} 01:36:50 INFO - Return code: 0 01:36:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:36:50 INFO - # TBPL SUCCESS # 01:36:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:36:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:36:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:36:50 INFO - 2017-05-18 01:36:50.798 screenresolution[1993:18158] starting screenresolution argv=screenresolution get 01:36:50 INFO - 2017-05-18 01:36:50.801 screenresolution[1993:18158] Display 0: 1600x1200x32@60 01:36:50 INFO - 2017-05-18 01:36:50.807 screenresolution[1994:18162] starting screenresolution argv=screenresolution list 01:36:50 INFO - Available Modes on Display 0 01:36:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:36:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:36:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:36:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:36:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:36:51 INFO - Intel Iris: 01:36:51 INFO - Chipset Model: Intel Iris 01:36:51 INFO - Type: GPU 01:36:51 INFO - Bus: Built-In 01:36:51 INFO - VRAM (Dynamic, Max): 1536 MB 01:36:51 INFO - Vendor: Intel (0x8086) 01:36:51 INFO - Device ID: 0x0a2e 01:36:51 INFO - Revision ID: 0x0009 01:36:51 INFO - Displays: 01:36:51 INFO - Display: 01:36:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:36:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:36:51 INFO - Display Serial Number: 1600x1200 60 01:36:51 INFO - Main Display: Yes 01:36:51 INFO - Mirror: Off 01:36:51 INFO - Online: Yes 01:36:51 INFO - Rotation: Supported 01:36:51 INFO - Return code: 0 01:36:51 INFO - Running post-action listener: _resource_record_post_action 01:36:51 INFO - [mozharness: 2017-05-18 08:36:51.043746Z] Finished run-tests step (success) 01:36:51 INFO - Running post-run listener: _resource_record_post_run 01:36:51 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 4198400; Write bytes: 420483072; Read time: 23; Write time: 1554 01:36:51 INFO - TinderboxPrint: CPU usage
53.6% 01:36:51 INFO - TinderboxPrint: I/O read bytes / time
4,198,400 / 23 01:36:51 INFO - TinderboxPrint: I/O write bytes / time
420,483,072 / 1,554 01:36:51 INFO - TinderboxPrint: CPU idle
2,214.9 (46.4%) 01:36:51 INFO - TinderboxPrint: CPU system
952.7 (20.0%) 01:36:51 INFO - TinderboxPrint: CPU user
1,606.2 (33.6%) 01:36:51 INFO - TinderboxPrint: Swap in / out
126,263,296 / 0 01:36:51 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119325184; Write bytes: 116918272; Read time: 10839; Write time: 295 01:36:51 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 3284992; Write bytes: 293558272; Read time: 18; Write time: 1221 01:36:51 INFO - Running post-run listener: _upload_blobber_files 01:36:51 INFO - Blob upload gear active. 01:36:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:36:51 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 01:36:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:36:51 INFO - (blobuploader) - INFO - Open directory for files ... 01:36:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:36:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:36:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:36:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:36:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:36:53 INFO - (blobuploader) - INFO - Done attempting. 01:36:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:36:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:36:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:36:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:36:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:36:53 INFO - (blobuploader) - INFO - Done attempting. 01:36:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:36:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:36:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:36:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:36:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:36:54 INFO - (blobuploader) - INFO - Done attempting. 01:36:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:36:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:36:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:36:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:36:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:36:57 INFO - (blobuploader) - INFO - Done attempting. 01:36:57 INFO - (blobuploader) - INFO - Iteration through files over. 01:36:57 INFO - Return code: 0 01:36:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:36:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:36:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f458bdf40a2e825fa92d496f664ce771ac59682bca004ef93386a2378332bf72f962e546a1cf17d63a395abfb793bb869632a16326016c15cd81a99d81ed92b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6718611696e682227f8ceec70d14f6aa005123abfcb809e7df671e106f8f133094e37876c10f3921e7e5219c86c2c0b31d0dc7a32bfd4726cbedde6927a3a10a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b93409c37c083abe03c1801cc0e42d70265622ae00c5373ff0a51f9bbc2581f9c4e6b937f0240ad74482701f60e38a22693dbfefea5fdb0cef8a163d6ca220b7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/37f78c88b3613573a22b30f62daa9e119797e13b665c3f34e6279fc5f96d6b55dfed7b59584bf05a982d55911350d7f16d14b93ad60970284c79e3e76967bb55"} 01:36:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:36:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:36:57 INFO - Contents: 01:36:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f458bdf40a2e825fa92d496f664ce771ac59682bca004ef93386a2378332bf72f962e546a1cf17d63a395abfb793bb869632a16326016c15cd81a99d81ed92b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6718611696e682227f8ceec70d14f6aa005123abfcb809e7df671e106f8f133094e37876c10f3921e7e5219c86c2c0b31d0dc7a32bfd4726cbedde6927a3a10a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b93409c37c083abe03c1801cc0e42d70265622ae00c5373ff0a51f9bbc2581f9c4e6b937f0240ad74482701f60e38a22693dbfefea5fdb0cef8a163d6ca220b7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/37f78c88b3613573a22b30f62daa9e119797e13b665c3f34e6279fc5f96d6b55dfed7b59584bf05a982d55911350d7f16d14b93ad60970284c79e3e76967bb55"} 01:36:57 INFO - Running post-run listener: copy_logs_to_upload_dir 01:36:57 INFO - Copying logs to upload dir... 01:36:57 INFO - mkdir: /builds/slave/test/build/upload/logs 01:36:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.642074 ========= master_lag: 0.93 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-05-18 01:36:58.617127) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-05-18 01:36:58.624609) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dy3MqpbtAR/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f458bdf40a2e825fa92d496f664ce771ac59682bca004ef93386a2378332bf72f962e546a1cf17d63a395abfb793bb869632a16326016c15cd81a99d81ed92b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6718611696e682227f8ceec70d14f6aa005123abfcb809e7df671e106f8f133094e37876c10f3921e7e5219c86c2c0b31d0dc7a32bfd4726cbedde6927a3a10a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b93409c37c083abe03c1801cc0e42d70265622ae00c5373ff0a51f9bbc2581f9c4e6b937f0240ad74482701f60e38a22693dbfefea5fdb0cef8a163d6ca220b7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/37f78c88b3613573a22b30f62daa9e119797e13b665c3f34e6279fc5f96d6b55dfed7b59584bf05a982d55911350d7f16d14b93ad60970284c79e3e76967bb55"} build_url:https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011307 build_url: 'https://queue.taskcluster.net/v1/task/FzqQXPMATeOP-BFj9GBzew/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f458bdf40a2e825fa92d496f664ce771ac59682bca004ef93386a2378332bf72f962e546a1cf17d63a395abfb793bb869632a16326016c15cd81a99d81ed92b2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6718611696e682227f8ceec70d14f6aa005123abfcb809e7df671e106f8f133094e37876c10f3921e7e5219c86c2c0b31d0dc7a32bfd4726cbedde6927a3a10a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b93409c37c083abe03c1801cc0e42d70265622ae00c5373ff0a51f9bbc2581f9c4e6b937f0240ad74482701f60e38a22693dbfefea5fdb0cef8a163d6ca220b7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/37f78c88b3613573a22b30f62daa9e119797e13b665c3f34e6279fc5f96d6b55dfed7b59584bf05a982d55911350d7f16d14b93ad60970284c79e3e76967bb55"}' ========= master_lag: 1.18 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-05-18 01:36:59.811451) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 01:36:59.811808) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dy3MqpbtAR/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.VWC6Uly7AH/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005530 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 01:36:59.838989) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 01:36:59.839333) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 01:36:59.839678) ========= ========= Total master_lag: 2.20 =========