builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0200 starttime: 1475611642.99 results: success (0) buildid: 20161004115401 builduid: ea5f1e52cfa14611a38f0626657fcb60 revision: e0ef5898308b07ceb17f5bbf2192a5d496c7b6e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 13:07:22.991294) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 13:07:22.991763) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 13:07:22.992056) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 13:07:23.022116) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 13:07:23.022392) ========= 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.7lJZWHAl1F/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.5kd33JuYxa/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 --2016-10-04 13:07:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 203M=0s 2016-10-04 13:07:23 (203 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134446 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 13:07:23.179103) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 13:07:23.179416) ========= 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.7lJZWHAl1F/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.5kd33JuYxa/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.026796 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 13:07:23.227927) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-04 13:07:23.228281) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev e0ef5898308b07ceb17f5bbf2192a5d496c7b6e7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev e0ef5898308b07ceb17f5bbf2192a5d496c7b6e7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7lJZWHAl1F/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.5kd33JuYxa/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 2016-10-04 13:07:23,306 truncating revision to first 12 chars 2016-10-04 13:07:23,306 Setting DEBUG logging. 2016-10-04 13:07:23,306 attempt 1/10 2016-10-04 13:07:23,306 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/e0ef5898308b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-04 13:07:25,243 unpacking tar archive at: autoland-e0ef5898308b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.198237 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-04 13:07:25.449153) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 13:07:25.449631) ========= script_repo_revision: e0ef5898308b07ceb17f5bbf2192a5d496c7b6e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 13:07:25.450274) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 13:07:25.450721) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 13:07:25.470011) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-04 13:07:25.470292) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7lJZWHAl1F/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.5kd33JuYxa/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 13:07:25 INFO - MultiFileLogger online at 20161004 13:07:25 in /builds/slave/test 13:07:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 13:07:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:07:25 INFO - {'append_to_log': False, 13:07:25 INFO - 'base_work_dir': '/builds/slave/test', 13:07:25 INFO - 'blob_upload_branch': 'Autoland', 13:07:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:07:25 INFO - 'branch': 'Autoland', 13:07:25 INFO - 'buildbot_json_path': 'buildprops.json', 13:07:25 INFO - 'config_files': ('talos/mac_config.py',), 13:07:25 INFO - 'default_actions': ('clobber', 13:07:25 INFO - 'read-buildbot-config', 13:07:25 INFO - 'download-and-extract', 13:07:25 INFO - 'populate-webroot', 13:07:25 INFO - 'create-virtualenv', 13:07:25 INFO - 'install', 13:07:25 INFO - 'run-tests'), 13:07:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:07:25 INFO - 'download_minidump_stackwalk': True, 13:07:25 INFO - 'download_symbols': 'ondemand', 13:07:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:07:25 INFO - 'tooltool.py': '/tools/tooltool.py', 13:07:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:07:25 INFO - '/tools/misc-python/virtualenv.py')}, 13:07:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:07:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:07:25 INFO - 'installer_path': 'installer.exe', 13:07:25 INFO - 'log_level': 'info', 13:07:25 INFO - 'log_name': 'talos', 13:07:25 INFO - 'log_to_console': True, 13:07:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:07:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:07:25 INFO - 'opt_config_files': (), 13:07:25 INFO - 'pip_index': False, 13:07:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:07:25 INFO - 'cmd': ('bash', 13:07:25 INFO - '-c', 13:07:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:07:25 INFO - 'enabled': True, 13:07:25 INFO - 'halt_on_failure': False, 13:07:25 INFO - 'name': 'check_screen_resolution'},), 13:07:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:07:25 INFO - 'cmd': ('bash', 13:07:25 INFO - '-c', 13:07:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:07:25 INFO - 'enabled': True, 13:07:25 INFO - 'halt_on_failure': False, 13:07:25 INFO - 'name': 'check_screen_resolution'},), 13:07:25 INFO - 'run_cmd_checks_enabled': True, 13:07:25 INFO - 'sps_profile': False, 13:07:25 INFO - 'sps_profile_interval': 0, 13:07:25 INFO - 'suite': 'dromaeojs-e10s', 13:07:25 INFO - 'system_bits': '32', 13:07:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:07:25 INFO - 'title': 't-yosemite-r7-0200', 13:07:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:07:25 INFO - 'use_talos_json': True, 13:07:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:07:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:07:25 INFO - 'work_dir': 'build'} 13:07:25 INFO - [mozharness: 2016-10-04 20:07:25.672723Z] Running clobber step. 13:07:25 INFO - Running pre-action listener: _resource_record_pre_action 13:07:25 INFO - Running main action method: clobber 13:07:25 INFO - rmtree: /builds/slave/test/build 13:07:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:07:27 INFO - Running post-action listener: _resource_record_post_action 13:07:27 INFO - [mozharness: 2016-10-04 20:07:27.776823Z] Finished clobber step (success) 13:07:27 INFO - [mozharness: 2016-10-04 20:07:27.776947Z] Running read-buildbot-config step. 13:07:27 INFO - Running pre-action listener: _resource_record_pre_action 13:07:27 INFO - Running main action method: read_buildbot_config 13:07:27 INFO - Using buildbot properties: 13:07:27 INFO - { 13:07:27 INFO - "project": "", 13:07:27 INFO - "product": "firefox", 13:07:27 INFO - "script_repo_revision": "production", 13:07:27 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 13:07:27 INFO - "repository": "", 13:07:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 13:07:27 INFO - "buildid": "20161004115401", 13:07:27 INFO - "pgo_build": "False", 13:07:27 INFO - "basedir": "/builds/slave/test", 13:07:27 INFO - "buildnumber": 235, 13:07:27 INFO - "slavename": "t-yosemite-r7-0200", 13:07:27 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 13:07:27 INFO - "platform": "yosemite_r7", 13:07:27 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 13:07:27 INFO - "branch": "autoland", 13:07:27 INFO - "repo_path": "integration/autoland", 13:07:27 INFO - "revision": "e0ef5898308b07ceb17f5bbf2192a5d496c7b6e7", 13:07:27 INFO - "stage_platform": "macosx64", 13:07:27 INFO - "builduid": "ea5f1e52cfa14611a38f0626657fcb60", 13:07:27 INFO - "slavebuilddir": "test" 13:07:27 INFO - } 13:07:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 13:07:27 INFO - Running post-action listener: _resource_record_post_action 13:07:27 INFO - [mozharness: 2016-10-04 20:07:27.780321Z] Finished read-buildbot-config step (success) 13:07:27 INFO - [mozharness: 2016-10-04 20:07:27.780431Z] Running download-and-extract step. 13:07:27 INFO - Running pre-action listener: _resource_record_pre_action 13:07:27 INFO - Running main action method: download_and_extract 13:07:27 INFO - mkdir: /builds/slave/test/build/tests 13:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:07:27 INFO - trying https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 13:07:27 INFO - Downloading https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 13:07:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 13:07:28 INFO - Downloaded 1057 bytes. 13:07:28 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 13:07:28 INFO - Using the following test package requirements: 13:07:28 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 13:07:28 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:07:28 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 13:07:28 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:07:28 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 13:07:28 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:07:28 INFO - u'jsshell-mac.zip'], 13:07:28 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:07:28 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 13:07:28 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 13:07:28 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:07:28 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 13:07:28 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:07:28 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 13:07:28 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:07:28 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 13:07:28 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:07:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 13:07:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:07:28 INFO - Fetch https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 13:07:29 INFO - Http code: 200 13:07:29 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 13:07:29 INFO - x-amz-cf-id: On9TF2qvZxQQA05RgkVdG_LrSlkgRYYVYlhElk_ZghBchPDL2-aDgA== 13:07:29 INFO - x-amz-version-id: n5ZT_YDWs7GivApBAMfNid0WUbMf7u66 13:07:29 INFO - Expected file size: 16477094 13:07:29 INFO - Obtained file size: 16477094 13:07:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:07:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 13:07:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:07:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 13:07:37 INFO - Http code: 200 13:07:37 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 13:07:37 INFO - x-amz-cf-id: 4JwlLEdBTxEpannKJvvioOU-k0SF3YFQgaWEwFQGP7GX5OwlL-rRdQ== 13:07:37 INFO - x-amz-version-id: n5ZT_YDWs7GivApBAMfNid0WUbMf7u66 13:07:37 INFO - Expected file size: 16477094 13:07:37 INFO - Obtained file size: 16477094 13:07:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 13:07:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:07:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 13:07:44 INFO - Http code: 200 13:07:44 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 13:07:44 INFO - x-amz-cf-id: VIlpqei3rYxv8muRSDYo2tRndqi0ADiszSaOx3X3zBKdILfBh4415w== 13:07:44 INFO - x-amz-version-id: Qa6Crh3BN03oRTxgvA_xVYJ_D1_y7_eW 13:07:45 INFO - Expected file size: 14409006 13:07:45 INFO - Obtained file size: 14409006 13:07:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:07:45 INFO - trying https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:07:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 13:07:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 13:07:48 INFO - Downloaded 111627246 bytes. 13:07:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:07:48 INFO - mkdir: /builds/slave/test/properties 13:07:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:07:48 INFO - Writing to file /builds/slave/test/properties/build_url 13:07:48 INFO - Contents: 13:07:48 INFO - build_url:https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:07:49 INFO - Running post-action listener: _resource_record_post_action 13:07:49 INFO - Running post-action listener: set_extra_try_arguments 13:07:49 INFO - [mozharness: 2016-10-04 20:07:49.129143Z] Finished download-and-extract step (success) 13:07:49 INFO - [mozharness: 2016-10-04 20:07:49.129266Z] Running populate-webroot step. 13:07:49 INFO - Running pre-action listener: _resource_record_pre_action 13:07:49 INFO - Running main action method: populate_webroot 13:07:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:07:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:07:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:07:49 INFO - '--tppagecycles', 13:07:49 INFO - '7'], 13:07:49 INFO - 'tests': ['tsvgm']}}, 13:07:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:07:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:07:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:07:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:07:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:07:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:07:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:49 INFO - 'talos_options': ['--disable-e10s'], 13:07:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:07:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:07:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:49 INFO - 'talos_options': ['--disable-e10s'], 13:07:49 INFO - 'tests': ['damp', 'tps']}, 13:07:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:49 INFO - 'tests': ['damp', 'tps']}, 13:07:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:07:49 INFO - 'tests': ['dromaeo_dom']}, 13:07:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:07:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:07:49 INFO - 'tests': ['basic_compositor_video']}, 13:07:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:07:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:07:49 INFO - 'tests': ['a11yr', 13:07:49 INFO - 'ts_paint', 13:07:49 INFO - 'tpaint', 13:07:49 INFO - 'sessionrestore', 13:07:49 INFO - 'sessionrestore_no_auto_restore', 13:07:49 INFO - 'tabpaint']}, 13:07:49 INFO - 'other-e10s': {'tests': ['a11yr', 13:07:49 INFO - 'ts_paint', 13:07:49 INFO - 'tpaint', 13:07:49 INFO - 'sessionrestore', 13:07:49 INFO - 'sessionrestore_no_auto_restore', 13:07:49 INFO - 'tabpaint']}, 13:07:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:07:49 INFO - 'ts_paint', 13:07:49 INFO - 'tpaint', 13:07:49 INFO - 'sessionrestore', 13:07:49 INFO - 'sessionrestore_no_auto_restore', 13:07:49 INFO - 'tabpaint']}, 13:07:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:07:49 INFO - 'ts_paint', 13:07:49 INFO - 'tpaint', 13:07:49 INFO - 'sessionrestore', 13:07:49 INFO - 'sessionrestore_no_auto_restore', 13:07:49 INFO - 'tabpaint']}, 13:07:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:07:49 INFO - 'tests': ['a11yr', 13:07:49 INFO - 'ts_paint', 13:07:49 INFO - 'tpaint', 13:07:49 INFO - 'sessionrestore', 13:07:49 INFO - 'sessionrestore_no_auto_restore', 13:07:49 INFO - 'tabpaint']}, 13:07:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:07:49 INFO - 'tests': ['a11yr', 13:07:49 INFO - 'ts_paint', 13:07:49 INFO - 'tpaint', 13:07:49 INFO - 'sessionrestore', 13:07:49 INFO - 'sessionrestore_no_auto_restore', 13:07:49 INFO - 'tabpaint']}, 13:07:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:07:49 INFO - 'tests': ['tsvgx', 13:07:49 INFO - 'tsvgr_opacity', 13:07:49 INFO - 'tart', 13:07:49 INFO - 'tscrollx', 13:07:49 INFO - 'cart']}, 13:07:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:07:49 INFO - 'tsvgr_opacity', 13:07:49 INFO - 'tart', 13:07:49 INFO - 'tscrollx', 13:07:49 INFO - 'cart']}, 13:07:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:49 INFO - 'talos_options': ['--disable-e10s'], 13:07:49 INFO - 'tests': ['tp5o']}, 13:07:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:49 INFO - 'tests': ['tp5o']}, 13:07:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:49 INFO - 'talos_options': ['--disable-e10s', 13:07:49 INFO - '--xperf_path', 13:07:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:07:49 INFO - 'tests': ['tp5n']}, 13:07:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:49 INFO - 'talos_options': ['--xperf_path', 13:07:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:07:49 INFO - 'tests': ['tp5n']}}, 13:07:49 INFO - 'talos.zip': {'path': '', 13:07:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:07:49 INFO - Running post-action listener: _resource_record_post_action 13:07:49 INFO - [mozharness: 2016-10-04 20:07:49.148288Z] Finished populate-webroot step (success) 13:07:49 INFO - [mozharness: 2016-10-04 20:07:49.148410Z] Running create-virtualenv step. 13:07:49 INFO - Running pre-action listener: _resource_record_pre_action 13:07:49 INFO - Running main action method: create_virtualenv 13:07:49 INFO - Creating virtualenv /builds/slave/test/build/venv 13:07:49 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 13:07:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:07:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:07:49 INFO - Using real prefix '/tools/python27' 13:07:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:07:50 INFO - Installing distribute.............................................................................................................................................................................................done. 13:07:52 INFO - Installing pip.................done. 13:07:52 INFO - Return code: 0 13:07:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:07:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:07:53 INFO - Reading from file tmpfile_stdout 13:07:53 INFO - Output received: 13:07:53 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) 13:07:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:07:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:53 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc80490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9835b6a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da94d30>, '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 0x10d8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da8cb20>, '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.7lJZWHAl1F/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.5kd33JuYxa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 13:07:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:07:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:07:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lJZWHAl1F/Render', 13:07:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:07:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:07:53 INFO - 'HOME': '/Users/cltbld', 13:07:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:07:53 INFO - 'LOGNAME': 'cltbld', 13:07:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:53 INFO - 'MOZ_NO_REMOTE': '1', 13:07:53 INFO - 'NO_EM_RESTART': '1', 13:07:53 INFO - 'PAGER': '/bin/cat', 13:07:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:07:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:53 INFO - 'PWD': '/builds/slave/test', 13:07:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:07:53 INFO - 'SHELL': '/bin/bash', 13:07:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5kd33JuYxa/Listeners', 13:07:53 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 13:07:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:07:53 INFO - 'USER': 'cltbld', 13:07:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:07:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:07:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:53 INFO - 'XPC_FLAGS': '0x0', 13:07:53 INFO - 'XPC_SERVICE_NAME': '0', 13:07:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:07:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:53 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 13:07:53 INFO - Cleaning up... 13:07:53 INFO - Return code: 0 13:07:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:07:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:53 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc80490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9835b6a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da94d30>, '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 0x10d8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da8cb20>, '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.7lJZWHAl1F/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.5kd33JuYxa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 13:07:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:07:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:07:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lJZWHAl1F/Render', 13:07:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:07:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:07:53 INFO - 'HOME': '/Users/cltbld', 13:07:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:07:53 INFO - 'LOGNAME': 'cltbld', 13:07:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:53 INFO - 'MOZ_NO_REMOTE': '1', 13:07:53 INFO - 'NO_EM_RESTART': '1', 13:07:53 INFO - 'PAGER': '/bin/cat', 13:07:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:07:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:53 INFO - 'PWD': '/builds/slave/test', 13:07:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:07:53 INFO - 'SHELL': '/bin/bash', 13:07:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5kd33JuYxa/Listeners', 13:07:53 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 13:07:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:07:53 INFO - 'USER': 'cltbld', 13:07:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:07:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:07:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:53 INFO - 'XPC_FLAGS': '0x0', 13:07:53 INFO - 'XPC_SERVICE_NAME': '0', 13:07:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:07:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:53 INFO - Downloading/unpacking psutil>=3.1.1 13:07:53 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 13:07:53 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 13:07:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:07:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:07:53 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 13:07:53 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 13:07:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:07:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:07:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:07:56 INFO - Installing collected packages: psutil 13:07:56 INFO - Running setup.py install for psutil 13:07:57 INFO - building 'psutil._psutil_osx' extension 13:07:57 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 13:07:57 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 13:07:57 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 13:07:57 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 13:07:57 INFO - building 'psutil._psutil_posix' extension 13:07:57 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 13:07:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:07:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:07:57 INFO - ^ 13:07:58 INFO - 1 warning generated. 13:07:58 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 13:07:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:07:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:07:58 INFO - Successfully installed psutil 13:07:58 INFO - Cleaning up... 13:07:58 INFO - Return code: 0 13:07:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07:58 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc80490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9835b6a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da94d30>, '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 0x10d8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da8cb20>, '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.7lJZWHAl1F/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.5kd33JuYxa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 13:07:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:07:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lJZWHAl1F/Render', 13:07:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:07:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:07:58 INFO - 'HOME': '/Users/cltbld', 13:07:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:07:58 INFO - 'LOGNAME': 'cltbld', 13:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:58 INFO - 'MOZ_NO_REMOTE': '1', 13:07:58 INFO - 'NO_EM_RESTART': '1', 13:07:58 INFO - 'PAGER': '/bin/cat', 13:07:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:58 INFO - 'PWD': '/builds/slave/test', 13:07:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:07:58 INFO - 'SHELL': '/bin/bash', 13:07:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5kd33JuYxa/Listeners', 13:07:58 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 13:07:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:07:58 INFO - 'USER': 'cltbld', 13:07:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:07:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:58 INFO - 'XPC_FLAGS': '0x0', 13:07:58 INFO - 'XPC_SERVICE_NAME': '0', 13:07:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:07:58 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 13:07:58 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 13:07:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:07:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:07:58 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 13:07:58 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 13:08:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:08:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:08:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:08:01 INFO - Installing collected packages: mozsystemmonitor 13:08:01 INFO - Running setup.py install for mozsystemmonitor 13:08:01 INFO - Successfully installed mozsystemmonitor 13:08:01 INFO - Cleaning up... 13:08:01 INFO - Return code: 0 13:08:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:01 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc80490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9835b6a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da94d30>, '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 0x10d8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da8cb20>, '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.7lJZWHAl1F/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.5kd33JuYxa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 13:08:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:08:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:08:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lJZWHAl1F/Render', 13:08:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:01 INFO - 'HOME': '/Users/cltbld', 13:08:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:01 INFO - 'LOGNAME': 'cltbld', 13:08:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:01 INFO - 'MOZ_NO_REMOTE': '1', 13:08:01 INFO - 'NO_EM_RESTART': '1', 13:08:01 INFO - 'PAGER': '/bin/cat', 13:08:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:01 INFO - 'PWD': '/builds/slave/test', 13:08:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:01 INFO - 'SHELL': '/bin/bash', 13:08:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5kd33JuYxa/Listeners', 13:08:01 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 13:08:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:01 INFO - 'USER': 'cltbld', 13:08:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:01 INFO - 'XPC_FLAGS': '0x0', 13:08:01 INFO - 'XPC_SERVICE_NAME': '0', 13:08:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:01 INFO - Downloading/unpacking jsonschema==2.5.1 13:08:01 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 13:08:01 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 13:08:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:01 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 13:08:01 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 13:08:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:08:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:08:04 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 13:08:04 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 13:08:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:04 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 13:08:04 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 13:08:04 INFO - Downloading functools32-3.2.3-2.tar.gz 13:08:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:08:04 INFO - warning: no files found matching '*.txt' 13:08:04 INFO - no previously-included directories found matching 'build' 13:08:04 INFO - no previously-included directories found matching 'dist' 13:08:04 INFO - no previously-included directories found matching '.git*' 13:08:04 INFO - Installing collected packages: jsonschema, functools32 13:08:04 INFO - Running setup.py install for functools32 13:08:04 INFO - warning: no files found matching '*.txt' 13:08:04 INFO - no previously-included directories found matching 'build' 13:08:04 INFO - no previously-included directories found matching 'dist' 13:08:04 INFO - no previously-included directories found matching '.git*' 13:08:04 INFO - Successfully installed jsonschema functools32 13:08:04 INFO - Cleaning up... 13:08:04 INFO - Return code: 0 13:08:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:04 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc80490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9835b6a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da94d30>, '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 0x10d8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da8cb20>, '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.7lJZWHAl1F/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.5kd33JuYxa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 13:08:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:08:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:08:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lJZWHAl1F/Render', 13:08:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:04 INFO - 'HOME': '/Users/cltbld', 13:08:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:04 INFO - 'LOGNAME': 'cltbld', 13:08:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:04 INFO - 'MOZ_NO_REMOTE': '1', 13:08:04 INFO - 'NO_EM_RESTART': '1', 13:08:04 INFO - 'PAGER': '/bin/cat', 13:08:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:04 INFO - 'PWD': '/builds/slave/test', 13:08:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:04 INFO - 'SHELL': '/bin/bash', 13:08:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5kd33JuYxa/Listeners', 13:08:04 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 13:08:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:04 INFO - 'USER': 'cltbld', 13:08:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:04 INFO - 'XPC_FLAGS': '0x0', 13:08:04 INFO - 'XPC_SERVICE_NAME': '0', 13:08:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:08:05 INFO - Cleaning up... 13:08:05 INFO - Return code: 0 13:08:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc80490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9835b6a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da94d30>, '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 0x10d8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da8cb20>, '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.7lJZWHAl1F/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.5kd33JuYxa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 13:08:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:08:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:08:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lJZWHAl1F/Render', 13:08:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:05 INFO - 'HOME': '/Users/cltbld', 13:08:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:05 INFO - 'LOGNAME': 'cltbld', 13:08:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:05 INFO - 'MOZ_NO_REMOTE': '1', 13:08:05 INFO - 'NO_EM_RESTART': '1', 13:08:05 INFO - 'PAGER': '/bin/cat', 13:08:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:05 INFO - 'PWD': '/builds/slave/test', 13:08:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:05 INFO - 'SHELL': '/bin/bash', 13:08:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5kd33JuYxa/Listeners', 13:08:05 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 13:08:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:05 INFO - 'USER': 'cltbld', 13:08:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:05 INFO - 'XPC_FLAGS': '0x0', 13:08:05 INFO - 'XPC_SERVICE_NAME': '0', 13:08:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:05 INFO - Downloading/unpacking blobuploader==1.2.4 13:08:05 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 13:08:05 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 13:08:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:05 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 13:08:05 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 13:08:07 INFO - Downloading blobuploader-1.2.4.tar.gz 13:08:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:08:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:08:07 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 13:08:07 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 13:08:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:07 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 13:08:07 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 13:08:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:08:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:08:08 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 13:08:08 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 13:08:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:08 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 13:08:08 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 13:08:08 INFO - Downloading docopt-0.6.1.tar.gz 13:08:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:08:09 INFO - Installing collected packages: blobuploader, requests, docopt 13:08:09 INFO - Running setup.py install for blobuploader 13:08:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:08:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:08:09 INFO - Running setup.py install for requests 13:08:09 INFO - Running setup.py install for docopt 13:08:09 INFO - Successfully installed blobuploader requests docopt 13:08:09 INFO - Cleaning up... 13:08:09 INFO - Return code: 0 13:08:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:09 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc80490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9835b6a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da94d30>, '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 0x10d8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da8cb20>, '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.7lJZWHAl1F/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.5kd33JuYxa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 13:08:09 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:08:09 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:08:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lJZWHAl1F/Render', 13:08:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:09 INFO - 'HOME': '/Users/cltbld', 13:08:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:09 INFO - 'LOGNAME': 'cltbld', 13:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:09 INFO - 'MOZ_NO_REMOTE': '1', 13:08:09 INFO - 'NO_EM_RESTART': '1', 13:08:09 INFO - 'PAGER': '/bin/cat', 13:08:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:09 INFO - 'PWD': '/builds/slave/test', 13:08:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:09 INFO - 'SHELL': '/bin/bash', 13:08:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5kd33JuYxa/Listeners', 13:08:09 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 13:08:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:09 INFO - 'USER': 'cltbld', 13:08:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:09 INFO - 'XPC_FLAGS': '0x0', 13:08:09 INFO - 'XPC_SERVICE_NAME': '0', 13:08:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:08:10 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-7VTgOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:08:10 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Qo_rtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:08:10 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-1gGe8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:08:10 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-tguDpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:08:10 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-jK0taj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:08:10 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Oja6Yp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:08:10 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-KnHNuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:08:10 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-pepjSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:08:10 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-vC8YW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:08:11 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-4PGJo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:08:11 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-piuRT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:08:11 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-CKoPFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:08:11 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-8o64wK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:08:11 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-in9kRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:08:11 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-3M93Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:08:11 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-5SwgVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:08:11 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-qTd9l2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:08:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:08:11 INFO - Running setup.py install for manifestparser 13:08:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:08:12 INFO - Running setup.py install for mozcrash 13:08:12 INFO - Running setup.py install for mozdebug 13:08:12 INFO - Running setup.py install for mozdevice 13:08:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:08:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:08:12 INFO - Running setup.py install for mozfile 13:08:12 INFO - Running setup.py install for mozhttpd 13:08:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:08:12 INFO - Running setup.py install for mozinfo 13:08:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:08:12 INFO - Running setup.py install for mozInstall 13:08:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:08:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:08:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:08:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:08:13 INFO - Running setup.py install for mozleak 13:08:13 INFO - Running setup.py install for mozlog 13:08:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:08:13 INFO - Running setup.py install for moznetwork 13:08:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:08:13 INFO - Running setup.py install for mozprocess 13:08:13 INFO - Running setup.py install for mozprofile 13:08:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:08:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:08:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:08:13 INFO - Running setup.py install for mozrunner 13:08:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:08:14 INFO - Running setup.py install for mozscreenshot 13:08:14 INFO - Running setup.py install for moztest 13:08:14 INFO - Running setup.py install for mozversion 13:08:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:08:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:08:14 INFO - Cleaning up... 13:08:14 INFO - Return code: 0 13:08:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:08:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:14 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc80490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9835b6a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da94d30>, '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 0x10d8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da8cb20>, '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.7lJZWHAl1F/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.5kd33JuYxa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 13:08:14 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:08:14 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:08:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lJZWHAl1F/Render', 13:08:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:14 INFO - 'HOME': '/Users/cltbld', 13:08:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:14 INFO - 'LOGNAME': 'cltbld', 13:08:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:14 INFO - 'MOZ_NO_REMOTE': '1', 13:08:14 INFO - 'NO_EM_RESTART': '1', 13:08:14 INFO - 'PAGER': '/bin/cat', 13:08:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:14 INFO - 'PWD': '/builds/slave/test', 13:08:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:14 INFO - 'SHELL': '/bin/bash', 13:08:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5kd33JuYxa/Listeners', 13:08:14 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 13:08:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:14 INFO - 'USER': 'cltbld', 13:08:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:14 INFO - 'XPC_FLAGS': '0x0', 13:08:14 INFO - 'XPC_SERVICE_NAME': '0', 13:08:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:08:14 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-YqcCoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:08:14 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)) 13:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:08:14 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-WBybXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:08:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 13:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:08:15 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-ovWU7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:08:15 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)) 13:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:08:15 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-vXt8Qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:08:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 13:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:08:15 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-h8AWOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:08:15 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)) 13:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:08:15 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-cOYHD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:08:15 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)) 13:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:08:15 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-2ElImd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:08:15 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)) 13:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:08:15 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-pFoP7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:08:15 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)) 13:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:08:15 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-dRyTWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:08:15 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)) 13:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:08:15 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-GeitaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:08:15 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)) 13:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:08:15 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-S63XiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:08:15 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)) 13:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:08:15 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-OXoaRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:08:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 13:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:08:16 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-z66OFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:08:16 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)) 13:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:08:16 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-6bJruI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:08:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 13:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:08:16 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-nB0T4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:08:16 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)) 13:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:08:16 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-jaugsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:08:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 13:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:08:16 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Qe9mm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:08:16 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)) 13:08:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:08:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:08:16 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)) 13:08:16 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:08:16 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:08:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:08:16 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 13:08:16 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 13:08:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:16 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 13:08:16 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 13:08:19 INFO - Downloading blessings-1.6.tar.gz 13:08:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:08:19 INFO - Installing collected packages: blessings 13:08:19 INFO - Running setup.py install for blessings 13:08:19 INFO - Successfully installed blessings 13:08:19 INFO - Cleaning up... 13:08:19 INFO - Return code: 0 13:08:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:08:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:08:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:08:19 INFO - Reading from file tmpfile_stdout 13:08:19 INFO - Current package versions: 13:08:19 INFO - blessings == 1.6 13:08:19 INFO - blobuploader == 1.2.4 13:08:19 INFO - docopt == 0.6.1 13:08:19 INFO - functools32 == 3.2.3-2 13:08:19 INFO - jsonschema == 2.5.1 13:08:19 INFO - manifestparser == 1.1 13:08:19 INFO - mozInstall == 1.12 13:08:19 INFO - mozcrash == 0.17 13:08:19 INFO - mozdebug == 0.1 13:08:19 INFO - mozdevice == 0.48 13:08:19 INFO - mozfile == 1.2 13:08:19 INFO - mozhttpd == 0.7 13:08:19 INFO - mozinfo == 0.9 13:08:19 INFO - mozleak == 0.1 13:08:19 INFO - mozlog == 3.4 13:08:19 INFO - moznetwork == 0.27 13:08:19 INFO - mozprocess == 0.23 13:08:19 INFO - mozprofile == 0.28 13:08:19 INFO - mozrunner == 6.12 13:08:19 INFO - mozscreenshot == 0.1 13:08:19 INFO - mozsystemmonitor == 0.3 13:08:19 INFO - moztest == 0.7 13:08:19 INFO - mozversion == 1.4 13:08:19 INFO - psutil == 3.1.1 13:08:19 INFO - requests == 1.2.3 13:08:19 INFO - wsgiref == 0.1.2 13:08:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:20 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc80490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9835b6a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da94d30>, '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 0x10d8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da8cb20>, '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.7lJZWHAl1F/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.5kd33JuYxa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 13:08:20 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:08:20 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:08:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lJZWHAl1F/Render', 13:08:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:20 INFO - 'HOME': '/Users/cltbld', 13:08:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:20 INFO - 'LOGNAME': 'cltbld', 13:08:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:20 INFO - 'MOZ_NO_REMOTE': '1', 13:08:20 INFO - 'NO_EM_RESTART': '1', 13:08:20 INFO - 'PAGER': '/bin/cat', 13:08:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:20 INFO - 'PWD': '/builds/slave/test', 13:08:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:20 INFO - 'SHELL': '/bin/bash', 13:08:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5kd33JuYxa/Listeners', 13:08:20 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 13:08:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:20 INFO - 'USER': 'cltbld', 13:08:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:20 INFO - 'XPC_FLAGS': '0x0', 13:08:20 INFO - 'XPC_SERVICE_NAME': '0', 13:08:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:20 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)) 13:08:20 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)) 13:08:20 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)) 13:08:20 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)) 13:08:20 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)) 13:08:20 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)) 13:08:20 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)) 13:08:20 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)) 13:08:20 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)) 13:08:20 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)) 13:08:20 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)) 13:08:20 INFO - Cleaning up... 13:08:20 INFO - Return code: 0 13:08:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:08:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10dc80490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9835b6a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da94d30>, '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 0x10d8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da8cb20>, '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.7lJZWHAl1F/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.5kd33JuYxa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 13:08:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:08:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lJZWHAl1F/Render', 13:08:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:21 INFO - 'HOME': '/Users/cltbld', 13:08:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:21 INFO - 'LOGNAME': 'cltbld', 13:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:21 INFO - 'MOZ_NO_REMOTE': '1', 13:08:21 INFO - 'NO_EM_RESTART': '1', 13:08:21 INFO - 'PAGER': '/bin/cat', 13:08:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:21 INFO - 'PWD': '/builds/slave/test', 13:08:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:21 INFO - 'SHELL': '/bin/bash', 13:08:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5kd33JuYxa/Listeners', 13:08:21 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 13:08:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:21 INFO - 'USER': 'cltbld', 13:08:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:21 INFO - 'XPC_FLAGS': '0x0', 13:08:21 INFO - 'XPC_SERVICE_NAME': '0', 13:08:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:08:21 INFO - Cleaning up... 13:08:21 INFO - Return code: 0 13:08:21 INFO - Running post-action listener: _resource_record_post_action 13:08:21 INFO - Running post-action listener: _start_resource_monitoring 13:08:21 INFO - Starting resource monitoring. 13:08:21 INFO - [mozharness: 2016-10-04 20:08:21.482412Z] Finished create-virtualenv step (success) 13:08:21 INFO - [mozharness: 2016-10-04 20:08:21.483441Z] Running install step. 13:08:21 INFO - Running pre-action listener: _resource_record_pre_action 13:08:21 INFO - Running main action method: install 13:08:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:08:21 INFO - Reading from file tmpfile_stdout 13:08:21 INFO - Detecting whether we're running mozinstall >=1.0... 13:08:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:08:21 INFO - Reading from file tmpfile_stdout 13:08:21 INFO - Output received: 13:08:21 INFO - Usage: mozinstall [options] installer 13:08:21 INFO - Options: 13:08:21 INFO - -h, --help show this help message and exit 13:08:21 INFO - -d DEST, --destination=DEST 13:08:21 INFO - Directory to install application into. [default: 13:08:21 INFO - "/builds/slave/test"] 13:08:21 INFO - --app=APP Application being installed. [default: firefox] 13:08:21 INFO - mkdir: /builds/slave/test/build/application 13:08:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:08:55 INFO - Reading from file tmpfile_stdout 13:08:55 INFO - Output received: 13:08:55 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:08:55 INFO - Running post-action listener: _resource_record_post_action 13:08:55 INFO - [mozharness: 2016-10-04 20:08:55.861091Z] Finished install step (success) 13:08:55 INFO - [mozharness: 2016-10-04 20:08:55.861226Z] Running run-tests step. 13:08:55 INFO - Running pre-action listener: _resource_record_pre_action 13:08:55 INFO - Running main action method: run_tests 13:08:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:08:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:08:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:08:55 INFO - 2016-10-04 13:08:55.875 screenresolution[1636:7517] starting screenresolution argv=screenresolution get 13:08:55 INFO - 2016-10-04 13:08:55.878 screenresolution[1636:7517] Display 0: 1600x1200x32@60 13:08:55 INFO - 2016-10-04 13:08:55.884 screenresolution[1637:7519] starting screenresolution argv=screenresolution list 13:08:55 INFO - Available Modes on Display 0 13:08:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:08:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:08:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:08:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:08:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:08:56 INFO - Intel Iris: 13:08:56 INFO - Chipset Model: Intel Iris 13:08:56 INFO - Type: GPU 13:08:56 INFO - Bus: Built-In 13:08:56 INFO - VRAM (Dynamic, Max): 1536 MB 13:08:56 INFO - Vendor: Intel (0x8086) 13:08:56 INFO - Device ID: 0x0a2e 13:08:56 INFO - Revision ID: 0x0009 13:08:56 INFO - Displays: 13:08:56 INFO - Display: 13:08:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:08:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:08:56 INFO - Display Serial Number: 1600x1200 60 13:08:56 INFO - Main Display: Yes 13:08:56 INFO - Mirror: Off 13:08:56 INFO - Online: Yes 13:08:56 INFO - Rotation: Supported 13:08:56 INFO - Return code: 0 13:08:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:08:56 INFO - Python 2.7.3 13:08:56 INFO - Return code: 0 13:08:56 INFO - grabbing minidump binary from tooltool 13:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:08:56 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 0x10da94d30>, '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 0x10d8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da8cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:08:56 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 13:08:56 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 13:08:56 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 13:08:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:08:56 INFO - Return code: 0 13:08:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:08:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:08:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:08:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:08:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:08:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 13:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0200 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 13:08:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lJZWHAl1F/Render', 13:08:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:56 INFO - 'HOME': '/Users/cltbld', 13:08:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:56 INFO - 'LOGNAME': 'cltbld', 13:08:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:08:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:56 INFO - 'MOZ_NO_REMOTE': '1', 13:08:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:08:56 INFO - 'NO_EM_RESTART': '1', 13:08:56 INFO - 'PAGER': '/bin/cat', 13:08:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:56 INFO - 'PWD': '/builds/slave/test', 13:08:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:08:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:56 INFO - 'SHELL': '/bin/bash', 13:08:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5kd33JuYxa/Listeners', 13:08:56 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 13:08:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:56 INFO - 'USER': 'cltbld', 13:08:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:56 INFO - 'XPC_FLAGS': '0x0', 13:08:56 INFO - 'XPC_SERVICE_NAME': '0', 13:08:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 13:08:56 INFO - mozversion application_buildid: 20161004115401 13:08:56 INFO - mozversion application_changeset: e0ef5898308b07ceb17f5bbf2192a5d496c7b6e7 13:08:56 INFO - mozversion application_display_name: Nightly 13:08:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:08:56 INFO - mozversion application_name: Firefox 13:08:56 INFO - mozversion application_remotingname: firefox 13:08:56 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 13:08:56 INFO - mozversion application_vendor: Mozilla 13:08:56 INFO - mozversion application_version: 52.0a1 13:08:56 INFO - mozversion platform_buildid: 20161004115401 13:08:56 INFO - mozversion platform_changeset: e0ef5898308b07ceb17f5bbf2192a5d496c7b6e7 13:08:56 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 13:08:56 INFO - mozversion platform_version: 52.0a1 13:08:56 INFO - using testdate: 1475611736 13:08:56 INFO - actual date: 1475611736 13:08:56 INFO - starting webserver on 'localhost:49267' 13:08:56 INFO - SUITE-START | Running 2 tests 13:08:56 INFO - TEST-START | dromaeo_css 13:08:56 INFO - operating with platform_type : mac_ 13:08:56 INFO - Initialising browser for dromaeo_css test... 13:08:56 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpBlYQGb/profile http://localhost:49267/getInfo.html) 13:08:59 INFO - PROCESS | 1647 | 2016-10-04 13:08:59.366 plugin-container[1649:7690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 13:08:59 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:08:59 INFO - PROCESS | 1647 | 2016-10-04 13:08:59.369 plugin-container[1649:7690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 13:08:59 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:08:59 INFO - PROCESS | 1647 | 2016-10-04 13:08:59.369 plugin-container[1649:7690] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:08:59 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 13:08:59 INFO - PROCESS | 1647 | colorDepth:24 13:08:59 INFO - PROCESS | 1647 | Browser inner width/height: 1024/663 13:08:59 INFO - PROCESS | 1647 | __metrics 13:08:59 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 13:09:00 INFO - TEST-INFO | 1647: exit 0 13:09:00 INFO - Browser initialized. 13:09:00 INFO - Running cycle 1/1 for dromaeo_css test... 13:09:00 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpBlYQGb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:09:02 INFO - PROCESS | 1650 | 2016-10-04 13:09:02.891 plugin-container[1654:7858] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d23, name = 'com.apple.tsm.portname' 13:09:02 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:09:02 INFO - PROCESS | 1650 | 2016-10-04 13:09:02.894 plugin-container[1654:7858] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 13:09:02 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:09:02 INFO - PROCESS | 1650 | 2016-10-04 13:09:02.894 plugin-container[1654:7858] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:09:12 INFO - PROCESS | 1650 | RSS: Main: 250687488 13:09:12 INFO - PROCESS | 1650 | 13:10:02 INFO - PROCESS | 1650 | 1475611802094 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:12:15 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 13:12:16 INFO - PROCESS | 1650 | RSS: Main: 267976704 13:12:16 INFO - PROCESS | 1650 | 13:15:19 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 13:15:19 INFO - PROCESS | 1650 | RSS: Main: 279339008 13:15:19 INFO - PROCESS | 1650 | 13:18:21 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 13:18:22 INFO - PROCESS | 1650 | RSS: Main: 278704128 13:18:22 INFO - PROCESS | 1650 | 13:21:24 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 13:21:24 INFO - PROCESS | 1650 | RSS: Main: 278208512 13:21:24 INFO - PROCESS | 1650 | 13:24:27 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 13:24:28 INFO - PROCESS | 1650 | RSS: Main: 283688960 13:24:28 INFO - PROCESS | 1650 | 13:27:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 13:27:31 INFO - PROCESS | 1650 | RSS: Main: 286040064 13:27:31 INFO - PROCESS | 1650 | 13:27:31 INFO - PROCESS | 1650 | __start_tp_report 13:27:31 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 13:27:31 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 13:27:31 INFO - PROCESS | 1650 | |i|pagename|runs| 13:27:31 INFO - PROCESS | 1650 | |0;dojo.html;2837.092130518234;3403.319336132774;3410.266792639582;3431.3823511767873;3433.193199598095;5780.739866706003;5849.473547380735;5850.180974663551;5857.297124345079;5866.85332866678;5654.180143879145;5767.33675627303;5787.681677507735;5821.3305469870975;5893.823185304437;5214.243934629477;5280.023195708796;5296.443873393296;5303.7082960437165;5335.653182543142;5229.947700523003;5316.5215130638235;5327.520523152915;5403.324584426947;5412.756425960822;4552;5299.5760339172775;5331.733413329337;5335.946640533583;5355.598330125236;4274.508431530352;5192.636515443921;5231.006108839309;5312.229726689647;5335.386430560497;5216.37403511581;5218.66078704883;5238.376978188351;5250.921236181461;5271.710055946922;4785.617150627942;5568;5678.4037676044245;5864.413558644144;5874.50066744323;6142.498775697854;6154.692265386758;6188.288346840126;6204.503639708812;6330.430261276461;6277;6293.905591416133;6297.779577714758;6310;6324.810255692337;5925.525957923356;5926.4369884861735;5946.401985111663;5953.970230148865;5972.880542389128;6175.567710260329;6231.4391704746795;6240.063990401476;6291.622502649856;6303.905441418382;6200.3179650239335;6226.40849119333;6229.127922090911;6231.376862313823;6238.438540531374;6252.874942501125;6263.966445536526;6310.968445157745;6341.492680585542;6348.841278968062;5448.591355648342;6135.110179431455;6206.472449841724;6214.627122372672;6219.751209951642;6230.190075290274;6241.75032998684;6298.8425289367215;6313.715882785285;6330.841728956813;3945.585713500041;4605.953940460552;4814.470408255089;4972;5096.082705113041;4343.218220720238;4409.470863496337;4455.086707225011;4469.307257375112;4504.189245935763;5835.67903765297;5873.676947767914;5909.438603332676;5932.762689492372;5932.970335148297;6245.500359971192;6259.374062593704;6264.561480696308;6291.087792270096;6302.653354065479;5884.558658100659;5892.970535147297;5894.941050589439;5897.469227769521;5938.198343223696;5374.4625537445945;5448.318960129984;5463.31708536433;5482.205080263339;5486.3690675572025;6213.130161777355;6225.035119556476;6262.780802671976;6298.748050077946;6345.175127233522;6294.370562943669;6300.684965751786;6307.653079080695;6314.810555683337;6338;6363.490920726238;6435.1312572803945;6435.967820160869;6446.838829029124;6447.967760161169;6116.969415152896;6155.692215389302;6209.0996805462055;6296.622202667671;6307.621542707452;5183.714895680623;6227.532935029982;6293.527985401021;6297.590656607306;6314.431701146735;6242.469390101892;6253.96873015632;6260.217472815898;6265.122882796321;6267.435930766253;6066.636001839904;6120.785772497941;6199.194104766351;6225.750969961151;6249.218847644044;5886;5978.103284507359;6023.698815059317;6116.670564642638;6119.015143218386;6095.8780824382375;6203.348648391854;6206.255249370104;6231.657258850807;6268.655223962726;5906.497947674496;5976.193213916065;6030.90953635687;6058.303295120942;6078.939210607838 13:27:31 INFO - PROCESS | 1650 | |1;ext.html;448.34541569308806;480.87715185042924;486.6252985201394;521.2207749414625;535.9571234301256;37353;42216.366754498704;42542.787286063605;44653;44774.77612611932;42506.787466062706;43368.91577710551;43728.781356093255;43891;43938;9874.802503949917;9984.301098923079;10038.949805250964;10063.245256605747;10070.84893726591;9280.443173409618;9524.714258572254;9935.503224838765;10021.34861234021;10030.498475076254;8501.61742721579;9961.203103751683;10004.59981600735;10047.799044019115;10060.295779295453;9969.900300997006;9971.252156088321;9989.700308990741;10005.54975026122;10027.54876030573;9866.26003049767;9971.20230381575;9983.101520863085;10006.149477294439;10011;41114.177716445505;41229.793851030554;41287.38068928968;41414.792926035174;41498.79250603758;3402.5406570112964;3453.5165076889257;3454.0674018014997;3465.7747246428903;3470.201853573667;31486.842565787254;31885.84057079723;32008.359832803213;32129;32152.356952861046;3150.503510832359;3243.9837800810847;3266.5426840242385;3381.306832099414;3419.145213696576;372129;404670;437029;491155.54422228015;493816;61936.69031654903;62019.689901550206;62040;62386.68806655938;62392.68803655953;64188.67905660535;65380;65414.34585654082;65547;65593.67203163954;16284.918575407284;16401.343946242017;16624;16625;16648.500544983668;12738.108332416641;12801.423935922905;12828.80756788649;12833.230006199658;12910.22538647684;14214.573562793134;17311.528520075684;17650.117494125243;17660.11699415024;17673.558161046076;5516;5595.272614560081;6618.834529136714;6636.402723754885;6704.463642908556;884.6019291318933;993.9701808945744;1026.4252018869456;1046.3042077018697;1053.441675911768;29171.416571668447;30295.69704302973;30477.847610761804;31117.68882311193;31348.37303253922;964.9951750241204;1058.6506452870535;1120.6525976947173;1138.3170097941334;1174.2015429508017;15549.84450155484;15762.448314308944;15772.842271577138;15805.36778528869;16084.839151608334;577740;606940;610666;617104;617929;58050.41949580535;58582;59604.70197648984;59730;59733.70133149307;1284.8522419921642;1317.9934100329438;1328.1433475408307;1360.7142500074897;1378.696686728918;29762;30729.031355542713;30755.692443075284;31033;31224;51362.48637513727;51858;51895;52259.73870130625;52265.7386713064;575060;577421;577941;579416;580485;41611.78146889786;41700.79149604232;41721.58278417177;42597.5740242606;42602.57397425986;46041.76979115083;46801.53198468108;46842.53157468518;47430.28854567115;48051.75974120107;39278.60721392864;39301.41047884227;39705.60294397019;40880.591194087676;41193.38209926913;38724.6127538721;38837;39357.60642393654;40276.597234027286;40326.39510407288;32524.349513009132;33405.49891751577;37632;38212;39420.01449963774;32628.184295392806;34524.48213276853;35206.295874081865;35219.47170792492;35296.64703353036;21691.783082168975;25589.616155758053;25981.090661826744;26246.737532625193;26309.94760209647 13:27:31 INFO - PROCESS | 1650 | |2;jquery.html;375.07543904275957;377.3057574063722;380.43315459964646;387.101923537393;402.3220872829283;17168.48494545164;21523.46191345217;21852.45368865779;22059.44851378714;22060.227892023784;20911.268105616306;20924.686129708065;21115.049822757974;21344.8932755336;21405.57188856222;1597.4169428158755;1600.8559229669327;1604.9117298548574;1933.787283398825;5277.656952298088;6703.329667033307;6981.965090174542;6994.4754143439195;7012.193597736275;7017.578945263275;6524.282328943812;6604.273529911706;6808.3191680832015;6819.488538359618;6928.861422771542;5290.629655924087;6760.836532309663;6831.077804496379;6878.793636190872;6901.585904845725;5936.7328470218945;6067.484263837579;6075.908861367083;6076.5442591805795;6089.695515224265;11324.830127548094;11777.411129443579;12159.392030398532;13591.456341746318;13662.726745465134;2352.8353015288935;2425.369403954967;2612.8562929038894;2620.737926207383;2988.2678743707716;10342.069213770721;10461;10564.996325349079;11049;14358.846173074018;1293.7994610835242;1783.4382169616529;1805.6728304696037;1864.4872660018602;1872.5412273992958;112695;124777.37611311885;124945.37527312305;126267;126502;11286.717832054264;11555.595554155565;11769.470373833197;11770.823437648442;11796.823047654292;14698.926505367619;14713;14738.336774845158;14752.852471475147;14786.704265914621;8425.452345597516;8643.135686431306;8666.306695464475;8777.122287771172;8796.724474951096;8626.611802468778;8634.136586341316;8659.610317535726;8730.650773969097;8807.47155170679;4488.147252022105;4582.289745089516;4608.746518941423;4648.442186937589;4692.178868698033;1708.9401870934457;1888.1314595286321;1935.8935258560632;1936.1287420660758;1969.0843757652474;843.9915600844035;892.9866052009226;945.8770359853175;1009.1985805337438;1062.3625824505234;18282.54293642643;20868.373948781562;21669.458263543223;21690.132394704036;21704.80623565719;817.3134566963779;855.5764896376334;926.4719110107175;947.6541062512133;966.0532677975623;9834.901650983542;11062.263064922654;11110.833337499944;11563.924677898909;11573.88426115745;566819;581073.1892681019;590058;596840;598258;94884.52557737168;104091.43862842064;112359;127586.36206818906;131003;902.6750369866843;928.2388441477923;941.5198248893114;954.9904500954901;982.5332966840693;19294.842309461477;19440.90279548593;19654.410367688994;19780.912049837396;19806.801931980495;54869.72565137149;54910;55482;55720.44279557152;56799.71600141973;61603;62395;80517.59741201256;81301.59349203216;82530.62040693944;8059.758207253792;8251.669933202606;8301.958490207511;8322.16778322163;8333.208345207195;12267.509299627915;13039.739205216032;13250.271235081778;13501.324933753469;13865.09876858001;10896.182786291218;11308;11663.59177428786;11937;11958.46186921608;11168.385738784445;11298.830517542408;11372.601958931398;11844.526218951145;11855.644330670093;9444.763880903032;11367.54529818831;11455.91168838959;11584.304941703525;11858.40707964581;6855.314468553304;6864.485163612849;6966.885298352239;8207.794805129919;8494.193051660082;10990.230683852054;11113.44432778374;11284.43577821122;11661.125415593695;11884.940575297069 13:27:31 INFO - PROCESS | 1650 | |3;mootools.html;1379.020895164433;1559.6646720954993;1619.4008216959726;1739.6694628020675;1901.95245118872;8037.91962080379;8310.501369917809;8547.145285471457;9047.321450891184;9108.134727200922;8617.439866408678;8927.419717718343;8934.86597701035;8990.595423205956;9014.053524379928;2350.5886469867746;2371.205646108556;2372.063035101134;2374.0859768988976;2378.714554253492;2362.397588614901;2380.631002194654;2385.487120269142;2394.449276666368;2405.20628192696;2372.3476044087843;2392.1986134644917;2398.352444839892;2399.8920048597856;2400.6759087523224;2347.213683416058;2355.882205889716;2359.327591636384;2362.338545207345;2391.629297458897;2355.611324131516;2363.7163540375263;2364.929052128439;2367.9289621311386;2374.2402431222017;8973.910260897374;8979.551022448917;9002.639894404216;9048.547572621343;9055.411398259092;5260.8684782880355;5390.78436862525;5430.619856610039;5433.728313584305;5445.9727701361635;3246.853891574885;3430.9485357719655;3601.9099522511883;3617;3676.1728611062563;1072.774717309366;1080.330195278932;1097.3470784883061;1098.1489345757022;1106.4025426269884;11705.941470292679;11721.70695732605;11820.349880756641;11844.407779610985;11861.46623401949;4539.886502837389;4622.930656040162;4771.809127634856;4827.565519103297;4830.855074347705;9937.950310248547;10046.29675922693;10072;10075;10104.242181836391;4562;5357.35711714589;5365.597580181424;5368.2752828367675;5376.059189641797;4394.560543945579;4544.477385100689;4737;4815.7351345676325;4925.458199598042;3878.6121387861554;3951.68386529077;3994.6205110514456;4026.798660066985;4048.4939382577177;1273.961781146567;1288.1948782011243;1289.4584274604688;1294.643972907458;1302.3423171298623;875.5009644502572;885.2873436883242;896.4397251717677;896.4531635702216;934.6541779541614;5631.521320687705;5693.800716974969;5895.233619629421;5991.430814072656;5998.550108741862;363.68541211851556;425.4936625415745;441.30274166816355;441.40630851504676;443.4102643484158;3459.7232221422228;3474.4614584739406;3837.309284328792;3945.8027098644952;3964.266610677034;10521.21090918169;10781.514831832586;10904.781904362027;10931.781364372828;10934.56261749537;10871.130309575216;10875.564977400816;10917.23579349438;10931.070858976916;10936.125109991182;7842.411819113589;8231.09457959624;8246;8275.54484503346;8354;5465.754041068082;5513;5977.9103313451205;6009.369016253405;6106.961816491296;8288.41981061344;8505.617247224014;9536.618535258513;10214.846777298199;10282.794344112926;9217.124373184537;9229.676961306322;10099.596016159272;10117.392956422638;10315.896841031794;6139.171211886338;6996.860062798613;7379.704811807683;7575.356094731789;7704.884426733715;6884.827879302858;8423.07346191906;8491;8493.490390576586;8507.744767656981;6708.496862735216;7551.433642476726;7667.424943129176;7697.807554811175;7755.72854950074;6917.688704008432;7216.170140433707;7498.587577683062;7513.361364283878;7717.112532058661;7065.1521817382245;7093.290670932865;7171.067761191011;7180.640967951477;7191.17301510333;4879.829205977774;5002.724850133278;5034.0938631046765;5051.823186188612;5103.642745007963;4956.231784073474;5001.24981252815;5009.949900500948;5072.340595722532;5174.14626586609 13:27:31 INFO - PROCESS | 1650 | |4;prototype.html;282.92078218098925;305.37855464130485;305.6195037178712;310.9471389863723;312.9311551458678;4855.484399508456;5281.283582835579;5570.184423669789;5630.662160270382;5663.66018038918;5379.870227252272;5464.3442786865635;5473;5505.119180931052;5597.692126933017;3504.5093686883856;3648.58041325247;3940.9999999999927;3961.4652021977095;4150.294449943502;3044.2541577313477;4114.876553703393;4159.856039589107;4162.980069882868;4210;3643.635636436362;4147.357159640245;4157.97921010396;4165.416841642173;4182.393552934838;3842.5773164951834;4096.877093687194;4114.670826333916;4187.560306167854;4191.643710284629;3499.30013997199;3500.4399296112756;3515.683588477024;3524.4184709522897;3526.7883926964464;3973.4834471518807;4645.630728328543;5198.766055527511;5301;5317.335333083364;4118.979405102985;4288.935665965013;4678.461976872669;4696.7651617419;4703.952960470386;5754.3094828620415;5797.971010144923;5801.883962320772;5836.2996440427;5973.970130149365;3937.7834219117935;3948.1116748731592;3950.822213000422;3950.980245098785;3970.2530886410177;5522.9447705523235;5751.884962300731;6076.3316035236085;6132.370060604215;6134.325224225332;3865.3042452358845;3894.2600905827803;3929.135590170158;3967.583403742623;3972.980135099364;3161.3993341265086;3178.7113278439488;3653.104989277591;4006.3958427022667;4012.3981402789814;3730.570984336836;4676.836310729108;4697.459792123881;4746.837024928915;4774.570288674036;5030.019146266516;5210.863545946144;5603.943960560424;5728.598998070179;5840.474357307863;1099.3239157917824;1259.8110283457554;1356.4506374918174;1371.451419432235;1388.722375414621;756.7691853984478;789.676232744572;813.650130443903;837.8952630921135;838.6393850644281;4324.956750432519;5260.316158899319;5432.674039557639;5456.590755693339;5471.534919531884;6019.4883434908525;6061.909071363933;6101.633901965896;6189.288231853305;6234.875302493925;4701.8354357598;4904.314424857677;5064.341635587423;5403.2705584746345;5491.7254137292975;3172.254520187754;3183.602049743782;3184.0288711942617;3206.887758928426;3255.4953982132806;5315.867103322448;6170.629762214281;6203.31763506014;6216.451254985434;6250.90594650788;5837.532997360201;5877.353491115974;6214.658193799294;6218.3882769236125;6263.561550691499;5200.271961925334;5640.802571909963;6026.849328766816;6233.719482623293;6368.64972426521;5772.307323121252;6168.290646575701;6176.980798168252;6178.876422471615;6196.907046394398;5433.1153225443995;5844.298684157929;6082.482988945989;6093.299270583762;6137.96931015342;5491.643043202179;5849.402617390359;6015.789447369321;6271.498280137669;6347.0479428086155;3989.6409323161056;4109.198706252371;4122.361034039729;4145.233131870614;4147.834086636622;5264.921026184534;5949.910751338647;5987.670678112746;6059.303180134341;6111.908321375094;4570.314452832102;5726.5683579105225;5773.336066352255;5811.941880581309;5961.2846458425265;5972.432618901197;5991.910121348096;6016.127661489147;6029;6057.363976782374;4859.424505012349;5114.590832733298;5802;5878.911816322673;5880.617759845596;4964.404271487445;5103.948960510347;5429.18562215655;5571.0807716726285;5687.402822703557;4885;5607.2149899014985;5703.657780533182;5763.020286551213;5807.096451774113 13:27:31 INFO - PROCESS | 1650 | |5;yui.html;647.62761412188;735.3308489274759;786.8150984518641;795.5266616363261;835.6156168162647;15221.543353699379;15827.604309892258;15951.52145435636;16001.19994000301;16042.83957160428;14510;14654.63363415915;14662.340194691238;14689;14695.265236738174;676.5061505101279;700.6181631011086;818.7789296889836;847.4068152293388;851.0638297872352;862.3403096631082;875.8729984152326;876.0057334924875;877.3770622857778;880.7533890510667;914.2411798207472;915.3546749541557;915.771057235691;917.5320586500866;917.7980844214262;905.8595917632778;907.4827348411408;910.3900386740834;911.2527727263647;912.2109375390306;667.7529314153745;678.6267552845915;681.264234626602;682.7405585877384;684.2780866186172;15006.849931500654;15017.324220409999;15026.699466010728;15045.473408430653;15078.0199287046;12206;14629.268536573129;14647.780283295759;14819.77770333446;14888;11936.70158246042;12059.457324420335;12332.938335308356;12363.196392234477;12536.561220357244;3740.7942563159017;3745.9812700936322;3908.667763240128;3954.782486963216;3955.1694144229746;109742;110424.4478777609;111244;111660.4416977918;115055.99999999916;9373.859392109123;11260.436978151223;11353.205275630791;11505.079593632488;11735.119866009913;24117.51764964726;24231.273061808173;24281.757182428304;24319.270421887373;24437.51124977526;6887.483438742114;7884;7975.481593696391;8068.233517815798;8164.061132969666;4226.4505614270565;4319.179355922364;4363.716358436692;4399.890002749957;4416.447944006999;3409.09658940381;3552.91117722059;3560.6973407260157;3561.8753343633366;3810.447485114643;705.0517054561607;705.2665228162756;708.7094291340526;722.6134018300185;723.2875617516847;495.7546014722663;533.9599530035263;630.9399412847653;659.7876773489471;670.7518218259275;9355.158035776813;9504.239660827117;9508.667196648084;9539.18916892058;9752.9024709752;627.1940556385023;638.2639221318008;641.7433026789322;668.5620918298522;678.5453745990199;4331.523532411432;4759.690620109682;4827.179379505493;4993.350864387679;5100.081985242692;66112;70406.29593703998;88390;95523.04476955142;156135.43864561437;71508.21229469201;72489;110728.4463577677;114711;117870.41064794792;69784.6510767443;98321;107475.46262268639;112263.43868280607;115355.42322288334;8580;8662.610182541675;8789.373965816088;9628.47043412619;9822.5088745561;41366.37950430686;46600.6019819406;56783.71608141933;60665;61324.69337653283;61578;66439.66780166069;72005;96267.51866240623;97890;12582.370881456074;12838.807417888553;13148.145370550883;13152.848953897841;13792.586222413343;14941.850581494045;15044.849551504783;15217.467388641344;15254.161021143944;15476.922615386851;13773.724525509635;14069.718605628035;14092.154470731788;14320.427182912983;14388.35252413623;13355;13696.794548081587;13836.23900685472;13839.30803459843;14105.365258563184;13807.51673691416;13870.65323366924;13911.443542258196;13924.58226253214;14017.369218384993;11142.164337674545;11146.498407571517;11316.660500185008;11908.047356211655;11966.401679915794;8357.414980951275;8767.780805479913;10143.7464063399;10786.730331741455;11466.827997579876 13:27:31 INFO - PROCESS | 1650 | __end_tp_report 13:27:31 INFO - PROCESS | 1650 | __start_cc_report 13:27:31 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,371 13:27:31 INFO - PROCESS | 1650 | __end_cc_report 13:27:31 INFO - PROCESS | 1650 | __startTimestamp1475612851201__endTimestamp 13:27:31 INFO - PROCESS | 1650 | 13:27:31 INFO - PROCESS | 1650 | ------- Summary: start ------- 13:27:31 INFO - PROCESS | 1650 | Number of tests: 6 13:27:31 INFO - PROCESS | 1650 | 13:27:31 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5780.10 Median:6030.91 stddev:688.11 (11.4%) stddev-sans-first:650.34 13:27:31 INFO - PROCESS | 1650 | Values: 2837.1 3403.3 3410.3 3431.4 3433.2 5780.7 5849.5 5850.2 5857.3 5866.9 5654.2 5767.3 5787.7 5821.3 5893.8 5214.2 5280.0 5296.4 5303.7 5335.7 5229.9 5316.5 5327.5 5403.3 5412.8 4552.0 5299.6 5331.7 5335.9 5355.6 4274.5 5192.6 5231.0 5312.2 5335.4 5216.4 5218.7 5238.4 5250.9 5271.7 4785.6 5568.0 5678.4 5864.4 5874.5 6142.5 6154.7 6188.3 6204.5 6330.4 6277.0 6293.9 6297.8 6310.0 6324.8 5925.5 5926.4 5946.4 5954.0 5972.9 6175.6 6231.4 6240.1 6291.6 6303.9 6200.3 6226.4 6229.1 6231.4 6238.4 6252.9 6264.0 6311.0 6341.5 6348.8 5448.6 6135.1 6206.5 6214.6 6219.8 6230.2 6241.8 6298.8 6313.7 6330.8 3945.6 4606.0 4814.5 4972.0 5096.1 4343.2 4409.5 4455.1 4469.3 4504.2 5835.7 5873.7 5909.4 5932.8 5933.0 6245.5 6259.4 6264.6 6291.1 6302.7 5884.6 5893.0 5894.9 5897.5 5938.2 5374.5 5448.3 5463.3 5482.2 5486.4 6213.1 6225.0 6262.8 6298.7 6345.2 6294.4 6300.7 6307.7 6314.8 6338.0 6363.5 6435.1 6436.0 6446.8 6448.0 6117.0 6155.7 6209.1 6296.6 6307.6 5183.7 6227.5 6293.5 6297.6 6314.4 6242.5 6254.0 6260.2 6265.1 6267.4 6066.6 6120.8 6199.2 6225.8 6249.2 5886.0 5978.1 6023.7 6116.7 6119.0 6095.9 6203.3 6206.3 6231.7 6268.7 5906.5 5976.2 6030.9 6058.3 6078.9 13:27:31 INFO - PROCESS | 1650 | 13:27:31 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:68765.43 Median:39562.81 stddev:145852.34 (368.7%) stddev-sans-first:146171.30 13:27:31 INFO - PROCESS | 1650 | Values: 448.3 480.9 486.6 521.2 536.0 37353.0 42216.4 42542.8 44653.0 44774.8 42506.8 43368.9 43728.8 43891.0 43938.0 9874.8 9984.3 10038.9 10063.2 10070.8 9280.4 9524.7 9935.5 10021.3 10030.5 8501.6 9961.2 10004.6 10047.8 10060.3 9969.9 9971.3 9989.7 10005.5 10027.5 9866.3 9971.2 9983.1 10006.1 10011.0 41114.2 41229.8 41287.4 41414.8 41498.8 3402.5 3453.5 3454.1 3465.8 3470.2 31486.8 31885.8 32008.4 32129.0 32152.4 3150.5 3244.0 3266.5 3381.3 3419.1 372129.0 404670.0 437029.0 491155.5 493816.0 61936.7 62019.7 62040.0 62386.7 62392.7 64188.7 65380.0 65414.3 65547.0 65593.7 16284.9 16401.3 16624.0 16625.0 16648.5 12738.1 12801.4 12828.8 12833.2 12910.2 14214.6 17311.5 17650.1 17660.1 17673.6 5516.0 5595.3 6618.8 6636.4 6704.5 884.6 994.0 1026.4 1046.3 1053.4 29171.4 30295.7 30477.8 31117.7 31348.4 965.0 1058.7 1120.7 1138.3 1174.2 15549.8 15762.4 15772.8 15805.4 16084.8 577740.0 606940.0 610666.0 617104.0 617929.0 58050.4 58582.0 59604.7 59730.0 59733.7 1284.9 1318.0 1328.1 1360.7 1378.7 29762.0 30729.0 30755.7 31033.0 31224.0 51362.5 51858.0 51895.0 52259.7 52265.7 575060.0 577421.0 577941.0 579416.0 580485.0 41611.8 41700.8 41721.6 42597.6 42602.6 46041.8 46801.5 46842.5 47430.3 48051.8 39278.6 39301.4 39705.6 40880.6 41193.4 38724.6 38837.0 39357.6 40276.6 40326.4 32524.3 33405.5 37632.0 38212.0 39420.0 32628.2 34524.5 35206.3 35219.5 35296.6 21691.8 25589.6 25981.1 26246.7 26309.9 13:27:31 INFO - PROCESS | 1650 | 13:27:31 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:34198.60 Median:21955.95 stddev:97847.55 (445.7%) stddev-sans-first:98089.08 13:27:31 INFO - PROCESS | 1650 | Values: 375.1 377.3 380.4 387.1 402.3 17168.5 21523.5 21852.5 22059.4 22060.2 20911.3 20924.7 21115.0 21344.9 21405.6 1597.4 1600.9 1604.9 1933.8 5277.7 6703.3 6982.0 6994.5 7012.2 7017.6 6524.3 6604.3 6808.3 6819.5 6928.9 5290.6 6760.8 6831.1 6878.8 6901.6 5936.7 6067.5 6075.9 6076.5 6089.7 11324.8 11777.4 12159.4 13591.5 13662.7 2352.8 2425.4 2612.9 2620.7 2988.3 10342.1 10461.0 10565.0 11049.0 14358.8 1293.8 1783.4 1805.7 1864.5 1872.5 112695.0 124777.4 124945.4 126267.0 126502.0 11286.7 11555.6 11769.5 11770.8 11796.8 14698.9 14713.0 14738.3 14752.9 14786.7 8425.5 8643.1 8666.3 8777.1 8796.7 8626.6 8634.1 8659.6 8730.7 8807.5 4488.1 4582.3 4608.7 4648.4 4692.2 1708.9 1888.1 1935.9 1936.1 1969.1 844.0 893.0 945.9 1009.2 1062.4 18282.5 20868.4 21669.5 21690.1 21704.8 817.3 855.6 926.5 947.7 966.1 9834.9 11062.3 11110.8 11563.9 11573.9 566819.0 581073.2 590058.0 596840.0 598258.0 94884.5 104091.4 112359.0 127586.4 131003.0 902.7 928.2 941.5 955.0 982.5 19294.8 19440.9 19654.4 19780.9 19806.8 54869.7 54910.0 55482.0 55720.4 56799.7 61603.0 62395.0 80517.6 81301.6 82530.6 8059.8 8251.7 8302.0 8322.2 8333.2 12267.5 13039.7 13250.3 13501.3 13865.1 10896.2 11308.0 11663.6 11937.0 11958.5 11168.4 11298.8 11372.6 11844.5 11855.6 9444.8 11367.5 11455.9 11584.3 11858.4 6855.3 6864.5 6966.9 8207.8 8494.2 10990.2 11113.4 11284.4 11661.1 11884.9 13:27:31 INFO - PROCESS | 1650 | 13:27:31 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5691.68 Median:4793.77 stddev:3224.68 (67.3%) stddev-sans-first:3217.44 13:27:31 INFO - PROCESS | 1650 | Values: 1379.0 1559.7 1619.4 1739.7 1902.0 8037.9 8310.5 8547.1 9047.3 9108.1 8617.4 8927.4 8934.9 8990.6 9014.1 2350.6 2371.2 2372.1 2374.1 2378.7 2362.4 2380.6 2385.5 2394.4 2405.2 2372.3 2392.2 2398.4 2399.9 2400.7 2347.2 2355.9 2359.3 2362.3 2391.6 2355.6 2363.7 2364.9 2367.9 2374.2 8973.9 8979.6 9002.6 9048.5 9055.4 5260.9 5390.8 5430.6 5433.7 5446.0 3246.9 3430.9 3601.9 3617.0 3676.2 1072.8 1080.3 1097.3 1098.1 1106.4 11705.9 11721.7 11820.3 11844.4 11861.5 4539.9 4622.9 4771.8 4827.6 4830.9 9938.0 10046.3 10072.0 10075.0 10104.2 4562.0 5357.4 5365.6 5368.3 5376.1 4394.6 4544.5 4737.0 4815.7 4925.5 3878.6 3951.7 3994.6 4026.8 4048.5 1274.0 1288.2 1289.5 1294.6 1302.3 875.5 885.3 896.4 896.5 934.7 5631.5 5693.8 5895.2 5991.4 5998.6 363.7 425.5 441.3 441.4 443.4 3459.7 3474.5 3837.3 3945.8 3964.3 10521.2 10781.5 10904.8 10931.8 10934.6 10871.1 10875.6 10917.2 10931.1 10936.1 7842.4 8231.1 8246.0 8275.5 8354.0 5465.8 5513.0 5977.9 6009.4 6107.0 8288.4 8505.6 9536.6 10214.8 10282.8 9217.1 9229.7 10099.6 10117.4 10315.9 6139.2 6996.9 7379.7 7575.4 7704.9 6884.8 8423.1 8491.0 8493.5 8507.7 6708.5 7551.4 7667.4 7697.8 7755.7 6917.7 7216.2 7498.6 7513.4 7717.1 7065.2 7093.3 7171.1 7180.6 7191.2 4879.8 5002.7 5034.1 5051.8 5103.6 4956.2 5001.2 5009.9 5072.3 5174.1 13:27:31 INFO - PROCESS | 1650 | 13:27:31 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4693.70 Median:5308.43 stddev:1497.28 (28.2%) stddev-sans-first:1464.42 13:27:31 INFO - PROCESS | 1650 | Values: 282.9 305.4 305.6 310.9 312.9 4855.5 5281.3 5570.2 5630.7 5663.7 5379.9 5464.3 5473.0 5505.1 5597.7 3504.5 3648.6 3941.0 3961.5 4150.3 3044.3 4114.9 4159.9 4163.0 4210.0 3643.6 4147.4 4158.0 4165.4 4182.4 3842.6 4096.9 4114.7 4187.6 4191.6 3499.3 3500.4 3515.7 3524.4 3526.8 3973.5 4645.6 5198.8 5301.0 5317.3 4119.0 4288.9 4678.5 4696.8 4704.0 5754.3 5798.0 5801.9 5836.3 5974.0 3937.8 3948.1 3950.8 3951.0 3970.3 5522.9 5751.9 6076.3 6132.4 6134.3 3865.3 3894.3 3929.1 3967.6 3973.0 3161.4 3178.7 3653.1 4006.4 4012.4 3730.6 4676.8 4697.5 4746.8 4774.6 5030.0 5210.9 5603.9 5728.6 5840.5 1099.3 1259.8 1356.5 1371.5 1388.7 756.8 789.7 813.7 837.9 838.6 4325.0 5260.3 5432.7 5456.6 5471.5 6019.5 6061.9 6101.6 6189.3 6234.9 4701.8 4904.3 5064.3 5403.3 5491.7 3172.3 3183.6 3184.0 3206.9 3255.5 5315.9 6170.6 6203.3 6216.5 6250.9 5837.5 5877.4 6214.7 6218.4 6263.6 5200.3 5640.8 6026.8 6233.7 6368.6 5772.3 6168.3 6177.0 6178.9 6196.9 5433.1 5844.3 6082.5 6093.3 6138.0 5491.6 5849.4 6015.8 6271.5 6347.0 3989.6 4109.2 4122.4 4145.2 4147.8 5264.9 5949.9 5987.7 6059.3 6111.9 4570.3 5726.6 5773.3 5811.9 5961.3 5972.4 5991.9 6016.1 6029.0 6057.4 4859.4 5114.6 5802.0 5878.9 5880.6 4964.4 5103.9 5429.2 5571.1 5687.4 4885.0 5607.2 5703.7 5763.0 5807.1 13:27:31 INFO - PROCESS | 1650 | 13:27:31 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:21830.60 Median:4272.81 stddev:32756.29 (766.6%) stddev-sans-first:32809.56 13:27:31 INFO - PROCESS | 1650 | Values: 647.6 735.3 786.8 795.5 835.6 15221.5 15827.6 15951.5 16001.2 16042.8 14510.0 14654.6 14662.3 14689.0 14695.3 676.5 700.6 818.8 847.4 851.1 862.3 875.9 876.0 877.4 880.8 914.2 915.4 915.8 917.5 917.8 905.9 907.5 910.4 911.3 912.2 667.8 678.6 681.3 682.7 684.3 15006.8 15017.3 15026.7 15045.5 15078.0 12206.0 14629.3 14647.8 14819.8 14888.0 11936.7 12059.5 12332.9 12363.2 12536.6 3740.8 3746.0 3908.7 3954.8 3955.2 109742.0 110424.4 111244.0 111660.4 115056.0 9373.9 11260.4 11353.2 11505.1 11735.1 24117.5 24231.3 24281.8 24319.3 24437.5 6887.5 7884.0 7975.5 8068.2 8164.1 4226.5 4319.2 4363.7 4399.9 4416.4 3409.1 3552.9 3560.7 3561.9 3810.4 705.1 705.3 708.7 722.6 723.3 495.8 534.0 630.9 659.8 670.8 9355.2 9504.2 9508.7 9539.2 9752.9 627.2 638.3 641.7 668.6 678.5 4331.5 4759.7 4827.2 4993.4 5100.1 66112.0 70406.3 88390.0 95523.0 156135.4 71508.2 72489.0 110728.4 114711.0 117870.4 69784.7 98321.0 107475.5 112263.4 115355.4 8580.0 8662.6 8789.4 9628.5 9822.5 41366.4 46600.6 56783.7 60665.0 61324.7 61578.0 66439.7 72005.0 96267.5 97890.0 12582.4 12838.8 13148.1 13152.8 13792.6 14941.9 15044.8 15217.5 15254.2 15476.9 13773.7 14069.7 14092.2 14320.4 14388.4 13355.0 13696.8 13836.2 13839.3 14105.4 13807.5 13870.7 13911.4 13924.6 14017.4 11142.2 11146.5 11316.7 11908.0 11966.4 8357.4 8767.8 10143.7 10786.7 11466.8 13:27:31 INFO - PROCESS | 1650 | -------- Summary: end -------- 13:27:31 INFO - PROCESS | 1650 | 13:27:31 INFO - TEST-INFO | 1650: exit 0 13:27:31 INFO - TEST-OK | dromaeo_css | took 1115458ms 13:27:31 INFO - TEST-START | kraken 13:27:31 INFO - operating with platform_type : mac_ 13:27:31 INFO - Initialising browser for kraken test... 13:27:31 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmp1048UA/profile http://localhost:49267/getInfo.html) 13:27:34 INFO - PROCESS | 1662 | 2016-10-04 13:27:34.580 plugin-container[1663:9986] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.tsm.portname' 13:27:34 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:27:34 INFO - PROCESS | 1662 | 2016-10-04 13:27:34.583 plugin-container[1663:9986] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 13:27:34 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:27:34 INFO - PROCESS | 1662 | 2016-10-04 13:27:34.583 plugin-container[1663:9986] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:27:34 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 13:27:34 INFO - PROCESS | 1662 | colorDepth:24 13:27:34 INFO - PROCESS | 1662 | Browser inner width/height: 1024/663 13:27:34 INFO - PROCESS | 1662 | __metrics 13:27:34 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 13:27:35 INFO - TEST-INFO | 1662: exit 0 13:27:35 INFO - Browser initialized. 13:27:35 INFO - Running cycle 1/1 for kraken test... 13:27:35 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmp1048UA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:27:37 INFO - PROCESS | 1664 | 2016-10-04 13:27:37.866 plugin-container[1665:10098] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1f, name = 'com.apple.tsm.portname' 13:27:37 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:27:37 INFO - PROCESS | 1664 | 2016-10-04 13:27:37.870 plugin-container[1665:10098] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9203, name = 'com.apple.CFPasteboardClient' 13:27:37 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:27:37 INFO - PROCESS | 1664 | 2016-10-04 13:27:37.870 plugin-container[1665:10098] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:27:47 INFO - PROCESS | 1664 | RSS: Main: 253677568 13:27:47 INFO - PROCESS | 1664 | 13:27:50 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 13:27:51 INFO - PROCESS | 1664 | RSS: Main: 264818688 13:27:51 INFO - PROCESS | 1664 | 13:27:53 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 13:27:54 INFO - PROCESS | 1664 | RSS: Main: 272310272 13:27:54 INFO - PROCESS | 1664 | 13:27:57 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 13:27:58 INFO - PROCESS | 1664 | RSS: Main: 270049280 13:27:58 INFO - PROCESS | 1664 | 13:28:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 13:28:01 INFO - PROCESS | 1664 | RSS: Main: 271028224 13:28:01 INFO - PROCESS | 1664 | 13:28:03 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:28:03 INFO - PROCESS | 1664 | RSS: Main: 271085568 13:28:03 INFO - PROCESS | 1664 | 13:28:06 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 13:28:07 INFO - PROCESS | 1664 | RSS: Main: 271638528 13:28:07 INFO - PROCESS | 1664 | 13:28:10 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 13:28:10 INFO - PROCESS | 1664 | RSS: Main: 271462400 13:28:10 INFO - PROCESS | 1664 | 13:28:13 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 13:28:14 INFO - PROCESS | 1664 | RSS: Main: 272125952 13:28:14 INFO - PROCESS | 1664 | 13:28:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:28:16 INFO - PROCESS | 1664 | RSS: Main: 271560704 13:28:16 INFO - PROCESS | 1664 | 13:28:18 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:28:19 INFO - PROCESS | 1664 | RSS: Main: 271671296 13:28:19 INFO - PROCESS | 1664 | 13:28:21 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:28:22 INFO - PROCESS | 1664 | RSS: Main: 271683584 13:28:22 INFO - PROCESS | 1664 | 13:28:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:28:26 INFO - PROCESS | 1664 | RSS: Main: 271941632 13:28:26 INFO - PROCESS | 1664 | 13:28:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:28:29 INFO - PROCESS | 1664 | RSS: Main: 272183296 13:28:29 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 13:28:31 INFO - PROCESS | 1664 | RSS: Main: 273866752 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | __start_tp_report 13:28:31 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 13:28:31 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 13:28:31 INFO - PROCESS | 1664 | |i|pagename|runs| 13:28:31 INFO - PROCESS | 1664 | |0;ai-astar;114;118;116;117;118;118;117;118;118;120 13:28:31 INFO - PROCESS | 1664 | |1;audio-beat-detection;145;115;115;139;116;113;118;113;146;119 13:28:31 INFO - PROCESS | 1664 | |2;audio-dft;193;199;204;267;207;196;199;196;201;195 13:28:31 INFO - PROCESS | 1664 | |3;audio-fft;86;59;60;83;62;73;86;50;91;84 13:28:31 INFO - PROCESS | 1664 | |4;audio-oscillator;85;72;115;70;70;71;87;69;71;87 13:28:31 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;90;88;93;88;92;86;93;75;136;106 13:28:31 INFO - PROCESS | 1664 | |6;imaging-darkroom;101;101;101;106;101;99;100;99;128;100 13:28:31 INFO - PROCESS | 1664 | |7;imaging-desaturate;112;97;148;154;114;91;90;90;133;146 13:28:31 INFO - PROCESS | 1664 | |8;json-parse-financial;57;50;52;56;75;80;58;56;58;56 13:28:31 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;67;83;67;76;75;67;69;69;68;69 13:28:31 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;102;92;91;83;83;111;112;78;91;88 13:28:31 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;146;160;165;162;158;159;165;165;220;163 13:28:31 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;174;175;176;196;206;176;173;173;174;211 13:28:31 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;69;70;68;66;70;67;71;71;70;69 13:28:31 INFO - PROCESS | 1664 | __end_tp_report 13:28:31 INFO - PROCESS | 1664 | __start_cc_report 13:28:31 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,946 13:28:31 INFO - PROCESS | 1664 | __end_cc_report 13:28:31 INFO - PROCESS | 1664 | __startTimestamp1475612911612__endTimestamp 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | ------- Summary: start ------- 13:28:31 INFO - PROCESS | 1664 | Number of tests: 14 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:117.40 Median:118.00 stddev:1.58 (1.3%) stddev-sans-first:1.09 13:28:31 INFO - PROCESS | 1664 | Values: 114.0 118.0 116.0 117.0 118.0 118.0 117.0 118.0 118.0 120.0 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:123.90 Median:118.50 stddev:13.66 (11.5%) stddev-sans-first:12.17 13:28:31 INFO - PROCESS | 1664 | Values: 145.0 115.0 115.0 139.0 116.0 113.0 118.0 113.0 146.0 119.0 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:205.70 Median:200.00 stddev:21.95 (11.0%) stddev-sans-first:22.80 13:28:31 INFO - PROCESS | 1664 | Values: 193.0 199.0 204.0 267.0 207.0 196.0 199.0 196.0 201.0 195.0 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:73.40 Median:83.50 stddev:14.52 (17.4%) stddev-sans-first:14.66 13:28:31 INFO - PROCESS | 1664 | Values: 86.0 59.0 60.0 83.0 62.0 73.0 86.0 50.0 91.0 84.0 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:79.70 Median:71.50 stddev:14.51 (20.3%) stddev-sans-first:15.26 13:28:31 INFO - PROCESS | 1664 | Values: 85.0 72.0 115.0 70.0 70.0 71.0 87.0 69.0 71.0 87.0 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:89.00 stddev:16.40 (18.4%) stddev-sans-first:17.31 13:28:31 INFO - PROCESS | 1664 | Values: 90.0 88.0 93.0 88.0 92.0 86.0 93.0 75.0 136.0 106.0 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:103.60 Median:103.50 stddev:8.80 (8.5%) stddev-sans-first:9.28 13:28:31 INFO - PROCESS | 1664 | Values: 101.0 101.0 101.0 106.0 101.0 99.0 100.0 99.0 128.0 100.0 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:117.50 Median:122.00 stddev:25.80 (21.2%) stddev-sans-first:27.29 13:28:31 INFO - PROCESS | 1664 | Values: 112.0 97.0 148.0 154.0 114.0 91.0 90.0 90.0 133.0 146.0 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:57.50 stddev:9.74 (16.9%) stddev-sans-first:10.28 13:28:31 INFO - PROCESS | 1664 | Values: 57.0 50.0 52.0 56.0 75.0 80.0 58.0 56.0 58.0 56.0 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:69.00 stddev:5.31 (7.7%) stddev-sans-first:5.43 13:28:31 INFO - PROCESS | 1664 | Values: 67.0 83.0 67.0 76.0 75.0 67.0 69.0 69.0 68.0 69.0 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:93.10 Median:85.50 stddev:11.67 (13.6%) stddev-sans-first:11.92 13:28:31 INFO - PROCESS | 1664 | Values: 102.0 92.0 91.0 83.0 83.0 111.0 112.0 78.0 91.0 88.0 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:166.30 Median:164.00 stddev:19.70 (12.0%) stddev-sans-first:19.48 13:28:31 INFO - PROCESS | 1664 | Values: 146.0 160.0 165.0 162.0 158.0 159.0 165.0 165.0 220.0 163.0 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.40 Median:176.00 stddev:14.92 (8.5%) stddev-sans-first:15.44 13:28:31 INFO - PROCESS | 1664 | Values: 174.0 175.0 176.0 196.0 206.0 176.0 173.0 173.0 174.0 211.0 13:28:31 INFO - PROCESS | 1664 | 13:28:31 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:70.00 stddev:1.66 (2.4%) stddev-sans-first:1.76 13:28:31 INFO - PROCESS | 1664 | Values: 69.0 70.0 68.0 66.0 70.0 67.0 71.0 71.0 70.0 69.0 13:28:31 INFO - PROCESS | 1664 | -------- Summary: end -------- 13:28:31 INFO - PROCESS | 1664 | 13:28:32 INFO - TEST-INFO | 1664: exit 0 13:28:32 INFO - TEST-OK | kraken | took 60557ms 13:28:32 INFO - SUITE-END | took 1176s 13:28:32 INFO - Completed test suite (00:19:36) 13:28:32 INFO - javascript benchmark 13:28:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7350.2427595558165, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2837.092130518234, 3403.319336132774, 3410.266792639582, 3431.3823511767873, 3433.193199598095, 5780.739866706003, 5849.473547380735, 5850.180974663551, 5857.297124345079, 5866.85332866678, 5654.180143879145, 5767.33675627303, 5787.681677507735, 5821.3305469870975, 5893.823185304437, 5214.243934629477, 5280.023195708796, 5296.443873393296, 5303.7082960437165, 5335.653182543142, 5229.947700523003, 5316.5215130638235, 5327.520523152915, 5403.324584426947, 5412.756425960822, 4552.0, 5299.5760339172775, 5331.733413329337, 5335.946640533583, 5355.598330125236, 4274.508431530352, 5192.636515443921, 5231.006108839309, 5312.229726689647, 5335.386430560497, 5216.37403511581, 5218.66078704883, 5238.376978188351, 5250.921236181461, 5271.710055946922, 4785.617150627942, 5568.0, 5678.4037676044245, 5864.413558644144, 5874.50066744323, 6142.498775697854, 6154.692265386758, 6188.288346840126, 6204.503639708812, 6330.430261276461, 6277.0, 6293.905591416133, 6297.779577714758, 6310.0, 6324.810255692337, 5925.525957923356, 5926.4369884861735, 5946.401985111663, 5953.970230148865, 5972.880542389128, 6175.567710260329, 6231.4391704746795, 6240.063990401476, 6291.622502649856, 6303.905441418382, 6200.3179650239335, 6226.40849119333, 6229.127922090911, 6231.376862313823, 6238.438540531374, 6252.874942501125, 6263.966445536526, 6310.968445157745, 6341.492680585542, 6348.841278968062, 5448.591355648342, 6135.110179431455, 6206.472449841724, 6214.627122372672, 6219.751209951642, 6230.190075290274, 6241.75032998684, 6298.8425289367215, 6313.715882785285, 6330.841728956813, 3945.585713500041, 4605.953940460552, 4814.470408255089, 4972.0, 5096.082705113041, 4343.218220720238, 4409.470863496337, 4455.086707225011, 4469.307257375112, 4504.189245935763, 5835.67903765297, 5873.676947767914, 5909.438603332676, 5932.762689492372, 5932.970335148297, 6245.500359971192, 6259.374062593704, 6264.561480696308, 6291.087792270096, 6302.653354065479, 5884.558658100659, 5892.970535147297, 5894.941050589439, 5897.469227769521, 5938.198343223696, 5374.4625537445945, 5448.318960129984, 5463.31708536433, 5482.205080263339, 5486.3690675572025, 6213.130161777355, 6225.035119556476, 6262.780802671976, 6298.748050077946, 6345.175127233522, 6294.370562943669, 6300.684965751786, 6307.653079080695, 6314.810555683337, 6338.0, 6363.490920726238, 6435.1312572803945, 6435.967820160869, 6446.838829029124, 6447.967760161169, 6116.969415152896, 6155.692215389302, 6209.0996805462055, 6296.622202667671, 6307.621542707452, 5183.714895680623, 6227.532935029982, 6293.527985401021, 6297.590656607306, 6314.431701146735, 6242.469390101892, 6253.96873015632, 6260.217472815898, 6265.122882796321, 6267.435930766253, 6066.636001839904, 6120.785772497941, 6199.194104766351, 6225.750969961151, 6249.218847644044, 5886.0, 5978.103284507359, 6023.698815059317, 6116.670564642638, 6119.015143218386, 6095.8780824382375, 6203.348648391854, 6206.255249370104, 6231.657258850807, 6268.655223962726, 5906.497947674496, 5976.193213916065, 6030.90953635687, 6058.303295120942, 6078.939210607838], "value": 5734.10774528077, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.34541569308806, 480.87715185042924, 486.6252985201394, 521.2207749414625, 535.9571234301256, 37353.0, 42216.366754498704, 42542.787286063605, 44653.0, 44774.77612611932, 42506.787466062706, 43368.91577710551, 43728.781356093255, 43891.0, 43938.0, 9874.802503949917, 9984.301098923079, 10038.949805250964, 10063.245256605747, 10070.84893726591, 9280.443173409618, 9524.714258572254, 9935.503224838765, 10021.34861234021, 10030.498475076254, 8501.61742721579, 9961.203103751683, 10004.59981600735, 10047.799044019115, 10060.295779295453, 9969.900300997006, 9971.252156088321, 9989.700308990741, 10005.54975026122, 10027.54876030573, 9866.26003049767, 9971.20230381575, 9983.101520863085, 10006.149477294439, 10011.0, 41114.177716445505, 41229.793851030554, 41287.38068928968, 41414.792926035174, 41498.79250603758, 3402.5406570112964, 3453.5165076889257, 3454.0674018014997, 3465.7747246428903, 3470.201853573667, 31486.842565787254, 31885.84057079723, 32008.359832803213, 32129.0, 32152.356952861046, 3150.503510832359, 3243.9837800810847, 3266.5426840242385, 3381.306832099414, 3419.145213696576, 372129.0, 404670.0, 437029.0, 491155.54422228015, 493816.0, 61936.69031654903, 62019.689901550206, 62040.0, 62386.68806655938, 62392.68803655953, 64188.67905660535, 65380.0, 65414.34585654082, 65547.0, 65593.67203163954, 16284.918575407284, 16401.343946242017, 16624.0, 16625.0, 16648.500544983668, 12738.108332416641, 12801.423935922905, 12828.80756788649, 12833.230006199658, 12910.22538647684, 14214.573562793134, 17311.528520075684, 17650.117494125243, 17660.11699415024, 17673.558161046076, 5516.0, 5595.272614560081, 6618.834529136714, 6636.402723754885, 6704.463642908556, 884.6019291318933, 993.9701808945744, 1026.4252018869456, 1046.3042077018697, 1053.441675911768, 29171.416571668447, 30295.69704302973, 30477.847610761804, 31117.68882311193, 31348.37303253922, 964.9951750241204, 1058.6506452870535, 1120.6525976947173, 1138.3170097941334, 1174.2015429508017, 15549.84450155484, 15762.448314308944, 15772.842271577138, 15805.36778528869, 16084.839151608334, 577740.0, 606940.0, 610666.0, 617104.0, 617929.0, 58050.41949580535, 58582.0, 59604.70197648984, 59730.0, 59733.70133149307, 1284.8522419921642, 1317.9934100329438, 1328.1433475408307, 1360.7142500074897, 1378.696686728918, 29762.0, 30729.031355542713, 30755.692443075284, 31033.0, 31224.0, 51362.48637513727, 51858.0, 51895.0, 52259.73870130625, 52265.7386713064, 575060.0, 577421.0, 577941.0, 579416.0, 580485.0, 41611.78146889786, 41700.79149604232, 41721.58278417177, 42597.5740242606, 42602.57397425986, 46041.76979115083, 46801.53198468108, 46842.53157468518, 47430.28854567115, 48051.75974120107, 39278.60721392864, 39301.41047884227, 39705.60294397019, 40880.591194087676, 41193.38209926913, 38724.6127538721, 38837.0, 39357.60642393654, 40276.597234027286, 40326.39510407288, 32524.349513009132, 33405.49891751577, 37632.0, 38212.0, 39420.01449963774, 32628.184295392806, 34524.48213276853, 35206.295874081865, 35219.47170792492, 35296.64703353036, 21691.783082168975, 25589.616155758053, 25981.090661826744, 26246.737532625193, 26309.94760209647], "value": 20299.87443920277, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.07543904275957, 377.3057574063722, 380.43315459964646, 387.101923537393, 402.3220872829283, 17168.48494545164, 21523.46191345217, 21852.45368865779, 22059.44851378714, 22060.227892023784, 20911.268105616306, 20924.686129708065, 21115.049822757974, 21344.8932755336, 21405.57188856222, 1597.4169428158755, 1600.8559229669327, 1604.9117298548574, 1933.787283398825, 5277.656952298088, 6703.329667033307, 6981.965090174542, 6994.4754143439195, 7012.193597736275, 7017.578945263275, 6524.282328943812, 6604.273529911706, 6808.3191680832015, 6819.488538359618, 6928.861422771542, 5290.629655924087, 6760.836532309663, 6831.077804496379, 6878.793636190872, 6901.585904845725, 5936.7328470218945, 6067.484263837579, 6075.908861367083, 6076.5442591805795, 6089.695515224265, 11324.830127548094, 11777.411129443579, 12159.392030398532, 13591.456341746318, 13662.726745465134, 2352.8353015288935, 2425.369403954967, 2612.8562929038894, 2620.737926207383, 2988.2678743707716, 10342.069213770721, 10461.0, 10564.996325349079, 11049.0, 14358.846173074018, 1293.7994610835242, 1783.4382169616529, 1805.6728304696037, 1864.4872660018602, 1872.5412273992958, 112695.0, 124777.37611311885, 124945.37527312305, 126267.0, 126502.0, 11286.717832054264, 11555.595554155565, 11769.470373833197, 11770.823437648442, 11796.823047654292, 14698.926505367619, 14713.0, 14738.336774845158, 14752.852471475147, 14786.704265914621, 8425.452345597516, 8643.135686431306, 8666.306695464475, 8777.122287771172, 8796.724474951096, 8626.611802468778, 8634.136586341316, 8659.610317535726, 8730.650773969097, 8807.47155170679, 4488.147252022105, 4582.289745089516, 4608.746518941423, 4648.442186937589, 4692.178868698033, 1708.9401870934457, 1888.1314595286321, 1935.8935258560632, 1936.1287420660758, 1969.0843757652474, 843.9915600844035, 892.9866052009226, 945.8770359853175, 1009.1985805337438, 1062.3625824505234, 18282.54293642643, 20868.373948781562, 21669.458263543223, 21690.132394704036, 21704.80623565719, 817.3134566963779, 855.5764896376334, 926.4719110107175, 947.6541062512133, 966.0532677975623, 9834.901650983542, 11062.263064922654, 11110.833337499944, 11563.924677898909, 11573.88426115745, 566819.0, 581073.1892681019, 590058.0, 596840.0, 598258.0, 94884.52557737168, 104091.43862842064, 112359.0, 127586.36206818906, 131003.0, 902.6750369866843, 928.2388441477923, 941.5198248893114, 954.9904500954901, 982.5332966840693, 19294.842309461477, 19440.90279548593, 19654.410367688994, 19780.912049837396, 19806.801931980495, 54869.72565137149, 54910.0, 55482.0, 55720.44279557152, 56799.71600141973, 61603.0, 62395.0, 80517.59741201256, 81301.59349203216, 82530.62040693944, 8059.758207253792, 8251.669933202606, 8301.958490207511, 8322.16778322163, 8333.208345207195, 12267.509299627915, 13039.739205216032, 13250.271235081778, 13501.324933753469, 13865.09876858001, 10896.182786291218, 11308.0, 11663.59177428786, 11937.0, 11958.46186921608, 11168.385738784445, 11298.830517542408, 11372.601958931398, 11844.526218951145, 11855.644330670093, 9444.763880903032, 11367.54529818831, 11455.91168838959, 11584.304941703525, 11858.40707964581, 6855.314468553304, 6864.485163612849, 6966.885298352239, 8207.794805129919, 8494.193051660082, 10990.230683852054, 11113.44432778374, 11284.43577821122, 11661.125415593695, 11884.940575297069], "value": 9356.954429360216, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1379.020895164433, 1559.6646720954993, 1619.4008216959726, 1739.6694628020675, 1901.95245118872, 8037.91962080379, 8310.501369917809, 8547.145285471457, 9047.321450891184, 9108.134727200922, 8617.439866408678, 8927.419717718343, 8934.86597701035, 8990.595423205956, 9014.053524379928, 2350.5886469867746, 2371.205646108556, 2372.063035101134, 2374.0859768988976, 2378.714554253492, 2362.397588614901, 2380.631002194654, 2385.487120269142, 2394.449276666368, 2405.20628192696, 2372.3476044087843, 2392.1986134644917, 2398.352444839892, 2399.8920048597856, 2400.6759087523224, 2347.213683416058, 2355.882205889716, 2359.327591636384, 2362.338545207345, 2391.629297458897, 2355.611324131516, 2363.7163540375263, 2364.929052128439, 2367.9289621311386, 2374.2402431222017, 8973.910260897374, 8979.551022448917, 9002.639894404216, 9048.547572621343, 9055.411398259092, 5260.8684782880355, 5390.78436862525, 5430.619856610039, 5433.728313584305, 5445.9727701361635, 3246.853891574885, 3430.9485357719655, 3601.9099522511883, 3617.0, 3676.1728611062563, 1072.774717309366, 1080.330195278932, 1097.3470784883061, 1098.1489345757022, 1106.4025426269884, 11705.941470292679, 11721.70695732605, 11820.349880756641, 11844.407779610985, 11861.46623401949, 4539.886502837389, 4622.930656040162, 4771.809127634856, 4827.565519103297, 4830.855074347705, 9937.950310248547, 10046.29675922693, 10072.0, 10075.0, 10104.242181836391, 4562.0, 5357.35711714589, 5365.597580181424, 5368.2752828367675, 5376.059189641797, 4394.560543945579, 4544.477385100689, 4737.0, 4815.7351345676325, 4925.458199598042, 3878.6121387861554, 3951.68386529077, 3994.6205110514456, 4026.798660066985, 4048.4939382577177, 1273.961781146567, 1288.1948782011243, 1289.4584274604688, 1294.643972907458, 1302.3423171298623, 875.5009644502572, 885.2873436883242, 896.4397251717677, 896.4531635702216, 934.6541779541614, 5631.521320687705, 5693.800716974969, 5895.233619629421, 5991.430814072656, 5998.550108741862, 363.68541211851556, 425.4936625415745, 441.30274166816355, 441.40630851504676, 443.4102643484158, 3459.7232221422228, 3474.4614584739406, 3837.309284328792, 3945.8027098644952, 3964.266610677034, 10521.21090918169, 10781.514831832586, 10904.781904362027, 10931.781364372828, 10934.56261749537, 10871.130309575216, 10875.564977400816, 10917.23579349438, 10931.070858976916, 10936.125109991182, 7842.411819113589, 8231.09457959624, 8246.0, 8275.54484503346, 8354.0, 5465.754041068082, 5513.0, 5977.9103313451205, 6009.369016253405, 6106.961816491296, 8288.41981061344, 8505.617247224014, 9536.618535258513, 10214.846777298199, 10282.794344112926, 9217.124373184537, 9229.676961306322, 10099.596016159272, 10117.392956422638, 10315.896841031794, 6139.171211886338, 6996.860062798613, 7379.704811807683, 7575.356094731789, 7704.884426733715, 6884.827879302858, 8423.07346191906, 8491.0, 8493.490390576586, 8507.744767656981, 6708.496862735216, 7551.433642476726, 7667.424943129176, 7697.807554811175, 7755.72854950074, 6917.688704008432, 7216.170140433707, 7498.587577683062, 7513.361364283878, 7717.112532058661, 7065.1521817382245, 7093.290670932865, 7171.067761191011, 7180.640967951477, 7191.17301510333, 4879.829205977774, 5002.724850133278, 5034.0938631046765, 5051.823186188612, 5103.642745007963, 4956.231784073474, 5001.24981252815, 5009.949900500948, 5072.340595722532, 5174.14626586609], "value": 4492.479221383096, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.92078218098925, 305.37855464130485, 305.6195037178712, 310.9471389863723, 312.9311551458678, 4855.484399508456, 5281.283582835579, 5570.184423669789, 5630.662160270382, 5663.66018038918, 5379.870227252272, 5464.3442786865635, 5473.0, 5505.119180931052, 5597.692126933017, 3504.5093686883856, 3648.58041325247, 3940.9999999999927, 3961.4652021977095, 4150.294449943502, 3044.2541577313477, 4114.876553703393, 4159.856039589107, 4162.980069882868, 4210.0, 3643.635636436362, 4147.357159640245, 4157.97921010396, 4165.416841642173, 4182.393552934838, 3842.5773164951834, 4096.877093687194, 4114.670826333916, 4187.560306167854, 4191.643710284629, 3499.30013997199, 3500.4399296112756, 3515.683588477024, 3524.4184709522897, 3526.7883926964464, 3973.4834471518807, 4645.630728328543, 5198.766055527511, 5301.0, 5317.335333083364, 4118.979405102985, 4288.935665965013, 4678.461976872669, 4696.7651617419, 4703.952960470386, 5754.3094828620415, 5797.971010144923, 5801.883962320772, 5836.2996440427, 5973.970130149365, 3937.7834219117935, 3948.1116748731592, 3950.822213000422, 3950.980245098785, 3970.2530886410177, 5522.9447705523235, 5751.884962300731, 6076.3316035236085, 6132.370060604215, 6134.325224225332, 3865.3042452358845, 3894.2600905827803, 3929.135590170158, 3967.583403742623, 3972.980135099364, 3161.3993341265086, 3178.7113278439488, 3653.104989277591, 4006.3958427022667, 4012.3981402789814, 3730.570984336836, 4676.836310729108, 4697.459792123881, 4746.837024928915, 4774.570288674036, 5030.019146266516, 5210.863545946144, 5603.943960560424, 5728.598998070179, 5840.474357307863, 1099.3239157917824, 1259.8110283457554, 1356.4506374918174, 1371.451419432235, 1388.722375414621, 756.7691853984478, 789.676232744572, 813.650130443903, 837.8952630921135, 838.6393850644281, 4324.956750432519, 5260.316158899319, 5432.674039557639, 5456.590755693339, 5471.534919531884, 6019.4883434908525, 6061.909071363933, 6101.633901965896, 6189.288231853305, 6234.875302493925, 4701.8354357598, 4904.314424857677, 5064.341635587423, 5403.2705584746345, 5491.7254137292975, 3172.254520187754, 3183.602049743782, 3184.0288711942617, 3206.887758928426, 3255.4953982132806, 5315.867103322448, 6170.629762214281, 6203.31763506014, 6216.451254985434, 6250.90594650788, 5837.532997360201, 5877.353491115974, 6214.658193799294, 6218.3882769236125, 6263.561550691499, 5200.271961925334, 5640.802571909963, 6026.849328766816, 6233.719482623293, 6368.64972426521, 5772.307323121252, 6168.290646575701, 6176.980798168252, 6178.876422471615, 6196.907046394398, 5433.1153225443995, 5844.298684157929, 6082.482988945989, 6093.299270583762, 6137.96931015342, 5491.643043202179, 5849.402617390359, 6015.789447369321, 6271.498280137669, 6347.0479428086155, 3989.6409323161056, 4109.198706252371, 4122.361034039729, 4145.233131870614, 4147.834086636622, 5264.921026184534, 5949.910751338647, 5987.670678112746, 6059.303180134341, 6111.908321375094, 4570.314452832102, 5726.5683579105225, 5773.336066352255, 5811.941880581309, 5961.2846458425265, 5972.432618901197, 5991.910121348096, 6016.127661489147, 6029.0, 6057.363976782374, 4859.424505012349, 5114.590832733298, 5802.0, 5878.911816322673, 5880.617759845596, 4964.404271487445, 5103.948960510347, 5429.18562215655, 5571.0807716726285, 5687.402822703557, 4885.0, 5607.2149899014985, 5703.657780533182, 5763.020286551213, 5807.096451774113], "value": 4203.3009325839275, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.62761412188, 735.3308489274759, 786.8150984518641, 795.5266616363261, 835.6156168162647, 15221.543353699379, 15827.604309892258, 15951.52145435636, 16001.19994000301, 16042.83957160428, 14510.0, 14654.63363415915, 14662.340194691238, 14689.0, 14695.265236738174, 676.5061505101279, 700.6181631011086, 818.7789296889836, 847.4068152293388, 851.0638297872352, 862.3403096631082, 875.8729984152326, 876.0057334924875, 877.3770622857778, 880.7533890510667, 914.2411798207472, 915.3546749541557, 915.771057235691, 917.5320586500866, 917.7980844214262, 905.8595917632778, 907.4827348411408, 910.3900386740834, 911.2527727263647, 912.2109375390306, 667.7529314153745, 678.6267552845915, 681.264234626602, 682.7405585877384, 684.2780866186172, 15006.849931500654, 15017.324220409999, 15026.699466010728, 15045.473408430653, 15078.0199287046, 12206.0, 14629.268536573129, 14647.780283295759, 14819.77770333446, 14888.0, 11936.70158246042, 12059.457324420335, 12332.938335308356, 12363.196392234477, 12536.561220357244, 3740.7942563159017, 3745.9812700936322, 3908.667763240128, 3954.782486963216, 3955.1694144229746, 109742.0, 110424.4478777609, 111244.0, 111660.4416977918, 115055.99999999916, 9373.859392109123, 11260.436978151223, 11353.205275630791, 11505.079593632488, 11735.119866009913, 24117.51764964726, 24231.273061808173, 24281.757182428304, 24319.270421887373, 24437.51124977526, 6887.483438742114, 7884.0, 7975.481593696391, 8068.233517815798, 8164.061132969666, 4226.4505614270565, 4319.179355922364, 4363.716358436692, 4399.890002749957, 4416.447944006999, 3409.09658940381, 3552.91117722059, 3560.6973407260157, 3561.8753343633366, 3810.447485114643, 705.0517054561607, 705.2665228162756, 708.7094291340526, 722.6134018300185, 723.2875617516847, 495.7546014722663, 533.9599530035263, 630.9399412847653, 659.7876773489471, 670.7518218259275, 9355.158035776813, 9504.239660827117, 9508.667196648084, 9539.18916892058, 9752.9024709752, 627.1940556385023, 638.2639221318008, 641.7433026789322, 668.5620918298522, 678.5453745990199, 4331.523532411432, 4759.690620109682, 4827.179379505493, 4993.350864387679, 5100.081985242692, 66112.0, 70406.29593703998, 88390.0, 95523.04476955142, 156135.43864561437, 71508.21229469201, 72489.0, 110728.4463577677, 114711.0, 117870.41064794792, 69784.6510767443, 98321.0, 107475.46262268639, 112263.43868280607, 115355.42322288334, 8580.0, 8662.610182541675, 8789.373965816088, 9628.47043412619, 9822.5088745561, 41366.37950430686, 46600.6019819406, 56783.71608141933, 60665.0, 61324.69337653283, 61578.0, 66439.66780166069, 72005.0, 96267.51866240623, 97890.0, 12582.370881456074, 12838.807417888553, 13148.145370550883, 13152.848953897841, 13792.586222413343, 14941.850581494045, 15044.849551504783, 15217.467388641344, 15254.161021143944, 15476.922615386851, 13773.724525509635, 14069.718605628035, 14092.154470731788, 14320.427182912983, 14388.35252413623, 13355.0, 13696.794548081587, 13836.23900685472, 13839.30803459843, 14105.365258563184, 13807.51673691416, 13870.65323366924, 13911.443542258196, 13924.58226253214, 14017.369218384993, 11142.164337674545, 11146.498407571517, 11316.660500185008, 11908.047356211655, 11966.401679915794, 8357.414980951275, 8767.780805479913, 10143.7464063399, 10786.730331741455, 11466.827997579876], "value": 7666.447369923353, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 116.0, 117.0, 118.0, 118.0, 117.0, 118.0, 118.0, 120.0], "value": 117.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 115.0, 139.0, 116.0, 113.0, 118.0, 113.0, 146.0, 119.0], "value": 123.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 199.0, 204.0, 267.0, 207.0, 196.0, 199.0, 196.0, 201.0, 195.0], "value": 205.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 59.0, 60.0, 83.0, 62.0, 73.0, 86.0, 50.0, 91.0, 84.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 72.0, 115.0, 70.0, 70.0, 71.0, 87.0, 69.0, 71.0, 87.0], "value": 79.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 93.0, 88.0, 92.0, 86.0, 93.0, 75.0, 136.0, 106.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 101.0, 106.0, 101.0, 99.0, 100.0, 99.0, 128.0, 100.0], "value": 103.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 97.0, 148.0, 154.0, 114.0, 91.0, 90.0, 90.0, 133.0, 146.0], "value": 117.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 50.0, 52.0, 56.0, 75.0, 80.0, 58.0, 56.0, 58.0, 56.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 83.0, 67.0, 76.0, 75.0, 67.0, 69.0, 69.0, 68.0, 69.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 92.0, 91.0, 83.0, 83.0, 111.0, 112.0, 78.0, 91.0, 88.0], "value": 93.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 160.0, 165.0, 162.0, 158.0, 159.0, 165.0, 165.0, 220.0, 163.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 175.0, 176.0, 196.0, 206.0, 176.0, 173.0, 173.0, 174.0, 211.0], "value": 183.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 68.0, 66.0, 70.0, 67.0, 71.0, 71.0, 70.0, 69.0], "value": 69.1, "unit": "score"}]}]} 13:28:32 INFO - Return code: 0 13:28:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:28:32 ERROR - # TBPL SUCCESS # 13:28:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:28:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:28:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:28:32 INFO - 2016-10-04 13:28:32.934 screenresolution[1669:10477] starting screenresolution argv=screenresolution get 13:28:32 INFO - 2016-10-04 13:28:32.936 screenresolution[1669:10477] Display 0: 1600x1200x32@60 13:28:32 INFO - 2016-10-04 13:28:32.942 screenresolution[1670:10481] starting screenresolution argv=screenresolution list 13:28:32 INFO - Available Modes on Display 0 13:28:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:28:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:28:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:28:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:28:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:28:33 INFO - Intel Iris: 13:28:33 INFO - Chipset Model: Intel Iris 13:28:33 INFO - Type: GPU 13:28:33 INFO - Bus: Built-In 13:28:33 INFO - VRAM (Dynamic, Max): 1536 MB 13:28:33 INFO - Vendor: Intel (0x8086) 13:28:33 INFO - Device ID: 0x0a2e 13:28:33 INFO - Revision ID: 0x0009 13:28:33 INFO - Displays: 13:28:33 INFO - Display: 13:28:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:28:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:28:33 INFO - Display Serial Number: 1600x1200 60 13:28:33 INFO - Main Display: Yes 13:28:33 INFO - Mirror: Off 13:28:33 INFO - Online: Yes 13:28:33 INFO - Rotation: Supported 13:28:33 INFO - Return code: 0 13:28:33 INFO - Running post-action listener: _resource_record_post_action 13:28:33 INFO - [mozharness: 2016-10-04 20:28:33.182608Z] Finished run-tests step (success) 13:28:33 INFO - Running post-run listener: _resource_record_post_run 13:28:33 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38189568; Write bytes: 709392896; Read time: 395; Write time: 2196 13:28:33 INFO - TinderboxPrint: CPU usage
54.4% 13:28:33 INFO - TinderboxPrint: I/O read bytes / time
38,189,568 / 395 13:28:33 INFO - TinderboxPrint: I/O write bytes / time
709,392,896 / 2,196 13:28:33 INFO - TinderboxPrint: CPU idle
2,208.5 (45.6%) 13:28:33 INFO - TinderboxPrint: CPU system
980.2 (20.2%) 13:28:33 INFO - TinderboxPrint: CPU user
1,656.9 (34.2%) 13:28:33 INFO - TinderboxPrint: Swap in / out
445,816,832 / 0 13:28:33 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401908224; Write bytes: 396503040; Read time: 29554; Write time: 700 13:28:33 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36096512; Write bytes: 297931264; Read time: 373; Write time: 1461 13:28:33 INFO - Running post-run listener: _upload_blobber_files 13:28:33 INFO - Blob upload gear active. 13:28:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:28:33 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 13:28:33 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:28:33 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:28:34 INFO - (blobuploader) - INFO - Open directory for files ... 13:28:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:28:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:28:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:28:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:28:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:28:35 INFO - (blobuploader) - INFO - Done attempting. 13:28:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:28:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:28:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:28:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:28:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:28:36 INFO - (blobuploader) - INFO - Done attempting. 13:28:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:28:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:28:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:28:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:28:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:28:38 INFO - (blobuploader) - INFO - Done attempting. 13:28:38 INFO - (blobuploader) - INFO - Iteration through files over. 13:28:38 INFO - Return code: 0 13:28:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:28:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:28:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/af3fbb745073328eece650c1f93db7177e92a0ec108fd5622325726a9eae81db129b5f909043fcc95398b81445656aa6074f6df18b27ba69e1111141bfba18ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/73b23806b394da30732f49d865ab74a9dd39e062716f58c1c274d3ebe3a433ceae022fe4f3668bb931e6a4650c3f852226001ce26a28e4b62116332bdfd6fb4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ed82892cb9d9b150efe502b78fc69fe489228b1ee0451cfd59e4ac7bfee636d824d6493a7895e9283f9f729dc9873fc6bb97e62e536e7a78cb1c061ca09b700"} 13:28:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:28:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:28:38 INFO - Contents: 13:28:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/af3fbb745073328eece650c1f93db7177e92a0ec108fd5622325726a9eae81db129b5f909043fcc95398b81445656aa6074f6df18b27ba69e1111141bfba18ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/73b23806b394da30732f49d865ab74a9dd39e062716f58c1c274d3ebe3a433ceae022fe4f3668bb931e6a4650c3f852226001ce26a28e4b62116332bdfd6fb4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ed82892cb9d9b150efe502b78fc69fe489228b1ee0451cfd59e4ac7bfee636d824d6493a7895e9283f9f729dc9873fc6bb97e62e536e7a78cb1c061ca09b700"} 13:28:38 INFO - Running post-run listener: copy_logs_to_upload_dir 13:28:38 INFO - Copying logs to upload dir... 13:28:38 INFO - mkdir: /builds/slave/test/build/upload/logs 13:28:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.148694 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-04 13:28:38.656142) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 13:28:38.659332) ========= 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.7lJZWHAl1F/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.5kd33JuYxa/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/af3fbb745073328eece650c1f93db7177e92a0ec108fd5622325726a9eae81db129b5f909043fcc95398b81445656aa6074f6df18b27ba69e1111141bfba18ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/73b23806b394da30732f49d865ab74a9dd39e062716f58c1c274d3ebe3a433ceae022fe4f3668bb931e6a4650c3f852226001ce26a28e4b62116332bdfd6fb4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ed82892cb9d9b150efe502b78fc69fe489228b1ee0451cfd59e4ac7bfee636d824d6493a7895e9283f9f729dc9873fc6bb97e62e536e7a78cb1c061ca09b700"} build_url:https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011091 build_url: 'https://queue.taskcluster.net/v1/task/Kliean0ySsGwSJtOs_2JfQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/af3fbb745073328eece650c1f93db7177e92a0ec108fd5622325726a9eae81db129b5f909043fcc95398b81445656aa6074f6df18b27ba69e1111141bfba18ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/73b23806b394da30732f49d865ab74a9dd39e062716f58c1c274d3ebe3a433ceae022fe4f3668bb931e6a4650c3f852226001ce26a28e4b62116332bdfd6fb4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ed82892cb9d9b150efe502b78fc69fe489228b1ee0451cfd59e4ac7bfee636d824d6493a7895e9283f9f729dc9873fc6bb97e62e536e7a78cb1c061ca09b700"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 13:28:38.691650) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 13:28:38.691980) ========= 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.7lJZWHAl1F/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.5kd33JuYxa/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.004973 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 13:28:38.742348) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 13:28:38.742677) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 13:28:38.743006) ========= ========= Total master_lag: 0.17 =========