builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0151 starttime: 1462673281.99 results: success (0) buildid: 20160507165048 builduid: 130fe3b8846c4d81b0cd11182a72d8df revision: 794c7ee92f4805df1b31624b79b74d4a91878d5e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-07 19:08:01.985546) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-07 19:08:01.985990) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-07 19:08:01.986295) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-07 19:08:02.494578) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 19:08:02.494969) ========= 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.0W66jaFTZQ/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.4V2rqxuyhV/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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-05-07 19:08:02-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-05-07 19:08:02 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.191167 ========= master_lag: 0.64 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 19:08:03.324931) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 19:08:03.325316) ========= 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.0W66jaFTZQ/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.4V2rqxuyhV/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.021935 ========= master_lag: 0.10 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 19:08:03.446078) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-07 19:08:03.446401) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 794c7ee92f4805df1b31624b79b74d4a91878d5e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 794c7ee92f4805df1b31624b79b74d4a91878d5e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0W66jaFTZQ/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.4V2rqxuyhV/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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-05-07 19:08:03,534 truncating revision to first 12 chars 2016-05-07 19:08:03,534 Setting DEBUG logging. 2016-05-07 19:08:03,534 attempt 1/10 2016-05-07 19:08:03,534 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/794c7ee92f48?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-07 19:08:03,906 unpacking tar archive at: fx-team-794c7ee92f48/testing/mozharness/ program finished with exit code 0 elapsedTime=0.621539 ========= master_lag: 1.81 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-07 19:08:05.875342) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-07 19:08:05.881651) ========= script_repo_revision: 794c7ee92f4805df1b31624b79b74d4a91878d5e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-07 19:08:05.882170) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-05-07 19:08:05.882456) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-05-07 19:08:07.830813) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-07 19:08:07.831069) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0W66jaFTZQ/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.4V2rqxuyhV/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 19:08:08 INFO - MultiFileLogger online at 20160507 19:08:08 in /builds/slave/test 19:08:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 19:08:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:08:08 INFO - {'append_to_log': False, 19:08:08 INFO - 'base_work_dir': '/builds/slave/test', 19:08:08 INFO - 'blob_upload_branch': 'Fx-Team', 19:08:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:08:08 INFO - 'branch': 'Fx-Team', 19:08:08 INFO - 'buildbot_json_path': 'buildprops.json', 19:08:08 INFO - 'config_files': ('talos/mac_config.py',), 19:08:08 INFO - 'default_actions': ('clobber', 19:08:08 INFO - 'read-buildbot-config', 19:08:08 INFO - 'download-and-extract', 19:08:08 INFO - 'populate-webroot', 19:08:08 INFO - 'create-virtualenv', 19:08:08 INFO - 'install', 19:08:08 INFO - 'run-tests'), 19:08:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:08:08 INFO - 'download_minidump_stackwalk': True, 19:08:08 INFO - 'download_symbols': 'ondemand', 19:08:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:08:08 INFO - 'tooltool.py': '/tools/tooltool.py', 19:08:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:08:08 INFO - '/tools/misc-python/virtualenv.py')}, 19:08:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:08:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:08:08 INFO - 'installer_path': 'installer.exe', 19:08:08 INFO - 'log_level': 'info', 19:08:08 INFO - 'log_name': 'talos', 19:08:08 INFO - 'log_to_console': True, 19:08:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:08:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:08:08 INFO - 'opt_config_files': (), 19:08:08 INFO - 'pip_index': False, 19:08:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:08:08 INFO - 'cmd': ('bash', 19:08:08 INFO - '-c', 19:08:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:08:08 INFO - 'enabled': True, 19:08:08 INFO - 'halt_on_failure': False, 19:08:08 INFO - 'name': 'check_screen_resolution'},), 19:08:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:08:08 INFO - 'cmd': ('bash', 19:08:08 INFO - '-c', 19:08:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:08:08 INFO - 'enabled': True, 19:08:08 INFO - 'halt_on_failure': False, 19:08:08 INFO - 'name': 'check_screen_resolution'},), 19:08:08 INFO - 'run_cmd_checks_enabled': True, 19:08:08 INFO - 'sps_profile': False, 19:08:08 INFO - 'sps_profile_interval': 0, 19:08:08 INFO - 'suite': 'dromaeojs-e10s', 19:08:08 INFO - 'system_bits': '32', 19:08:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:08:08 INFO - 'title': 't-yosemite-r7-0151', 19:08:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:08:08 INFO - 'use_talos_json': True, 19:08:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:08:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:08:08 INFO - 'work_dir': 'build'} 19:08:08 INFO - ##### 19:08:08 INFO - ##### Running clobber step. 19:08:08 INFO - ##### 19:08:08 INFO - Running pre-action listener: _resource_record_pre_action 19:08:08 INFO - Running main action method: clobber 19:08:08 INFO - rmtree: /builds/slave/test/build 19:08:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:08:09 INFO - Running post-action listener: _resource_record_post_action 19:08:09 INFO - ##### 19:08:09 INFO - ##### Finished clobber step (success) 19:08:09 INFO - ##### 19:08:09 INFO - ##### 19:08:09 INFO - ##### Running read-buildbot-config step. 19:08:09 INFO - ##### 19:08:09 INFO - Running pre-action listener: _resource_record_pre_action 19:08:09 INFO - Running main action method: read_buildbot_config 19:08:09 INFO - Using buildbot properties: 19:08:09 INFO - { 19:08:09 INFO - "project": "", 19:08:09 INFO - "product": "firefox", 19:08:09 INFO - "script_repo_revision": "production", 19:08:09 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 19:08:09 INFO - "repository": "", 19:08:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 19:08:09 INFO - "buildid": "20160507165048", 19:08:09 INFO - "pgo_build": "False", 19:08:09 INFO - "basedir": "/builds/slave/test", 19:08:09 INFO - "buildnumber": 3, 19:08:09 INFO - "slavename": "t-yosemite-r7-0151", 19:08:09 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 19:08:09 INFO - "platform": "yosemite_r7", 19:08:09 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 19:08:09 INFO - "branch": "fx-team", 19:08:09 INFO - "repo_path": "integration/fx-team", 19:08:09 INFO - "revision": "794c7ee92f4805df1b31624b79b74d4a91878d5e", 19:08:09 INFO - "stage_platform": "macosx64", 19:08:09 INFO - "builduid": "130fe3b8846c4d81b0cd11182a72d8df", 19:08:09 INFO - "slavebuilddir": "test" 19:08:09 INFO - } 19:08:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 19:08:09 INFO - Running post-action listener: _resource_record_post_action 19:08:09 INFO - ##### 19:08:09 INFO - ##### Finished read-buildbot-config step (success) 19:08:09 INFO - ##### 19:08:09 INFO - ##### 19:08:09 INFO - ##### Running download-and-extract step. 19:08:09 INFO - ##### 19:08:09 INFO - Running pre-action listener: _resource_record_pre_action 19:08:09 INFO - Running main action method: download_and_extract 19:08:09 INFO - mkdir: /builds/slave/test/build/tests 19:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:09 INFO - https://queue.taskcluster.net/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:08:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:08:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:08:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:08:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 19:08:12 INFO - Downloaded 1199 bytes. 19:08:12 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:08:12 INFO - Using the following test package requirements: 19:08:12 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:08:12 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:08:12 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 19:08:12 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:08:12 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 19:08:12 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:08:12 INFO - u'jsshell-mac.zip'], 19:08:12 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:08:12 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 19:08:12 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:08:12 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:08:12 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 19:08:12 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:08:12 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 19:08:12 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:08:12 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 19:08:12 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:08:12 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 19:08:12 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:08:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:12 INFO - https://queue.taskcluster.net/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:08:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:08:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:08:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:08:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:08:14 INFO - Downloaded 18145050 bytes. 19:08:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:08:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:08:17 INFO - Return code: 0 19:08:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:08:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:17 INFO - https://queue.taskcluster.net/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:08:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:08:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:08:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:08:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:08:19 INFO - Downloaded 18145050 bytes. 19:08:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:08:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:08:22 INFO - Return code: 0 19:08:22 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:22 INFO - https://queue.taskcluster.net/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:08:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:08:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:08:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:08:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:08:23 INFO - Downloaded 10840152 bytes. 19:08:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:08:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:08:23 INFO - Return code: 0 19:08:23 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:23 INFO - https://queue.taskcluster.net/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:08:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:08:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:08:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 19:08:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 19:08:26 INFO - Downloaded 110312043 bytes. 19:08:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:08:26 INFO - mkdir: /builds/slave/test/properties 19:08:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:08:26 INFO - Writing to file /builds/slave/test/properties/build_url 19:08:26 INFO - Contents: 19:08:26 INFO - build_url:https://queue.taskcluster.net/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:08:28 INFO - Running post-action listener: _resource_record_post_action 19:08:28 INFO - Running post-action listener: set_extra_try_arguments 19:08:28 INFO - ##### 19:08:28 INFO - ##### Finished download-and-extract step (success) 19:08:28 INFO - ##### 19:08:28 INFO - ##### 19:08:28 INFO - ##### Running populate-webroot step. 19:08:28 INFO - ##### 19:08:28 INFO - Running pre-action listener: _resource_record_pre_action 19:08:28 INFO - Running main action method: populate_webroot 19:08:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:08:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:08:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:08:28 INFO - '--tppagecycles', 19:08:28 INFO - '7'], 19:08:28 INFO - 'tests': ['tsvgm']}}, 19:08:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:08:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:08:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:08:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:08:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:08:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:08:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:28 INFO - 'talos_options': ['--disable-e10s'], 19:08:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:08:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:08:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:28 INFO - 'talos_options': ['--disable-e10s'], 19:08:28 INFO - 'tests': ['damp', 'tps']}, 19:08:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:28 INFO - 'tests': ['damp', 'tps']}, 19:08:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:08:28 INFO - 'tests': ['dromaeo_dom']}, 19:08:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:08:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:08:28 INFO - 'tests': ['a11yr', 19:08:28 INFO - 'ts_paint', 19:08:28 INFO - 'tpaint', 19:08:28 INFO - 'sessionrestore', 19:08:28 INFO - 'sessionrestore_no_auto_restore', 19:08:28 INFO - 'tabpaint']}, 19:08:28 INFO - 'other-e10s': {'tests': ['a11yr', 19:08:28 INFO - 'ts_paint', 19:08:28 INFO - 'tpaint', 19:08:28 INFO - 'sessionrestore', 19:08:28 INFO - 'sessionrestore_no_auto_restore', 19:08:28 INFO - 'tabpaint']}, 19:08:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:08:28 INFO - 'ts_paint', 19:08:28 INFO - 'tpaint', 19:08:28 INFO - 'sessionrestore', 19:08:28 INFO - 'sessionrestore_no_auto_restore', 19:08:28 INFO - 'tabpaint']}, 19:08:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:08:28 INFO - 'ts_paint', 19:08:28 INFO - 'tpaint', 19:08:28 INFO - 'sessionrestore', 19:08:28 INFO - 'sessionrestore_no_auto_restore', 19:08:28 INFO - 'tabpaint']}, 19:08:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:08:28 INFO - 'tests': ['a11yr', 19:08:28 INFO - 'ts_paint', 19:08:28 INFO - 'tpaint', 19:08:28 INFO - 'sessionrestore', 19:08:28 INFO - 'sessionrestore_no_auto_restore', 19:08:28 INFO - 'tabpaint']}, 19:08:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:08:28 INFO - 'tests': ['a11yr', 19:08:28 INFO - 'ts_paint', 19:08:28 INFO - 'tpaint', 19:08:28 INFO - 'sessionrestore', 19:08:28 INFO - 'sessionrestore_no_auto_restore', 19:08:28 INFO - 'tabpaint']}, 19:08:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:08:28 INFO - 'tests': ['tsvgx', 19:08:28 INFO - 'tsvgr_opacity', 19:08:28 INFO - 'tart', 19:08:28 INFO - 'tscrollx', 19:08:28 INFO - 'cart']}, 19:08:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:08:28 INFO - 'tsvgr_opacity', 19:08:28 INFO - 'tart', 19:08:28 INFO - 'tscrollx', 19:08:28 INFO - 'cart']}, 19:08:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:28 INFO - 'talos_options': ['--disable-e10s'], 19:08:28 INFO - 'tests': ['tp5o']}, 19:08:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:28 INFO - 'tests': ['tp5o']}, 19:08:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:28 INFO - 'talos_options': ['--disable-e10s', 19:08:28 INFO - '--xperf_path', 19:08:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:08:28 INFO - 'tests': ['tp5n']}, 19:08:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:28 INFO - 'talos_options': ['--xperf_path', 19:08:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:08:28 INFO - 'tests': ['tp5n']}}, 19:08:28 INFO - 'talos.zip': {'path': '', 19:08:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:08:28 INFO - Running post-action listener: _resource_record_post_action 19:08:28 INFO - ##### 19:08:28 INFO - ##### Finished populate-webroot step (success) 19:08:28 INFO - ##### 19:08:28 INFO - ##### 19:08:28 INFO - ##### Running create-virtualenv step. 19:08:28 INFO - ##### 19:08:28 INFO - Running pre-action listener: _resource_record_pre_action 19:08:28 INFO - Running main action method: create_virtualenv 19:08:28 INFO - Creating virtualenv /builds/slave/test/build/venv 19:08:28 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 19:08:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:08:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:08:28 INFO - Using real prefix '/tools/python27' 19:08:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:08:29 INFO - Installing distribute.............................................................................................................................................................................................done. 19:08:32 INFO - Installing pip.................done. 19:08:32 INFO - Return code: 0 19:08:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:08:32 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:32 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:32 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 0x10080ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100814570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bb84aca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006b2d30>, '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 0x1004d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a9b20>, '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.0W66jaFTZQ/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.4V2rqxuyhV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 19:08:32 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 19:08:32 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 19:08:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0W66jaFTZQ/Render', 19:08:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:32 INFO - 'HOME': '/Users/cltbld', 19:08:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:32 INFO - 'LOGNAME': 'cltbld', 19:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:32 INFO - 'MOZ_NO_REMOTE': '1', 19:08:32 INFO - 'NO_EM_RESTART': '1', 19:08:32 INFO - 'PAGER': '/bin/cat', 19:08:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:32 INFO - 'PWD': '/builds/slave/test', 19:08:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:32 INFO - 'SHELL': '/bin/bash', 19:08:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4V2rqxuyhV/Listeners', 19:08:32 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 19:08:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:32 INFO - 'USER': 'cltbld', 19:08:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:32 INFO - 'XPC_FLAGS': '0x0', 19:08:32 INFO - 'XPC_SERVICE_NAME': '0', 19:08:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:32 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 19:08:32 INFO - Cleaning up... 19:08:32 INFO - Return code: 0 19:08:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 19:08:32 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:32 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:32 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10080ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100814570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bb84aca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006b2d30>, '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 0x1004d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a9b20>, '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.0W66jaFTZQ/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.4V2rqxuyhV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 19:08:32 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>=0.7.1'] in /builds/slave/test/build 19:08:32 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>=0.7.1 19:08:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0W66jaFTZQ/Render', 19:08:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:32 INFO - 'HOME': '/Users/cltbld', 19:08:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:32 INFO - 'LOGNAME': 'cltbld', 19:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:32 INFO - 'MOZ_NO_REMOTE': '1', 19:08:32 INFO - 'NO_EM_RESTART': '1', 19:08:32 INFO - 'PAGER': '/bin/cat', 19:08:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:32 INFO - 'PWD': '/builds/slave/test', 19:08:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:32 INFO - 'SHELL': '/bin/bash', 19:08:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4V2rqxuyhV/Listeners', 19:08:32 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 19:08:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:32 INFO - 'USER': 'cltbld', 19:08:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:32 INFO - 'XPC_FLAGS': '0x0', 19:08:32 INFO - 'XPC_SERVICE_NAME': '0', 19:08:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:32 INFO - Downloading/unpacking psutil>=0.7.1 19:08:32 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 19:08:32 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 19:08:32 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 19:08:32 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 19:08:32 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 19:08:32 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 19:08:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:08:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:08:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:08:36 INFO - Installing collected packages: psutil 19:08:36 INFO - Running setup.py install for psutil 19:08:36 INFO - building 'psutil._psutil_osx' extension 19:08:36 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 19:08:36 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 19:08:36 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 19:08:36 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 19:08:36 INFO - building 'psutil._psutil_posix' extension 19:08:36 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 19:08:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:08:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:08:36 INFO - ^ 19:08:36 INFO - 1 warning generated. 19:08:36 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 19:08:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:08:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:08:36 INFO - Successfully installed psutil 19:08:36 INFO - Cleaning up... 19:08:37 INFO - Return code: 0 19:08:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 19:08:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10080ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100814570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bb84aca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006b2d30>, '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 0x1004d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a9b20>, '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.0W66jaFTZQ/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.4V2rqxuyhV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 19:08:37 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.0.0'] in /builds/slave/test/build 19:08:37 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.0.0 19:08:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0W66jaFTZQ/Render', 19:08:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:37 INFO - 'HOME': '/Users/cltbld', 19:08:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:37 INFO - 'LOGNAME': 'cltbld', 19:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:37 INFO - 'MOZ_NO_REMOTE': '1', 19:08:37 INFO - 'NO_EM_RESTART': '1', 19:08:37 INFO - 'PAGER': '/bin/cat', 19:08:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:37 INFO - 'PWD': '/builds/slave/test', 19:08:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:37 INFO - 'SHELL': '/bin/bash', 19:08:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4V2rqxuyhV/Listeners', 19:08:37 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 19:08:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:37 INFO - 'USER': 'cltbld', 19:08:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:37 INFO - 'XPC_FLAGS': '0x0', 19:08:37 INFO - 'XPC_SERVICE_NAME': '0', 19:08:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:08:37 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 19:08:37 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 19:08:37 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 19:08:37 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 19:08:37 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 19:08:37 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 19:08:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:08:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:08:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:08:39 INFO - Installing collected packages: mozsystemmonitor 19:08:39 INFO - Running setup.py install for mozsystemmonitor 19:08:39 INFO - Successfully installed mozsystemmonitor 19:08:39 INFO - Cleaning up... 19:08:40 INFO - Return code: 0 19:08:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:08: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:40 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 0x10080ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100814570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bb84aca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006b2d30>, '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 0x1004d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a9b20>, '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.0W66jaFTZQ/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.4V2rqxuyhV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 19:08:40 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 19:08:40 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 19:08:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0W66jaFTZQ/Render', 19:08:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:40 INFO - 'HOME': '/Users/cltbld', 19:08:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:40 INFO - 'LOGNAME': 'cltbld', 19:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:40 INFO - 'MOZ_NO_REMOTE': '1', 19:08:40 INFO - 'NO_EM_RESTART': '1', 19:08:40 INFO - 'PAGER': '/bin/cat', 19:08:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:40 INFO - 'PWD': '/builds/slave/test', 19:08:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:40 INFO - 'SHELL': '/bin/bash', 19:08:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4V2rqxuyhV/Listeners', 19:08:40 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 19:08:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:40 INFO - 'USER': 'cltbld', 19:08:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:40 INFO - 'XPC_FLAGS': '0x0', 19:08:40 INFO - 'XPC_SERVICE_NAME': '0', 19:08:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:40 INFO - Downloading/unpacking blobuploader==1.2.4 19:08: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 19:08: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 19:08: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 19:08: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 19:08: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 19:08: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 19:08:42 INFO - Downloading blobuploader-1.2.4.tar.gz 19:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:08:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:08:42 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 19:08:42 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 19:08:42 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 19:08:42 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 19:08:42 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 19:08:42 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 19:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:08:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:08: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 19:08: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 19:08: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 19:08: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 19:08: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 19:08: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 19:08:43 INFO - Downloading docopt-0.6.1.tar.gz 19:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:08:44 INFO - Installing collected packages: blobuploader, requests, docopt 19:08:44 INFO - Running setup.py install for blobuploader 19:08:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:08:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:08:44 INFO - Running setup.py install for requests 19:08:44 INFO - Running setup.py install for docopt 19:08:44 INFO - Successfully installed blobuploader requests docopt 19:08:44 INFO - Cleaning up... 19:08:44 INFO - Return code: 0 19:08:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:44 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 0x10080ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100814570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bb84aca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006b2d30>, '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 0x1004d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a9b20>, '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.0W66jaFTZQ/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.4V2rqxuyhV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 19:08:44 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 19:08:44 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 19:08:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0W66jaFTZQ/Render', 19:08:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:44 INFO - 'HOME': '/Users/cltbld', 19:08:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:44 INFO - 'LOGNAME': 'cltbld', 19:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:44 INFO - 'MOZ_NO_REMOTE': '1', 19:08:44 INFO - 'NO_EM_RESTART': '1', 19:08:44 INFO - 'PAGER': '/bin/cat', 19:08:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:44 INFO - 'PWD': '/builds/slave/test', 19:08:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:44 INFO - 'SHELL': '/bin/bash', 19:08:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4V2rqxuyhV/Listeners', 19:08:44 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 19:08:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:44 INFO - 'USER': 'cltbld', 19:08:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:44 INFO - 'XPC_FLAGS': '0x0', 19:08:44 INFO - 'XPC_SERVICE_NAME': '0', 19:08:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:08:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-d16vzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:08:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-7PPWmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:08:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-LO8c4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:08:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-TOyuGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:08:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-_c_lt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:08:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-p3cbNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:08:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ddPlBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:08:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-OrsqJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:08:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-MTo7Xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:08:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-f4IvcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:08:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-RUbNXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:08:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-0sVY3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:08:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-OZN8vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:08:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-KAvNDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-JT_f3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:08:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-jd2qRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:08:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-9moskb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:08:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:08:46 INFO - Running setup.py install for manifestparser 19:08:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:08:47 INFO - Running setup.py install for mozcrash 19:08:47 INFO - Running setup.py install for mozdebug 19:08:47 INFO - Running setup.py install for mozdevice 19:08:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:08:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:08:47 INFO - Running setup.py install for mozfile 19:08:47 INFO - Running setup.py install for mozhttpd 19:08:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:08:47 INFO - Running setup.py install for mozinfo 19:08:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:08:48 INFO - Running setup.py install for mozInstall 19:08:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:08:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:08:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:08:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:08:48 INFO - Running setup.py install for mozleak 19:08:48 INFO - Running setup.py install for mozlog 19:08:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:08:48 INFO - Running setup.py install for moznetwork 19:08:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:08:48 INFO - Running setup.py install for mozprocess 19:08:48 INFO - Running setup.py install for mozprofile 19:08:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:08:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:08:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:08:48 INFO - Running setup.py install for mozrunner 19:08:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:08:49 INFO - Running setup.py install for mozscreenshot 19:08:49 INFO - Running setup.py install for moztest 19:08:49 INFO - Running setup.py install for mozversion 19:08:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:08:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:08:49 INFO - Cleaning up... 19:08:49 INFO - Return code: 0 19:08:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:49 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 0x10080ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100814570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bb84aca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006b2d30>, '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 0x1004d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a9b20>, '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.0W66jaFTZQ/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.4V2rqxuyhV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 19:08:49 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 19:08:49 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 19:08:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0W66jaFTZQ/Render', 19:08:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:49 INFO - 'HOME': '/Users/cltbld', 19:08:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:49 INFO - 'LOGNAME': 'cltbld', 19:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:49 INFO - 'MOZ_NO_REMOTE': '1', 19:08:49 INFO - 'NO_EM_RESTART': '1', 19:08:49 INFO - 'PAGER': '/bin/cat', 19:08:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:49 INFO - 'PWD': '/builds/slave/test', 19:08:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:49 INFO - 'SHELL': '/bin/bash', 19:08:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4V2rqxuyhV/Listeners', 19:08:49 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 19:08:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:49 INFO - 'USER': 'cltbld', 19:08:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:49 INFO - 'XPC_FLAGS': '0x0', 19:08:49 INFO - 'XPC_SERVICE_NAME': '0', 19:08:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:08:49 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-UjKexO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:08:50 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)) 19:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:08:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-cxREOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:08:50 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)) 19:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:08:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ZOzXh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:08:50 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)) 19:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:08:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-PkgKoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:08:50 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)) 19:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:08:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-RsHGU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:08:50 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)) 19:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:08:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-jMDRIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:08:50 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)) 19:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:08:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-swh8as-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:08:50 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)) 19:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:08:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-JrjPum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:08:50 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)) 19:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:08:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-si1gXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:08:50 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)) 19:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:08:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-QuAWZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:08:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 19:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:08:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-iTdPjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:08:51 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)) 19:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:08:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-yodcxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:08:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 19:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:08:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-1Q27SQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:08:51 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)) 19:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:08:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-InFONO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:08:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 19:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-pZmlZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:51 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)) 19:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:08:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-8iEynG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:08:51 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)) 19:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:08:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-nC87vP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:08:51 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)) 19:08:51 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)) 19:08:51 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)) 19:08:51 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)) 19:08:51 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)) 19:08:51 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)) 19:08:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:08:51 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 19:08:51 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 19:08:51 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 19:08:51 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 19:08:51 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 19:08:51 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 19:08:54 INFO - Downloading blessings-1.6.tar.gz 19:08:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:08:54 INFO - Installing collected packages: blessings 19:08:54 INFO - Running setup.py install for blessings 19:08:54 INFO - Successfully installed blessings 19:08:54 INFO - Cleaning up... 19:08:54 INFO - Return code: 0 19:08:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:08:54 INFO - Reading from file tmpfile_stdout 19:08:54 INFO - Current package versions: 19:08:54 INFO - blessings == 1.6 19:08:54 INFO - blobuploader == 1.2.4 19:08:54 INFO - docopt == 0.6.1 19:08:54 INFO - manifestparser == 1.1 19:08:54 INFO - mozInstall == 1.12 19:08:54 INFO - mozcrash == 0.17 19:08:54 INFO - mozdebug == 0.1 19:08:54 INFO - mozdevice == 0.48 19:08:54 INFO - mozfile == 1.2 19:08:54 INFO - mozhttpd == 0.7 19:08:54 INFO - mozinfo == 0.9 19:08:54 INFO - mozleak == 0.1 19:08:54 INFO - mozlog == 3.1 19:08:54 INFO - moznetwork == 0.27 19:08:54 INFO - mozprocess == 0.22 19:08:54 INFO - mozprofile == 0.28 19:08:54 INFO - mozrunner == 6.11 19:08:54 INFO - mozscreenshot == 0.1 19:08:54 INFO - mozsystemmonitor == 0.0 19:08:54 INFO - moztest == 0.7 19:08:54 INFO - mozversion == 1.4 19:08:54 INFO - psutil == 3.1.1 19:08:54 INFO - requests == 1.2.3 19:08:54 INFO - wsgiref == 0.1.2 19:08:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:54 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:54 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:54 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 0x10080ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100814570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bb84aca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006b2d30>, '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 0x1004d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a9b20>, '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.0W66jaFTZQ/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.4V2rqxuyhV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 19:08:54 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 19:08:54 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 19:08:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0W66jaFTZQ/Render', 19:08:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:54 INFO - 'HOME': '/Users/cltbld', 19:08:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:54 INFO - 'LOGNAME': 'cltbld', 19:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:54 INFO - 'MOZ_NO_REMOTE': '1', 19:08:54 INFO - 'NO_EM_RESTART': '1', 19:08:54 INFO - 'PAGER': '/bin/cat', 19:08:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:54 INFO - 'PWD': '/builds/slave/test', 19:08:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:54 INFO - 'SHELL': '/bin/bash', 19:08:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4V2rqxuyhV/Listeners', 19:08:54 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 19:08:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:54 INFO - 'USER': 'cltbld', 19:08:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:54 INFO - 'XPC_FLAGS': '0x0', 19:08:54 INFO - 'XPC_SERVICE_NAME': '0', 19:08:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:54 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)) 19:08:54 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)) 19:08:54 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)) 19:08:54 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)) 19:08:54 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)) 19:08:54 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)) 19:08:54 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)) 19:08:54 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)) 19:08:54 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)) 19:08:54 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)) 19:08:54 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)) 19:08:54 INFO - Cleaning up... 19:08:54 INFO - Return code: 0 19:08:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:08:54 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:54 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:54 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 0x10080ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100814570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bb84aca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006b2d30>, '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 0x1004d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a9b20>, '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.0W66jaFTZQ/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.4V2rqxuyhV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 19:08:54 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 19:08:55 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 19:08:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0W66jaFTZQ/Render', 19:08:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:55 INFO - 'HOME': '/Users/cltbld', 19:08:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:55 INFO - 'LOGNAME': 'cltbld', 19:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:55 INFO - 'MOZ_NO_REMOTE': '1', 19:08:55 INFO - 'NO_EM_RESTART': '1', 19:08:55 INFO - 'PAGER': '/bin/cat', 19:08:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:55 INFO - 'PWD': '/builds/slave/test', 19:08:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:55 INFO - 'SHELL': '/bin/bash', 19:08:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4V2rqxuyhV/Listeners', 19:08:55 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 19:08:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:55 INFO - 'USER': 'cltbld', 19:08:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:55 INFO - 'XPC_FLAGS': '0x0', 19:08:55 INFO - 'XPC_SERVICE_NAME': '0', 19:08:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:55 INFO - Downloading/unpacking jsonschema 19:08:55 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 19:08:55 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 19:08:55 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 19:08:55 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 19:08:55 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 19:08:55 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 19:08:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:08:57 INFO - Downloading/unpacking functools32 (from jsonschema) 19:08:57 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 19:08:57 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 19:08:57 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 19:08:57 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 19:08:57 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 19:08:57 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 19:08:58 INFO - Downloading functools32-3.2.3-2.tar.gz 19:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:08:58 INFO - warning: no files found matching '*.txt' 19:08:58 INFO - no previously-included directories found matching 'build' 19:08:58 INFO - no previously-included directories found matching 'dist' 19:08:58 INFO - no previously-included directories found matching '.git*' 19:08:58 INFO - Installing collected packages: jsonschema, functools32 19:08:58 INFO - Running setup.py install for functools32 19:08:58 INFO - warning: no files found matching '*.txt' 19:08:58 INFO - no previously-included directories found matching 'build' 19:08:58 INFO - no previously-included directories found matching 'dist' 19:08:58 INFO - no previously-included directories found matching '.git*' 19:08:58 INFO - Successfully installed jsonschema functools32 19:08:58 INFO - Cleaning up... 19:08:58 INFO - Return code: 0 19:08:58 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10080ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100814570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bb84aca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006b2d30>, '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 0x1004d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a9b20>, '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.0W66jaFTZQ/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.4V2rqxuyhV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 19:08:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 19:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 19:08:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0W66jaFTZQ/Render', 19:08:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:58 INFO - 'HOME': '/Users/cltbld', 19:08:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:58 INFO - 'LOGNAME': 'cltbld', 19:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:58 INFO - 'MOZ_NO_REMOTE': '1', 19:08:58 INFO - 'NO_EM_RESTART': '1', 19:08:58 INFO - 'PAGER': '/bin/cat', 19:08:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:58 INFO - 'PWD': '/builds/slave/test', 19:08:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:58 INFO - 'SHELL': '/bin/bash', 19:08:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4V2rqxuyhV/Listeners', 19:08:58 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 19:08:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:58 INFO - 'USER': 'cltbld', 19:08:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:58 INFO - 'XPC_FLAGS': '0x0', 19:08:58 INFO - 'XPC_SERVICE_NAME': '0', 19:08:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:58 INFO - Downloading/unpacking flake8 19:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:09:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:09:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:09:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:09:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:09:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:09:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:09:02 INFO - no previously-included directories found matching 'docs/_build' 19:09:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:09:02 INFO - Running setup.py install for pep8 19:09:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:09:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:09:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:09:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:09:02 INFO - no previously-included directories found matching 'docs/_build' 19:09:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:09:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:09:02 INFO - Cleaning up... 19:09:02 INFO - Return code: 0 19:09:02 INFO - Running post-action listener: _resource_record_post_action 19:09:02 INFO - Running post-action listener: _start_resource_monitoring 19:09:02 INFO - Starting resource monitoring. 19:09:02 INFO - ##### 19:09:02 INFO - ##### Finished create-virtualenv step (success) 19:09:02 INFO - ##### 19:09:02 INFO - ##### 19:09:02 INFO - ##### Running install step. 19:09:02 INFO - ##### 19:09:02 INFO - Running pre-action listener: _resource_record_pre_action 19:09:02 INFO - Running main action method: install 19:09:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:03 INFO - Reading from file tmpfile_stdout 19:09:03 INFO - Detecting whether we're running mozinstall >=1.0... 19:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:09:03 INFO - Reading from file tmpfile_stdout 19:09:03 INFO - Output received: 19:09:03 INFO - Usage: mozinstall [options] installer 19:09:03 INFO - Options: 19:09:03 INFO - -h, --help show this help message and exit 19:09:03 INFO - -d DEST, --destination=DEST 19:09:03 INFO - Directory to install application into. [default: 19:09:03 INFO - "/builds/slave/test"] 19:09:03 INFO - --app=APP Application being installed. [default: firefox] 19:09:03 INFO - mkdir: /builds/slave/test/build/application 19:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:09:37 INFO - Reading from file tmpfile_stdout 19:09:37 INFO - Output received: 19:09:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:09:37 INFO - Running post-action listener: _resource_record_post_action 19:09:37 INFO - ##### 19:09:37 INFO - ##### Finished install step (success) 19:09:37 INFO - ##### 19:09:37 INFO - ##### 19:09:37 INFO - ##### Running run-tests step. 19:09:37 INFO - ##### 19:09:37 INFO - Running pre-action listener: _resource_record_pre_action 19:09:37 INFO - Running main action method: run_tests 19:09:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:09:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:09:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:09:37 INFO - 2016-05-07 19:09:37.286 screenresolution[2870:43052] starting screenresolution argv=screenresolution get 19:09:37 INFO - 2016-05-07 19:09:37.289 screenresolution[2870:43052] Display 0: 1600x1200x32@60 19:09:37 INFO - 2016-05-07 19:09:37.295 screenresolution[2871:43054] starting screenresolution argv=screenresolution list 19:09:37 INFO - Available Modes on Display 0 19:09:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:09:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:09:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:09:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:09:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:09:37 INFO - Intel Iris: 19:09:37 INFO - Chipset Model: Intel Iris 19:09:37 INFO - Type: GPU 19:09:37 INFO - Bus: Built-In 19:09:37 INFO - VRAM (Dynamic, Max): 1536 MB 19:09:37 INFO - Vendor: Intel (0x8086) 19:09:37 INFO - Device ID: 0x0a2e 19:09:37 INFO - Revision ID: 0x0009 19:09:37 INFO - Displays: 19:09:37 INFO - Display: 19:09:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:09:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:09:37 INFO - Display Serial Number: 1600x1200 60 19:09:37 INFO - Main Display: Yes 19:09:37 INFO - Mirror: Off 19:09:37 INFO - Online: Yes 19:09:37 INFO - Rotation: Supported 19:09:37 INFO - Return code: 0 19:09:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:09:37 INFO - Python 2.7.3 19:09:37 INFO - Return code: 0 19:09:37 INFO - grabbing minidump binary from tooltool 19:09:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:37 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006b2d30>, '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 0x1004d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:09:37 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 19:09:37 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 19:09:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:09:37 INFO - Return code: 0 19:09:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:09:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:09:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:09:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:09:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:09:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:09:38 INFO - Return code: 0 19:09:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 19:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0151 --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 19:09:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0W66jaFTZQ/Render', 19:09:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:38 INFO - 'HOME': '/Users/cltbld', 19:09:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:38 INFO - 'LOGNAME': 'cltbld', 19:09:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:09:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:38 INFO - 'MOZ_NO_REMOTE': '1', 19:09:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:09:38 INFO - 'NO_EM_RESTART': '1', 19:09:38 INFO - 'PAGER': '/bin/cat', 19:09:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:38 INFO - 'PWD': '/builds/slave/test', 19:09:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:09:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:38 INFO - 'SHELL': '/bin/bash', 19:09:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4V2rqxuyhV/Listeners', 19:09:38 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 19:09:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:38 INFO - 'USER': 'cltbld', 19:09:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:38 INFO - 'XPC_FLAGS': '0x0', 19:09:38 INFO - 'XPC_SERVICE_NAME': '0', 19:09:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 19:09:38 INFO - mozversion application_buildid: 20160507165048 19:09:38 INFO - mozversion application_changeset: 794c7ee92f4805df1b31624b79b74d4a91878d5e 19:09:38 INFO - mozversion application_display_name: Nightly 19:09:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:09:38 INFO - mozversion application_name: Firefox 19:09:38 INFO - mozversion application_remotingname: firefox 19:09:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 19:09:38 INFO - mozversion application_vendor: Mozilla 19:09:38 INFO - mozversion application_version: 49.0a1 19:09:38 INFO - mozversion platform_buildid: 20160507165048 19:09:38 INFO - mozversion platform_changeset: 794c7ee92f4805df1b31624b79b74d4a91878d5e 19:09:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 19:09:38 INFO - mozversion platform_version: 49.0a1 19:09:38 INFO - using testdate: 1462673378 19:09:38 INFO - actual date: 1462673378 19:09:38 INFO - starting webserver on 'localhost:56279' 19:09:38 INFO - SUITE-START | Running 2 tests 19:09:38 INFO - TEST-START | dromaeo_css 19:09:38 INFO - operating with platform_type : mac_ 19:09:38 INFO - Initialising browser for dromaeo_css test... 19:09:38 INFO - TEST-INFO | started process 2889 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmptEfkbr/profile http://localhost:56279/getInfo.html) 19:09:41 INFO - PROCESS | 2889 | __metrics Screen width/height:1600/1200 19:09:41 INFO - PROCESS | 2889 | colorDepth:24 19:09:41 INFO - PROCESS | 2889 | Browser inner width/height: 1024/689 19:09:41 INFO - PROCESS | 2889 | __metrics 19:09:42 ERROR - PROCESS | 2889 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 19:09:42 INFO - TEST-INFO | 2889: exit 0 19:09:42 INFO - Browser initialized. 19:09:42 INFO - Running cycle 1/1 for dromaeo_css test... 19:09:42 INFO - TEST-INFO | started process 2892 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmptEfkbr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:09:54 INFO - PROCESS | 2892 | RSS: Main: 250105856 19:09:54 INFO - PROCESS | 2892 | 19:10:44 INFO - PROCESS | 2892 | 1462673444248 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] 19:12:55 INFO - PROCESS | 2892 | Cycle 1(1): loaded http://localhost:56279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56279/tests/dromaeo/cssquery-ext.html) 19:12:56 INFO - PROCESS | 2892 | RSS: Main: 262139904 19:12:56 INFO - PROCESS | 2892 | 19:15:58 INFO - PROCESS | 2892 | Cycle 1(1): loaded http://localhost:56279/tests/dromaeo/cssquery-ext.html (next: http://localhost:56279/tests/dromaeo/cssquery-jquery.html) 19:15:59 INFO - PROCESS | 2892 | RSS: Main: 273833984 19:15:59 INFO - PROCESS | 2892 | 19:19:01 INFO - PROCESS | 2892 | Cycle 1(1): loaded http://localhost:56279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56279/tests/dromaeo/cssquery-mootools.html) 19:19:01 INFO - PROCESS | 2892 | RSS: Main: 275783680 19:19:01 INFO - PROCESS | 2892 | 19:22:03 INFO - PROCESS | 2892 | Cycle 1(1): loaded http://localhost:56279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56279/tests/dromaeo/cssquery-prototype.html) 19:22:04 INFO - PROCESS | 2892 | RSS: Main: 277716992 19:22:04 INFO - PROCESS | 2892 | 19:25:07 INFO - PROCESS | 2892 | Cycle 1(1): loaded http://localhost:56279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56279/tests/dromaeo/cssquery-yui.html) 19:25:07 INFO - PROCESS | 2892 | RSS: Main: 279896064 19:25:07 INFO - PROCESS | 2892 | 19:28:10 INFO - PROCESS | 2892 | Cycle 1(1): loaded http://localhost:56279/tests/dromaeo/cssquery-yui.html (next: http://localhost:56279/tests/dromaeo/cssquery-dojo.html) 19:28:10 INFO - PROCESS | 2892 | RSS: Main: 281382912 19:28:10 INFO - PROCESS | 2892 | 19:28:10 INFO - PROCESS | 2892 | __start_tp_report 19:28:10 INFO - PROCESS | 2892 | _x_x_mozilla_page_load 19:28:10 INFO - PROCESS | 2892 | _x_x_mozilla_page_load_details 19:28:10 INFO - PROCESS | 2892 | |i|pagename|runs| 19:28:10 INFO - PROCESS | 2892 | |0;dojo.html;3439.4324936385497;3460.3079384123166;3493.4977959477415;3494.109002204439;3524.136586536299;5754.913676294859;5866.973279676061;5874.559408044398;5887.205227294314;5903.498202652774;5687.857803554903;5834.27071616048;5858.5606079543995;5870.530357571395;5958.642481451116;5248.02911461379;5296.311479507678;5350.892982140354;5381.67709937403;5411.83764487066;276.3604948478507;5323.574114070884;5325.520703136717;5362.865928351784;5392.487713667196;4552.317152427129;5314.256004159421;5337.599680024014;5357.866053348678;5405.891882162374;5199.974000129975;5328.0675881720545;5347.732613369316;5352.357717073937;5409.350877894639;5161.406438259611;5239.502247286502;5266.289050978107;5331.466853314638;5341.385740639837;5706.600537962344;5744.82765517031;5773.422657734235;5812.418758124154;5850.502707269887;5343.225232341289;6185.474234690057;6230.626162430269;6257.217847769029;6276.654783986879;5371.704556249405;6090.243817847612;6267.592606480564;6277.497800175975;6281.528885333572;6027.969860150672;6032.698365081728;6039.426254505903;6049.637021778707;6049.909251361233;5265.842024739264;6278.654673992883;6310.810675679737;6436.452901503425;6457.418832305021;6193.566450348432;6195.721192546346;6200.193974783339;6207.875842483125;6220.098085777537;6182.196314479089;6250.218722659668;6271.6550589718;6272.749090036347;6288.213973253343;6108.22065524731;6122.947104152868;6138.232720909887;6159.1069294951985;6180.47465965398;5051.6716413433005;6125.47964319526;6176.88816013122;6192.195014648067;6211.378862113753;5007.123753343223;5022.74886255693;5027.095122877844;5029.5724863386295;5045.117104506697;3708.4437334400054;4401.757903315349;4447.310666846644;4506.008678090815;4582.679212455096;5844.415558444121;5985.760569577168;6053.818385448443;6055.545834062463;6070.817875463743;6248.968755156195;6267.65527895961;6273.749050037947;6280;6372.980323148274;4670.276107203389;5977.983742763654;5993.190919225849;6003.489703375175;6027.6684782336515;4633.2818413146015;4913;5048.949510504922;5498.257735205776;5522.530584900329;6266.154069200782;6447.355264473609;6453.903191452132;6464.191976002906;6481.935180648227;6174.660393678211;6195.566310358232;6220.502359811114;6271.4669253114;6280.214973128359;6439.935600644121;6452.193475815523;6458;6480.449161821298;6561.1470508833545;6119.388061193845;6197.380261973766;6242.563020588515;6259;6268.7492500301305;6183.721732522137;6241.531885108726;6248.937510624835;6257.56197066201;6268.27914789805;6231.189945307081;6250.656213908279;6268.780592679234;6287.905681414691;6302.590331628429;6086.239220097488;6106.824719425016;6139.706800529493;6169.753209871555;6174.228221472316;4890.877728056827;5935.792247271324;5974;6142.201513803177;6142.375998160927;6181.783637572664;6233.065040243819;6252.030935205051;6266.248050234001;6271.6550589718;5833.979675039257;6038;6131.754729810936;6142.723577438936;6206.255249370104 19:28:10 INFO - PROCESS | 2892 | |1;ext.html;487.54901715912774;510.44361645806066;529.8913722686849;537.0360203434835;537.2210295072147;38901.610983890154;41442.75671729853;41506.99999999996;41538.792306038464;41615.95960100991;42124.78937605308;42437.939051523725;42738.57261427385;42771.78614106933;42916;9548.80902381952;9616.903830961706;9763.463009534473;9772.560234789451;9774.706758797212;7340.486165968385;8119.472234304781;8587.699430519933;9666.903330966707;9699.03009699028;9724.805503889918;9728.221742260637;9733;9741.172000379976;9747.90252097477;8311.459755115899;9636.469994150319;9656.80686386272;9668.371555848882;9695.66065187722;9527;9538.856917146248;9603.567839447172;9609.231261499064;9632.903670963271;39359.21281574381;39504.604953950664;39694.610688626046;39836.60163398358;39871.8006409969;2806.9859650701615;2937.941241175186;3067.3251884585356;3141.026281852633;3364.8654053838063;26035.609465857837;27037.459250814874;27654.308642283904;27905.441891162267;28649.427011459655;2507.6489291499206;2526.5325914705836;2575.394782226194;2581.2256323102993;2627.8423294602385;297888;371527;375852;377951.11024444975;379043;34163;34971.125721856646;35137.648623513436;35194.82402587971;35994.64005359965;31075.844620776752;36956.63043369586;37057.629423705956;37105.44341834875;37163.628363716554;15348.693026139415;15488.22558872052;15505;15511;15517.689646207013;10848.511816968256;12939.5471158509;12974.286414247306;13035.869641303465;13047.086703930634;8192.795180120425;8281.047679516812;8281.8343633127;8316.209960053786;8339.583020848933;3366.2762506060826;3373.7132343751055;3416.743744219144;3432.2105915639545;3449.9827500862334;907.8093600344001;979.1579241852002;1022.3150489172273;1025.4975062219607;1168.5325869652206;22938.42653933665;29640.555391669142;29792.553111703342;30229.79080836786;30482.390352193277;975.6341371985505;1095.1403148528368;1101.1191047162238;1107.5071593141136;1144.862616486027;13709.451621935012;15281.38874445032;15498.147601882005;15557.45548905783;15662.060276383454;598854;664127;672080;672640;673812.6309368422;58994.41005589975;59410.70294648499;59656.403435965956;59705;59867.70066149641;1393.5610282760897;1444.8555144485676;1446.2913172545377;1478.031889112633;1481.7999570057905;30142;30174.547381788852;30219.244518887204;30255.148296852425;30410.03127315037;50669;50676.98646027133;51159.74420128024;51453;51674.224886625976;512130;516616;517327;518825.4058729682;518858;26835.597466037634;26938.922443102056;27286.317842053315;27624.75688593978;27670.308242293297;34476.48285275673;45091.54908451005;45145.32282015707;45202.54797451983;45716.54283457123;36901;37128.443073354465;37179.07052323713;37398.439023414125;37553.6244637561;36572.451413229355;36946.44580331351;36984.26031479301;39953.60046399499;41207.79396103;37836.62163378441;39971.40042899417;40172.1965560693;40183.1963360737;40522.1895562093;38657.613423865405;40093.79953100332;40874.59125408827;41025.79487102546;41089.79455102705;30354;30730;30746.538801918436;31411.52882706807;31475.213119672193 19:28:10 INFO - PROCESS | 2892 | |2;jquery.html;389.94540764293004;393.498289680657;393.57690482731067;396.4964495091236;417.21979897591507;14593.562193134223;16487.83512164878;20747.896260518675;21351.57296854064;21376.89311553442;20521.692174617394;20748.377548673565;21057.26299579511;21318.360449186548;21555.784442155575;4744.525547445262;5812.796552120666;5837.182794408786;5838.678872662002;5902.527797776168;5375.596830237729;5772.740226689809;5778.942210577903;5780.595358324919;5819.7672093116225;5627.352854421732;5815.098659707731;5831.5626328025355;5860.912086318709;6346.682665866711;5077.8067154218725;6342.682865856688;6362.077498762654;6365.52258580606;6388.712507937131;5287.4448182941005;5681.715914204273;5727.799527016576;5730.885382292373;5739.942600573982;13214.66963325915;13504.797428038588;13745.725085498334;13782.793258101035;13822.239776812272;1856.675081860669;2068.7103805467245;2134.530403311269;2372.7851598866873;2707.6073969274344;9960.352577082467;10002.849957250646;10197.031282028196;10261.43562104084;14503.1298122113;1492.843251458603;1702.9148542572946;1733.0554847607957;1748.038578781665;1756.9648607027916;102971;108419.37370939442;118008.40995795051;119813;138950.30524847412;9902.405855648683;9952.402855828528;11872.228305160137;11927.940360298317;12152;14964.476243331648;15196.392144314104;15258.31337589789;15276.38894444232;15353.232338383035;7376.225496322916;8695.869561956575;8853.07042760501;8930.776730581658;9023.187913087853;7362.564299961563;8684.60919258635;8860.512671803113;8964.462132272085;8968.506732129796;3882.4758657580865;4637.281221410687;4692.577668009895;4723.362346083235;4731.313959475857;1647.4975132584677;1869.6260747850483;1980.2475059477306;2001.8398528117716;2006.1273346094204;817.9100298967109;818.6725309876098;821.7000794710008;969.471637957315;1007.2093406675871;19953.201871925252;20628.79371206269;20640.793592064187;20796.688049679266;20805.687914681293;871.4988881393098;880.9119088091139;966.3042609321277;968.162539403411;979.8187335342984;7349.63251837406;10920.945395272973;10967.780644387067;10976.780464390828;11005.889941100646;556403;607771;608099;612986;618532;125185.7481425174;128681;142943.85584216245;145767;145820;986.4968865878453;1003.5484032185545;1010.5856598794459;1024.7438140465033;1028.7685270814006;15818.68362632764;17585.736213957058;18083;18440.35458758937;18684.065796710383;52399.21401179062;53938.46061539334;55209.447905522036;55295.17057244064;56170.59573510695;80190.19809802061;80319.59840200761;80573.59713201631;80596.59701701454;81025.59487202526;8070.6368213431715;8214.958925205336;8291.253787159185;8519.446235994641;8587.441816281922;11497.367644779617;12539.93730031344;12653.367331633197;12853.16454430459;12942.417591208607;10266.281360305005;10958.506867190836;11051;11309.830352544554;11457.942710286728;10834.512446940063;10891.346519208555;11115.388653624128;11128.1653875958;11340.489677964677;11309.604163854226;11329.206955513035;11348.602798901999;11390.544378224778;11596;6881.724731010904;7155.785326440215;8171;8297.751067467985;8603.741887743377;10328.948355258175;10921;10961.890381096087;11140.442977851237;11269.605563805555 19:28:10 INFO - PROCESS | 2892 | |3;mootools.html;1543.2052492966125;1623.9025658460494;1892.7823300320458;1899.876508026979;1965.4594986378754;7407.666655000525;7888.96055519723;8507.489550626966;9039.050899655533;9055.366124371296;7221.313975172363;8771.56142192891;8867;8882.644694212224;8913.866292005625;2135.7826039157726;2393.56915755164;2493.7256901740793;2496.6504689343506;2503.7371076037025;2075.325519206264;2444.5844206484853;2467.753224677536;2490.1533478617266;2501.6122501012283;2467.4571594249237;2468.8518688878726;2485.6768620079365;2496.325991982164;2497.500499900018;2049.897505124738;2343.742188359288;2426.3812727754485;2456.6069428891383;2463.987680061606;2408.771166739157;2430.5138972205627;2431.3192306154306;2435.57377458944;2450.215930902112;8774.868376974351;8946.284297256203;8958.148975847284;8962.596683149273;8995.55022248885;5374.167004114369;5505.394406615269;5569.66582005081;5584.274044374205;5608.663480191201;3602.423612222058;3615.2407994321225;3618;3678.797666128362;3701.851925922987;845.8096928191108;888.9199972002486;1013.4881884648279;1032.4270030133273;1033.2405681823852;11536.480858361394;11701.473433695504;11706.297622142783;11747.353895535742;11764.000059994825;4830.275458681226;4870.172070747911;4970.478099799541;4985.975070124626;5056.342675452168;10037.79924401508;10061.496925153713;10073.949630251802;10079.596816127272;10086.546105425274;4642.814287428465;5485.807996720096;5525.778968841281;5526.088195447786;5526.309211348582;4092.836286548564;4423.8230470781455;4827.831025914076;4994.650374473752;5030.496950304941;4064.9796751016056;4097.720157854111;4133.8966525837095;4156.293430116887;4159.10579225462;1351.72965406919;1374.0725010617793;1380.6065271397804;1384.0173476831562;1390.242317936727;873.2926329672986;899.3030401438937;922.4649703172278;963.1476143612813;971.9805603887883;5113.590912726973;5861.853453663693;5975.880482390327;6070.605410648293;6092.786752463642;463.81215607678945;470.7034568221998;477.5773440505178;484.056090623286;485.32782096796194;3922.2547715933883;3996.2606917720314;4002.679785617202;4070.2063097695664;4095.627964631822;10237.692869213935;10730.248882578144;10788.676339709822;10798.406087665255;10829.729256768485;10547.683569492927;10685.57257709683;10756.946215268874;10769.299995500423;10786.352818830896;7817.413693972975;8176.223258790405;8323.66705331792;8325.084240733393;8325.8334833303;4756.857294281177;5127.897442051213;5711.485966263056;5950.523958083257;6180.412860778219;9573;9821.116099551075;10134.442605656759;10135.695929122137;10162.898371016196;9731.562079706571;10061.239283125278;10132.138768204784;10254.436006019741;10363.170946324126;7270.781876543712;7525.58609276495;7636.923630763621;7642.388608911163;7656.885146722692;8446.450980686235;8496;8536;8546.78633034179;8552.828943420973;6852.554583952027;6927.445804335742;6972.477064220305;7073.186583542958;7100.183478899786;6153.84615384619;6331.113644089739;6491.766564352947;6569.967150164219;6876.312368763284;6958;7050.471214659024;7132;7145.1068616422945;7163.785086447415;4944.258361245842;4977.800887964441;5012.899742005213;5024.145895197752;5051.419086805065;4670.042641258602;4822.276658501253;4878.731669758197;4889.071076495455;5035.144025515744 19:28:10 INFO - PROCESS | 2892 | |4;prototype.html;310.469097842689;317.17059888391856;322.29578371258793;328.4514860183493;329.1376593325486;4668.743219122947;5094.057599344119;5222.90319033003;5433.320834895638;5720.2849643794525;5123.12906805843;5397.109476936311;5605.747741351639;5609.887802243953;5638.7445021991225;3596.4785106159593;4245.41029971717;4261.318189089746;4261.722988005785;4265.445492086024;4026.7315795524464;4115.753054816721;4179.958200417987;4264.23243816113;4283.828646854122;3979.845844705032;4153.06556024895;4222.577742225783;4234.640055595262;4252.893677658052;4261.531231564525;4265.296226122686;4272.935905961397;4288.69979101463;4295.398644189816;2914.489964256247;3251.3659836331926;3268.7548433867555;3645.8645564814237;3711.5731690855628;4537.591616754475;4765.8791837631925;4953;4959.189895688084;5295.126304159848;4342;4623.398958135421;4871.930683669266;4893.657443978923;4943;5331.2003199519995;5581.888362232733;5672.978863804555;5751.325633580405;5928.28860536734;2720.7551320381062;3900.7854567998747;4004.939925901114;4013.538443079025;4029.133736246721;6207.7206525706;6305;6306;6314.305426403092;6331.398517140864;3606.6032736398975;3617.312710585033;4022;4070.470838790938;4096.426500289962;3667.413213885819;3717.4052151655605;3775.5091838061426;4104.56902025289;4157.417961485395;4035.1324465240114;4717.410323709536;4779.474257831635;4808.3027960945465;4870.756462176877;4863.781129849165;5038.866255092575;5291.550218231493;5312.468753124734;5999.340072592011;1141.560499207811;1208.8186771984272;1228.2139430764319;1287.660832733946;1333.4332908513843;756.9016027916335;879.8284334554828;895.8488342479882;896.3591032411857;905.343625871238;4476.574725401082;4610.654200934943;4764.97553026101;4937.679050861682;5176.738654647624;5002.849914502571;5123.949590333973;5265.605079619044;5869.35437101927;5951.880962380728;4340.739555626673;4714.218611019515;4865.9026819463415;5066.36670416198;5230.593926793641;2685.8719337878138;2874.9712502875122;2975.152081656761;3014.3217776000665;3097.8915737949064;5375.731213439312;6056.485878530368;6161.568690191643;6196.645274795549;6229.657368844757;5312.556224136941;5369.590310153081;5569.3823902765635;5828.795992140254;6203.96898015507;5455.918161227585;5515.365732940683;6225.688715564113;6244.843878903063;6251.9684252097895;5373.650712703662;5734.59857809966;5898.4101589840675;5939;5949.286085669574;5370.409254982027;5732.283464566929;5805.593608447368;5819.301683797971;5868.2958045033165;5628.60599758013;5778.139820788737;5826.446487583673;6216.326979398902;6242.094896240111;3726.9068273293383;3829.5787463379565;3865.9420108697827;4040.1213435751342;4064.4394031760985;5131.794028403397;6104.572679912541;6113.209809917879;6131.662758548323;6144.508439324753;5116.692998420107;5264.131418315935;5687.488126068673;5966.224390829165;6010.549208809444;5721.342045664802;5722.942770572241;5940.55445841555;5963.344032156372;6004.39956004396;5641.206544475298;5650.547956163579;5722.856928576819;5803.3616302207565;5817.621854579439;5928.110783382354;5976.641401515923;5999.790007349722;6028.819135425769;6044.727987240497;4865.878353041061;5572.244742905972;5807.593468457337;5846.895117757519;5895.29256489224 19:28:10 INFO - PROCESS | 2892 | |5;yui.html;659.5449140093335;748.7678819565931;755.1881727143319;759.3507551043854;769.5036701327642;12492.750724927502;13676.111052781574;14036.719265614694;14038.087524310939;14049.648758781035;10878.891211087906;12950.546730864422;12985.350732463387;13013.284269365182;13036.283004434752;700.6286668065933;874.715717391847;880.1770344727668;905.7056456651582;912.0469109780296;860.7632900952257;868.4702331577732;886.171429713217;892.9598168082451;893.0578239956875;946.3990366117531;962.5620342744035;963.3593660215982;966.9274804389664;966.9613215471372;957.1385752822481;957.4159762544842;957.7940742740275;961.8749562556893;962.3263715399214;588.6468119128532;709.3225969199402;710.1442761472414;711.1999001123736;713.1264201353353;11552.306861588331;11850.407479625985;13545.796813047813;13695.493495715464;13743.518976835863;11850.3482308473;11921.165518413714;11954.282743035446;12044.096692748079;12090.214136081127;9376.546754043831;9405.858912116255;9433.622655093788;9469.479178645171;9763.951180244054;3029.59100521429;3222.91942701432;3222.9677703223138;3236.0453666168537;3295;81184.18815811825;81682.18317816805;82245.58877205575;82517;82593;9143.54282285883;9173.036831132635;9249.12133347331;9400.576974036185;9462.00648931866;19021.524461888563;19208.519787005436;19267.325643602686;19268;19547.31584394568;6167.136600875881;6829.590224586541;6879.174499060145;6907.447404207652;6914.997325387791;3653.780773153619;3669.9816500917323;3779.6771130104244;3864.8840534784003;3935.0359162005493;3149.102505785886;3257.5928008998876;3258.1528802511516;3258.951115733266;3267.379197952381;646.3052218864739;664.0669858848325;666.4501786026548;676.5061505101304;685.1538350137577;473.5974421741492;517.1389636255592;573.6214098694842;606.4541912278896;609.5732986909181;7447.925520744831;7767.145613982458;7885.921140788519;8001.839963200703;8163.877541836877;557.2282388891415;566.3265051772004;575.5050656435462;583.3932709981657;592.1532208941255;3708.962910370916;3768.5854555998817;3782.243551289698;3818.5035945327486;3867.477890484805;71089;73724.63137684384;73774.63112684402;93637;94475;74290;74577.62711186409;74880;75360.62319688367;75548;56658.716706416206;60048.49878753066;62788;63482.41293967595;69591.30408695848;8263.83472330538;8305.335573154254;8348.833023339621;8351;8363.243718819116;43297.78351108224;43408.34887476627;43790;43906.12187756291;44696.10607787891;43517.34723979206;59429;59724;60177;60277;10738.087262582769;10927.672169834917;11069.280496767684;11161.665150045512;11332.830007549728;11744.3334364677;11990.940045299718;12068.939655301667;12146.75706485883;12286;11504.82742758876;11686;11693.824592631288;11763.882361176278;12075.27548347102;9387.906120938704;11443;11519.19365644397;11604.825927611262;12057.638270851889;11281.548738050386;11381.317120972768;11698.239614425034;11706.356150411468;11746.58886938953;9285.70000199959;9337.673181438617;9703.466309353053;9819.36174148678;9920.255980801325;8200.466969646954;9602.423854568748;9831.115199632068;9886.456244906312;10094.242931780293 19:28:10 INFO - PROCESS | 2892 | __end_tp_report 19:28:10 INFO - PROCESS | 2892 | __start_cc_report 19:28:10 INFO - PROCESS | 2892 | _x_x_mozilla_cycle_collect,418 19:28:10 INFO - PROCESS | 2892 | __end_cc_report 19:28:10 INFO - PROCESS | 2892 | __startTimestamp1462674490615__endTimestamp 19:28:10 INFO - PROCESS | 2892 | 19:28:10 INFO - PROCESS | 2892 | ------- Summary: start ------- 19:28:10 INFO - PROCESS | 2892 | Number of tests: 6 19:28:10 INFO - PROCESS | 2892 | 19:28:10 INFO - PROCESS | 2892 | [#0] dojo.html Cycles:165 Average:5754.59 Median:6055.55 stddev:791.30 (13.1%) stddev-sans-first:772.60 19:28:10 INFO - PROCESS | 2892 | Values: 3439.4 3460.3 3493.5 3494.1 3524.1 5754.9 5867.0 5874.6 5887.2 5903.5 5687.9 5834.3 5858.6 5870.5 5958.6 5248.0 5296.3 5350.9 5381.7 5411.8 276.4 5323.6 5325.5 5362.9 5392.5 4552.3 5314.3 5337.6 5357.9 5405.9 5200.0 5328.1 5347.7 5352.4 5409.4 5161.4 5239.5 5266.3 5331.5 5341.4 5706.6 5744.8 5773.4 5812.4 5850.5 5343.2 6185.5 6230.6 6257.2 6276.7 5371.7 6090.2 6267.6 6277.5 6281.5 6028.0 6032.7 6039.4 6049.6 6049.9 5265.8 6278.7 6310.8 6436.5 6457.4 6193.6 6195.7 6200.2 6207.9 6220.1 6182.2 6250.2 6271.7 6272.7 6288.2 6108.2 6122.9 6138.2 6159.1 6180.5 5051.7 6125.5 6176.9 6192.2 6211.4 5007.1 5022.7 5027.1 5029.6 5045.1 3708.4 4401.8 4447.3 4506.0 4582.7 5844.4 5985.8 6053.8 6055.5 6070.8 6249.0 6267.7 6273.7 6280.0 6373.0 4670.3 5978.0 5993.2 6003.5 6027.7 4633.3 4913.0 5048.9 5498.3 5522.5 6266.2 6447.4 6453.9 6464.2 6481.9 6174.7 6195.6 6220.5 6271.5 6280.2 6439.9 6452.2 6458.0 6480.4 6561.1 6119.4 6197.4 6242.6 6259.0 6268.7 6183.7 6241.5 6248.9 6257.6 6268.3 6231.2 6250.7 6268.8 6287.9 6302.6 6086.2 6106.8 6139.7 6169.8 6174.2 4890.9 5935.8 5974.0 6142.2 6142.4 6181.8 6233.1 6252.0 6266.2 6271.7 5834.0 6038.0 6131.8 6142.7 6206.3 19:28:10 INFO - PROCESS | 2892 | 19:28:10 INFO - PROCESS | 2892 | [#1] ext.html Cycles:180 Average:63704.09 Median:208439.81 stddev:140876.47 (67.6%) stddev-sans-first:141191.70 19:28:10 INFO - PROCESS | 2892 | Values: 487.5 510.4 529.9 537.0 537.2 38901.6 41442.8 41507.0 41538.8 41616.0 42124.8 42437.9 42738.6 42771.8 42916.0 9548.8 9616.9 9763.5 9772.6 9774.7 7340.5 8119.5 8587.7 9666.9 9699.0 9724.8 9728.2 9733.0 9741.2 9747.9 8311.5 9636.5 9656.8 9668.4 9695.7 9527.0 9538.9 9603.6 9609.2 9632.9 39359.2 39504.6 39694.6 39836.6 39871.8 2807.0 2937.9 3067.3 3141.0 3364.9 26035.6 27037.5 27654.3 27905.4 28649.4 2507.6 2526.5 2575.4 2581.2 2627.8 297888.0 371527.0 375852.0 377951.1 379043.0 34163.0 34971.1 35137.6 35194.8 35994.6 31075.8 36956.6 37057.6 37105.4 37163.6 15348.7 15488.2 15505.0 15511.0 15517.7 10848.5 12939.5 12974.3 13035.9 13047.1 8192.8 8281.0 8281.8 8316.2 8339.6 3366.3 3373.7 3416.7 3432.2 3450.0 907.8 979.2 1022.3 1025.5 1168.5 22938.4 29640.6 29792.6 30229.8 30482.4 975.6 1095.1 1101.1 1107.5 1144.9 13709.5 15281.4 15498.1 15557.5 15662.1 598854.0 664127.0 672080.0 672640.0 673812.6 58994.4 59410.7 59656.4 59705.0 59867.7 1393.6 1444.9 1446.3 1478.0 1481.8 30142.0 30174.5 30219.2 30255.1 30410.0 50669.0 50677.0 51159.7 51453.0 51674.2 512130.0 516616.0 517327.0 518825.4 518858.0 26835.6 26938.9 27286.3 27624.8 27670.3 34476.5 45091.5 45145.3 45202.5 45716.5 36901.0 37128.4 37179.1 37398.4 37553.6 36572.5 36946.4 36984.3 39953.6 41207.8 37836.6 39971.4 40172.2 40183.2 40522.2 38657.6 40093.8 40874.6 41025.8 41089.8 30354.0 30730.0 30746.5 31411.5 31475.2 19:28:10 INFO - PROCESS | 2892 | 19:28:10 INFO - PROCESS | 2892 | [#2] jquery.html Cycles:180 Average:35094.61 Median:11743.05 stddev:100699.36 (857.5%) stddev-sans-first:100948.14 19:28:10 INFO - PROCESS | 2892 | Values: 389.9 393.5 393.6 396.5 417.2 14593.6 16487.8 20747.9 21351.6 21376.9 20521.7 20748.4 21057.3 21318.4 21555.8 4744.5 5812.8 5837.2 5838.7 5902.5 5375.6 5772.7 5778.9 5780.6 5819.8 5627.4 5815.1 5831.6 5860.9 6346.7 5077.8 6342.7 6362.1 6365.5 6388.7 5287.4 5681.7 5727.8 5730.9 5739.9 13214.7 13504.8 13745.7 13782.8 13822.2 1856.7 2068.7 2134.5 2372.8 2707.6 9960.4 10002.8 10197.0 10261.4 14503.1 1492.8 1702.9 1733.1 1748.0 1757.0 102971.0 108419.4 118008.4 119813.0 138950.3 9902.4 9952.4 11872.2 11927.9 12152.0 14964.5 15196.4 15258.3 15276.4 15353.2 7376.2 8695.9 8853.1 8930.8 9023.2 7362.6 8684.6 8860.5 8964.5 8968.5 3882.5 4637.3 4692.6 4723.4 4731.3 1647.5 1869.6 1980.2 2001.8 2006.1 817.9 818.7 821.7 969.5 1007.2 19953.2 20628.8 20640.8 20796.7 20805.7 871.5 880.9 966.3 968.2 979.8 7349.6 10920.9 10967.8 10976.8 11005.9 556403.0 607771.0 608099.0 612986.0 618532.0 125185.7 128681.0 142943.9 145767.0 145820.0 986.5 1003.5 1010.6 1024.7 1028.8 15818.7 17585.7 18083.0 18440.4 18684.1 52399.2 53938.5 55209.4 55295.2 56170.6 80190.2 80319.6 80573.6 80596.6 81025.6 8070.6 8215.0 8291.3 8519.4 8587.4 11497.4 12539.9 12653.4 12853.2 12942.4 10266.3 10958.5 11051.0 11309.8 11457.9 10834.5 10891.3 11115.4 11128.2 11340.5 11309.6 11329.2 11348.6 11390.5 11596.0 6881.7 7155.8 8171.0 8297.8 8603.7 10328.9 10921.0 10961.9 11140.4 11269.6 19:28:10 INFO - PROCESS | 2892 | 19:28:10 INFO - PROCESS | 2892 | [#3] mootools.html Cycles:180 Average:5693.37 Median:2657.17 stddev:3192.80 (120.2%) stddev-sans-first:3186.52 19:28:10 INFO - PROCESS | 2892 | Values: 1543.2 1623.9 1892.8 1899.9 1965.5 7407.7 7889.0 8507.5 9039.1 9055.4 7221.3 8771.6 8867.0 8882.6 8913.9 2135.8 2393.6 2493.7 2496.7 2503.7 2075.3 2444.6 2467.8 2490.2 2501.6 2467.5 2468.9 2485.7 2496.3 2497.5 2049.9 2343.7 2426.4 2456.6 2464.0 2408.8 2430.5 2431.3 2435.6 2450.2 8774.9 8946.3 8958.1 8962.6 8995.6 5374.2 5505.4 5569.7 5584.3 5608.7 3602.4 3615.2 3618.0 3678.8 3701.9 845.8 888.9 1013.5 1032.4 1033.2 11536.5 11701.5 11706.3 11747.4 11764.0 4830.3 4870.2 4970.5 4986.0 5056.3 10037.8 10061.5 10073.9 10079.6 10086.5 4642.8 5485.8 5525.8 5526.1 5526.3 4092.8 4423.8 4827.8 4994.7 5030.5 4065.0 4097.7 4133.9 4156.3 4159.1 1351.7 1374.1 1380.6 1384.0 1390.2 873.3 899.3 922.5 963.1 972.0 5113.6 5861.9 5975.9 6070.6 6092.8 463.8 470.7 477.6 484.1 485.3 3922.3 3996.3 4002.7 4070.2 4095.6 10237.7 10730.2 10788.7 10798.4 10829.7 10547.7 10685.6 10756.9 10769.3 10786.4 7817.4 8176.2 8323.7 8325.1 8325.8 4756.9 5127.9 5711.5 5950.5 6180.4 9573.0 9821.1 10134.4 10135.7 10162.9 9731.6 10061.2 10132.1 10254.4 10363.2 7270.8 7525.6 7636.9 7642.4 7656.9 8446.5 8496.0 8536.0 8546.8 8552.8 6852.6 6927.4 6972.5 7073.2 7100.2 6153.8 6331.1 6491.8 6570.0 6876.3 6958.0 7050.5 7132.0 7145.1 7163.8 4944.3 4977.8 5012.9 5024.1 5051.4 4670.0 4822.3 4878.7 4889.1 5035.1 19:28:10 INFO - PROCESS | 2892 | 19:28:10 INFO - PROCESS | 2892 | [#4] prototype.html Cycles:180 Average:4664.95 Median:5127.87 stddev:1471.40 (28.7%) stddev-sans-first:1438.77 19:28:10 INFO - PROCESS | 2892 | Values: 310.5 317.2 322.3 328.5 329.1 4668.7 5094.1 5222.9 5433.3 5720.3 5123.1 5397.1 5605.7 5609.9 5638.7 3596.5 4245.4 4261.3 4261.7 4265.4 4026.7 4115.8 4180.0 4264.2 4283.8 3979.8 4153.1 4222.6 4234.6 4252.9 4261.5 4265.3 4272.9 4288.7 4295.4 2914.5 3251.4 3268.8 3645.9 3711.6 4537.6 4765.9 4953.0 4959.2 5295.1 4342.0 4623.4 4871.9 4893.7 4943.0 5331.2 5581.9 5673.0 5751.3 5928.3 2720.8 3900.8 4004.9 4013.5 4029.1 6207.7 6305.0 6306.0 6314.3 6331.4 3606.6 3617.3 4022.0 4070.5 4096.4 3667.4 3717.4 3775.5 4104.6 4157.4 4035.1 4717.4 4779.5 4808.3 4870.8 4863.8 5038.9 5291.6 5312.5 5999.3 1141.6 1208.8 1228.2 1287.7 1333.4 756.9 879.8 895.8 896.4 905.3 4476.6 4610.7 4765.0 4937.7 5176.7 5002.8 5123.9 5265.6 5869.4 5951.9 4340.7 4714.2 4865.9 5066.4 5230.6 2685.9 2875.0 2975.2 3014.3 3097.9 5375.7 6056.5 6161.6 6196.6 6229.7 5312.6 5369.6 5569.4 5828.8 6204.0 5455.9 5515.4 6225.7 6244.8 6252.0 5373.7 5734.6 5898.4 5939.0 5949.3 5370.4 5732.3 5805.6 5819.3 5868.3 5628.6 5778.1 5826.4 6216.3 6242.1 3726.9 3829.6 3865.9 4040.1 4064.4 5131.8 6104.6 6113.2 6131.7 6144.5 5116.7 5264.1 5687.5 5966.2 6010.5 5721.3 5722.9 5940.6 5963.3 6004.4 5641.2 5650.5 5722.9 5803.4 5817.6 5928.1 5976.6 5999.8 6028.8 6044.7 4865.9 5572.2 5807.6 5846.9 5895.3 19:28:10 INFO - PROCESS | 2892 | 19:28:10 INFO - PROCESS | 2892 | [#5] yui.html Cycles:180 Average:16970.58 Median:30441.73 stddev:23685.06 (77.8%) stddev-sans-first:23719.84 19:28:10 INFO - PROCESS | 2892 | Values: 659.5 748.8 755.2 759.4 769.5 12492.8 13676.1 14036.7 14038.1 14049.6 10878.9 12950.5 12985.4 13013.3 13036.3 700.6 874.7 880.2 905.7 912.0 860.8 868.5 886.2 893.0 893.1 946.4 962.6 963.4 966.9 967.0 957.1 957.4 957.8 961.9 962.3 588.6 709.3 710.1 711.2 713.1 11552.3 11850.4 13545.8 13695.5 13743.5 11850.3 11921.2 11954.3 12044.1 12090.2 9376.5 9405.9 9433.6 9469.5 9764.0 3029.6 3222.9 3223.0 3236.0 3295.0 81184.2 81682.2 82245.6 82517.0 82593.0 9143.5 9173.0 9249.1 9400.6 9462.0 19021.5 19208.5 19267.3 19268.0 19547.3 6167.1 6829.6 6879.2 6907.4 6915.0 3653.8 3670.0 3779.7 3864.9 3935.0 3149.1 3257.6 3258.2 3259.0 3267.4 646.3 664.1 666.5 676.5 685.2 473.6 517.1 573.6 606.5 609.6 7447.9 7767.1 7885.9 8001.8 8163.9 557.2 566.3 575.5 583.4 592.2 3709.0 3768.6 3782.2 3818.5 3867.5 71089.0 73724.6 73774.6 93637.0 94475.0 74290.0 74577.6 74880.0 75360.6 75548.0 56658.7 60048.5 62788.0 63482.4 69591.3 8263.8 8305.3 8348.8 8351.0 8363.2 43297.8 43408.3 43790.0 43906.1 44696.1 43517.3 59429.0 59724.0 60177.0 60277.0 10738.1 10927.7 11069.3 11161.7 11332.8 11744.3 11990.9 12068.9 12146.8 12286.0 11504.8 11686.0 11693.8 11763.9 12075.3 9387.9 11443.0 11519.2 11604.8 12057.6 11281.5 11381.3 11698.2 11706.4 11746.6 9285.7 9337.7 9703.5 9819.4 9920.3 8200.5 9602.4 9831.1 9886.5 10094.2 19:28:10 INFO - PROCESS | 2892 | -------- Summary: end -------- 19:28:10 INFO - PROCESS | 2892 | 19:28:11 INFO - TEST-INFO | 2892: exit 0 19:28:11 INFO - TEST-OK | dromaeo_css | took 1112345ms 19:28:11 INFO - TEST-START | kraken 19:28:11 INFO - operating with platform_type : mac_ 19:28:11 INFO - Initialising browser for kraken test... 19:28:11 INFO - TEST-INFO | started process 2896 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpbb6jaD/profile http://localhost:56279/getInfo.html) 19:28:14 INFO - PROCESS | 2896 | __metrics Screen width/height:1600/1200 19:28:14 INFO - PROCESS | 2896 | colorDepth:24 19:28:14 INFO - PROCESS | 2896 | Browser inner width/height: 1024/689 19:28:14 INFO - PROCESS | 2896 | __metrics 19:28:14 ERROR - PROCESS | 2896 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 19:28:14 INFO - TEST-INFO | 2896: exit 0 19:28:14 INFO - Browser initialized. 19:28:14 INFO - Running cycle 1/1 for kraken test... 19:28:14 INFO - TEST-INFO | started process 2899 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpbb6jaD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:28:26 INFO - PROCESS | 2899 | RSS: Main: 250175488 19:28:26 INFO - PROCESS | 2899 | 19:28:29 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56279/tests/kraken/driver.html?testName=audio-beat-detection) 19:28:30 INFO - PROCESS | 2899 | RSS: Main: 261885952 19:28:30 INFO - PROCESS | 2899 | 19:28:33 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56279/tests/kraken/driver.html?testName=audio-dft) 19:28:33 INFO - PROCESS | 2899 | RSS: Main: 266559488 19:28:33 INFO - PROCESS | 2899 | 19:28:37 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56279/tests/kraken/driver.html?testName=audio-fft) 19:28:38 INFO - PROCESS | 2899 | RSS: Main: 266919936 19:28:38 INFO - PROCESS | 2899 | 19:28:40 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56279/tests/kraken/driver.html?testName=audio-oscillator) 19:28:40 INFO - PROCESS | 2899 | RSS: Main: 268013568 19:28:40 INFO - PROCESS | 2899 | 19:28:43 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:28:43 INFO - PROCESS | 2899 | RSS: Main: 267972608 19:28:43 INFO - PROCESS | 2899 | 19:28:46 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56279/tests/kraken/driver.html?testName=imaging-darkroom) 19:28:47 INFO - PROCESS | 2899 | RSS: Main: 268062720 19:28:47 INFO - PROCESS | 2899 | 19:28:50 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56279/tests/kraken/driver.html?testName=imaging-desaturate) 19:28:51 INFO - PROCESS | 2899 | RSS: Main: 268349440 19:28:51 INFO - PROCESS | 2899 | 19:28:53 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56279/tests/kraken/driver.html?testName=json-parse-financial) 19:28:54 INFO - PROCESS | 2899 | RSS: Main: 268402688 19:28:54 INFO - PROCESS | 2899 | 19:28:56 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:28:56 INFO - PROCESS | 2899 | RSS: Main: 268730368 19:28:56 INFO - PROCESS | 2899 | 19:28:58 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56279/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:28:59 INFO - PROCESS | 2899 | RSS: Main: 268238848 19:28:59 INFO - PROCESS | 2899 | 19:29:01 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:29:02 INFO - PROCESS | 2899 | RSS: Main: 268738560 19:29:02 INFO - PROCESS | 2899 | 19:29:05 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:29:05 INFO - PROCESS | 2899 | RSS: Main: 268632064 19:29:05 INFO - PROCESS | 2899 | 19:29:08 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:29:09 INFO - PROCESS | 2899 | RSS: Main: 268587008 19:29:09 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | Cycle 1(1): loaded http://localhost:56279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56279/tests/kraken/driver.html?testName=ai-astar) 19:29:11 INFO - PROCESS | 2899 | RSS: Main: 270184448 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | __start_tp_report 19:29:11 INFO - PROCESS | 2899 | _x_x_mozilla_page_load 19:29:11 INFO - PROCESS | 2899 | _x_x_mozilla_page_load_details 19:29:11 INFO - PROCESS | 2899 | |i|pagename|runs| 19:29:11 INFO - PROCESS | 2899 | |0;ai-astar;113;117;109;121;119;116;109;118;117;117 19:29:11 INFO - PROCESS | 2899 | |1;audio-beat-detection;155;151;181;165;134;172;165;146;201;123 19:29:11 INFO - PROCESS | 2899 | |2;audio-dft;263;205;204;222;193;215;232;206;208;168 19:29:11 INFO - PROCESS | 2899 | |3;audio-fft;64;110;66;155;84;66;52;78;160;84 19:29:11 INFO - PROCESS | 2899 | |4;audio-oscillator;99;147;178;65;109;129;160;116;108;127 19:29:11 INFO - PROCESS | 2899 | |5;imaging-gaussian-blur;89;113;89;86;88;85;90;87;88;85 19:29:11 INFO - PROCESS | 2899 | |6;imaging-darkroom;94;130;112;151;127;144;107;152;153;131 19:29:11 INFO - PROCESS | 2899 | |7;imaging-desaturate;90;95;92;90;91;89;89;91;91;91 19:29:11 INFO - PROCESS | 2899 | |8;json-parse-financial;59;62;62;61;63;61;63;79;64;60 19:29:11 INFO - PROCESS | 2899 | |9;json-stringify-tinderbox;65;86;100;104;95;73;74;67;81;61 19:29:11 INFO - PROCESS | 2899 | |10;stanford-crypto-aes;88;88;88;98;75;108;117;111;111;94 19:29:11 INFO - PROCESS | 2899 | |11;stanford-crypto-ccm;130;151;144;152;156;141;146;138;224;139 19:29:11 INFO - PROCESS | 2899 | |12;stanford-crypto-pbkdf2;170;169;166;169;168;170;167;172;168;168 19:29:11 INFO - PROCESS | 2899 | |13;stanford-crypto-sha256-iterative;67;68;66;68;69;78;82;90;85;68 19:29:11 INFO - PROCESS | 2899 | __end_tp_report 19:29:11 INFO - PROCESS | 2899 | __start_cc_report 19:29:11 INFO - PROCESS | 2899 | _x_x_mozilla_cycle_collect,1074 19:29:11 INFO - PROCESS | 2899 | __end_cc_report 19:29:11 INFO - PROCESS | 2899 | __startTimestamp1462674551278__endTimestamp 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | ------- Summary: start ------- 19:29:11 INFO - PROCESS | 2899 | Number of tests: 14 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#0] ai-astar Cycles:10 Average:115.60 Median:117.00 stddev:4.03 (3.4%) stddev-sans-first:4.17 19:29:11 INFO - PROCESS | 2899 | Values: 113.0 117.0 109.0 121.0 119.0 116.0 109.0 118.0 117.0 117.0 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#1] audio-beat-detection Cycles:10 Average:159.30 Median:165.00 stddev:22.75 (13.8%) stddev-sans-first:24.08 19:29:11 INFO - PROCESS | 2899 | Values: 155.0 151.0 181.0 165.0 134.0 172.0 165.0 146.0 201.0 123.0 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#2] audio-dft Cycles:10 Average:211.60 Median:211.50 stddev:24.88 (11.8%) stddev-sans-first:18.15 19:29:11 INFO - PROCESS | 2899 | Values: 263.0 205.0 204.0 222.0 193.0 215.0 232.0 206.0 208.0 168.0 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#3] audio-fft Cycles:10 Average:91.90 Median:66.00 stddev:37.97 (57.5%) stddev-sans-first:38.91 19:29:11 INFO - PROCESS | 2899 | Values: 64.0 110.0 66.0 155.0 84.0 66.0 52.0 78.0 160.0 84.0 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#4] audio-oscillator Cycles:10 Average:123.80 Median:153.50 stddev:32.36 (21.1%) stddev-sans-first:33.06 19:29:11 INFO - PROCESS | 2899 | Values: 99.0 147.0 178.0 65.0 109.0 129.0 160.0 116.0 108.0 127.0 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#5] imaging-gaussian-blur Cycles:10 Average:90.00 Median:88.00 stddev:8.26 (9.4%) stddev-sans-first:8.75 19:29:11 INFO - PROCESS | 2899 | Values: 89.0 113.0 89.0 86.0 88.0 85.0 90.0 87.0 88.0 85.0 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#6] imaging-darkroom Cycles:10 Average:130.10 Median:147.50 stddev:20.57 (13.9%) stddev-sans-first:17.18 19:29:11 INFO - PROCESS | 2899 | Values: 94.0 130.0 112.0 151.0 127.0 144.0 107.0 152.0 153.0 131.0 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#7] imaging-desaturate Cycles:10 Average:90.90 Median:91.00 stddev:1.73 (1.9%) stddev-sans-first:1.80 19:29:11 INFO - PROCESS | 2899 | Values: 90.0 95.0 92.0 90.0 91.0 89.0 89.0 91.0 91.0 91.0 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:62.50 stddev:5.68 (9.1%) stddev-sans-first:5.80 19:29:11 INFO - PROCESS | 2899 | Values: 59.0 62.0 62.0 61.0 63.0 61.0 63.0 79.0 64.0 60.0 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#9] json-stringify-tinderbox Cycles:10 Average:80.60 Median:73.50 stddev:15.18 (20.7%) stddev-sans-first:15.02 19:29:11 INFO - PROCESS | 2899 | Values: 65.0 86.0 100.0 104.0 95.0 73.0 74.0 67.0 81.0 61.0 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#10] stanford-crypto-aes Cycles:10 Average:97.80 Median:88.00 stddev:13.51 (15.4%) stddev-sans-first:13.86 19:29:11 INFO - PROCESS | 2899 | Values: 88.0 88.0 88.0 98.0 75.0 108.0 117.0 111.0 111.0 94.0 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#11] stanford-crypto-ccm Cycles:10 Average:152.10 Median:148.50 stddev:26.40 (17.8%) stddev-sans-first:26.76 19:29:11 INFO - PROCESS | 2899 | Values: 130.0 151.0 144.0 152.0 156.0 141.0 146.0 138.0 224.0 139.0 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.70 Median:169.00 stddev:1.70 (1.0%) stddev-sans-first:1.74 19:29:11 INFO - PROCESS | 2899 | Values: 170.0 169.0 166.0 169.0 168.0 170.0 167.0 172.0 168.0 168.0 19:29:11 INFO - PROCESS | 2899 | 19:29:11 INFO - PROCESS | 2899 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:73.50 stddev:8.84 (12.0%) stddev-sans-first:8.99 19:29:11 INFO - PROCESS | 2899 | Values: 67.0 68.0 66.0 68.0 69.0 78.0 82.0 90.0 85.0 68.0 19:29:11 INFO - PROCESS | 2899 | -------- Summary: end -------- 19:29:11 INFO - PROCESS | 2899 | 19:29:12 INFO - TEST-INFO | 2899: exit 0 19:29:12 INFO - TEST-OK | kraken | took 60766ms 19:29:12 INFO - SUITE-END | took 1173s 19:29:12 INFO - Completed test suite (00:19:33) 19:29:12 INFO - javascript benchmark 19:29:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3439.4324936385497, 3460.3079384123166, 3493.4977959477415, 3494.109002204439, 3524.136586536299, 5754.913676294859, 5866.973279676061, 5874.559408044398, 5887.205227294314, 5903.498202652774, 5687.857803554903, 5834.27071616048, 5858.5606079543995, 5870.530357571395, 5958.642481451116, 5248.02911461379, 5296.311479507678, 5350.892982140354, 5381.67709937403, 5411.83764487066, 276.3604948478507, 5323.574114070884, 5325.520703136717, 5362.865928351784, 5392.487713667196, 4552.317152427129, 5314.256004159421, 5337.599680024014, 5357.866053348678, 5405.891882162374, 5199.974000129975, 5328.0675881720545, 5347.732613369316, 5352.357717073937, 5409.350877894639, 5161.406438259611, 5239.502247286502, 5266.289050978107, 5331.466853314638, 5341.385740639837, 5706.600537962344, 5744.82765517031, 5773.422657734235, 5812.418758124154, 5850.502707269887, 5343.225232341289, 6185.474234690057, 6230.626162430269, 6257.217847769029, 6276.654783986879, 5371.704556249405, 6090.243817847612, 6267.592606480564, 6277.497800175975, 6281.528885333572, 6027.969860150672, 6032.698365081728, 6039.426254505903, 6049.637021778707, 6049.909251361233, 5265.842024739264, 6278.654673992883, 6310.810675679737, 6436.452901503425, 6457.418832305021, 6193.566450348432, 6195.721192546346, 6200.193974783339, 6207.875842483125, 6220.098085777537, 6182.196314479089, 6250.218722659668, 6271.6550589718, 6272.749090036347, 6288.213973253343, 6108.22065524731, 6122.947104152868, 6138.232720909887, 6159.1069294951985, 6180.47465965398, 5051.6716413433005, 6125.47964319526, 6176.88816013122, 6192.195014648067, 6211.378862113753, 5007.123753343223, 5022.74886255693, 5027.095122877844, 5029.5724863386295, 5045.117104506697, 3708.4437334400054, 4401.757903315349, 4447.310666846644, 4506.008678090815, 4582.679212455096, 5844.415558444121, 5985.760569577168, 6053.818385448443, 6055.545834062463, 6070.817875463743, 6248.968755156195, 6267.65527895961, 6273.749050037947, 6280.0, 6372.980323148274, 4670.276107203389, 5977.983742763654, 5993.190919225849, 6003.489703375175, 6027.6684782336515, 4633.2818413146015, 4913.0, 5048.949510504922, 5498.257735205776, 5522.530584900329, 6266.154069200782, 6447.355264473609, 6453.903191452132, 6464.191976002906, 6481.935180648227, 6174.660393678211, 6195.566310358232, 6220.502359811114, 6271.4669253114, 6280.214973128359, 6439.935600644121, 6452.193475815523, 6458.0, 6480.449161821298, 6561.1470508833545, 6119.388061193845, 6197.380261973766, 6242.563020588515, 6259.0, 6268.7492500301305, 6183.721732522137, 6241.531885108726, 6248.937510624835, 6257.56197066201, 6268.27914789805, 6231.189945307081, 6250.656213908279, 6268.780592679234, 6287.905681414691, 6302.590331628429, 6086.239220097488, 6106.824719425016, 6139.706800529493, 6169.753209871555, 6174.228221472316, 4890.877728056827, 5935.792247271324, 5974.0, 6142.201513803177, 6142.375998160927, 6181.783637572664, 6233.065040243819, 6252.030935205051, 6266.248050234001, 6271.6550589718, 5833.979675039257, 6038.0, 6131.754729810936, 6142.723577438936, 6206.255249370104], "value": 5708.53564496914, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.54901715912774, 510.44361645806066, 529.8913722686849, 537.0360203434835, 537.2210295072147, 38901.610983890154, 41442.75671729853, 41506.99999999996, 41538.792306038464, 41615.95960100991, 42124.78937605308, 42437.939051523725, 42738.57261427385, 42771.78614106933, 42916.0, 9548.80902381952, 9616.903830961706, 9763.463009534473, 9772.560234789451, 9774.706758797212, 7340.486165968385, 8119.472234304781, 8587.699430519933, 9666.903330966707, 9699.03009699028, 9724.805503889918, 9728.221742260637, 9733.0, 9741.172000379976, 9747.90252097477, 8311.459755115899, 9636.469994150319, 9656.80686386272, 9668.371555848882, 9695.66065187722, 9527.0, 9538.856917146248, 9603.567839447172, 9609.231261499064, 9632.903670963271, 39359.21281574381, 39504.604953950664, 39694.610688626046, 39836.60163398358, 39871.8006409969, 2806.9859650701615, 2937.941241175186, 3067.3251884585356, 3141.026281852633, 3364.8654053838063, 26035.609465857837, 27037.459250814874, 27654.308642283904, 27905.441891162267, 28649.427011459655, 2507.6489291499206, 2526.5325914705836, 2575.394782226194, 2581.2256323102993, 2627.8423294602385, 297888.0, 371527.0, 375852.0, 377951.11024444975, 379043.0, 34163.0, 34971.125721856646, 35137.648623513436, 35194.82402587971, 35994.64005359965, 31075.844620776752, 36956.63043369586, 37057.629423705956, 37105.44341834875, 37163.628363716554, 15348.693026139415, 15488.22558872052, 15505.0, 15511.0, 15517.689646207013, 10848.511816968256, 12939.5471158509, 12974.286414247306, 13035.869641303465, 13047.086703930634, 8192.795180120425, 8281.047679516812, 8281.8343633127, 8316.209960053786, 8339.583020848933, 3366.2762506060826, 3373.7132343751055, 3416.743744219144, 3432.2105915639545, 3449.9827500862334, 907.8093600344001, 979.1579241852002, 1022.3150489172273, 1025.4975062219607, 1168.5325869652206, 22938.42653933665, 29640.555391669142, 29792.553111703342, 30229.79080836786, 30482.390352193277, 975.6341371985505, 1095.1403148528368, 1101.1191047162238, 1107.5071593141136, 1144.862616486027, 13709.451621935012, 15281.38874445032, 15498.147601882005, 15557.45548905783, 15662.060276383454, 598854.0, 664127.0, 672080.0, 672640.0, 673812.6309368422, 58994.41005589975, 59410.70294648499, 59656.403435965956, 59705.0, 59867.70066149641, 1393.5610282760897, 1444.8555144485676, 1446.2913172545377, 1478.031889112633, 1481.7999570057905, 30142.0, 30174.547381788852, 30219.244518887204, 30255.148296852425, 30410.03127315037, 50669.0, 50676.98646027133, 51159.74420128024, 51453.0, 51674.224886625976, 512130.0, 516616.0, 517327.0, 518825.4058729682, 518858.0, 26835.597466037634, 26938.922443102056, 27286.317842053315, 27624.75688593978, 27670.308242293297, 34476.48285275673, 45091.54908451005, 45145.32282015707, 45202.54797451983, 45716.54283457123, 36901.0, 37128.443073354465, 37179.07052323713, 37398.439023414125, 37553.6244637561, 36572.451413229355, 36946.44580331351, 36984.26031479301, 39953.60046399499, 41207.79396103, 37836.62163378441, 39971.40042899417, 40172.1965560693, 40183.1963360737, 40522.1895562093, 38657.613423865405, 40093.79953100332, 40874.59125408827, 41025.79487102546, 41089.79455102705, 30354.0, 30730.0, 30746.538801918436, 31411.52882706807, 31475.213119672193], "value": 18259.794668636023, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.94540764293004, 393.498289680657, 393.57690482731067, 396.4964495091236, 417.21979897591507, 14593.562193134223, 16487.83512164878, 20747.896260518675, 21351.57296854064, 21376.89311553442, 20521.692174617394, 20748.377548673565, 21057.26299579511, 21318.360449186548, 21555.784442155575, 4744.525547445262, 5812.796552120666, 5837.182794408786, 5838.678872662002, 5902.527797776168, 5375.596830237729, 5772.740226689809, 5778.942210577903, 5780.595358324919, 5819.7672093116225, 5627.352854421732, 5815.098659707731, 5831.5626328025355, 5860.912086318709, 6346.682665866711, 5077.8067154218725, 6342.682865856688, 6362.077498762654, 6365.52258580606, 6388.712507937131, 5287.4448182941005, 5681.715914204273, 5727.799527016576, 5730.885382292373, 5739.942600573982, 13214.66963325915, 13504.797428038588, 13745.725085498334, 13782.793258101035, 13822.239776812272, 1856.675081860669, 2068.7103805467245, 2134.530403311269, 2372.7851598866873, 2707.6073969274344, 9960.352577082467, 10002.849957250646, 10197.031282028196, 10261.43562104084, 14503.1298122113, 1492.843251458603, 1702.9148542572946, 1733.0554847607957, 1748.038578781665, 1756.9648607027916, 102971.0, 108419.37370939442, 118008.40995795051, 119813.0, 138950.30524847412, 9902.405855648683, 9952.402855828528, 11872.228305160137, 11927.940360298317, 12152.0, 14964.476243331648, 15196.392144314104, 15258.31337589789, 15276.38894444232, 15353.232338383035, 7376.225496322916, 8695.869561956575, 8853.07042760501, 8930.776730581658, 9023.187913087853, 7362.564299961563, 8684.60919258635, 8860.512671803113, 8964.462132272085, 8968.506732129796, 3882.4758657580865, 4637.281221410687, 4692.577668009895, 4723.362346083235, 4731.313959475857, 1647.4975132584677, 1869.6260747850483, 1980.2475059477306, 2001.8398528117716, 2006.1273346094204, 817.9100298967109, 818.6725309876098, 821.7000794710008, 969.471637957315, 1007.2093406675871, 19953.201871925252, 20628.79371206269, 20640.793592064187, 20796.688049679266, 20805.687914681293, 871.4988881393098, 880.9119088091139, 966.3042609321277, 968.162539403411, 979.8187335342984, 7349.63251837406, 10920.945395272973, 10967.780644387067, 10976.780464390828, 11005.889941100646, 556403.0, 607771.0, 608099.0, 612986.0, 618532.0, 125185.7481425174, 128681.0, 142943.85584216245, 145767.0, 145820.0, 986.4968865878453, 1003.5484032185545, 1010.5856598794459, 1024.7438140465033, 1028.7685270814006, 15818.68362632764, 17585.736213957058, 18083.0, 18440.35458758937, 18684.065796710383, 52399.21401179062, 53938.46061539334, 55209.447905522036, 55295.17057244064, 56170.59573510695, 80190.19809802061, 80319.59840200761, 80573.59713201631, 80596.59701701454, 81025.59487202526, 8070.6368213431715, 8214.958925205336, 8291.253787159185, 8519.446235994641, 8587.441816281922, 11497.367644779617, 12539.93730031344, 12653.367331633197, 12853.16454430459, 12942.417591208607, 10266.281360305005, 10958.506867190836, 11051.0, 11309.830352544554, 11457.942710286728, 10834.512446940063, 10891.346519208555, 11115.388653624128, 11128.1653875958, 11340.489677964677, 11309.604163854226, 11329.206955513035, 11348.602798901999, 11390.544378224778, 11596.0, 6881.724731010904, 7155.785326440215, 8171.0, 8297.751067467985, 8603.741887743377, 10328.948355258175, 10921.0, 10961.890381096087, 11140.442977851237, 11269.605563805555], "value": 9389.640271933582, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1543.2052492966125, 1623.9025658460494, 1892.7823300320458, 1899.876508026979, 1965.4594986378754, 7407.666655000525, 7888.96055519723, 8507.489550626966, 9039.050899655533, 9055.366124371296, 7221.313975172363, 8771.56142192891, 8867.0, 8882.644694212224, 8913.866292005625, 2135.7826039157726, 2393.56915755164, 2493.7256901740793, 2496.6504689343506, 2503.7371076037025, 2075.325519206264, 2444.5844206484853, 2467.753224677536, 2490.1533478617266, 2501.6122501012283, 2467.4571594249237, 2468.8518688878726, 2485.6768620079365, 2496.325991982164, 2497.500499900018, 2049.897505124738, 2343.742188359288, 2426.3812727754485, 2456.6069428891383, 2463.987680061606, 2408.771166739157, 2430.5138972205627, 2431.3192306154306, 2435.57377458944, 2450.215930902112, 8774.868376974351, 8946.284297256203, 8958.148975847284, 8962.596683149273, 8995.55022248885, 5374.167004114369, 5505.394406615269, 5569.66582005081, 5584.274044374205, 5608.663480191201, 3602.423612222058, 3615.2407994321225, 3618.0, 3678.797666128362, 3701.851925922987, 845.8096928191108, 888.9199972002486, 1013.4881884648279, 1032.4270030133273, 1033.2405681823852, 11536.480858361394, 11701.473433695504, 11706.297622142783, 11747.353895535742, 11764.000059994825, 4830.275458681226, 4870.172070747911, 4970.478099799541, 4985.975070124626, 5056.342675452168, 10037.79924401508, 10061.496925153713, 10073.949630251802, 10079.596816127272, 10086.546105425274, 4642.814287428465, 5485.807996720096, 5525.778968841281, 5526.088195447786, 5526.309211348582, 4092.836286548564, 4423.8230470781455, 4827.831025914076, 4994.650374473752, 5030.496950304941, 4064.9796751016056, 4097.720157854111, 4133.8966525837095, 4156.293430116887, 4159.10579225462, 1351.72965406919, 1374.0725010617793, 1380.6065271397804, 1384.0173476831562, 1390.242317936727, 873.2926329672986, 899.3030401438937, 922.4649703172278, 963.1476143612813, 971.9805603887883, 5113.590912726973, 5861.853453663693, 5975.880482390327, 6070.605410648293, 6092.786752463642, 463.81215607678945, 470.7034568221998, 477.5773440505178, 484.056090623286, 485.32782096796194, 3922.2547715933883, 3996.2606917720314, 4002.679785617202, 4070.2063097695664, 4095.627964631822, 10237.692869213935, 10730.248882578144, 10788.676339709822, 10798.406087665255, 10829.729256768485, 10547.683569492927, 10685.57257709683, 10756.946215268874, 10769.299995500423, 10786.352818830896, 7817.413693972975, 8176.223258790405, 8323.66705331792, 8325.084240733393, 8325.8334833303, 4756.857294281177, 5127.897442051213, 5711.485966263056, 5950.523958083257, 6180.412860778219, 9573.0, 9821.116099551075, 10134.442605656759, 10135.695929122137, 10162.898371016196, 9731.562079706571, 10061.239283125278, 10132.138768204784, 10254.436006019741, 10363.170946324126, 7270.781876543712, 7525.58609276495, 7636.923630763621, 7642.388608911163, 7656.885146722692, 8446.450980686235, 8496.0, 8536.0, 8546.78633034179, 8552.828943420973, 6852.554583952027, 6927.445804335742, 6972.477064220305, 7073.186583542958, 7100.183478899786, 6153.84615384619, 6331.113644089739, 6491.766564352947, 6569.967150164219, 6876.312368763284, 6958.0, 7050.471214659024, 7132.0, 7145.1068616422945, 7163.785086447415, 4944.258361245842, 4977.800887964441, 5012.899742005213, 5024.145895197752, 5051.419086805065, 4670.042641258602, 4822.276658501253, 4878.731669758197, 4889.071076495455, 5035.144025515744], "value": 4526.24538841255, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.469097842689, 317.17059888391856, 322.29578371258793, 328.4514860183493, 329.1376593325486, 4668.743219122947, 5094.057599344119, 5222.90319033003, 5433.320834895638, 5720.2849643794525, 5123.12906805843, 5397.109476936311, 5605.747741351639, 5609.887802243953, 5638.7445021991225, 3596.4785106159593, 4245.41029971717, 4261.318189089746, 4261.722988005785, 4265.445492086024, 4026.7315795524464, 4115.753054816721, 4179.958200417987, 4264.23243816113, 4283.828646854122, 3979.845844705032, 4153.06556024895, 4222.577742225783, 4234.640055595262, 4252.893677658052, 4261.531231564525, 4265.296226122686, 4272.935905961397, 4288.69979101463, 4295.398644189816, 2914.489964256247, 3251.3659836331926, 3268.7548433867555, 3645.8645564814237, 3711.5731690855628, 4537.591616754475, 4765.8791837631925, 4953.0, 4959.189895688084, 5295.126304159848, 4342.0, 4623.398958135421, 4871.930683669266, 4893.657443978923, 4943.0, 5331.2003199519995, 5581.888362232733, 5672.978863804555, 5751.325633580405, 5928.28860536734, 2720.7551320381062, 3900.7854567998747, 4004.939925901114, 4013.538443079025, 4029.133736246721, 6207.7206525706, 6305.0, 6306.0, 6314.305426403092, 6331.398517140864, 3606.6032736398975, 3617.312710585033, 4022.0, 4070.470838790938, 4096.426500289962, 3667.413213885819, 3717.4052151655605, 3775.5091838061426, 4104.56902025289, 4157.417961485395, 4035.1324465240114, 4717.410323709536, 4779.474257831635, 4808.3027960945465, 4870.756462176877, 4863.781129849165, 5038.866255092575, 5291.550218231493, 5312.468753124734, 5999.340072592011, 1141.560499207811, 1208.8186771984272, 1228.2139430764319, 1287.660832733946, 1333.4332908513843, 756.9016027916335, 879.8284334554828, 895.8488342479882, 896.3591032411857, 905.343625871238, 4476.574725401082, 4610.654200934943, 4764.97553026101, 4937.679050861682, 5176.738654647624, 5002.849914502571, 5123.949590333973, 5265.605079619044, 5869.35437101927, 5951.880962380728, 4340.739555626673, 4714.218611019515, 4865.9026819463415, 5066.36670416198, 5230.593926793641, 2685.8719337878138, 2874.9712502875122, 2975.152081656761, 3014.3217776000665, 3097.8915737949064, 5375.731213439312, 6056.485878530368, 6161.568690191643, 6196.645274795549, 6229.657368844757, 5312.556224136941, 5369.590310153081, 5569.3823902765635, 5828.795992140254, 6203.96898015507, 5455.918161227585, 5515.365732940683, 6225.688715564113, 6244.843878903063, 6251.9684252097895, 5373.650712703662, 5734.59857809966, 5898.4101589840675, 5939.0, 5949.286085669574, 5370.409254982027, 5732.283464566929, 5805.593608447368, 5819.301683797971, 5868.2958045033165, 5628.60599758013, 5778.139820788737, 5826.446487583673, 6216.326979398902, 6242.094896240111, 3726.9068273293383, 3829.5787463379565, 3865.9420108697827, 4040.1213435751342, 4064.4394031760985, 5131.794028403397, 6104.572679912541, 6113.209809917879, 6131.662758548323, 6144.508439324753, 5116.692998420107, 5264.131418315935, 5687.488126068673, 5966.224390829165, 6010.549208809444, 5721.342045664802, 5722.942770572241, 5940.55445841555, 5963.344032156372, 6004.39956004396, 5641.206544475298, 5650.547956163579, 5722.856928576819, 5803.3616302207565, 5817.621854579439, 5928.110783382354, 5976.641401515923, 5999.790007349722, 6028.819135425769, 6044.727987240497, 4865.878353041061, 5572.244742905972, 5807.593468457337, 5846.895117757519, 5895.29256489224], "value": 4190.142181890522, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.5449140093335, 748.7678819565931, 755.1881727143319, 759.3507551043854, 769.5036701327642, 12492.750724927502, 13676.111052781574, 14036.719265614694, 14038.087524310939, 14049.648758781035, 10878.891211087906, 12950.546730864422, 12985.350732463387, 13013.284269365182, 13036.283004434752, 700.6286668065933, 874.715717391847, 880.1770344727668, 905.7056456651582, 912.0469109780296, 860.7632900952257, 868.4702331577732, 886.171429713217, 892.9598168082451, 893.0578239956875, 946.3990366117531, 962.5620342744035, 963.3593660215982, 966.9274804389664, 966.9613215471372, 957.1385752822481, 957.4159762544842, 957.7940742740275, 961.8749562556893, 962.3263715399214, 588.6468119128532, 709.3225969199402, 710.1442761472414, 711.1999001123736, 713.1264201353353, 11552.306861588331, 11850.407479625985, 13545.796813047813, 13695.493495715464, 13743.518976835863, 11850.3482308473, 11921.165518413714, 11954.282743035446, 12044.096692748079, 12090.214136081127, 9376.546754043831, 9405.858912116255, 9433.622655093788, 9469.479178645171, 9763.951180244054, 3029.59100521429, 3222.91942701432, 3222.9677703223138, 3236.0453666168537, 3295.0, 81184.18815811825, 81682.18317816805, 82245.58877205575, 82517.0, 82593.0, 9143.54282285883, 9173.036831132635, 9249.12133347331, 9400.576974036185, 9462.00648931866, 19021.524461888563, 19208.519787005436, 19267.325643602686, 19268.0, 19547.31584394568, 6167.136600875881, 6829.590224586541, 6879.174499060145, 6907.447404207652, 6914.997325387791, 3653.780773153619, 3669.9816500917323, 3779.6771130104244, 3864.8840534784003, 3935.0359162005493, 3149.102505785886, 3257.5928008998876, 3258.1528802511516, 3258.951115733266, 3267.379197952381, 646.3052218864739, 664.0669858848325, 666.4501786026548, 676.5061505101304, 685.1538350137577, 473.5974421741492, 517.1389636255592, 573.6214098694842, 606.4541912278896, 609.5732986909181, 7447.925520744831, 7767.145613982458, 7885.921140788519, 8001.839963200703, 8163.877541836877, 557.2282388891415, 566.3265051772004, 575.5050656435462, 583.3932709981657, 592.1532208941255, 3708.962910370916, 3768.5854555998817, 3782.243551289698, 3818.5035945327486, 3867.477890484805, 71089.0, 73724.63137684384, 73774.63112684402, 93637.0, 94475.0, 74290.0, 74577.62711186409, 74880.0, 75360.62319688367, 75548.0, 56658.716706416206, 60048.49878753066, 62788.0, 63482.41293967595, 69591.30408695848, 8263.83472330538, 8305.335573154254, 8348.833023339621, 8351.0, 8363.243718819116, 43297.78351108224, 43408.34887476627, 43790.0, 43906.12187756291, 44696.10607787891, 43517.34723979206, 59429.0, 59724.0, 60177.0, 60277.0, 10738.087262582769, 10927.672169834917, 11069.280496767684, 11161.665150045512, 11332.830007549728, 11744.3334364677, 11990.940045299718, 12068.939655301667, 12146.75706485883, 12286.0, 11504.82742758876, 11686.0, 11693.824592631288, 11763.882361176278, 12075.27548347102, 9387.906120938704, 11443.0, 11519.19365644397, 11604.825927611262, 12057.638270851889, 11281.548738050386, 11381.317120972768, 11698.239614425034, 11706.356150411468, 11746.58886938953, 9285.70000199959, 9337.673181438617, 9703.466309353053, 9819.36174148678, 9920.255980801325, 8200.466969646954, 9602.423854568748, 9831.115199632068, 9886.456244906312, 10094.242931780293], "value": 6600.952087561475, "unit": "score"}], "name": "dromaeo_css", "value": 7047.70081667571, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 109.0, 121.0, 119.0, 116.0, 109.0, 118.0, 117.0, 117.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 151.0, 181.0, 165.0, 134.0, 172.0, 165.0, 146.0, 201.0, 123.0], "value": 159.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [263.0, 205.0, 204.0, 222.0, 193.0, 215.0, 232.0, 206.0, 208.0, 168.0], "value": 211.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 110.0, 66.0, 155.0, 84.0, 66.0, 52.0, 78.0, 160.0, 84.0], "value": 91.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 147.0, 178.0, 65.0, 109.0, 129.0, 160.0, 116.0, 108.0, 127.0], "value": 123.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 113.0, 89.0, 86.0, 88.0, 85.0, 90.0, 87.0, 88.0, 85.0], "value": 90.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 130.0, 112.0, 151.0, 127.0, 144.0, 107.0, 152.0, 153.0, 131.0], "value": 130.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 92.0, 90.0, 91.0, 89.0, 89.0, 91.0, 91.0, 91.0], "value": 90.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 62.0, 61.0, 63.0, 61.0, 63.0, 79.0, 64.0, 60.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 86.0, 100.0, 104.0, 95.0, 73.0, 74.0, 67.0, 81.0, 61.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 98.0, 75.0, 108.0, 117.0, 111.0, 111.0, 94.0], "value": 97.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 151.0, 144.0, 152.0, 156.0, 141.0, 146.0, 138.0, 224.0, 139.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 166.0, 169.0, 168.0, 170.0, 167.0, 172.0, 168.0, 168.0], "value": 168.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 68.0, 69.0, 78.0, 82.0, 90.0, 85.0, 68.0], "value": 74.1, "unit": "score"}], "name": "kraken", "value": 1649.8999999999996, "alertThreshold": 2.0}]} 19:29:12 INFO - Return code: 0 19:29:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:29:12 ERROR - # TBPL SUCCESS # 19:29:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:29:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:29:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:29:12 INFO - 2016-05-07 19:29:12.593 screenresolution[2904:45723] starting screenresolution argv=screenresolution get 19:29:12 INFO - 2016-05-07 19:29:12.595 screenresolution[2904:45723] Display 0: 1600x1200x32@60 19:29:12 INFO - 2016-05-07 19:29:12.601 screenresolution[2905:45726] starting screenresolution argv=screenresolution list 19:29:12 INFO - Available Modes on Display 0 19:29:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:29:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:29:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:29:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:29:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:29:12 INFO - Intel Iris: 19:29:12 INFO - Chipset Model: Intel Iris 19:29:12 INFO - Type: GPU 19:29:12 INFO - Bus: Built-In 19:29:12 INFO - VRAM (Dynamic, Max): 1536 MB 19:29:12 INFO - Vendor: Intel (0x8086) 19:29:12 INFO - Device ID: 0x0a2e 19:29:12 INFO - Revision ID: 0x0009 19:29:12 INFO - Displays: 19:29:12 INFO - Display: 19:29:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:29:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:29:12 INFO - Display Serial Number: 1600x1200 60 19:29:12 INFO - Main Display: Yes 19:29:12 INFO - Mirror: Off 19:29:12 INFO - Online: Yes 19:29:12 INFO - Rotation: Supported 19:29:12 INFO - Return code: 0 19:29:12 INFO - Running post-action listener: _resource_record_post_action 19:29:12 INFO - ##### 19:29:12 INFO - ##### Finished run-tests step (success) 19:29:12 INFO - ##### 19:29:12 INFO - Running post-run listener: _resource_record_post_run 19:29:13 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 241664; Write bytes: 698169344; Read time: 2; Write time: 2017 19:29:13 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 390345216; Write bytes: 384751616; Read time: 29606; Write time: 730 19:29:13 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 241664; Write bytes: 298254336; Read time: 2; Write time: 1225 19:29:13 INFO - Running post-run listener: _upload_blobber_files 19:29:13 INFO - Blob upload gear active. 19:29:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:29:13 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 19:29:13 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:29:13 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:29:14 INFO - (blobuploader) - INFO - Open directory for files ... 19:29:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:29:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:29:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:15 INFO - (blobuploader) - INFO - Done attempting. 19:29:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:29:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:29:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:16 INFO - (blobuploader) - INFO - Done attempting. 19:29:16 INFO - (blobuploader) - INFO - Iteration through files over. 19:29:16 INFO - Return code: 0 19:29:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:29:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:29:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5210a68d0af83b3252026fc5cc9f46afcb0766ad34fc595dea201360f89e53bdf37ef60c049c460bb26b2425da4fef8100cbd3986640c423ec2869fb052938b4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb2bd1cf8724acce803a03b04f40c713efc2afea7476e28a41a16b0e5f8a9d5b7e988f6cdca353e56338c59ef184f0f0ca337d84e1c4e839ac2d41d2cde05593"} 19:29:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:29:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:29:16 INFO - Contents: 19:29:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5210a68d0af83b3252026fc5cc9f46afcb0766ad34fc595dea201360f89e53bdf37ef60c049c460bb26b2425da4fef8100cbd3986640c423ec2869fb052938b4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb2bd1cf8724acce803a03b04f40c713efc2afea7476e28a41a16b0e5f8a9d5b7e988f6cdca353e56338c59ef184f0f0ca337d84e1c4e839ac2d41d2cde05593"} 19:29:16 INFO - Running post-run listener: copy_logs_to_upload_dir 19:29:16 INFO - Copying logs to upload dir... 19:29:16 INFO - mkdir: /builds/slave/test/build/upload/logs 19:29:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.944187 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-07 19:29:16.817162) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-07 19:29:16.821020) ========= 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.0W66jaFTZQ/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.4V2rqxuyhV/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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/Fx-Team/sha512/5210a68d0af83b3252026fc5cc9f46afcb0766ad34fc595dea201360f89e53bdf37ef60c049c460bb26b2425da4fef8100cbd3986640c423ec2869fb052938b4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb2bd1cf8724acce803a03b04f40c713efc2afea7476e28a41a16b0e5f8a9d5b7e988f6cdca353e56338c59ef184f0f0ca337d84e1c4e839ac2d41d2cde05593"} build_url:https://queue.taskcluster.net/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011540 build_url: 'https://queue.taskcluster.net/v1/task/dP9tmQOEQuuuNQR2aN5CYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5210a68d0af83b3252026fc5cc9f46afcb0766ad34fc595dea201360f89e53bdf37ef60c049c460bb26b2425da4fef8100cbd3986640c423ec2869fb052938b4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb2bd1cf8724acce803a03b04f40c713efc2afea7476e28a41a16b0e5f8a9d5b7e988f6cdca353e56338c59ef184f0f0ca337d84e1c4e839ac2d41d2cde05593"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-07 19:29:16.849381) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 19:29:16.849713) ========= 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.0W66jaFTZQ/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.4V2rqxuyhV/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.005388 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 19:29:16.898505) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-07 19:29:16.898836) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-07 19:29:16.899152) ========= ========= Total master_lag: 2.65 =========