builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0034 starttime: 1476838985.16 results: success (0) buildid: 20161018163322 builduid: 01e91958283d45cca98a686384b13f18 revision: 41d7a4864f3325cd9abcc5c5a17788334c8ca863 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:05.158709) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:05.159223) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:05.159567) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:05.186638) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:05.187002) ========= 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.sIko1GJRLL/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.LuBr47ydlY/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-18 18:03:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 54.0M=0s 2016-10-18 18:03:05 (54.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140903 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:05.351439) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:05.351762) ========= 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.sIko1GJRLL/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.LuBr47ydlY/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.026387 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:05.402140) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:05.402820) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 41d7a4864f3325cd9abcc5c5a17788334c8ca863 --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 41d7a4864f3325cd9abcc5c5a17788334c8ca863 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sIko1GJRLL/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.LuBr47ydlY/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-18 18:03:05,480 truncating revision to first 12 chars 2016-10-18 18:03:05,480 Setting DEBUG logging. 2016-10-18 18:03:05,480 attempt 1/10 2016-10-18 18:03:05,480 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/41d7a4864f33?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 18:03:06,154 unpacking tar archive at: autoland-41d7a4864f33/testing/mozharness/ program finished with exit code 0 elapsedTime=0.926486 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:06.354644) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:06.355199) ========= script_repo_revision: 41d7a4864f3325cd9abcc5c5a17788334c8ca863 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:06.355886) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:06.356355) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 18:03:06.376914) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-18 18:03:06.377508) ========= /tools/buildbot/bin/python -u 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', '-u', '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.sIko1GJRLL/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.LuBr47ydlY/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:03:06 INFO - MultiFileLogger online at 20161018 18:03:06 in /builds/slave/test 18:03:06 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 18:03:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:03:06 INFO - {'append_to_log': False, 18:03:06 INFO - 'base_work_dir': '/builds/slave/test', 18:03:06 INFO - 'blob_upload_branch': 'Autoland', 18:03:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:03:06 INFO - 'branch': 'Autoland', 18:03:06 INFO - 'buildbot_json_path': 'buildprops.json', 18:03:06 INFO - 'config_files': ('talos/mac_config.py',), 18:03:06 INFO - 'default_actions': ('clobber', 18:03:06 INFO - 'read-buildbot-config', 18:03:06 INFO - 'download-and-extract', 18:03:06 INFO - 'populate-webroot', 18:03:06 INFO - 'create-virtualenv', 18:03:06 INFO - 'install', 18:03:06 INFO - 'run-tests'), 18:03:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:03:06 INFO - 'download_minidump_stackwalk': True, 18:03:06 INFO - 'download_symbols': 'ondemand', 18:03:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:03:06 INFO - 'tooltool.py': '/tools/tooltool.py', 18:03:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:03:06 INFO - '/tools/misc-python/virtualenv.py')}, 18:03:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:03:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:03:06 INFO - 'installer_path': 'installer.exe', 18:03:06 INFO - 'log_level': 'info', 18:03:06 INFO - 'log_name': 'talos', 18:03:06 INFO - 'log_to_console': True, 18:03:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:03:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:03:06 INFO - 'opt_config_files': (), 18:03:06 INFO - 'pip_index': False, 18:03:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:03:06 INFO - 'cmd': ('bash', 18:03:06 INFO - '-c', 18:03:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:03:06 INFO - 'enabled': True, 18:03:06 INFO - 'halt_on_failure': False, 18:03:06 INFO - 'name': 'check_screen_resolution'},), 18:03:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:03:06 INFO - 'cmd': ('bash', 18:03:06 INFO - '-c', 18:03:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:03:06 INFO - 'enabled': True, 18:03:06 INFO - 'halt_on_failure': False, 18:03:06 INFO - 'name': 'check_screen_resolution'},), 18:03:06 INFO - 'run_cmd_checks_enabled': True, 18:03:06 INFO - 'sps_profile': False, 18:03:06 INFO - 'sps_profile_interval': 0, 18:03:06 INFO - 'suite': 'dromaeojs-e10s', 18:03:06 INFO - 'system_bits': '32', 18:03:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:03:06 INFO - 'title': 't-yosemite-r7-0034', 18:03:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:03:06 INFO - 'use_talos_json': True, 18:03:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:03:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:03:06 INFO - 'work_dir': 'build'} 18:03:06 INFO - [mozharness: 2016-10-19 01:03:06.591846Z] Running clobber step. 18:03:06 INFO - Running pre-action listener: _resource_record_pre_action 18:03:06 INFO - Running main action method: clobber 18:03:06 INFO - rmtree: /builds/slave/test/build 18:03:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:03:08 INFO - Running post-action listener: _resource_record_post_action 18:03:08 INFO - [mozharness: 2016-10-19 01:03:08.694649Z] Finished clobber step (success) 18:03:08 INFO - [mozharness: 2016-10-19 01:03:08.694808Z] Running read-buildbot-config step. 18:03:08 INFO - Running pre-action listener: _resource_record_pre_action 18:03:08 INFO - Running main action method: read_buildbot_config 18:03:08 INFO - Using buildbot properties: 18:03:08 INFO - { 18:03:08 INFO - "project": "", 18:03:08 INFO - "product": "firefox", 18:03:08 INFO - "script_repo_revision": "production", 18:03:08 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 18:03:08 INFO - "repository": "", 18:03:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 18:03:08 INFO - "buildid": "20161018163322", 18:03:08 INFO - "pgo_build": "False", 18:03:08 INFO - "basedir": "/builds/slave/test", 18:03:08 INFO - "buildnumber": 50, 18:03:08 INFO - "slavename": "t-yosemite-r7-0034", 18:03:08 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:03:08 INFO - "platform": "yosemite_r7", 18:03:08 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 18:03:08 INFO - "branch": "autoland", 18:03:08 INFO - "repo_path": "integration/autoland", 18:03:08 INFO - "revision": "41d7a4864f3325cd9abcc5c5a17788334c8ca863", 18:03:08 INFO - "stage_platform": "macosx64", 18:03:08 INFO - "builduid": "01e91958283d45cca98a686384b13f18", 18:03:08 INFO - "slavebuilddir": "test" 18:03:08 INFO - } 18:03:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 18:03:08 INFO - Running post-action listener: _resource_record_post_action 18:03:08 INFO - [mozharness: 2016-10-19 01:03:08.698246Z] Finished read-buildbot-config step (success) 18:03:08 INFO - [mozharness: 2016-10-19 01:03:08.698389Z] Running download-and-extract step. 18:03:08 INFO - Running pre-action listener: _resource_record_pre_action 18:03:08 INFO - Running main action method: download_and_extract 18:03:08 INFO - mkdir: /builds/slave/test/build/tests 18:03:08 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')]} 18:03:08 INFO - trying https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 18:03:08 INFO - Downloading https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/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 18:03:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/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 18:03:09 INFO - Downloaded 1199 bytes. 18:03:09 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 18:03:09 INFO - Using the following test package requirements: 18:03:09 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 18:03:09 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:03:09 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 18:03:09 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:03:09 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 18:03:09 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:03:09 INFO - u'jsshell-mac.zip'], 18:03:09 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:03:09 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 18:03:09 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 18:03:09 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:03:09 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 18:03:09 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:03:09 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 18:03:09 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:03:09 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 18:03:09 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:03:09 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 18:03:09 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:03:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 18:03:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:03:09 INFO - Fetch https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 18:03:10 INFO - Http code: 200 18:03:10 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 18:03:10 INFO - x-amz-cf-id: 8eEP6CryOzgJGZEWLPmmhblAvB3GvS2xj8cpzgQsNGTd5FHkXwL0BA== 18:03:10 INFO - x-amz-version-id: sZAWpuqyuOWJh6Ju2Svj4VSjdJZh7d2l 18:03:10 INFO - Expected file size: 16483655 18:03:10 INFO - Obtained file size: 16483655 18:03:17 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 18:03:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 18:03:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:03:17 INFO - Fetch https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 18:03:18 INFO - Http code: 200 18:03:18 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 18:03:18 INFO - x-amz-cf-id: 62rX_Tg0sJ2XfjDE4MaLGNkTVKdINrylYZZUrF9Ml8CJ7ck_cY_U7g== 18:03:18 INFO - x-amz-version-id: sZAWpuqyuOWJh6Ju2Svj4VSjdJZh7d2l 18:03:18 INFO - Expected file size: 16483655 18:03:18 INFO - Obtained file size: 16483655 18:03:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 18:03:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:03:25 INFO - Fetch https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 18:03:26 INFO - Http code: 200 18:03:26 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 18:03:26 INFO - x-amz-cf-id: rKjeOTk-Fz7NPFIOWv-Uf4hoF62pI0b6-RtnLZQRdUYlxZWKHE6C8w== 18:03:26 INFO - x-amz-version-id: KaiRb3ZQAlzCXijj3lleTJa4lAA4KGho 18:03:26 INFO - Expected file size: 14409028 18:03:26 INFO - Obtained file size: 14409028 18:03:26 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')]} 18:03:26 INFO - trying https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:03:26 INFO - Downloading https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 18:03:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/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 18:03:29 INFO - Downloaded 111628444 bytes. 18:03:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:03:29 INFO - mkdir: /builds/slave/test/properties 18:03:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:03:29 INFO - Writing to file /builds/slave/test/properties/build_url 18:03:29 INFO - Contents: 18:03:29 INFO - build_url:https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:03:30 INFO - Running post-action listener: _resource_record_post_action 18:03:30 INFO - Running post-action listener: set_extra_try_arguments 18:03:30 INFO - [mozharness: 2016-10-19 01:03:30.225035Z] Finished download-and-extract step (success) 18:03:30 INFO - [mozharness: 2016-10-19 01:03:30.225173Z] Running populate-webroot step. 18:03:30 INFO - Running pre-action listener: _resource_record_pre_action 18:03:30 INFO - Running main action method: populate_webroot 18:03:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:03:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:03:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:03:30 INFO - '--tppagecycles', 18:03:30 INFO - '7'], 18:03:30 INFO - 'tests': ['tsvgm']}}, 18:03:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:03:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:03:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:03:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:03:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:03:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:03:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:30 INFO - 'talos_options': ['--disable-e10s'], 18:03:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:03:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:03:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:30 INFO - 'talos_options': ['--disable-e10s'], 18:03:30 INFO - 'tests': ['damp', 'tps']}, 18:03:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:30 INFO - 'tests': ['damp', 'tps']}, 18:03:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:03:30 INFO - 'tests': ['dromaeo_dom']}, 18:03:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:03:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:03:30 INFO - 'tests': ['basic_compositor_video']}, 18:03:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:03:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:03:30 INFO - 'tests': ['a11yr', 18:03:30 INFO - 'ts_paint', 18:03:30 INFO - 'tpaint', 18:03:30 INFO - 'sessionrestore', 18:03:30 INFO - 'sessionrestore_no_auto_restore', 18:03:30 INFO - 'tabpaint']}, 18:03:30 INFO - 'other-e10s': {'tests': ['a11yr', 18:03:30 INFO - 'ts_paint', 18:03:30 INFO - 'tpaint', 18:03:30 INFO - 'sessionrestore', 18:03:30 INFO - 'sessionrestore_no_auto_restore', 18:03:30 INFO - 'tabpaint']}, 18:03:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:03:30 INFO - 'ts_paint', 18:03:30 INFO - 'tpaint', 18:03:30 INFO - 'sessionrestore', 18:03:30 INFO - 'sessionrestore_no_auto_restore', 18:03:30 INFO - 'tabpaint']}, 18:03:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:03:30 INFO - 'ts_paint', 18:03:30 INFO - 'tpaint', 18:03:30 INFO - 'sessionrestore', 18:03:30 INFO - 'sessionrestore_no_auto_restore', 18:03:30 INFO - 'tabpaint']}, 18:03:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:03:30 INFO - 'tests': ['a11yr', 18:03:30 INFO - 'ts_paint', 18:03:30 INFO - 'tpaint', 18:03:30 INFO - 'sessionrestore', 18:03:30 INFO - 'sessionrestore_no_auto_restore', 18:03:30 INFO - 'tabpaint']}, 18:03:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:03:30 INFO - 'tests': ['a11yr', 18:03:30 INFO - 'ts_paint', 18:03:30 INFO - 'tpaint', 18:03:30 INFO - 'sessionrestore', 18:03:30 INFO - 'sessionrestore_no_auto_restore', 18:03:30 INFO - 'tabpaint']}, 18:03:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:03:30 INFO - 'tests': ['tsvgx', 18:03:30 INFO - 'tsvgr_opacity', 18:03:30 INFO - 'tart', 18:03:30 INFO - 'tscrollx', 18:03:30 INFO - 'cart']}, 18:03:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:03:30 INFO - 'tsvgr_opacity', 18:03:30 INFO - 'tart', 18:03:30 INFO - 'tscrollx', 18:03:30 INFO - 'cart']}, 18:03:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:30 INFO - 'talos_options': ['--disable-e10s'], 18:03:30 INFO - 'tests': ['tp5o']}, 18:03:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:30 INFO - 'tests': ['tp5o']}, 18:03:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:30 INFO - 'talos_options': ['--disable-e10s', 18:03:30 INFO - '--xperf_path', 18:03:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:03:30 INFO - 'tests': ['tp5n']}, 18:03:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:30 INFO - 'talos_options': ['--xperf_path', 18:03:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:03:30 INFO - 'tests': ['tp5n']}}, 18:03:30 INFO - 'talos.zip': {'path': '', 18:03:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:03:30 INFO - Running post-action listener: _resource_record_post_action 18:03:30 INFO - [mozharness: 2016-10-19 01:03:30.244114Z] Finished populate-webroot step (success) 18:03:30 INFO - [mozharness: 2016-10-19 01:03:30.244244Z] Running create-virtualenv step. 18:03:30 INFO - Running pre-action listener: _resource_record_pre_action 18:03:30 INFO - Running main action method: create_virtualenv 18:03:30 INFO - Creating virtualenv /builds/slave/test/build/venv 18:03:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:03:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:03:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:03:30 INFO - Using real prefix '/tools/python27' 18:03:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:03:31 INFO - Installing distribute.............................................................................................................................................................................................done. 18:03:34 INFO - Installing pip.................done. 18:03:34 INFO - Return code: 0 18:03:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:03:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:03:34 INFO - Reading from file tmpfile_stdout 18:03:34 INFO - Output received: 18:03:34 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:03:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:03:34 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')]} 18:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:34 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')]} 18:03:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10cebf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3141d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd3d30>, '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 0x10caf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cccbb20>, '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.sIko1GJRLL/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.LuBr47ydlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:03:34 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 18:03:34 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 18:03:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sIko1GJRLL/Render', 18:03:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:34 INFO - 'HOME': '/Users/cltbld', 18:03:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:34 INFO - 'LOGNAME': 'cltbld', 18:03:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:34 INFO - 'MOZ_NO_REMOTE': '1', 18:03:34 INFO - 'NO_EM_RESTART': '1', 18:03:34 INFO - 'PAGER': '/bin/cat', 18:03:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:34 INFO - 'PWD': '/builds/slave/test', 18:03:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:34 INFO - 'SHELL': '/bin/bash', 18:03:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuBr47ydlY/Listeners', 18:03:34 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:03:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:34 INFO - 'USER': 'cltbld', 18:03:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:34 INFO - 'XPC_FLAGS': '0x0', 18:03:34 INFO - 'XPC_SERVICE_NAME': '0', 18:03:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:03:34 INFO - Cleaning up... 18:03:34 INFO - Return code: 0 18:03:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:03:34 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')]} 18:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:34 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')]} 18:03:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10cebf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3141d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd3d30>, '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 0x10caf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cccbb20>, '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.sIko1GJRLL/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.LuBr47ydlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:03:34 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 18:03:34 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 18:03:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sIko1GJRLL/Render', 18:03:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:34 INFO - 'HOME': '/Users/cltbld', 18:03:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:34 INFO - 'LOGNAME': 'cltbld', 18:03:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:34 INFO - 'MOZ_NO_REMOTE': '1', 18:03:34 INFO - 'NO_EM_RESTART': '1', 18:03:34 INFO - 'PAGER': '/bin/cat', 18:03:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:34 INFO - 'PWD': '/builds/slave/test', 18:03:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:34 INFO - 'SHELL': '/bin/bash', 18:03:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuBr47ydlY/Listeners', 18:03:34 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:03:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:34 INFO - 'USER': 'cltbld', 18:03:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:34 INFO - 'XPC_FLAGS': '0x0', 18:03:34 INFO - 'XPC_SERVICE_NAME': '0', 18:03:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:34 INFO - Downloading/unpacking psutil>=3.1.1 18:03:34 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 18:03:34 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 18:03:34 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 18:03:34 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 18:03:34 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 18:03:34 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 18:03:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:03:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:03:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:03:38 INFO - Installing collected packages: psutil 18:03:38 INFO - Running setup.py install for psutil 18:03:38 INFO - building 'psutil._psutil_osx' extension 18:03:38 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 18:03:39 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 18:03:39 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 18:03:39 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 18:03:39 INFO - building 'psutil._psutil_posix' extension 18:03:39 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 18:03:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:03:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:03:39 INFO - ^ 18:03:39 INFO - 1 warning generated. 18:03:39 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 18:03:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:03:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:03:39 INFO - Successfully installed psutil 18:03:39 INFO - Cleaning up... 18:03:39 INFO - Return code: 0 18:03:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:03:39 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')]} 18:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:39 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')]} 18:03:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10cebf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3141d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd3d30>, '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 0x10caf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cccbb20>, '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.sIko1GJRLL/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.LuBr47ydlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:03:39 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 18:03:39 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 18:03:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sIko1GJRLL/Render', 18:03:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:39 INFO - 'HOME': '/Users/cltbld', 18:03:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:39 INFO - 'LOGNAME': 'cltbld', 18:03:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:39 INFO - 'MOZ_NO_REMOTE': '1', 18:03:39 INFO - 'NO_EM_RESTART': '1', 18:03:39 INFO - 'PAGER': '/bin/cat', 18:03:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:39 INFO - 'PWD': '/builds/slave/test', 18:03:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:39 INFO - 'SHELL': '/bin/bash', 18:03:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuBr47ydlY/Listeners', 18:03:39 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:03:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:39 INFO - 'USER': 'cltbld', 18:03:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:39 INFO - 'XPC_FLAGS': '0x0', 18:03:39 INFO - 'XPC_SERVICE_NAME': '0', 18:03:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:40 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 18:03:40 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 18:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:03:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:03:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:03:42 INFO - Installing collected packages: mozsystemmonitor 18:03:42 INFO - Running setup.py install for mozsystemmonitor 18:03:42 INFO - Successfully installed mozsystemmonitor 18:03:42 INFO - Cleaning up... 18:03:43 INFO - Return code: 0 18:03:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:03:43 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')]} 18:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:43 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')]} 18:03:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10cebf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3141d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd3d30>, '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 0x10caf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cccbb20>, '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.sIko1GJRLL/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.LuBr47ydlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:03:43 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 18:03:43 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 18:03:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sIko1GJRLL/Render', 18:03:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:43 INFO - 'HOME': '/Users/cltbld', 18:03:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:43 INFO - 'LOGNAME': 'cltbld', 18:03:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:43 INFO - 'MOZ_NO_REMOTE': '1', 18:03:43 INFO - 'NO_EM_RESTART': '1', 18:03:43 INFO - 'PAGER': '/bin/cat', 18:03:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:43 INFO - 'PWD': '/builds/slave/test', 18:03:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:43 INFO - 'SHELL': '/bin/bash', 18:03:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuBr47ydlY/Listeners', 18:03:43 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:03:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:43 INFO - 'USER': 'cltbld', 18:03:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:43 INFO - 'XPC_FLAGS': '0x0', 18:03:43 INFO - 'XPC_SERVICE_NAME': '0', 18:03:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:43 INFO - Downloading/unpacking jsonschema==2.5.1 18:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:43 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 18:03:43 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 18:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:03:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:03:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:45 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 18:03:45 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 18:03:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:46 INFO - Downloading functools32-3.2.3-2.tar.gz 18:03:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:03:46 INFO - warning: no files found matching '*.txt' 18:03:46 INFO - no previously-included directories found matching 'build' 18:03:46 INFO - no previously-included directories found matching 'dist' 18:03:46 INFO - no previously-included directories found matching '.git*' 18:03:46 INFO - Installing collected packages: jsonschema, functools32 18:03:46 INFO - Running setup.py install for functools32 18:03:46 INFO - warning: no files found matching '*.txt' 18:03:46 INFO - no previously-included directories found matching 'build' 18:03:46 INFO - no previously-included directories found matching 'dist' 18:03:46 INFO - no previously-included directories found matching '.git*' 18:03:46 INFO - Successfully installed jsonschema functools32 18:03:46 INFO - Cleaning up... 18:03:46 INFO - Return code: 0 18:03:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:03:46 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')]} 18:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:46 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')]} 18:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:46 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 0x10cebf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3141d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd3d30>, '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 0x10caf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cccbb20>, '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.sIko1GJRLL/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.LuBr47ydlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:03:46 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 18:03:46 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 18:03:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sIko1GJRLL/Render', 18:03:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:46 INFO - 'HOME': '/Users/cltbld', 18:03:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:46 INFO - 'LOGNAME': 'cltbld', 18:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:46 INFO - 'MOZ_NO_REMOTE': '1', 18:03:46 INFO - 'NO_EM_RESTART': '1', 18:03:46 INFO - 'PAGER': '/bin/cat', 18:03:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:46 INFO - 'PWD': '/builds/slave/test', 18:03:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:46 INFO - 'SHELL': '/bin/bash', 18:03:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuBr47ydlY/Listeners', 18:03:46 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:03:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:46 INFO - 'USER': 'cltbld', 18:03:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:46 INFO - 'XPC_FLAGS': '0x0', 18:03:46 INFO - 'XPC_SERVICE_NAME': '0', 18:03:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:03:47 INFO - Cleaning up... 18:03:47 INFO - Return code: 0 18:03:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:03:47 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')]} 18:03:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:47 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')]} 18:03:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:47 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 0x10cebf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3141d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd3d30>, '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 0x10caf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cccbb20>, '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.sIko1GJRLL/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.LuBr47ydlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:03:47 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 18:03:47 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 18:03:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sIko1GJRLL/Render', 18:03:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:47 INFO - 'HOME': '/Users/cltbld', 18:03:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:47 INFO - 'LOGNAME': 'cltbld', 18:03:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:47 INFO - 'MOZ_NO_REMOTE': '1', 18:03:47 INFO - 'NO_EM_RESTART': '1', 18:03:47 INFO - 'PAGER': '/bin/cat', 18:03:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:47 INFO - 'PWD': '/builds/slave/test', 18:03:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:47 INFO - 'SHELL': '/bin/bash', 18:03:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuBr47ydlY/Listeners', 18:03:47 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:03:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:47 INFO - 'USER': 'cltbld', 18:03:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:47 INFO - 'XPC_FLAGS': '0x0', 18:03:47 INFO - 'XPC_SERVICE_NAME': '0', 18:03:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:47 INFO - Downloading/unpacking blobuploader==1.2.4 18:03:47 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 18:03:47 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 18:03:47 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 18:03:47 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 18:03:47 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 18:03:47 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 18:03:49 INFO - Downloading blobuploader-1.2.4.tar.gz 18:03:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:03:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:03:50 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 18:03:50 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 18:03:50 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 18:03:50 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 18:03:50 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 18:03:50 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 18:03:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:03:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:03:50 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 18:03:50 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 18:03:50 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 18:03:50 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 18:03:50 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 18:03:50 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 18:03:51 INFO - Downloading docopt-0.6.1.tar.gz 18:03:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:03:51 INFO - Installing collected packages: blobuploader, requests, docopt 18:03:51 INFO - Running setup.py install for blobuploader 18:03:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:03:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:03:51 INFO - Running setup.py install for requests 18:03:51 INFO - Running setup.py install for docopt 18:03:52 INFO - Successfully installed blobuploader requests docopt 18:03:52 INFO - Cleaning up... 18:03:52 INFO - Return code: 0 18:03:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03:52 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')]} 18:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:52 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')]} 18:03:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:52 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 0x10cebf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3141d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd3d30>, '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 0x10caf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cccbb20>, '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.sIko1GJRLL/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.LuBr47ydlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:03:52 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 18:03:52 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 18:03:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sIko1GJRLL/Render', 18:03:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:52 INFO - 'HOME': '/Users/cltbld', 18:03:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:52 INFO - 'LOGNAME': 'cltbld', 18:03:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:52 INFO - 'MOZ_NO_REMOTE': '1', 18:03:52 INFO - 'NO_EM_RESTART': '1', 18:03:52 INFO - 'PAGER': '/bin/cat', 18:03:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:52 INFO - 'PWD': '/builds/slave/test', 18:03:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:52 INFO - 'SHELL': '/bin/bash', 18:03:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuBr47ydlY/Listeners', 18:03:52 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:03:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:52 INFO - 'USER': 'cltbld', 18:03:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:52 INFO - 'XPC_FLAGS': '0x0', 18:03:52 INFO - 'XPC_SERVICE_NAME': '0', 18:03:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:03:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-3uP01k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:03:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:03:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-3w3of3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:03:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:03:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-qs1qfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:03:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:03:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Qv5A0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:03:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:03:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-hrCDJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:03:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:03:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ueeJZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:03:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:03:53 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-dNOD26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:03:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:03:53 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Xj4juk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:03:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:03:53 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ZuPKMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:03:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:03:53 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-NIIjhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:03:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:03:53 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-OSpxDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:03:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:03:53 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-uNtlhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:03:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:03:53 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-L0armL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:03:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:03:53 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-e8C0wj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:03:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:53 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-NknEZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:03:54 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-wxThEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:03:54 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-NwgwqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:03:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:03:54 INFO - Running setup.py install for manifestparser 18:03:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:03:54 INFO - Running setup.py install for mozcrash 18:03:54 INFO - Running setup.py install for mozdebug 18:03:54 INFO - Running setup.py install for mozdevice 18:03:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:03:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:03:54 INFO - Running setup.py install for mozfile 18:03:55 INFO - Running setup.py install for mozhttpd 18:03:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:03:55 INFO - Running setup.py install for mozinfo 18:03:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:03:55 INFO - Running setup.py install for mozInstall 18:03:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:03:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:03:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:03:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:03:55 INFO - Running setup.py install for mozleak 18:03:55 INFO - Running setup.py install for mozlog 18:03:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:03:55 INFO - Running setup.py install for moznetwork 18:03:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:03:56 INFO - Running setup.py install for mozprocess 18:03:56 INFO - Running setup.py install for mozprofile 18:03:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:03:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:03:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:03:56 INFO - Running setup.py install for mozrunner 18:03:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:03:56 INFO - Running setup.py install for mozscreenshot 18:03:56 INFO - Running setup.py install for moztest 18:03:56 INFO - Running setup.py install for mozversion 18:03:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:03:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:03:57 INFO - Cleaning up... 18:03:57 INFO - Return code: 0 18:03:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03:57 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')]} 18:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:57 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')]} 18:03:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:03:57 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 0x10cebf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3141d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd3d30>, '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 0x10caf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cccbb20>, '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.sIko1GJRLL/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.LuBr47ydlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:03:57 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 18:03:57 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 18:03:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sIko1GJRLL/Render', 18:03:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:57 INFO - 'HOME': '/Users/cltbld', 18:03:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:57 INFO - 'LOGNAME': 'cltbld', 18:03:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:57 INFO - 'MOZ_NO_REMOTE': '1', 18:03:57 INFO - 'NO_EM_RESTART': '1', 18:03:57 INFO - 'PAGER': '/bin/cat', 18:03:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:57 INFO - 'PWD': '/builds/slave/test', 18:03:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:57 INFO - 'SHELL': '/bin/bash', 18:03:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuBr47ydlY/Listeners', 18:03:57 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:03:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:57 INFO - 'USER': 'cltbld', 18:03:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:57 INFO - 'XPC_FLAGS': '0x0', 18:03:57 INFO - 'XPC_SERVICE_NAME': '0', 18:03:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:03:57 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Bu_FHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:03:57 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)) 18:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:03:57 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-0jHrGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:03:57 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)) 18:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:03:57 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-hJCXT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:03:57 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)) 18:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:03:57 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-VdGQP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:03:57 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)) 18:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:03:57 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-tAn7nZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:03:57 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)) 18:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:03:57 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-cAYKRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:03:57 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)) 18:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:03:57 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-btRTbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:03:58 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)) 18:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:03:58 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Q1wgik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:03:58 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)) 18:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:03:58 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-JFMCVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:03:58 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)) 18:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:03:58 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-VN6QVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:03:58 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)) 18:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:03:58 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-134dsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:03:58 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)) 18:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:03:58 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-4Q7bWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:03:58 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)) 18:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:03:58 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-xWw1Ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:03:58 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)) 18:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:03:58 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-egBlh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:03:58 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)) 18:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:58 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-rhKYGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:58 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)) 18:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:03:59 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-pbRUOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:03:59 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)) 18:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:03:59 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-9nWpjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:03:59 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)) 18:03:59 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)) 18:03:59 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)) 18:03:59 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)) 18:03:59 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)) 18:03:59 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)) 18:03:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:03:59 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 18:03:59 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 18:03:59 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 18:03:59 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 18:03:59 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 18:03:59 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 18:04:01 INFO - Downloading blessings-1.6.tar.gz 18:04:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:04:01 INFO - Installing collected packages: blessings 18:04:01 INFO - Running setup.py install for blessings 18:04:02 INFO - Successfully installed blessings 18:04:02 INFO - Cleaning up... 18:04:02 INFO - Return code: 0 18:04:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:04:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:04:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:04:02 INFO - Reading from file tmpfile_stdout 18:04:02 INFO - Current package versions: 18:04:02 INFO - blessings == 1.6 18:04:02 INFO - blobuploader == 1.2.4 18:04:02 INFO - docopt == 0.6.1 18:04:02 INFO - functools32 == 3.2.3-2 18:04:02 INFO - jsonschema == 2.5.1 18:04:02 INFO - manifestparser == 1.1 18:04:02 INFO - mozInstall == 1.12 18:04:02 INFO - mozcrash == 0.17 18:04:02 INFO - mozdebug == 0.1 18:04:02 INFO - mozdevice == 0.48 18:04:02 INFO - mozfile == 1.2 18:04:02 INFO - mozhttpd == 0.7 18:04:02 INFO - mozinfo == 0.9 18:04:02 INFO - mozleak == 0.1 18:04:02 INFO - mozlog == 3.4 18:04:02 INFO - moznetwork == 0.27 18:04:02 INFO - mozprocess == 0.23 18:04:02 INFO - mozprofile == 0.28 18:04:02 INFO - mozrunner == 6.12 18:04:02 INFO - mozscreenshot == 0.1 18:04:02 INFO - mozsystemmonitor == 0.3 18:04:02 INFO - moztest == 0.7 18:04:02 INFO - mozversion == 1.4 18:04:02 INFO - psutil == 3.1.1 18:04:02 INFO - requests == 1.2.3 18:04:02 INFO - wsgiref == 0.1.2 18:04:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:04:02 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')]} 18:04:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:02 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')]} 18:04:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:03 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 0x10cebf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3141d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd3d30>, '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 0x10caf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cccbb20>, '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.sIko1GJRLL/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.LuBr47ydlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:04:03 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 18:04:03 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 18:04:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sIko1GJRLL/Render', 18:04:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:03 INFO - 'HOME': '/Users/cltbld', 18:04:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:03 INFO - 'LOGNAME': 'cltbld', 18:04:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:03 INFO - 'MOZ_NO_REMOTE': '1', 18:04:03 INFO - 'NO_EM_RESTART': '1', 18:04:03 INFO - 'PAGER': '/bin/cat', 18:04:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:03 INFO - 'PWD': '/builds/slave/test', 18:04:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:03 INFO - 'SHELL': '/bin/bash', 18:04:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuBr47ydlY/Listeners', 18:04:03 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:04:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:03 INFO - 'USER': 'cltbld', 18:04:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:03 INFO - 'XPC_FLAGS': '0x0', 18:04:03 INFO - 'XPC_SERVICE_NAME': '0', 18:04:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:03 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)) 18:04:03 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)) 18:04:03 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)) 18:04:03 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)) 18:04:03 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)) 18:04:03 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)) 18:04:03 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)) 18:04:03 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)) 18:04:03 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)) 18:04:03 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)) 18:04:03 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)) 18:04:03 INFO - Cleaning up... 18:04:03 INFO - Return code: 0 18:04:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:04:03 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')]} 18:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:03 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')]} 18:04:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cebf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce3141d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd3d30>, '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 0x10caf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cccbb20>, '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.sIko1GJRLL/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.LuBr47ydlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:04: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', 'jsonschema'] in /builds/slave/test/build 18:04: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 jsonschema 18:04:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sIko1GJRLL/Render', 18:04:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:04 INFO - 'HOME': '/Users/cltbld', 18:04:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:04 INFO - 'LOGNAME': 'cltbld', 18:04:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:04 INFO - 'MOZ_NO_REMOTE': '1', 18:04:04 INFO - 'NO_EM_RESTART': '1', 18:04:04 INFO - 'PAGER': '/bin/cat', 18:04:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:04 INFO - 'PWD': '/builds/slave/test', 18:04:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:04 INFO - 'SHELL': '/bin/bash', 18:04:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuBr47ydlY/Listeners', 18:04:04 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:04:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:04 INFO - 'USER': 'cltbld', 18:04:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:04 INFO - 'XPC_FLAGS': '0x0', 18:04:04 INFO - 'XPC_SERVICE_NAME': '0', 18:04:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:04:04 INFO - Cleaning up... 18:04:04 INFO - Return code: 0 18:04:04 INFO - Running post-action listener: _resource_record_post_action 18:04:04 INFO - Running post-action listener: _start_resource_monitoring 18:04:04 INFO - Starting resource monitoring. 18:04:04 INFO - [mozharness: 2016-10-19 01:04:04.352478Z] Finished create-virtualenv step (success) 18:04:04 INFO - [mozharness: 2016-10-19 01:04:04.353315Z] Running install step. 18:04:04 INFO - Running pre-action listener: _resource_record_pre_action 18:04:04 INFO - Running main action method: install 18:04:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:04:04 INFO - Reading from file tmpfile_stdout 18:04:04 INFO - Detecting whether we're running mozinstall >=1.0... 18:04:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:04:04 INFO - Reading from file tmpfile_stdout 18:04:04 INFO - Output received: 18:04:04 INFO - Usage: mozinstall [options] installer 18:04:04 INFO - Options: 18:04:04 INFO - -h, --help show this help message and exit 18:04:04 INFO - -d DEST, --destination=DEST 18:04:04 INFO - Directory to install application into. [default: 18:04:04 INFO - "/builds/slave/test"] 18:04:04 INFO - --app=APP Application being installed. [default: firefox] 18:04:04 INFO - mkdir: /builds/slave/test/build/application 18:04:04 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'] 18:04:04 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 18:04:40 INFO - Reading from file tmpfile_stdout 18:04:40 INFO - Output received: 18:04:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:04:40 INFO - Running post-action listener: _resource_record_post_action 18:04:40 INFO - [mozharness: 2016-10-19 01:04:40.360238Z] Finished install step (success) 18:04:40 INFO - [mozharness: 2016-10-19 01:04:40.360376Z] Running run-tests step. 18:04:40 INFO - Running pre-action listener: _resource_record_pre_action 18:04:40 INFO - Running main action method: run_tests 18:04:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:04:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:04:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:04:40 INFO - 2016-10-18 18:04:40.375 screenresolution[1641:7590] starting screenresolution argv=screenresolution get 18:04:40 INFO - 2016-10-18 18:04:40.378 screenresolution[1641:7590] Display 0: 1600x1200x32@60 18:04:40 INFO - 2016-10-18 18:04:40.384 screenresolution[1642:7592] starting screenresolution argv=screenresolution list 18:04:40 INFO - Available Modes on Display 0 18:04:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:04:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:04:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:04:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:04:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:04:40 INFO - Intel Iris: 18:04:40 INFO - Chipset Model: Intel Iris 18:04:40 INFO - Type: GPU 18:04:40 INFO - Bus: Built-In 18:04:40 INFO - VRAM (Dynamic, Max): 1536 MB 18:04:40 INFO - Vendor: Intel (0x8086) 18:04:40 INFO - Device ID: 0x0a2e 18:04:40 INFO - Revision ID: 0x0009 18:04:40 INFO - Displays: 18:04:40 INFO - Display: 18:04:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:04:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:04:40 INFO - Display Serial Number: 1600x1200 60 18:04:40 INFO - Main Display: Yes 18:04:40 INFO - Mirror: Off 18:04:40 INFO - Online: Yes 18:04:40 INFO - Rotation: Supported 18:04:40 INFO - Return code: 0 18:04:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:04:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:04:40 INFO - Python 2.7.3 18:04:40 INFO - Return code: 0 18:04:40 INFO - grabbing minidump binary from tooltool 18:04:40 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')]} 18:04:40 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 0x10ccd3d30>, '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 0x10caf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cccbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:04:40 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 18:04:40 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 18:04:40 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 18:04:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:04:40 INFO - Return code: 0 18:04:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:04:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:04:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:04:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:04:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:04:40 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/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 18:04:40 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/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0034 --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 18:04:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sIko1GJRLL/Render', 18:04:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:40 INFO - 'HOME': '/Users/cltbld', 18:04:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:40 INFO - 'LOGNAME': 'cltbld', 18:04:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:04:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:04:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:40 INFO - 'MOZ_NO_REMOTE': '1', 18:04:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:04:40 INFO - 'NO_EM_RESTART': '1', 18:04:40 INFO - 'PAGER': '/bin/cat', 18:04:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:40 INFO - 'PWD': '/builds/slave/test', 18:04:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:04:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:40 INFO - 'SHELL': '/bin/bash', 18:04:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuBr47ydlY/Listeners', 18:04:40 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:04:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:40 INFO - 'USER': 'cltbld', 18:04:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:40 INFO - 'XPC_FLAGS': '0x0', 18:04:40 INFO - 'XPC_SERVICE_NAME': '0', 18:04:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:40 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/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 18:04:40 INFO - mozversion application_buildid: 20161018163322 18:04:40 INFO - mozversion application_changeset: 41d7a4864f3325cd9abcc5c5a17788334c8ca863 18:04:40 INFO - mozversion application_display_name: Nightly 18:04:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:04:40 INFO - mozversion application_name: Firefox 18:04:40 INFO - mozversion application_remotingname: firefox 18:04:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:04:40 INFO - mozversion application_vendor: Mozilla 18:04:40 INFO - mozversion application_version: 52.0a1 18:04:40 INFO - mozversion platform_buildid: 20161018163322 18:04:40 INFO - mozversion platform_changeset: 41d7a4864f3325cd9abcc5c5a17788334c8ca863 18:04:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:04:40 INFO - mozversion platform_version: 52.0a1 18:04:40 INFO - using testdate: 1476839080 18:04:40 INFO - actual date: 1476839080 18:04:40 INFO - starting webserver on 'localhost:49275' 18:04:40 INFO - SUITE-START | Running 2 tests 18:04:40 INFO - TEST-START | dromaeo_css 18:04:40 INFO - operating with platform_type : mac_ 18:04:40 INFO - Initialising browser for dromaeo_css test... 18:04:40 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpBrjLgA/profile http://localhost:49275/getInfo.html) 18:04:44 INFO - PROCESS | 1652 | 2016-10-18 18:04:44.061 plugin-container[1654:7739] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c43, name = 'com.apple.tsm.portname' 18:04:44 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:44 INFO - PROCESS | 1652 | 2016-10-18 18:04:44.064 plugin-container[1654:7739] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480f, name = 'com.apple.CFPasteboardClient' 18:04:44 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:44 INFO - PROCESS | 1652 | 2016-10-18 18:04:44.064 plugin-container[1654:7739] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:04:44 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 18:04:44 INFO - PROCESS | 1652 | colorDepth:24 18:04:44 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 18:04:44 INFO - PROCESS | 1652 | __metrics 18:04:44 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 18:04:44 INFO - TEST-INFO | 1652: exit 0 18:04:44 INFO - Browser initialized. 18:04:44 INFO - Running cycle 1/1 for dromaeo_css test... 18:04:44 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpBrjLgA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:04:47 INFO - PROCESS | 1655 | 2016-10-18 18:04:47.380 plugin-container[1656:7854] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 18:04:47 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:47 INFO - PROCESS | 1655 | 2016-10-18 18:04:47.381 plugin-container[1656:7854] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 18:04:47 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:47 INFO - PROCESS | 1655 | 2016-10-18 18:04:47.381 plugin-container[1656:7854] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:04:56 INFO - PROCESS | 1655 | RSS: Main: 237318144 18:04:56 INFO - PROCESS | 1655 | 18:05:46 INFO - PROCESS | 1655 | 1476839146677 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] 18:07:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 18:08:00 INFO - PROCESS | 1655 | RSS: Main: 251133952 18:08:00 INFO - PROCESS | 1655 | 18:11:02 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 18:11:03 INFO - PROCESS | 1655 | RSS: Main: 263770112 18:11:03 INFO - PROCESS | 1655 | 18:14:05 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 18:14:05 INFO - PROCESS | 1655 | RSS: Main: 268492800 18:14:05 INFO - PROCESS | 1655 | 18:17:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 18:17:08 INFO - PROCESS | 1655 | RSS: Main: 267923456 18:17:08 INFO - PROCESS | 1655 | 18:20:11 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 18:20:11 INFO - PROCESS | 1655 | RSS: Main: 273342464 18:20:11 INFO - PROCESS | 1655 | 18:23:14 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 18:23:14 INFO - PROCESS | 1655 | RSS: Main: 275984384 18:23:14 INFO - PROCESS | 1655 | 18:23:14 INFO - PROCESS | 1655 | __start_tp_report 18:23:14 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 18:23:14 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 18:23:14 INFO - PROCESS | 1655 | |i|pagename|runs| 18:23:14 INFO - PROCESS | 1655 | |0;dojo.html;2644.7569642268127;2710.728927107288;2848.8290702557847;2894.551344541597;2945.1606292206725;4757.0723708876785;4780.737059461737;4793.1132740443045;4815.157347464193;4950.13372659784;4809.807607695688;4817.494163112867;4845.466998630148;4847;4864.0515099555605;4435.756033418161;4465.129299786543;4607.239805432099;4607.331936869152;4645;3610.729195310349;4602.953970460303;4608.28571571407;4681.83613573525;4688.671792974493;3339.983300083484;3699.278640665071;4535.433070866142;4566.862994110182;4571.1086338163905;3603.8198090095652;4352.7823608819745;4376.89057773555;4428.689991700582;4571.337156112379;4416.05054913192;4462.687611867171;4478.261086920655;4502.2796352583755;4521.593056624886;3372.5447064646205;4712.858614241544;4790.856274311776;4825.252085926687;4898.877528061791;5181.171012637998;5200.739963001834;5234.110201265794;5274.472552744734;5406.405295417501;5442.510174084351;5451.155070964007;5477.643953143072;5484.369297530795;5497.955388476177;5066.493350664941;5075;5077.289179514871;5079.822206222764;5126.359205099363;4698.979438841331;5107.259447380184;5433.429489903582;5452.236686863842;5473.644213126134;5225.216217567319;5279.762410691528;5281.815136470282;5296.4703529647495;5308.787648494017;5377.542908852792;5402.702851343135;5421.864453388697;5423.511883930465;5464.836054918359;4232.91534169321;5058.190689489664;5105.8723531912;5182.896342073137;5267.920981185285;4039.414284928669;4065.9390109148385;5319.86700332487;5341.118715411913;5379.569634429236;3637.927241455156;4339.674524410619;4389.539098394686;4393.18726035678;4399.868003959887;3819.9236015280094;3836.5918176574646;3845.288621605012;3850.210706805098;3850.8844734658005;3829.2532956073296;3927.6857851371824;4854.854354369375;4918.163912134874;4948.65359424844;5404.675719456845;5451.345838499326;5465.781368745205;5469.343678758495;5486.423925487846;3823.808809559511;4667.813287468532;4764.499727528629;4899.314096026559;4927.334809800703;4682.555157260055;4800.039992001613;4823.155947709137;4838.225883858636;4853.9029219416125;5649.067903795828;5698.601097923106;5709.914351284734;5710.828675139753;5779.595428320145;5154.407243166934;5238.816641417532;5444.591655625764;5468.9179662304305;5477.096279113902;5518.19986102021;5551.444855514578;5557;5574.024545704405;5642.605017648725;5332.280142180756;5341.813036543703;5380.569554435714;5388.973055134699;5435.510804027656;4297.419848320436;4399.780010999501;5220.9738951305;5392.865178370572;5460.453954604508;4990.50094990498;5057.342545469065;5064.772085256099;5289.920651190158;5292.232626269092;5101.413337466239;5207.1668533034535;5222.7127507987425;5230.267762513327;5301.575873930154;4271.914561708686;4803.519648035169;4903.95096049035;5011.273365362075;5155.484451554815;5126.487351264844;5235.4502777207845;5265.131253343156;5282.96982088489;5283.735813209401;4897.804087836446;4968;4999.400071991385;5020.447750747342;5130.923036154535 18:23:14 INFO - PROCESS | 1655 | |1;ext.html;419.19514532098367;428.81775245520646;452.30119465425895;474.80295677293924;476.36881132499434;36933.81533092334;38551;38634.42048369277;40839.59160408395;40921.59078409215;33904.49143262847;38571.035724106914;39914.40128398069;39942.40086398699;40065.799671001674;7890.447668663196;8983.146601072904;9224.215941644969;9235.538223088852;9252.53737313135;9007.279417646572;9081.228095611881;9085.863712044325;9089.454632722058;9106.362554621179;9083.863742043875;9084.046175151612;9126.634934602642;9149.313801464883;9158.816823663523;9057.230135438496;9107.089291070906;9111.088891110901;9123.452592844451;9130.086991300817;8992.370534062618;9074.954625226897;9078;9086.227670648002;9093.363464557484;37507.06232344213;37598.43602345967;37807.81096094502;37817.24365512702;38007.42988855169;3120.0795765249304;3232.951505727416;3246.9350612987846;3275.3449310138067;3335.34960682667;23466.7653323468;29451.969181078774;29840.5079746014;30469.69530304691;30724;2869.5121829288864;3056.2359410147465;3208.101731515156;3247.2044349134376;3257.6742325767473;428573;430217;434275.99999999686;434987;435152;56355.71822140863;56933;56952.43047569554;57339.71330143323;57424.42575574274;49501.504984949686;60176.69911650414;60326.69836650789;60397.69801151054;60497.69751151304;15059.47291844802;15292.388304467919;15411.229438528253;15411.61470963235;15443.150626715638;11726;11768.411579421167;11825.822612660817;11847.822282665767;11896.107791915641;16665.833341666672;17544.035078070574;17631.118444078;17646.2059207336;17702.468925932244;7354.455564331551;7366.705331786776;7380.950005487234;7399.371053460516;7437.665305061285;701.2888551933266;748.988765168523;759.3393747439652;804.3605333759675;839.7034111704484;24414.26757197287;26150.346241343737;26490.602640960402;27681.446371072867;28517.144485665463;970.6020531582018;989.7426669065951;990.7770751580981;1018.9184865210765;1037.7042542875245;11742.70643233926;13923.23422211788;14056.367463464168;14307.85692143086;14731;600096;603106;610553.7335519766;610876;612163;52267.73866130645;52731.73634131805;52759.208611870854;52801.735991319794;52897.471025290026;1229.1887354346268;1279.8848103670714;1284.0882973089024;1300.3031044487036;1304.778187708073;26522.60216096799;27354.5896811544;27702.307442314104;28530.714692853635;29136.7086329134;44517;44909.10181796411;45121;45217.09565808599;45222.64332070043;508326;512818;512940;513049.43475282384;513611;24604.261872143863;24724.38189045288;25054.749452505243;25110.49779004446;25258.368540785894;34642.146870800636;41746;42352.78823605862;42480.36279455749;42557.5744242566;28689.71310286954;28773.705183267222;29583.85208074032;34388.796392126154;36019.279614408086;28997.565036524047;35221.47167792434;35893;35900.461493077106;36240.27519449649;34451.31097377988;34522.30955380928;35195.472067919516;36527;36562.63437365699;29874;32529.83735081404;32565.67434325721;32803.67196328102;33115.83442082871;23601.64597531004;23792.643110353703;24028.639570406107;24103.276901692978;24217.273481795575 18:23:14 INFO - PROCESS | 1655 | |2;jquery.html;302.5915014730114;346.6931765387631;346.9537461091615;355.07502954802754;369.0018499957615;18688.62622747546;19280.035998200103;20503;20594.279200227997;20640.587188256224;19508.804911950876;19582.510437239078;19615.11731972061;19732.60534789307;20048.498787530283;5482.369527504338;5747.741351639186;5767.163761254616;5768.5385169186575;5783.6529808211435;5342.973285133569;5673.971630141844;5684.403137670547;5787.999999999979;5793.13103034544;5522.088855338864;5724.885502289952;5775.451332123463;5775.537956963432;5776.826695199151;5715.114157305624;5754.6547207167705;5816.447437493432;5816.82549523515;5818.41815818419;4545.886352841172;5367.570594352482;5589.944100558983;5592.6085174037835;5598.944010559923;12768.297743624096;12801.55194568195;12879.935600322033;12963.87036129636;12972.87027129726;2096.3396530092973;2154.687570302313;2218.589560931233;2238.82089432845;2416.565018296706;7850.842983140363;8618.267447266928;9130.543472826399;9173.58718857653;10004.59981600735;1564.4524416454265;1767.8232176782346;1805.1786437171056;1853.0827240515896;1857.7306290587924;113171.43414282959;118756;119557.40221298925;120742;121559.3922030393;10846.457677116112;10919.563217471212;11049.889501104886;11058.668239952814;11212.887871121184;13749.587512374645;13771.449142034407;13784.724305514033;13799.448022079205;13816.93091534556;7859.52842829432;8032.919670803217;8046.436749427484;8071.838563228703;8148.5518296494265;7030.261822508665;7940.325072368798;7966.920330796618;7997.840043199103;8136.877946830802;4157.3140431829015;4323.200207961537;4327.7186982846015;4403.185410698967;4411.18393097278;1834.7247912813184;1892.3820133785691;1897.459224121119;1905.3712274949235;1922.4521011511652;702.1433850702135;722.4762047515615;772.9140557516698;825.0182283083112;831.6590198018783;19334.516637083903;20169.99150042492;20262.784232945774;20342.08460619276;20416.8770717609;757.2503221810368;788.6884680551265;823.8393513264975;866.6143566113145;912.1562554636935;8071.757847264592;9467.100625440573;10267.691969240936;10770.946145269223;10798.460076996118;551163.2441837766;568975;573040;575422;578171;92732;104834.3791405221;111591.44204279089;115538.42230788793;117062.41468792773;790.2926880441972;838.2330167896429;843.2579330189394;910.7495438754263;943.4858002388578;18598.163082661584;18791.087381455895;19011.292797904276;19101.80898191056;19324.420267391437;33563;41691;49432.25851612301;54891.45108549023;54972;71619.64190179016;76307;77559;77810.61094694717;78319;7523.435742319239;7598.278163574451;7684.961575192088;7925.960370198112;8154.266116049584;8949.642014319354;10247.641332553325;10381.325213861377;10548.472576371305;12144.39278036077;9510.47692376926;10752.13982881352;10919.235653504496;10993.251687078231;11048.281861678965;9317.627294908129;10224.744381390226;10404.583816647551;10682.893171068501;11150.386728729998;5503.367112782082;8776.551868941553;10728.141748660244;10831.783364332512;11323.71690707706;7394.815129621803;7780.688772449039;7884.645190966535;7952.681892724227;7985.161558036322;9236.584353703965;11005.038846278083;11126.443677815914;11142.610008649659;11168.553257869595 18:23:14 INFO - PROCESS | 1655 | |3;mootools.html;1419.481889110475;1452.3682198243764;1597.5127586086246;1721.586819163401;1780.3768680961657;7379.114506259257;7628.732994345199;7814.179511151326;8359.080501144877;8361.874571881419;7795.064592248924;8100.027996640397;8120.593970301491;8198.1801819818;8199.344052475803;2153.8923053847325;2199.4281486813384;2256.131389415079;2257.954840903181;2260.4235919840416;2006.799320067989;2200.19692812124;2253.808426283768;2265.6035193841094;2275.12407723027;1864.9813501865012;2243.674667173259;2247.325802259324;2248.685184074231;2266.660000999847;1988.274279887845;2101.495641046153;2228.699125618045;2231.4086767006675;2235.910563577455;2204.2285200179967;2224.410531209231;2227.52108296717;2230.7546169921297;2236.284388995522;8077.717279895175;8245.876311855327;8246.752597422086;8247.917520824774;8250;4928.334674818925;5043;5067.797288108472;5068.062408454448;5074.310615373858;3262.6737326267184;3313.453280208763;3391.9830400847836;3419.5383623210796;3432.7597068205237;889.2130464538865;1004.8693669822949;1005.9245556583212;1006.2855372685402;1026.625281772148;10486.737831554196;10523.210759193014;10542.051215390575;10565.9471702641;10580.312279701871;4325.286327755885;4355.128974205171;4485.102979404132;4535.138323718483;4543.363929049936;9073.45559266446;9150.679726209552;9162.175404213653;9186.586603602855;9188.081191880758;5037.571806396498;5046.21783623539;5052.019308607939;5053.444121146671;5058.418281897555;3659.579148397915;4093.3859921012086;4580.519045500175;4595.2188128018315;4597.655175861823;3680.1903581212628;3693.4275187346007;3764.171882185915;3773.2264885698364;3804.714646401531;1219.7865373559591;1231.7659644667663;1238.8203710462112;1245.7508498300376;1249.5564074753513;732.3774791427244;733.1349008170304;772.447699894578;806.641044735099;807.801107613844;4374.343848422698;4742.648345221595;5301.151815709545;5434.483724046209;5602.481127277419;346.63256947635585;355.91623506423;409.5310869054915;416.40869964650267;423.46431763818794;3677.724170687209;3705.981470092632;3730.5896351401325;3775.8867233983024;3820.751651142667;9439.528023598792;9699.224062075016;9826.4104153751;9865.753356166153;9883.258755593215;9590.328676992543;9682.322237443312;9798.804023919482;9806.95096524527;9825.508724563742;7281.235470275651;7541.509801862751;7541.8114547135665;7564.432667549956;7584.08990921093;5246.186841039645;5314.946850531445;5320.255164277081;5379.870227252321;5462.9726851365485;8198.729196974478;8752.69365572202;8972.37193396456;9048;9091.363604547618;8563.272121869712;9069.138431848964;9223.262139028995;9265.953670231605;9325.673601423918;5696.458836410535;6394;6624.768133115318;6806.8638627228165;7087.822804429724;7728.497647652885;7883.5664038478435;7893.644785984759;7901.68393264263;7909.090454597795;6888.173419189729;7003.789886303419;7029.5430796998035;7049.29507049291;7149;6844.041834143124;6858.691358888762;6887.070245516991;6899.413549848319;6936.098307220029;6291.716872740829;6323.936760632335;6383.425491705769;6487.253965793805;6534.346565343428;4262.531121576686;4322.113966636769;4700.059988002345;4734.739589322483;4907.803687852589;3862.5944275851757;4108.609682080198;4537.6596755244145;4776.188048031773;4784.425868895755 18:23:14 INFO - PROCESS | 1655 | |4;prototype.html;252.25584525649333;280.61275439892944;284.68542260801814;289.1974770013212;294.4038322397144;3603.9099022524383;3909.2963266612;4257.276263035284;4306.870793876181;4405.0969551242;3976.4631774710406;4200.915981680365;4242.6847677220085;4256.595623415778;4288.869125538359;2660.4546068056097;2759.903403380882;2763.664703325687;2767.5571908494644;3400.914977125567;2771.584262360652;3399.796012239261;3411.0619579615554;3414.7951122932704;3423.0928803866996;2784.053421836575;3019.3961207758425;3073.4314151881863;3304.487804390323;3365.209175843675;3338.3490219407227;3346.966530334714;3347.4811404232382;3358.7145092667274;3365.125067482448;2384.5707772600927;2392.605220138675;2453.9202750789595;2650.3506640872915;2765.4469106178644;3887.5334959804723;3992.281389349916;4117.785253350239;4135.276326642826;4193.559676233982;3401;3808.638179372955;3835.7725527831103;3842.9231415371814;3846.9807650961848;4039.515258169009;4567.995041090955;4613.584777370019;4666.500124906321;4672.719636821802;3226.483762597973;3249.9350012999607;3267.901962941092;3272.443684573628;3274.5251938469028;4534.569215924482;4686.789094490722;4717.811287548494;4719.946823131914;4825.324454576362;2572.1674704689876;3020.7734419918593;3166.398384306974;3252.837358132131;3258.8207648579555;3006.7972810875826;3101.3332133590934;3327.7670563060356;3333.5666363372607;3360.6135294440965;2999.860053179778;3381.323735252959;3652.940647212332;3803.9429408558894;3840.135969406917;3694.4273637586216;3877.8836634901;4005.35914253718;4161.188568229166;4542.704724192983;1106.2422240765102;1127.9210455268053;1133.7052366384798;1141.3095077478172;1145.959891403797;717.9641017949082;753.5177486408703;762.7177944160696;791.4380789639306;798.8522123407188;3776.6223377662013;4124.680102367273;4132.504099508078;4154.376843473443;4194.937075943863;3925.980370098131;4311.706488053572;4651.511591282934;4715.575598196178;4732.787024583902;3974.28462876685;4097.487814023247;4181.560936101666;4205.633169220016;4264.317709166518;2309.4919117794057;2363.905443782264;2364.53891491161;2387.8806059696944;2391.485830546441;4543.88778155763;4724.71651700899;4746.715197088186;4756.953470236543;4761.381020467317;4122.319817230124;4691.554302341272;4711.976440117777;4715.929261061086;4742.95257047432;4401.603855653007;4617.653675974382;4641.791119399635;4700.6474514410875;4710.62315014798;3807.5251086793805;3845.769353806867;4176.937345939753;4463.977680111579;4538.319252112077;3853.766794625693;3865.033741564609;3944.694386050757;4143.357779544175;4569.240842275682;3944.6785326915806;4171.290880550253;4449.665100469911;4709.882252943704;4727.220008698526;3224.7420206383904;3226.6612005739057;3237.805731656108;3238.724708399718;3260.1962312411783;4516.503184649751;4557.794899229609;4613.884652883705;4624.352590637246;4684.625229981525;3668.7531534591544;3722.664960153599;4514.119746649503;4526.932096018497;4580.564846339593;3729.5151630287887;4368.868933931987;4415.779211039499;4424.380586717798;4448.354988526581;3783.356829339074;4020.6582440492884;4121.702637889616;4150.841562387482;4376.693631445768;4123.36087906375;4192.350185721218;4204.2432362175105;4498.865034048853;4520.819167233274;3804.9726573825465;4272.188284225987;4362.541933096979;4401.911961760683;4473.606620458039 18:23:14 INFO - PROCESS | 1655 | |5;yui.html;553.4354957942899;687.9243283238847;713.4931810153091;747.4954405776101;761.1741260732106;14553.563393098211;15584.766228506582;15590.53228403147;15651.608709782262;15658.295376708047;13887;14101;14120.940929430308;14271.286435678227;14359.282035898215;846.0989046665281;861.2894362151219;864.2222000199807;865.6191275838643;866.5017614871474;679.2019377231758;699.6326928362599;827.1604321613567;829.9294559962381;838.4256784102879;908.0420156734642;910.8497097978826;921.1571412157867;923.3951761596165;925.7639301977986;911.289194428347;914.6478605736772;916.3539704508306;918.7335672654956;921.3688623293066;655.4134050025249;667.2493444874516;668.7057694614402;684.7055766020609;687.9002544631;12370.876291237153;15202.011869228465;15217.847821521755;15255.160966146857;15283.770743438858;10757.563757326134;11705.297682139098;13947.721045579134;13977.720445591132;14035.578932632037;9254.796876406024;9883.456409897453;10686.732831679192;11511.30932144074;11573.652790416301;3328.866845326184;3494;3524.524189234446;3528.7229714308537;3551.183227857581;106925.99999999923;107200;108293.91706082918;108752.91247087508;108769;8102.596909297991;10160.809297809414;10745.892541074489;10890.945545272223;10972.780544389068;16820.74768878468;20817.89591052035;21342.78657213408;21390.78609213919;21399.037043333086;7607.619619019138;7647.694092236359;7663.961680191564;7698.384129269757;7810.375169986388;4096.873359826012;4117.209757168467;4140.855070072533;4196.580341965779;4243.724157929724;3384.5769278840144;3460.463628137643;3514.7012503937453;3518.1028837646236;3528.188516641187;642.2229102785682;644.1529388853643;644.6518879804853;646.9838254043592;648.8345471904634;498.3098408703971;533.6157966264284;557.7629507459313;569.5585920911327;583.6877270660176;8798.956005219932;9229.261659067259;9342.57958391874;9437.292203084797;9453.905460945303;503.2857511564702;573.8723408502314;579.939106393831;607.4138456389605;612.2255346986066;4289.227938970953;4417.447819022622;4551.431071116111;4679.836205732783;4781.593564546983;83785;84180;84423;141522;144584;86884.56557717171;102921;108243.45878270558;116469;120832;101559;101996;107044;107112.99999999844;108385;9209.539523023956;9462.290328225274;9519.333646644669;9569.330146889652;9620.567074481525;58227.70886145542;58910.41089589049;58972;60138.09792853023;61594.69202653958;79573;84271.57864210886;86483;87112.1288787104;93979.53010234905;12586.142740195057;12758.553450629184;12901.354932253162;13032.739345213233;13169.407376667881;13428.261445620194;13903.235322057384;14001.649958751113;14058.648533786738;14362.497312594009;13200.537981170613;13234.735305294033;13310.068295219242;13388.93305533466;13752;10860.619878304222;13267;13273.469061237829;13423.798643020167;13636;12822.294773787531;13007.544735934196;13234.735305293647;13329.733405331644;13589.524366647516;10266.767987841507;10698.679039628823;11325.433728313386;11383.487743051748;11457.541698332307;8722.694705685271;9234.722958311262;9982.750431239278;11177.217594768614;11179.273347232729 18:23:14 INFO - PROCESS | 1655 | __end_tp_report 18:23:14 INFO - PROCESS | 1655 | __start_cc_report 18:23:14 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,387 18:23:14 INFO - PROCESS | 1655 | __end_cc_report 18:23:14 INFO - PROCESS | 1655 | __startTimestamp1476840194848__endTimestamp 18:23:14 INFO - PROCESS | 1655 | 18:23:14 INFO - PROCESS | 1655 | ------- Summary: start ------- 18:23:14 INFO - PROCESS | 1655 | Number of tests: 6 18:23:14 INFO - PROCESS | 1655 | 18:23:14 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:4855.53 Median:4999.40 stddev:639.18 (12.8%) stddev-sans-first:617.16 18:23:14 INFO - PROCESS | 1655 | Values: 2644.8 2710.7 2848.8 2894.6 2945.2 4757.1 4780.7 4793.1 4815.2 4950.1 4809.8 4817.5 4845.5 4847.0 4864.1 4435.8 4465.1 4607.2 4607.3 4645.0 3610.7 4603.0 4608.3 4681.8 4688.7 3340.0 3699.3 4535.4 4566.9 4571.1 3603.8 4352.8 4376.9 4428.7 4571.3 4416.1 4462.7 4478.3 4502.3 4521.6 3372.5 4712.9 4790.9 4825.3 4898.9 5181.2 5200.7 5234.1 5274.5 5406.4 5442.5 5451.2 5477.6 5484.4 5498.0 5066.5 5075.0 5077.3 5079.8 5126.4 4699.0 5107.3 5433.4 5452.2 5473.6 5225.2 5279.8 5281.8 5296.5 5308.8 5377.5 5402.7 5421.9 5423.5 5464.8 4232.9 5058.2 5105.9 5182.9 5267.9 4039.4 4065.9 5319.9 5341.1 5379.6 3637.9 4339.7 4389.5 4393.2 4399.9 3819.9 3836.6 3845.3 3850.2 3850.9 3829.3 3927.7 4854.9 4918.2 4948.7 5404.7 5451.3 5465.8 5469.3 5486.4 3823.8 4667.8 4764.5 4899.3 4927.3 4682.6 4800.0 4823.2 4838.2 4853.9 5649.1 5698.6 5709.9 5710.8 5779.6 5154.4 5238.8 5444.6 5468.9 5477.1 5518.2 5551.4 5557.0 5574.0 5642.6 5332.3 5341.8 5380.6 5389.0 5435.5 4297.4 4399.8 5221.0 5392.9 5460.5 4990.5 5057.3 5064.8 5289.9 5292.2 5101.4 5207.2 5222.7 5230.3 5301.6 4271.9 4803.5 4904.0 5011.3 5155.5 5126.5 5235.5 5265.1 5283.0 5283.7 4897.8 4968.0 4999.4 5020.4 5130.9 18:23:14 INFO - PROCESS | 1655 | 18:23:14 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:64105.78 Median:38561.02 stddev:139571.55 (361.9%) stddev-sans-first:139881.18 18:23:14 INFO - PROCESS | 1655 | Values: 419.2 428.8 452.3 474.8 476.4 36933.8 38551.0 38634.4 40839.6 40921.6 33904.5 38571.0 39914.4 39942.4 40065.8 7890.4 8983.1 9224.2 9235.5 9252.5 9007.3 9081.2 9085.9 9089.5 9106.4 9083.9 9084.0 9126.6 9149.3 9158.8 9057.2 9107.1 9111.1 9123.5 9130.1 8992.4 9075.0 9078.0 9086.2 9093.4 37507.1 37598.4 37807.8 37817.2 38007.4 3120.1 3233.0 3246.9 3275.3 3335.3 23466.8 29452.0 29840.5 30469.7 30724.0 2869.5 3056.2 3208.1 3247.2 3257.7 428573.0 430217.0 434276.0 434987.0 435152.0 56355.7 56933.0 56952.4 57339.7 57424.4 49501.5 60176.7 60326.7 60397.7 60497.7 15059.5 15292.4 15411.2 15411.6 15443.2 11726.0 11768.4 11825.8 11847.8 11896.1 16665.8 17544.0 17631.1 17646.2 17702.5 7354.5 7366.7 7381.0 7399.4 7437.7 701.3 749.0 759.3 804.4 839.7 24414.3 26150.3 26490.6 27681.4 28517.1 970.6 989.7 990.8 1018.9 1037.7 11742.7 13923.2 14056.4 14307.9 14731.0 600096.0 603106.0 610553.7 610876.0 612163.0 52267.7 52731.7 52759.2 52801.7 52897.5 1229.2 1279.9 1284.1 1300.3 1304.8 26522.6 27354.6 27702.3 28530.7 29136.7 44517.0 44909.1 45121.0 45217.1 45222.6 508326.0 512818.0 512940.0 513049.4 513611.0 24604.3 24724.4 25054.7 25110.5 25258.4 34642.1 41746.0 42352.8 42480.4 42557.6 28689.7 28773.7 29583.9 34388.8 36019.3 28997.6 35221.5 35893.0 35900.5 36240.3 34451.3 34522.3 35195.5 36527.0 36562.6 29874.0 32529.8 32565.7 32803.7 33115.8 23601.6 23792.6 24028.6 24103.3 24217.3 18:23:14 INFO - PROCESS | 1655 | 18:23:14 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:32755.18 Median:4325.46 stddev:94963.91 (2195.5%) stddev-sans-first:95199.04 18:23:14 INFO - PROCESS | 1655 | Values: 302.6 346.7 347.0 355.1 369.0 18688.6 19280.0 20503.0 20594.3 20640.6 19508.8 19582.5 19615.1 19732.6 20048.5 5482.4 5747.7 5767.2 5768.5 5783.7 5343.0 5674.0 5684.4 5788.0 5793.1 5522.1 5724.9 5775.5 5775.5 5776.8 5715.1 5754.7 5816.4 5816.8 5818.4 4545.9 5367.6 5589.9 5592.6 5598.9 12768.3 12801.6 12879.9 12963.9 12972.9 2096.3 2154.7 2218.6 2238.8 2416.6 7850.8 8618.3 9130.5 9173.6 10004.6 1564.5 1767.8 1805.2 1853.1 1857.7 113171.4 118756.0 119557.4 120742.0 121559.4 10846.5 10919.6 11049.9 11058.7 11212.9 13749.6 13771.4 13784.7 13799.4 13816.9 7859.5 8032.9 8046.4 8071.8 8148.6 7030.3 7940.3 7966.9 7997.8 8136.9 4157.3 4323.2 4327.7 4403.2 4411.2 1834.7 1892.4 1897.5 1905.4 1922.5 702.1 722.5 772.9 825.0 831.7 19334.5 20170.0 20262.8 20342.1 20416.9 757.3 788.7 823.8 866.6 912.2 8071.8 9467.1 10267.7 10770.9 10798.5 551163.2 568975.0 573040.0 575422.0 578171.0 92732.0 104834.4 111591.4 115538.4 117062.4 790.3 838.2 843.3 910.7 943.5 18598.2 18791.1 19011.3 19101.8 19324.4 33563.0 41691.0 49432.3 54891.5 54972.0 71619.6 76307.0 77559.0 77810.6 78319.0 7523.4 7598.3 7685.0 7926.0 8154.3 8949.6 10247.6 10381.3 10548.5 12144.4 9510.5 10752.1 10919.2 10993.3 11048.3 9317.6 10224.7 10404.6 10682.9 11150.4 5503.4 8776.6 10728.1 10831.8 11323.7 7394.8 7780.7 7884.6 7952.7 7985.2 9236.6 11005.0 11126.4 11142.6 11168.6 18:23:14 INFO - PROCESS | 1655 | 18:23:14 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5238.02 Median:5052.73 stddev:2922.15 (57.8%) stddev-sans-first:2916.26 18:23:14 INFO - PROCESS | 1655 | Values: 1419.5 1452.4 1597.5 1721.6 1780.4 7379.1 7628.7 7814.2 8359.1 8361.9 7795.1 8100.0 8120.6 8198.2 8199.3 2153.9 2199.4 2256.1 2258.0 2260.4 2006.8 2200.2 2253.8 2265.6 2275.1 1865.0 2243.7 2247.3 2248.7 2266.7 1988.3 2101.5 2228.7 2231.4 2235.9 2204.2 2224.4 2227.5 2230.8 2236.3 8077.7 8245.9 8246.8 8247.9 8250.0 4928.3 5043.0 5067.8 5068.1 5074.3 3262.7 3313.5 3392.0 3419.5 3432.8 889.2 1004.9 1005.9 1006.3 1026.6 10486.7 10523.2 10542.1 10565.9 10580.3 4325.3 4355.1 4485.1 4535.1 4543.4 9073.5 9150.7 9162.2 9186.6 9188.1 5037.6 5046.2 5052.0 5053.4 5058.4 3659.6 4093.4 4580.5 4595.2 4597.7 3680.2 3693.4 3764.2 3773.2 3804.7 1219.8 1231.8 1238.8 1245.8 1249.6 732.4 733.1 772.4 806.6 807.8 4374.3 4742.6 5301.2 5434.5 5602.5 346.6 355.9 409.5 416.4 423.5 3677.7 3706.0 3730.6 3775.9 3820.8 9439.5 9699.2 9826.4 9865.8 9883.3 9590.3 9682.3 9798.8 9807.0 9825.5 7281.2 7541.5 7541.8 7564.4 7584.1 5246.2 5314.9 5320.3 5379.9 5463.0 8198.7 8752.7 8972.4 9048.0 9091.4 8563.3 9069.1 9223.3 9266.0 9325.7 5696.5 6394.0 6624.8 6806.9 7087.8 7728.5 7883.6 7893.6 7901.7 7909.1 6888.2 7003.8 7029.5 7049.3 7149.0 6844.0 6858.7 6887.1 6899.4 6936.1 6291.7 6323.9 6383.4 6487.3 6534.3 4262.5 4322.1 4700.1 4734.7 4907.8 3862.6 4108.6 4537.7 4776.2 4784.4 18:23:14 INFO - PROCESS | 1655 | 18:23:14 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:3620.93 Median:3998.82 stddev:1092.78 (27.3%) stddev-sans-first:1066.19 18:23:14 INFO - PROCESS | 1655 | Values: 252.3 280.6 284.7 289.2 294.4 3603.9 3909.3 4257.3 4306.9 4405.1 3976.5 4200.9 4242.7 4256.6 4288.9 2660.5 2759.9 2763.7 2767.6 3400.9 2771.6 3399.8 3411.1 3414.8 3423.1 2784.1 3019.4 3073.4 3304.5 3365.2 3338.3 3347.0 3347.5 3358.7 3365.1 2384.6 2392.6 2453.9 2650.4 2765.4 3887.5 3992.3 4117.8 4135.3 4193.6 3401.0 3808.6 3835.8 3842.9 3847.0 4039.5 4568.0 4613.6 4666.5 4672.7 3226.5 3249.9 3267.9 3272.4 3274.5 4534.6 4686.8 4717.8 4719.9 4825.3 2572.2 3020.8 3166.4 3252.8 3258.8 3006.8 3101.3 3327.8 3333.6 3360.6 2999.9 3381.3 3652.9 3803.9 3840.1 3694.4 3877.9 4005.4 4161.2 4542.7 1106.2 1127.9 1133.7 1141.3 1146.0 718.0 753.5 762.7 791.4 798.9 3776.6 4124.7 4132.5 4154.4 4194.9 3926.0 4311.7 4651.5 4715.6 4732.8 3974.3 4097.5 4181.6 4205.6 4264.3 2309.5 2363.9 2364.5 2387.9 2391.5 4543.9 4724.7 4746.7 4757.0 4761.4 4122.3 4691.6 4712.0 4715.9 4743.0 4401.6 4617.7 4641.8 4700.6 4710.6 3807.5 3845.8 4176.9 4464.0 4538.3 3853.8 3865.0 3944.7 4143.4 4569.2 3944.7 4171.3 4449.7 4709.9 4727.2 3224.7 3226.7 3237.8 3238.7 3260.2 4516.5 4557.8 4613.9 4624.4 4684.6 3668.8 3722.7 4514.1 4526.9 4580.6 3729.5 4368.9 4415.8 4424.4 4448.4 3783.4 4020.7 4121.7 4150.8 4376.7 4123.4 4192.4 4204.2 4498.9 4520.8 3805.0 4272.2 4362.5 4401.9 4473.6 18:23:14 INFO - PROCESS | 1655 | 18:23:14 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:22447.98 Median:4107.04 stddev:34462.89 (839.1%) stddev-sans-first:34520.36 18:23:14 INFO - PROCESS | 1655 | Values: 553.4 687.9 713.5 747.5 761.2 14553.6 15584.8 15590.5 15651.6 15658.3 13887.0 14101.0 14120.9 14271.3 14359.3 846.1 861.3 864.2 865.6 866.5 679.2 699.6 827.2 829.9 838.4 908.0 910.8 921.2 923.4 925.8 911.3 914.6 916.4 918.7 921.4 655.4 667.2 668.7 684.7 687.9 12370.9 15202.0 15217.8 15255.2 15283.8 10757.6 11705.3 13947.7 13977.7 14035.6 9254.8 9883.5 10686.7 11511.3 11573.7 3328.9 3494.0 3524.5 3528.7 3551.2 106926.0 107200.0 108293.9 108752.9 108769.0 8102.6 10160.8 10745.9 10890.9 10972.8 16820.7 20817.9 21342.8 21390.8 21399.0 7607.6 7647.7 7664.0 7698.4 7810.4 4096.9 4117.2 4140.9 4196.6 4243.7 3384.6 3460.5 3514.7 3518.1 3528.2 642.2 644.2 644.7 647.0 648.8 498.3 533.6 557.8 569.6 583.7 8799.0 9229.3 9342.6 9437.3 9453.9 503.3 573.9 579.9 607.4 612.2 4289.2 4417.4 4551.4 4679.8 4781.6 83785.0 84180.0 84423.0 141522.0 144584.0 86884.6 102921.0 108243.5 116469.0 120832.0 101559.0 101996.0 107044.0 107113.0 108385.0 9209.5 9462.3 9519.3 9569.3 9620.6 58227.7 58910.4 58972.0 60138.1 61594.7 79573.0 84271.6 86483.0 87112.1 93979.5 12586.1 12758.6 12901.4 13032.7 13169.4 13428.3 13903.2 14001.6 14058.6 14362.5 13200.5 13234.7 13310.1 13388.9 13752.0 10860.6 13267.0 13273.5 13423.8 13636.0 12822.3 13007.5 13234.7 13329.7 13589.5 10266.8 10698.7 11325.4 11383.5 11457.5 8722.7 9234.7 9982.8 11177.2 11179.3 18:23:14 INFO - PROCESS | 1655 | -------- Summary: end -------- 18:23:14 INFO - PROCESS | 1655 | 18:23:15 INFO - TEST-INFO | 1655: exit 0 18:23:15 INFO - TEST-OK | dromaeo_css | took 1114515ms 18:23:15 INFO - TEST-START | kraken 18:23:15 INFO - operating with platform_type : mac_ 18:23:15 INFO - Initialising browser for kraken test... 18:23:15 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmphmTxps/profile http://localhost:49275/getInfo.html) 18:23:18 INFO - PROCESS | 1663 | 2016-10-18 18:23:18.448 plugin-container[1664:10045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 18:23:18 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:23:18 INFO - PROCESS | 1663 | 2016-10-18 18:23:18.452 plugin-container[1664:10045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 18:23:18 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:23:18 INFO - PROCESS | 1663 | 2016-10-18 18:23:18.452 plugin-container[1664:10045] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:23:18 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 18:23:18 INFO - PROCESS | 1663 | colorDepth:24 18:23:18 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 18:23:18 INFO - PROCESS | 1663 | __metrics 18:23:18 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 18:23:19 INFO - TEST-INFO | 1663: exit 0 18:23:19 INFO - Browser initialized. 18:23:19 INFO - Running cycle 1/1 for kraken test... 18:23:19 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmphmTxps/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:22 INFO - PROCESS | 1665 | 2016-10-18 18:23:22.097 plugin-container[1666:10161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 18:23:22 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:23:22 INFO - PROCESS | 1665 | 2016-10-18 18:23:22.098 plugin-container[1666:10161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4523, name = 'com.apple.CFPasteboardClient' 18:23:22 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:23:22 INFO - PROCESS | 1665 | 2016-10-18 18:23:22.098 plugin-container[1666:10161] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:23:31 INFO - PROCESS | 1665 | RSS: Main: 237191168 18:23:31 INFO - PROCESS | 1665 | 18:23:34 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 18:23:35 INFO - PROCESS | 1665 | RSS: Main: 249528320 18:23:35 INFO - PROCESS | 1665 | 18:23:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 18:23:39 INFO - PROCESS | 1665 | RSS: Main: 257097728 18:23:39 INFO - PROCESS | 1665 | 18:23:42 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 18:23:43 INFO - PROCESS | 1665 | RSS: Main: 260198400 18:23:43 INFO - PROCESS | 1665 | 18:23:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 18:23:46 INFO - PROCESS | 1665 | RSS: Main: 261218304 18:23:46 INFO - PROCESS | 1665 | 18:23:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:23:49 INFO - PROCESS | 1665 | RSS: Main: 262864896 18:23:49 INFO - PROCESS | 1665 | 18:23:52 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 18:23:52 INFO - PROCESS | 1665 | RSS: Main: 263798784 18:23:52 INFO - PROCESS | 1665 | 18:23:55 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 18:23:56 INFO - PROCESS | 1665 | RSS: Main: 263942144 18:23:56 INFO - PROCESS | 1665 | 18:23:59 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 18:24:00 INFO - PROCESS | 1665 | RSS: Main: 264159232 18:24:00 INFO - PROCESS | 1665 | 18:24:02 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:24:02 INFO - PROCESS | 1665 | RSS: Main: 263426048 18:24:02 INFO - PROCESS | 1665 | 18:24:04 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:24:05 INFO - PROCESS | 1665 | RSS: Main: 263475200 18:24:05 INFO - PROCESS | 1665 | 18:24:08 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:24:08 INFO - PROCESS | 1665 | RSS: Main: 263557120 18:24:08 INFO - PROCESS | 1665 | 18:24:11 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:24:12 INFO - PROCESS | 1665 | RSS: Main: 263942144 18:24:12 INFO - PROCESS | 1665 | 18:24:16 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:24:16 INFO - PROCESS | 1665 | RSS: Main: 264187904 18:24:16 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 18:24:18 INFO - PROCESS | 1665 | RSS: Main: 266858496 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | __start_tp_report 18:24:18 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 18:24:18 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 18:24:18 INFO - PROCESS | 1665 | |i|pagename|runs| 18:24:18 INFO - PROCESS | 1665 | |0;ai-astar;129;181;123;126;123;122;124;123;120;102 18:24:18 INFO - PROCESS | 1665 | |1;audio-beat-detection;189;153;154;163;115;155;156;123;161;157 18:24:18 INFO - PROCESS | 1665 | |2;audio-dft;252;233;232;228;227;228;262;223;224;233 18:24:18 INFO - PROCESS | 1665 | |3;audio-fft;86;62;80;87;88;67;63;87;113;87 18:24:18 INFO - PROCESS | 1665 | |4;audio-oscillator;96;86;92;75;73;78;119;108;107;93 18:24:18 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;113;90;93;92;93;90;95;92;99;92 18:24:18 INFO - PROCESS | 1665 | |6;imaging-darkroom;103;149;105;107;105;105;143;124;180;106 18:24:18 INFO - PROCESS | 1665 | |7;imaging-desaturate;111;114;102;177;109;160;120;164;159;104 18:24:18 INFO - PROCESS | 1665 | |8;json-parse-financial;57;61;66;95;93;85;59;61;61;59 18:24:18 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;73;81;82;82;84;74;75;77;76;101 18:24:18 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;119;96;85;117;122;110;91;92;93;97 18:24:18 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;154;156;154;175;147;205;172;164;204;308 18:24:18 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;203;251;204;185;179;184;181;179;203;266 18:24:18 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;71;73;74;75;74;76;88;94;94;96 18:24:18 INFO - PROCESS | 1665 | __end_tp_report 18:24:18 INFO - PROCESS | 1665 | __start_cc_report 18:24:18 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,991 18:24:18 INFO - PROCESS | 1665 | __end_cc_report 18:24:18 INFO - PROCESS | 1665 | __startTimestamp1476840258775__endTimestamp 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | ------- Summary: start ------- 18:24:18 INFO - PROCESS | 1665 | Number of tests: 14 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:127.30 Median:123.50 stddev:20.21 (16.4%) stddev-sans-first:21.43 18:24:18 INFO - PROCESS | 1665 | Values: 129.0 181.0 123.0 126.0 123.0 122.0 124.0 123.0 120.0 102.0 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:152.60 Median:156.50 stddev:20.64 (13.2%) stddev-sans-first:17.18 18:24:18 INFO - PROCESS | 1665 | Values: 189.0 153.0 154.0 163.0 115.0 155.0 156.0 123.0 161.0 157.0 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:234.20 Median:232.50 stddev:12.72 (5.5%) stddev-sans-first:11.74 18:24:18 INFO - PROCESS | 1665 | Values: 252.0 233.0 232.0 228.0 227.0 228.0 262.0 223.0 224.0 233.0 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:82.00 Median:86.50 stddev:15.20 (17.6%) stddev-sans-first:16.05 18:24:18 INFO - PROCESS | 1665 | Values: 86.0 62.0 80.0 87.0 88.0 67.0 63.0 87.0 113.0 87.0 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:92.70 Median:82.00 stddev:15.29 (18.6%) stddev-sans-first:16.17 18:24:18 INFO - PROCESS | 1665 | Values: 96.0 86.0 92.0 75.0 73.0 78.0 119.0 108.0 107.0 93.0 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:94.90 Median:92.50 stddev:6.87 (7.4%) stddev-sans-first:2.76 18:24:18 INFO - PROCESS | 1665 | Values: 113.0 90.0 93.0 92.0 93.0 90.0 95.0 92.0 99.0 92.0 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:122.70 Median:115.50 stddev:26.34 (22.8%) stddev-sans-first:26.95 18:24:18 INFO - PROCESS | 1665 | Values: 103.0 149.0 105.0 107.0 105.0 105.0 143.0 124.0 180.0 106.0 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:132.00 Median:139.50 stddev:29.22 (20.9%) stddev-sans-first:29.99 18:24:18 INFO - PROCESS | 1665 | Values: 111.0 114.0 102.0 177.0 109.0 160.0 120.0 164.0 159.0 104.0 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:69.70 Median:63.50 stddev:15.09 (23.8%) stddev-sans-first:15.28 18:24:18 INFO - PROCESS | 1665 | Values: 57.0 61.0 66.0 95.0 93.0 85.0 59.0 61.0 61.0 59.0 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:80.50 Median:79.00 stddev:8.15 (10.3%) stddev-sans-first:8.19 18:24:18 INFO - PROCESS | 1665 | Values: 73.0 81.0 82.0 82.0 84.0 74.0 75.0 77.0 76.0 101.0 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:102.20 Median:91.50 stddev:13.46 (14.7%) stddev-sans-first:12.83 18:24:18 INFO - PROCESS | 1665 | Values: 119.0 96.0 85.0 117.0 122.0 110.0 91.0 92.0 93.0 97.0 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:183.90 Median:173.50 stddev:48.07 (27.7%) stddev-sans-first:49.75 18:24:18 INFO - PROCESS | 1665 | Values: 154.0 156.0 154.0 175.0 147.0 205.0 172.0 164.0 204.0 308.0 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:203.50 Median:203.00 stddev:30.90 (15.2%) stddev-sans-first:32.77 18:24:18 INFO - PROCESS | 1665 | Values: 203.0 251.0 204.0 185.0 179.0 184.0 181.0 179.0 203.0 266.0 18:24:18 INFO - PROCESS | 1665 | 18:24:18 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.50 Median:82.00 stddev:10.18 (12.4%) stddev-sans-first:10.06 18:24:18 INFO - PROCESS | 1665 | Values: 71.0 73.0 74.0 75.0 74.0 76.0 88.0 94.0 94.0 96.0 18:24:18 INFO - PROCESS | 1665 | -------- Summary: end -------- 18:24:18 INFO - PROCESS | 1665 | 18:24:19 INFO - TEST-INFO | 1665: exit 0 18:24:19 INFO - TEST-OK | kraken | took 64167ms 18:24:19 INFO - SUITE-END | took 1178s 18:24:19 INFO - Completed test suite (00:19:38) 18:24:19 INFO - javascript benchmark 18:24:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6553.536393618568, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2644.7569642268127, 2710.728927107288, 2848.8290702557847, 2894.551344541597, 2945.1606292206725, 4757.0723708876785, 4780.737059461737, 4793.1132740443045, 4815.157347464193, 4950.13372659784, 4809.807607695688, 4817.494163112867, 4845.466998630148, 4847.0, 4864.0515099555605, 4435.756033418161, 4465.129299786543, 4607.239805432099, 4607.331936869152, 4645.0, 3610.729195310349, 4602.953970460303, 4608.28571571407, 4681.83613573525, 4688.671792974493, 3339.983300083484, 3699.278640665071, 4535.433070866142, 4566.862994110182, 4571.1086338163905, 3603.8198090095652, 4352.7823608819745, 4376.89057773555, 4428.689991700582, 4571.337156112379, 4416.05054913192, 4462.687611867171, 4478.261086920655, 4502.2796352583755, 4521.593056624886, 3372.5447064646205, 4712.858614241544, 4790.856274311776, 4825.252085926687, 4898.877528061791, 5181.171012637998, 5200.739963001834, 5234.110201265794, 5274.472552744734, 5406.405295417501, 5442.510174084351, 5451.155070964007, 5477.643953143072, 5484.369297530795, 5497.955388476177, 5066.493350664941, 5075.0, 5077.289179514871, 5079.822206222764, 5126.359205099363, 4698.979438841331, 5107.259447380184, 5433.429489903582, 5452.236686863842, 5473.644213126134, 5225.216217567319, 5279.762410691528, 5281.815136470282, 5296.4703529647495, 5308.787648494017, 5377.542908852792, 5402.702851343135, 5421.864453388697, 5423.511883930465, 5464.836054918359, 4232.91534169321, 5058.190689489664, 5105.8723531912, 5182.896342073137, 5267.920981185285, 4039.414284928669, 4065.9390109148385, 5319.86700332487, 5341.118715411913, 5379.569634429236, 3637.927241455156, 4339.674524410619, 4389.539098394686, 4393.18726035678, 4399.868003959887, 3819.9236015280094, 3836.5918176574646, 3845.288621605012, 3850.210706805098, 3850.8844734658005, 3829.2532956073296, 3927.6857851371824, 4854.854354369375, 4918.163912134874, 4948.65359424844, 5404.675719456845, 5451.345838499326, 5465.781368745205, 5469.343678758495, 5486.423925487846, 3823.808809559511, 4667.813287468532, 4764.499727528629, 4899.314096026559, 4927.334809800703, 4682.555157260055, 4800.039992001613, 4823.155947709137, 4838.225883858636, 4853.9029219416125, 5649.067903795828, 5698.601097923106, 5709.914351284734, 5710.828675139753, 5779.595428320145, 5154.407243166934, 5238.816641417532, 5444.591655625764, 5468.9179662304305, 5477.096279113902, 5518.19986102021, 5551.444855514578, 5557.0, 5574.024545704405, 5642.605017648725, 5332.280142180756, 5341.813036543703, 5380.569554435714, 5388.973055134699, 5435.510804027656, 4297.419848320436, 4399.780010999501, 5220.9738951305, 5392.865178370572, 5460.453954604508, 4990.50094990498, 5057.342545469065, 5064.772085256099, 5289.920651190158, 5292.232626269092, 5101.413337466239, 5207.1668533034535, 5222.7127507987425, 5230.267762513327, 5301.575873930154, 4271.914561708686, 4803.519648035169, 4903.95096049035, 5011.273365362075, 5155.484451554815, 5126.487351264844, 5235.4502777207845, 5265.131253343156, 5282.96982088489, 5283.735813209401, 4897.804087836446, 4968.0, 4999.400071991385, 5020.447750747342, 5130.923036154535], "value": 4815.981043319071, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.19514532098367, 428.81775245520646, 452.30119465425895, 474.80295677293924, 476.36881132499434, 36933.81533092334, 38551.0, 38634.42048369277, 40839.59160408395, 40921.59078409215, 33904.49143262847, 38571.035724106914, 39914.40128398069, 39942.40086398699, 40065.799671001674, 7890.447668663196, 8983.146601072904, 9224.215941644969, 9235.538223088852, 9252.53737313135, 9007.279417646572, 9081.228095611881, 9085.863712044325, 9089.454632722058, 9106.362554621179, 9083.863742043875, 9084.046175151612, 9126.634934602642, 9149.313801464883, 9158.816823663523, 9057.230135438496, 9107.089291070906, 9111.088891110901, 9123.452592844451, 9130.086991300817, 8992.370534062618, 9074.954625226897, 9078.0, 9086.227670648002, 9093.363464557484, 37507.06232344213, 37598.43602345967, 37807.81096094502, 37817.24365512702, 38007.42988855169, 3120.0795765249304, 3232.951505727416, 3246.9350612987846, 3275.3449310138067, 3335.34960682667, 23466.7653323468, 29451.969181078774, 29840.5079746014, 30469.69530304691, 30724.0, 2869.5121829288864, 3056.2359410147465, 3208.101731515156, 3247.2044349134376, 3257.6742325767473, 428573.0, 430217.0, 434275.99999999686, 434987.0, 435152.0, 56355.71822140863, 56933.0, 56952.43047569554, 57339.71330143323, 57424.42575574274, 49501.504984949686, 60176.69911650414, 60326.69836650789, 60397.69801151054, 60497.69751151304, 15059.47291844802, 15292.388304467919, 15411.229438528253, 15411.61470963235, 15443.150626715638, 11726.0, 11768.411579421167, 11825.822612660817, 11847.822282665767, 11896.107791915641, 16665.833341666672, 17544.035078070574, 17631.118444078, 17646.2059207336, 17702.468925932244, 7354.455564331551, 7366.705331786776, 7380.950005487234, 7399.371053460516, 7437.665305061285, 701.2888551933266, 748.988765168523, 759.3393747439652, 804.3605333759675, 839.7034111704484, 24414.26757197287, 26150.346241343737, 26490.602640960402, 27681.446371072867, 28517.144485665463, 970.6020531582018, 989.7426669065951, 990.7770751580981, 1018.9184865210765, 1037.7042542875245, 11742.70643233926, 13923.23422211788, 14056.367463464168, 14307.85692143086, 14731.0, 600096.0, 603106.0, 610553.7335519766, 610876.0, 612163.0, 52267.73866130645, 52731.73634131805, 52759.208611870854, 52801.735991319794, 52897.471025290026, 1229.1887354346268, 1279.8848103670714, 1284.0882973089024, 1300.3031044487036, 1304.778187708073, 26522.60216096799, 27354.5896811544, 27702.307442314104, 28530.714692853635, 29136.7086329134, 44517.0, 44909.10181796411, 45121.0, 45217.09565808599, 45222.64332070043, 508326.0, 512818.0, 512940.0, 513049.43475282384, 513611.0, 24604.261872143863, 24724.38189045288, 25054.749452505243, 25110.49779004446, 25258.368540785894, 34642.146870800636, 41746.0, 42352.78823605862, 42480.36279455749, 42557.5744242566, 28689.71310286954, 28773.705183267222, 29583.85208074032, 34388.796392126154, 36019.279614408086, 28997.565036524047, 35221.47167792434, 35893.0, 35900.461493077106, 36240.27519449649, 34451.31097377988, 34522.30955380928, 35195.472067919516, 36527.0, 36562.63437365699, 29874.0, 32529.83735081404, 32565.67434325721, 32803.67196328102, 33115.83442082871, 23601.64597531004, 23792.643110353703, 24028.639570406107, 24103.276901692978, 24217.273481795575], "value": 18403.032359996534, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.5915014730114, 346.6931765387631, 346.9537461091615, 355.07502954802754, 369.0018499957615, 18688.62622747546, 19280.035998200103, 20503.0, 20594.279200227997, 20640.587188256224, 19508.804911950876, 19582.510437239078, 19615.11731972061, 19732.60534789307, 20048.498787530283, 5482.369527504338, 5747.741351639186, 5767.163761254616, 5768.5385169186575, 5783.6529808211435, 5342.973285133569, 5673.971630141844, 5684.403137670547, 5787.999999999979, 5793.13103034544, 5522.088855338864, 5724.885502289952, 5775.451332123463, 5775.537956963432, 5776.826695199151, 5715.114157305624, 5754.6547207167705, 5816.447437493432, 5816.82549523515, 5818.41815818419, 4545.886352841172, 5367.570594352482, 5589.944100558983, 5592.6085174037835, 5598.944010559923, 12768.297743624096, 12801.55194568195, 12879.935600322033, 12963.87036129636, 12972.87027129726, 2096.3396530092973, 2154.687570302313, 2218.589560931233, 2238.82089432845, 2416.565018296706, 7850.842983140363, 8618.267447266928, 9130.543472826399, 9173.58718857653, 10004.59981600735, 1564.4524416454265, 1767.8232176782346, 1805.1786437171056, 1853.0827240515896, 1857.7306290587924, 113171.43414282959, 118756.0, 119557.40221298925, 120742.0, 121559.3922030393, 10846.457677116112, 10919.563217471212, 11049.889501104886, 11058.668239952814, 11212.887871121184, 13749.587512374645, 13771.449142034407, 13784.724305514033, 13799.448022079205, 13816.93091534556, 7859.52842829432, 8032.919670803217, 8046.436749427484, 8071.838563228703, 8148.5518296494265, 7030.261822508665, 7940.325072368798, 7966.920330796618, 7997.840043199103, 8136.877946830802, 4157.3140431829015, 4323.200207961537, 4327.7186982846015, 4403.185410698967, 4411.18393097278, 1834.7247912813184, 1892.3820133785691, 1897.459224121119, 1905.3712274949235, 1922.4521011511652, 702.1433850702135, 722.4762047515615, 772.9140557516698, 825.0182283083112, 831.6590198018783, 19334.516637083903, 20169.99150042492, 20262.784232945774, 20342.08460619276, 20416.8770717609, 757.2503221810368, 788.6884680551265, 823.8393513264975, 866.6143566113145, 912.1562554636935, 8071.757847264592, 9467.100625440573, 10267.691969240936, 10770.946145269223, 10798.460076996118, 551163.2441837766, 568975.0, 573040.0, 575422.0, 578171.0, 92732.0, 104834.3791405221, 111591.44204279089, 115538.42230788793, 117062.41468792773, 790.2926880441972, 838.2330167896429, 843.2579330189394, 910.7495438754263, 943.4858002388578, 18598.163082661584, 18791.087381455895, 19011.292797904276, 19101.80898191056, 19324.420267391437, 33563.0, 41691.0, 49432.25851612301, 54891.45108549023, 54972.0, 71619.64190179016, 76307.0, 77559.0, 77810.61094694717, 78319.0, 7523.435742319239, 7598.278163574451, 7684.961575192088, 7925.960370198112, 8154.266116049584, 8949.642014319354, 10247.641332553325, 10381.325213861377, 10548.472576371305, 12144.39278036077, 9510.47692376926, 10752.13982881352, 10919.235653504496, 10993.251687078231, 11048.281861678965, 9317.627294908129, 10224.744381390226, 10404.583816647551, 10682.893171068501, 11150.386728729998, 5503.367112782082, 8776.551868941553, 10728.141748660244, 10831.783364332512, 11323.71690707706, 7394.815129621803, 7780.688772449039, 7884.645190966535, 7952.681892724227, 7985.161558036322, 9236.584353703965, 11005.038846278083, 11126.443677815914, 11142.610008649659, 11168.553257869595], "value": 8813.942869857821, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.481889110475, 1452.3682198243764, 1597.5127586086246, 1721.586819163401, 1780.3768680961657, 7379.114506259257, 7628.732994345199, 7814.179511151326, 8359.080501144877, 8361.874571881419, 7795.064592248924, 8100.027996640397, 8120.593970301491, 8198.1801819818, 8199.344052475803, 2153.8923053847325, 2199.4281486813384, 2256.131389415079, 2257.954840903181, 2260.4235919840416, 2006.799320067989, 2200.19692812124, 2253.808426283768, 2265.6035193841094, 2275.12407723027, 1864.9813501865012, 2243.674667173259, 2247.325802259324, 2248.685184074231, 2266.660000999847, 1988.274279887845, 2101.495641046153, 2228.699125618045, 2231.4086767006675, 2235.910563577455, 2204.2285200179967, 2224.410531209231, 2227.52108296717, 2230.7546169921297, 2236.284388995522, 8077.717279895175, 8245.876311855327, 8246.752597422086, 8247.917520824774, 8250.0, 4928.334674818925, 5043.0, 5067.797288108472, 5068.062408454448, 5074.310615373858, 3262.6737326267184, 3313.453280208763, 3391.9830400847836, 3419.5383623210796, 3432.7597068205237, 889.2130464538865, 1004.8693669822949, 1005.9245556583212, 1006.2855372685402, 1026.625281772148, 10486.737831554196, 10523.210759193014, 10542.051215390575, 10565.9471702641, 10580.312279701871, 4325.286327755885, 4355.128974205171, 4485.102979404132, 4535.138323718483, 4543.363929049936, 9073.45559266446, 9150.679726209552, 9162.175404213653, 9186.586603602855, 9188.081191880758, 5037.571806396498, 5046.21783623539, 5052.019308607939, 5053.444121146671, 5058.418281897555, 3659.579148397915, 4093.3859921012086, 4580.519045500175, 4595.2188128018315, 4597.655175861823, 3680.1903581212628, 3693.4275187346007, 3764.171882185915, 3773.2264885698364, 3804.714646401531, 1219.7865373559591, 1231.7659644667663, 1238.8203710462112, 1245.7508498300376, 1249.5564074753513, 732.3774791427244, 733.1349008170304, 772.447699894578, 806.641044735099, 807.801107613844, 4374.343848422698, 4742.648345221595, 5301.151815709545, 5434.483724046209, 5602.481127277419, 346.63256947635585, 355.91623506423, 409.5310869054915, 416.40869964650267, 423.46431763818794, 3677.724170687209, 3705.981470092632, 3730.5896351401325, 3775.8867233983024, 3820.751651142667, 9439.528023598792, 9699.224062075016, 9826.4104153751, 9865.753356166153, 9883.258755593215, 9590.328676992543, 9682.322237443312, 9798.804023919482, 9806.95096524527, 9825.508724563742, 7281.235470275651, 7541.509801862751, 7541.8114547135665, 7564.432667549956, 7584.08990921093, 5246.186841039645, 5314.946850531445, 5320.255164277081, 5379.870227252321, 5462.9726851365485, 8198.729196974478, 8752.69365572202, 8972.37193396456, 9048.0, 9091.363604547618, 8563.272121869712, 9069.138431848964, 9223.262139028995, 9265.953670231605, 9325.673601423918, 5696.458836410535, 6394.0, 6624.768133115318, 6806.8638627228165, 7087.822804429724, 7728.497647652885, 7883.5664038478435, 7893.644785984759, 7901.68393264263, 7909.090454597795, 6888.173419189729, 7003.789886303419, 7029.5430796998035, 7049.29507049291, 7149.0, 6844.041834143124, 6858.691358888762, 6887.070245516991, 6899.413549848319, 6936.098307220029, 6291.716872740829, 6323.936760632335, 6383.425491705769, 6487.253965793805, 6534.346565343428, 4262.531121576686, 4322.113966636769, 4700.059988002345, 4734.739589322483, 4907.803687852589, 3862.5944275851757, 4108.609682080198, 4537.6596755244145, 4776.188048031773, 4784.425868895755], "value": 4147.682697450983, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [252.25584525649333, 280.61275439892944, 284.68542260801814, 289.1974770013212, 294.4038322397144, 3603.9099022524383, 3909.2963266612, 4257.276263035284, 4306.870793876181, 4405.0969551242, 3976.4631774710406, 4200.915981680365, 4242.6847677220085, 4256.595623415778, 4288.869125538359, 2660.4546068056097, 2759.903403380882, 2763.664703325687, 2767.5571908494644, 3400.914977125567, 2771.584262360652, 3399.796012239261, 3411.0619579615554, 3414.7951122932704, 3423.0928803866996, 2784.053421836575, 3019.3961207758425, 3073.4314151881863, 3304.487804390323, 3365.209175843675, 3338.3490219407227, 3346.966530334714, 3347.4811404232382, 3358.7145092667274, 3365.125067482448, 2384.5707772600927, 2392.605220138675, 2453.9202750789595, 2650.3506640872915, 2765.4469106178644, 3887.5334959804723, 3992.281389349916, 4117.785253350239, 4135.276326642826, 4193.559676233982, 3401.0, 3808.638179372955, 3835.7725527831103, 3842.9231415371814, 3846.9807650961848, 4039.515258169009, 4567.995041090955, 4613.584777370019, 4666.500124906321, 4672.719636821802, 3226.483762597973, 3249.9350012999607, 3267.901962941092, 3272.443684573628, 3274.5251938469028, 4534.569215924482, 4686.789094490722, 4717.811287548494, 4719.946823131914, 4825.324454576362, 2572.1674704689876, 3020.7734419918593, 3166.398384306974, 3252.837358132131, 3258.8207648579555, 3006.7972810875826, 3101.3332133590934, 3327.7670563060356, 3333.5666363372607, 3360.6135294440965, 2999.860053179778, 3381.323735252959, 3652.940647212332, 3803.9429408558894, 3840.135969406917, 3694.4273637586216, 3877.8836634901, 4005.35914253718, 4161.188568229166, 4542.704724192983, 1106.2422240765102, 1127.9210455268053, 1133.7052366384798, 1141.3095077478172, 1145.959891403797, 717.9641017949082, 753.5177486408703, 762.7177944160696, 791.4380789639306, 798.8522123407188, 3776.6223377662013, 4124.680102367273, 4132.504099508078, 4154.376843473443, 4194.937075943863, 3925.980370098131, 4311.706488053572, 4651.511591282934, 4715.575598196178, 4732.787024583902, 3974.28462876685, 4097.487814023247, 4181.560936101666, 4205.633169220016, 4264.317709166518, 2309.4919117794057, 2363.905443782264, 2364.53891491161, 2387.8806059696944, 2391.485830546441, 4543.88778155763, 4724.71651700899, 4746.715197088186, 4756.953470236543, 4761.381020467317, 4122.319817230124, 4691.554302341272, 4711.976440117777, 4715.929261061086, 4742.95257047432, 4401.603855653007, 4617.653675974382, 4641.791119399635, 4700.6474514410875, 4710.62315014798, 3807.5251086793805, 3845.769353806867, 4176.937345939753, 4463.977680111579, 4538.319252112077, 3853.766794625693, 3865.033741564609, 3944.694386050757, 4143.357779544175, 4569.240842275682, 3944.6785326915806, 4171.290880550253, 4449.665100469911, 4709.882252943704, 4727.220008698526, 3224.7420206383904, 3226.6612005739057, 3237.805731656108, 3238.724708399718, 3260.1962312411783, 4516.503184649751, 4557.794899229609, 4613.884652883705, 4624.352590637246, 4684.625229981525, 3668.7531534591544, 3722.664960153599, 4514.119746649503, 4526.932096018497, 4580.564846339593, 3729.5151630287887, 4368.868933931987, 4415.779211039499, 4424.380586717798, 4448.354988526581, 3783.356829339074, 4020.6582440492884, 4121.702637889616, 4150.841562387482, 4376.693631445768, 4123.36087906375, 4192.350185721218, 4204.2432362175105, 4498.865034048853, 4520.819167233274, 3804.9726573825465, 4272.188284225987, 4362.541933096979, 4401.911961760683, 4473.606620458039], "value": 3289.3638430932033, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.4354957942899, 687.9243283238847, 713.4931810153091, 747.4954405776101, 761.1741260732106, 14553.563393098211, 15584.766228506582, 15590.53228403147, 15651.608709782262, 15658.295376708047, 13887.0, 14101.0, 14120.940929430308, 14271.286435678227, 14359.282035898215, 846.0989046665281, 861.2894362151219, 864.2222000199807, 865.6191275838643, 866.5017614871474, 679.2019377231758, 699.6326928362599, 827.1604321613567, 829.9294559962381, 838.4256784102879, 908.0420156734642, 910.8497097978826, 921.1571412157867, 923.3951761596165, 925.7639301977986, 911.289194428347, 914.6478605736772, 916.3539704508306, 918.7335672654956, 921.3688623293066, 655.4134050025249, 667.2493444874516, 668.7057694614402, 684.7055766020609, 687.9002544631, 12370.876291237153, 15202.011869228465, 15217.847821521755, 15255.160966146857, 15283.770743438858, 10757.563757326134, 11705.297682139098, 13947.721045579134, 13977.720445591132, 14035.578932632037, 9254.796876406024, 9883.456409897453, 10686.732831679192, 11511.30932144074, 11573.652790416301, 3328.866845326184, 3494.0, 3524.524189234446, 3528.7229714308537, 3551.183227857581, 106925.99999999923, 107200.0, 108293.91706082918, 108752.91247087508, 108769.0, 8102.596909297991, 10160.809297809414, 10745.892541074489, 10890.945545272223, 10972.780544389068, 16820.74768878468, 20817.89591052035, 21342.78657213408, 21390.78609213919, 21399.037043333086, 7607.619619019138, 7647.694092236359, 7663.961680191564, 7698.384129269757, 7810.375169986388, 4096.873359826012, 4117.209757168467, 4140.855070072533, 4196.580341965779, 4243.724157929724, 3384.5769278840144, 3460.463628137643, 3514.7012503937453, 3518.1028837646236, 3528.188516641187, 642.2229102785682, 644.1529388853643, 644.6518879804853, 646.9838254043592, 648.8345471904634, 498.3098408703971, 533.6157966264284, 557.7629507459313, 569.5585920911327, 583.6877270660176, 8798.956005219932, 9229.261659067259, 9342.57958391874, 9437.292203084797, 9453.905460945303, 503.2857511564702, 573.8723408502314, 579.939106393831, 607.4138456389605, 612.2255346986066, 4289.227938970953, 4417.447819022622, 4551.431071116111, 4679.836205732783, 4781.593564546983, 83785.0, 84180.0, 84423.0, 141522.0, 144584.0, 86884.56557717171, 102921.0, 108243.45878270558, 116469.0, 120832.0, 101559.0, 101996.0, 107044.0, 107112.99999999844, 108385.0, 9209.539523023956, 9462.290328225274, 9519.333646644669, 9569.330146889652, 9620.567074481525, 58227.70886145542, 58910.41089589049, 58972.0, 60138.09792853023, 61594.69202653958, 79573.0, 84271.57864210886, 86483.0, 87112.1288787104, 93979.53010234905, 12586.142740195057, 12758.553450629184, 12901.354932253162, 13032.739345213233, 13169.407376667881, 13428.261445620194, 13903.235322057384, 14001.649958751113, 14058.648533786738, 14362.497312594009, 13200.537981170613, 13234.735305294033, 13310.068295219242, 13388.93305533466, 13752.0, 10860.619878304222, 13267.0, 13273.469061237829, 13423.798643020167, 13636.0, 12822.294773787531, 13007.544735934196, 13234.735305293647, 13329.733405331644, 13589.524366647516, 10266.767987841507, 10698.679039628823, 11325.433728313386, 11383.487743051748, 11457.541698332307, 8722.694705685271, 9234.722958311262, 9982.750431239278, 11177.217594768614, 11179.273347232729], "value": 7432.447928802059, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1759.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 181.0, 123.0, 126.0, 123.0, 122.0, 124.0, 123.0, 120.0, 102.0], "value": 127.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 153.0, 154.0, 163.0, 115.0, 155.0, 156.0, 123.0, 161.0, 157.0], "value": 152.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [252.0, 233.0, 232.0, 228.0, 227.0, 228.0, 262.0, 223.0, 224.0, 233.0], "value": 234.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 62.0, 80.0, 87.0, 88.0, 67.0, 63.0, 87.0, 113.0, 87.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 86.0, 92.0, 75.0, 73.0, 78.0, 119.0, 108.0, 107.0, 93.0], "value": 92.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 90.0, 93.0, 92.0, 93.0, 90.0, 95.0, 92.0, 99.0, 92.0], "value": 94.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 149.0, 105.0, 107.0, 105.0, 105.0, 143.0, 124.0, 180.0, 106.0], "value": 122.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 102.0, 177.0, 109.0, 160.0, 120.0, 164.0, 159.0, 104.0], "value": 132.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 66.0, 95.0, 93.0, 85.0, 59.0, 61.0, 61.0, 59.0], "value": 69.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 81.0, 82.0, 82.0, 84.0, 74.0, 75.0, 77.0, 76.0, 101.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 96.0, 85.0, 117.0, 122.0, 110.0, 91.0, 92.0, 93.0, 97.0], "value": 102.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 156.0, 154.0, 175.0, 147.0, 205.0, 172.0, 164.0, 204.0, 308.0], "value": 183.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 251.0, 204.0, 185.0, 179.0, 184.0, 181.0, 179.0, 203.0, 266.0], "value": 203.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 74.0, 75.0, 74.0, 76.0, 88.0, 94.0, 94.0, 96.0], "value": 81.5, "unit": "score"}]}]} 18:24:19 INFO - Return code: 0 18:24:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:24:20 ERROR - # TBPL SUCCESS # 18:24:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:24:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:24:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:24:20 INFO - 2016-10-18 18:24:20.077 screenresolution[1670:10537] starting screenresolution argv=screenresolution get 18:24:20 INFO - 2016-10-18 18:24:20.082 screenresolution[1670:10537] Display 0: 1600x1200x32@60 18:24:20 INFO - 2016-10-18 18:24:20.088 screenresolution[1671:10540] starting screenresolution argv=screenresolution list 18:24:20 INFO - Available Modes on Display 0 18:24:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:24:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:24:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:24:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:24:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:24:20 INFO - Intel Iris: 18:24:20 INFO - Chipset Model: Intel Iris 18:24:20 INFO - Type: GPU 18:24:20 INFO - Bus: Built-In 18:24:20 INFO - VRAM (Dynamic, Max): 1536 MB 18:24:20 INFO - Vendor: Intel (0x8086) 18:24:20 INFO - Device ID: 0x0a2e 18:24:20 INFO - Revision ID: 0x0009 18:24:20 INFO - Displays: 18:24:20 INFO - Display: 18:24:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:24:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:24:20 INFO - Display Serial Number: 1600x1200 60 18:24:20 INFO - Main Display: Yes 18:24:20 INFO - Mirror: Off 18:24:20 INFO - Online: Yes 18:24:20 INFO - Rotation: Supported 18:24:20 INFO - Return code: 0 18:24:20 INFO - Running post-action listener: _resource_record_post_action 18:24:20 INFO - [mozharness: 2016-10-19 01:24:20.333834Z] Finished run-tests step (success) 18:24:20 INFO - Running post-run listener: _resource_record_post_run 18:24:20 INFO - Total resource usage - Wall time: 1215s; CPU: 55.0%; Read bytes: 38517248; Write bytes: 711152640; Read time: 400; Write time: 2077 18:24:20 INFO - TinderboxPrint: CPU usage
54.9% 18:24:20 INFO - TinderboxPrint: I/O read bytes / time
38,517,248 / 400 18:24:20 INFO - TinderboxPrint: I/O write bytes / time
711,152,640 / 2,077 18:24:20 INFO - TinderboxPrint: CPU idle
2,193.8 (45.1%) 18:24:20 INFO - TinderboxPrint: CPU system
995.9 (20.5%) 18:24:20 INFO - TinderboxPrint: CPU user
1,672.1 (34.4%) 18:24:20 INFO - TinderboxPrint: Swap in / out
444,985,344 / 0 18:24:20 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 387316224; Write bytes: 380978688; Read time: 29763; Write time: 800 18:24:20 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 32346624; Write bytes: 302222848; Read time: 313; Write time: 1223 18:24:20 INFO - Running post-run listener: _upload_blobber_files 18:24:20 INFO - Blob upload gear active. 18:24:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:24:20 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 18:24:20 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'] 18:24:20 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 18:24:21 INFO - (blobuploader) - INFO - Open directory for files ... 18:24:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:24:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:24:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:24:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:24:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:24:22 INFO - (blobuploader) - INFO - Done attempting. 18:24:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:24:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:24:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:24:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:24:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:24:23 INFO - (blobuploader) - INFO - Done attempting. 18:24:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:24:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:24:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:24:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:24:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:24:25 INFO - (blobuploader) - INFO - Done attempting. 18:24:25 INFO - (blobuploader) - INFO - Iteration through files over. 18:24:25 INFO - Return code: 0 18:24:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:24:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:24:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7afccb86b65092a4e598d2afd56339554de60f09b2d23b3347efb8383f278d7757b30cb5ed6c6e0ca7eb9fe80b9523421e2c5c41816c5bd6f92fcbe0ef556be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a8b3ef609330c279cda46a91cf3849f986ae80bb68a7e0e65d96be8d54aabfdba6a3d4f597d49dce2ed3cce7c10f320b362e0b832973605c3a68539cb0f832e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ac56e30ab09fcff07c958b4d2dac3950b59790daad2236e8733d0c578a1384629726012ac1b25bd1bc2292ab23ba4c153cd169842d964e8285d52527beb9529"} 18:24:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:24:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:24:25 INFO - Contents: 18:24:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7afccb86b65092a4e598d2afd56339554de60f09b2d23b3347efb8383f278d7757b30cb5ed6c6e0ca7eb9fe80b9523421e2c5c41816c5bd6f92fcbe0ef556be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a8b3ef609330c279cda46a91cf3849f986ae80bb68a7e0e65d96be8d54aabfdba6a3d4f597d49dce2ed3cce7c10f320b362e0b832973605c3a68539cb0f832e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ac56e30ab09fcff07c958b4d2dac3950b59790daad2236e8733d0c578a1384629726012ac1b25bd1bc2292ab23ba4c153cd169842d964e8285d52527beb9529"} 18:24:25 INFO - Running post-run listener: copy_logs_to_upload_dir 18:24:25 INFO - Copying logs to upload dir... 18:24:25 INFO - mkdir: /builds/slave/test/build/upload/logs 18:24:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.257444 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-18 18:24:25.672787) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 18:24:25.676618) ========= 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.sIko1GJRLL/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.LuBr47ydlY/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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/a7afccb86b65092a4e598d2afd56339554de60f09b2d23b3347efb8383f278d7757b30cb5ed6c6e0ca7eb9fe80b9523421e2c5c41816c5bd6f92fcbe0ef556be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a8b3ef609330c279cda46a91cf3849f986ae80bb68a7e0e65d96be8d54aabfdba6a3d4f597d49dce2ed3cce7c10f320b362e0b832973605c3a68539cb0f832e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ac56e30ab09fcff07c958b4d2dac3950b59790daad2236e8733d0c578a1384629726012ac1b25bd1bc2292ab23ba4c153cd169842d964e8285d52527beb9529"} build_url:https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011918 build_url: 'https://queue.taskcluster.net/v1/task/edlP988aSOiDcuklYQ2ZmQ/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/a7afccb86b65092a4e598d2afd56339554de60f09b2d23b3347efb8383f278d7757b30cb5ed6c6e0ca7eb9fe80b9523421e2c5c41816c5bd6f92fcbe0ef556be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a8b3ef609330c279cda46a91cf3849f986ae80bb68a7e0e65d96be8d54aabfdba6a3d4f597d49dce2ed3cce7c10f320b362e0b832973605c3a68539cb0f832e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ac56e30ab09fcff07c958b4d2dac3950b59790daad2236e8733d0c578a1384629726012ac1b25bd1bc2292ab23ba4c153cd169842d964e8285d52527beb9529"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 18:24:25.710659) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 18:24:25.710982) ========= 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.sIko1GJRLL/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.LuBr47ydlY/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.005390 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 18:24:25.759811) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 18:24:25.760118) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 18:24:25.760432) ========= ========= Total master_lag: 0.18 =========