builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0184 starttime: 1461356330.41 results: success (0) buildid: 20160422122844 builduid: 7cd0caf2d5824d22b57bb0c83a0813cb revision: 104bef115f31497dd411582328eef6ed375d0c72 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 13:18:50.408500) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 13:18:50.408935) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 13:18:50.409243) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 13:18:50.492254) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-22 13:18:50.492510) ========= 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.RnQXiyk2Ul/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.7haknWOEin/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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-04-22 13:18:50-- 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% 21.3M=0.001s 2016-04-22 13:18:50 (21.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.335504 ========= master_lag: 3.92 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-22 13:18:54.744014) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 13:18:54.744298) ========= 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.RnQXiyk2Ul/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.7haknWOEin/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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.027374 ========= master_lag: 1.26 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 13:18:56.036358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-22 13:18:56.036678) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 104bef115f31497dd411582328eef6ed375d0c72 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 104bef115f31497dd411582328eef6ed375d0c72 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RnQXiyk2Ul/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.7haknWOEin/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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-04-22 13:18:56,036 truncating revision to first 12 chars 2016-04-22 13:18:56,036 Setting DEBUG logging. 2016-04-22 13:18:56,037 attempt 1/10 2016-04-22 13:18:56,037 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/104bef115f31?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-22 13:18:56,771 unpacking tar archive at: mozilla-aurora-104bef115f31/testing/mozharness/ program finished with exit code 0 elapsedTime=0.980465 ========= master_lag: 3.92 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-22 13:19:00.941641) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 13:19:00.943403) ========= script_repo_revision: 104bef115f31497dd411582328eef6ed375d0c72 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 13:19:00.944061) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2016-04-22 13:19:00.944382) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2016-04-22 13:19:04.350433) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-22 13:19:04.350784) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RnQXiyk2Ul/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.7haknWOEin/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 13:19:04 INFO - MultiFileLogger online at 20160422 13:19:04 in /builds/slave/test 13:19:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:19:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:19:04 INFO - {'append_to_log': False, 13:19:04 INFO - 'base_work_dir': '/builds/slave/test', 13:19:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:19:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:19:04 INFO - 'branch': 'Mozilla-Aurora', 13:19:04 INFO - 'buildbot_json_path': 'buildprops.json', 13:19:04 INFO - 'config_files': ('talos/mac_config.py',), 13:19:04 INFO - 'default_actions': ('clobber', 13:19:04 INFO - 'read-buildbot-config', 13:19:04 INFO - 'download-and-extract', 13:19:04 INFO - 'populate-webroot', 13:19:04 INFO - 'create-virtualenv', 13:19:04 INFO - 'install', 13:19:04 INFO - 'run-tests'), 13:19:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:19:04 INFO - 'download_minidump_stackwalk': True, 13:19:04 INFO - 'download_symbols': 'ondemand', 13:19:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:19:04 INFO - 'tooltool.py': '/tools/tooltool.py', 13:19:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:19:04 INFO - '/tools/misc-python/virtualenv.py')}, 13:19:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:19:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:19:04 INFO - 'installer_path': 'installer.exe', 13:19:04 INFO - 'log_level': 'info', 13:19:04 INFO - 'log_name': 'talos', 13:19:04 INFO - 'log_to_console': True, 13:19:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:19:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:19:04 INFO - 'opt_config_files': (), 13:19:04 INFO - 'pip_index': False, 13:19:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:19:04 INFO - 'cmd': ('bash', 13:19:04 INFO - '-c', 13:19:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:19:04 INFO - 'enabled': True, 13:19:04 INFO - 'halt_on_failure': False, 13:19:04 INFO - 'name': 'check_screen_resolution'},), 13:19:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:19:04 INFO - 'cmd': ('bash', 13:19:04 INFO - '-c', 13:19:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:19:04 INFO - 'enabled': True, 13:19:04 INFO - 'halt_on_failure': False, 13:19:04 INFO - 'name': 'check_screen_resolution'},), 13:19:04 INFO - 'run_cmd_checks_enabled': True, 13:19:04 INFO - 'sps_profile': False, 13:19:04 INFO - 'sps_profile_interval': 0, 13:19:04 INFO - 'suite': 'dromaeojs', 13:19:04 INFO - 'system_bits': '32', 13:19:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:19:04 INFO - 'title': 't-yosemite-r7-0184', 13:19:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:19:04 INFO - 'use_talos_json': True, 13:19:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:19:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:19:04 INFO - 'work_dir': 'build'} 13:19:04 INFO - ##### 13:19:04 INFO - ##### Running clobber step. 13:19:04 INFO - ##### 13:19:04 INFO - Running pre-action listener: _resource_record_pre_action 13:19:04 INFO - Running main action method: clobber 13:19:04 INFO - rmtree: /builds/slave/test/build 13:19:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:19:06 INFO - Running post-action listener: _resource_record_post_action 13:19:06 INFO - ##### 13:19:06 INFO - ##### Running read-buildbot-config step. 13:19:06 INFO - ##### 13:19:06 INFO - Running pre-action listener: _resource_record_pre_action 13:19:06 INFO - Running main action method: read_buildbot_config 13:19:06 INFO - Using buildbot properties: 13:19:06 INFO - { 13:19:06 INFO - "project": "", 13:19:06 INFO - "product": "firefox", 13:19:06 INFO - "script_repo_revision": "production", 13:19:06 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 13:19:06 INFO - "repository": "", 13:19:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 13:19:06 INFO - "buildid": "20160422122844", 13:19:06 INFO - "pgo_build": "False", 13:19:06 INFO - "basedir": "/builds/slave/test", 13:19:06 INFO - "buildnumber": 309, 13:19:06 INFO - "slavename": "t-yosemite-r7-0184", 13:19:06 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 13:19:06 INFO - "platform": "yosemite_r7", 13:19:06 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:19:06 INFO - "branch": "mozilla-aurora", 13:19:06 INFO - "repo_path": "releases/mozilla-aurora", 13:19:06 INFO - "revision": "104bef115f31497dd411582328eef6ed375d0c72", 13:19:06 INFO - "stage_platform": "macosx64", 13:19:06 INFO - "builduid": "7cd0caf2d5824d22b57bb0c83a0813cb", 13:19:06 INFO - "slavebuilddir": "test" 13:19:06 INFO - } 13:19:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 13:19:06 INFO - Running post-action listener: _resource_record_post_action 13:19:06 INFO - ##### 13:19:06 INFO - ##### Running download-and-extract step. 13:19:06 INFO - ##### 13:19:06 INFO - Running pre-action listener: _resource_record_pre_action 13:19:06 INFO - Running main action method: download_and_extract 13:19:06 INFO - mkdir: /builds/slave/test/build/tests 13:19:06 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')]} 13:19:06 INFO - https://queue.taskcluster.net/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:19:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 13:19:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 13:19:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 13:19:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 13:19:07 INFO - Downloaded 1277 bytes. 13:19:07 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 13:19:07 INFO - Using the following test package requirements: 13:19:07 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 13:19:07 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:19:07 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 13:19:07 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:19:07 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 13:19:07 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:19:07 INFO - u'jsshell-mac.zip'], 13:19:07 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:19:07 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 13:19:07 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 13:19:07 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:19:07 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 13:19:07 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:19:07 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 13:19:07 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:19:07 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 13:19:07 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 13:19:07 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 13:19:07 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 13:19:07 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:19:07 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')]} 13:19:07 INFO - https://queue.taskcluster.net/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:19:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 13:19:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 13:19:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 13:19:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:19:11 INFO - Downloaded 17917551 bytes. 13:19:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:19:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:19:14 INFO - Return code: 0 13:19:14 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:19:14 INFO - https://queue.taskcluster.net/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:19:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 13:19:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 13:19:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 13:19:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:19:21 INFO - Downloaded 17917551 bytes. 13:19:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:19:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:19:25 INFO - Return code: 0 13:19:25 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')]} 13:19:25 INFO - https://queue.taskcluster.net/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:19:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 13:19:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 13:19:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 13:19:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:19:26 INFO - Downloaded 10845198 bytes. 13:19:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:19:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:19:27 INFO - Return code: 0 13:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:19:27 INFO - https://queue.taskcluster.net/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 13:19:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 13:19:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 13:19:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 13:19:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 13:19:30 INFO - Downloaded 115755320 bytes. 13:19:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 13:19:30 INFO - mkdir: /builds/slave/test/properties 13:19:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:19:30 INFO - Writing to file /builds/slave/test/properties/build_url 13:19:30 INFO - Contents: 13:19:30 INFO - build_url:https://queue.taskcluster.net/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 13:19:31 INFO - Running post-action listener: _resource_record_post_action 13:19:31 INFO - Running post-action listener: set_extra_try_arguments 13:19:31 INFO - ##### 13:19:31 INFO - ##### Running populate-webroot step. 13:19:31 INFO - ##### 13:19:31 INFO - Running pre-action listener: _resource_record_pre_action 13:19:31 INFO - Running main action method: populate_webroot 13:19:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:19:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:19:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:19:31 INFO - '--tppagecycles', 13:19:31 INFO - '7'], 13:19:31 INFO - 'tests': ['tsvgm']}}, 13:19:31 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 13:19:31 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 13:19:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:19:31 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 13:19:31 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 13:19:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:19:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:19:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:19:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:19:31 INFO - 'talos_options': ['--e10s'], 13:19:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:19:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:19:31 INFO - 'tests': ['damp', 'tps']}, 13:19:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:19:31 INFO - 'talos_options': ['--e10s'], 13:19:31 INFO - 'tests': ['damp', 'tps']}, 13:19:31 INFO - 'g3': {'tests': ['dromaeo_dom']}, 13:19:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:19:31 INFO - 'other': {'tests': ['a11yr', 13:19:31 INFO - 'ts_paint', 13:19:31 INFO - 'tpaint', 13:19:31 INFO - 'sessionrestore', 13:19:31 INFO - 'sessionrestore_no_auto_restore', 13:19:31 INFO - 'tabpaint']}, 13:19:31 INFO - 'other-e10s': {'talos_options': ['--e10s'], 13:19:31 INFO - 'tests': ['a11yr', 13:19:31 INFO - 'ts_paint', 13:19:31 INFO - 'tpaint', 13:19:31 INFO - 'sessionrestore', 13:19:31 INFO - 'sessionrestore_no_auto_restore', 13:19:31 INFO - 'tabpaint']}, 13:19:31 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 13:19:31 INFO - 'tests': ['a11yr', 13:19:31 INFO - 'ts_paint', 13:19:31 INFO - 'tpaint', 13:19:31 INFO - 'sessionrestore', 13:19:31 INFO - 'sessionrestore_no_auto_restore', 13:19:31 INFO - 'tabpaint']}, 13:19:31 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 13:19:31 INFO - 'tests': ['a11yr', 13:19:31 INFO - 'ts_paint', 13:19:31 INFO - 'tpaint', 13:19:31 INFO - 'sessionrestore', 13:19:31 INFO - 'sessionrestore_no_auto_restore', 13:19:31 INFO - 'tabpaint']}, 13:19:31 INFO - 'other_l64': {'tests': ['a11yr', 13:19:31 INFO - 'ts_paint', 13:19:31 INFO - 'tpaint', 13:19:31 INFO - 'sessionrestore', 13:19:31 INFO - 'sessionrestore_no_auto_restore', 13:19:31 INFO - 'tabpaint']}, 13:19:31 INFO - 'other_nol64': {'tests': ['a11yr', 13:19:31 INFO - 'ts_paint', 13:19:31 INFO - 'tpaint', 13:19:31 INFO - 'sessionrestore', 13:19:31 INFO - 'sessionrestore_no_auto_restore', 13:19:31 INFO - 'tabpaint']}, 13:19:31 INFO - 'svgr': {'tests': ['tsvgx', 13:19:31 INFO - 'tsvgr_opacity', 13:19:31 INFO - 'tart', 13:19:31 INFO - 'tscrollx', 13:19:31 INFO - 'cart']}, 13:19:31 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 13:19:31 INFO - 'tests': ['tsvgx', 13:19:31 INFO - 'tsvgr_opacity', 13:19:31 INFO - 'tart', 13:19:31 INFO - 'tscrollx', 13:19:31 INFO - 'cart']}, 13:19:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:19:31 INFO - 'tests': ['tp5o']}, 13:19:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:19:31 INFO - 'talos_options': ['--e10s'], 13:19:31 INFO - 'tests': ['tp5o']}, 13:19:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:19:31 INFO - 'talos_options': ['--xperf_path', 13:19:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:19:31 INFO - 'tests': ['tp5n']}, 13:19:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:19:31 INFO - 'talos_options': ['--e10s', 13:19:31 INFO - '--xperf_path', 13:19:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:19:31 INFO - 'tests': ['tp5n']}}, 13:19:31 INFO - 'talos.zip': {'path': '', 13:19:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:19:31 INFO - Running post-action listener: _resource_record_post_action 13:19:31 INFO - ##### 13:19:31 INFO - ##### Running create-virtualenv step. 13:19:31 INFO - ##### 13:19:31 INFO - Running pre-action listener: _resource_record_pre_action 13:19:31 INFO - Running main action method: create_virtualenv 13:19:31 INFO - Creating virtualenv /builds/slave/test/build/venv 13:19:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:19:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:19:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:19:31 INFO - Using real prefix '/tools/python27' 13:19:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:19:32 INFO - Installing distribute.............................................................................................................................................................................................done. 13:19:35 INFO - Installing pip.................done. 13:19:35 INFO - Return code: 0 13:19:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:19:35 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')]} 13:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:19:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:35 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')]} 13:19:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:19:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:35 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 0x10b77fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7864c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe2d924e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b623d30>, '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 0x10b487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b61ab20>, '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.RnQXiyk2Ul/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.7haknWOEin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:19:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:19:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnQXiyk2Ul/Render', 13:19:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:19:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:19:35 INFO - 'HOME': '/Users/cltbld', 13:19:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:19:35 INFO - 'LOGNAME': 'cltbld', 13:19:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:19:35 INFO - 'MOZ_NO_REMOTE': '1', 13:19:35 INFO - 'NO_EM_RESTART': '1', 13:19:35 INFO - 'PAGER': '/bin/cat', 13:19:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:19:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:19:35 INFO - 'PWD': '/builds/slave/test', 13:19:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:19:35 INFO - 'SHELL': '/bin/bash', 13:19:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7haknWOEin/Listeners', 13:19:35 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 13:19:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:19:35 INFO - 'USER': 'cltbld', 13:19:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:19:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:19:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:19:35 INFO - 'XPC_FLAGS': '0x0', 13:19:35 INFO - 'XPC_SERVICE_NAME': '0', 13:19:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:19:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:19:36 INFO - Cleaning up... 13:19:36 INFO - Return code: 0 13:19:36 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:19:36 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')]} 13:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:19:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:36 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')]} 13:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:19:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:36 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 0x10b77fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7864c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe2d924e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b623d30>, '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 0x10b487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b61ab20>, '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.RnQXiyk2Ul/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.7haknWOEin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:19:36 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 13:19:36 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 13:19:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnQXiyk2Ul/Render', 13:19:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:19:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:19:36 INFO - 'HOME': '/Users/cltbld', 13:19:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:19:36 INFO - 'LOGNAME': 'cltbld', 13:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:19:36 INFO - 'MOZ_NO_REMOTE': '1', 13:19:36 INFO - 'NO_EM_RESTART': '1', 13:19:36 INFO - 'PAGER': '/bin/cat', 13:19:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:19:36 INFO - 'PWD': '/builds/slave/test', 13:19:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:19:36 INFO - 'SHELL': '/bin/bash', 13:19:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7haknWOEin/Listeners', 13:19:36 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 13:19:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:19:36 INFO - 'USER': 'cltbld', 13:19:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:19:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:19:36 INFO - 'XPC_FLAGS': '0x0', 13:19:36 INFO - 'XPC_SERVICE_NAME': '0', 13:19:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:19:36 INFO - Downloading/unpacking psutil>=0.7.1 13:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:19:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:19:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:19:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:19:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:19:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:19:38 INFO - Installing collected packages: psutil 13:19:38 INFO - Running setup.py install for psutil 13:19:39 INFO - building 'psutil._psutil_osx' extension 13:19:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:19:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:19:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:19:39 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:19:39 INFO - building 'psutil._psutil_posix' extension 13:19:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:19:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:19:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:19:40 INFO - ^ 13:19:40 INFO - 1 warning generated. 13:19:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:19:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:19:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:19:40 INFO - Successfully installed psutil 13:19:40 INFO - Cleaning up... 13:19:40 INFO - Return code: 0 13:19:40 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:19: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')]} 13:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:19:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19: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')]} 13:19:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:19:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19: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', '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 0x10b77fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7864c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe2d924e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b623d30>, '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 0x10b487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b61ab20>, '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.RnQXiyk2Ul/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.7haknWOEin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:19: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:19: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 mozsystemmonitor==0.0.0 13:19:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnQXiyk2Ul/Render', 13:19:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:19:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:19:40 INFO - 'HOME': '/Users/cltbld', 13:19:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:19:40 INFO - 'LOGNAME': 'cltbld', 13:19:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:19:40 INFO - 'MOZ_NO_REMOTE': '1', 13:19:40 INFO - 'NO_EM_RESTART': '1', 13:19:40 INFO - 'PAGER': '/bin/cat', 13:19:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:19:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:19:40 INFO - 'PWD': '/builds/slave/test', 13:19:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:19:40 INFO - 'SHELL': '/bin/bash', 13:19:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7haknWOEin/Listeners', 13:19:40 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 13:19:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:19:40 INFO - 'USER': 'cltbld', 13:19:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:19:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:19:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:19:40 INFO - 'XPC_FLAGS': '0x0', 13:19:40 INFO - 'XPC_SERVICE_NAME': '0', 13:19:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:19:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:19:40 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:19: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 13:19: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 13:19: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 13:19: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 13:19: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 13:19: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 13:19:42 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:19:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:19:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:19:42 INFO - Installing collected packages: mozsystemmonitor 13:19:42 INFO - Running setup.py install for mozsystemmonitor 13:19:43 INFO - Successfully installed mozsystemmonitor 13:19:43 INFO - Cleaning up... 13:19:43 INFO - Return code: 0 13:19:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:19:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:19:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:19:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b77fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7864c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe2d924e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b623d30>, '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 0x10b487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b61ab20>, '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.RnQXiyk2Ul/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.7haknWOEin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:19:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:19:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnQXiyk2Ul/Render', 13:19:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:19:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:19:43 INFO - 'HOME': '/Users/cltbld', 13:19:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:19:43 INFO - 'LOGNAME': 'cltbld', 13:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:19:43 INFO - 'MOZ_NO_REMOTE': '1', 13:19:43 INFO - 'NO_EM_RESTART': '1', 13:19:43 INFO - 'PAGER': '/bin/cat', 13:19:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:19:43 INFO - 'PWD': '/builds/slave/test', 13:19:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:19:43 INFO - 'SHELL': '/bin/bash', 13:19:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7haknWOEin/Listeners', 13:19:43 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 13:19:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:19:43 INFO - 'USER': 'cltbld', 13:19:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:19:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:19:43 INFO - 'XPC_FLAGS': '0x0', 13:19:43 INFO - 'XPC_SERVICE_NAME': '0', 13:19:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:19:43 INFO - Downloading/unpacking blobuploader==1.2.4 13:19: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 13:19: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 13:19: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 13:19: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 13:19: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 13:19: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 13:19:45 INFO - Downloading blobuploader-1.2.4.tar.gz 13:19:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:19:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:19:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:19:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:19:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:19:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:19:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:19:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:19:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:19:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:19:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:19:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:19:46 INFO - Downloading docopt-0.6.1.tar.gz 13:19:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:19:47 INFO - Installing collected packages: blobuploader, requests, docopt 13:19:47 INFO - Running setup.py install for blobuploader 13:19:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:19:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:19:47 INFO - Running setup.py install for requests 13:19:47 INFO - Running setup.py install for docopt 13:19:47 INFO - Successfully installed blobuploader requests docopt 13:19:47 INFO - Cleaning up... 13:19:47 INFO - Return code: 0 13:19:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:19:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:19:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10b77fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7864c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe2d924e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b623d30>, '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 0x10b487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b61ab20>, '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.RnQXiyk2Ul/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.7haknWOEin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:19:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnQXiyk2Ul/Render', 13:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:19:47 INFO - 'HOME': '/Users/cltbld', 13:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:19:47 INFO - 'LOGNAME': 'cltbld', 13:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:19:47 INFO - 'MOZ_NO_REMOTE': '1', 13:19:47 INFO - 'NO_EM_RESTART': '1', 13:19:47 INFO - 'PAGER': '/bin/cat', 13:19:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:19:47 INFO - 'PWD': '/builds/slave/test', 13:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:19:47 INFO - 'SHELL': '/bin/bash', 13:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7haknWOEin/Listeners', 13:19:47 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 13:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:19:47 INFO - 'USER': 'cltbld', 13:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:19:47 INFO - 'XPC_FLAGS': '0x0', 13:19:47 INFO - 'XPC_SERVICE_NAME': '0', 13:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:19:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:19:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-cCdLX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:19:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-_2iQnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:19:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-oc7jWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:19:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-YIcMd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:19:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-CLXJnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:19:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-qJ5PbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:19:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-UmIgU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:19:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-W_xPmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:19:49 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-vL9ijs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:19:49 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-P3bSMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:19:49 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-QO7TjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:19:49 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-4FUEaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:19:49 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-NnbE_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:19:49 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-0uITO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:19:49 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-cF1GLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:19:49 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-PwECaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:19:49 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-1yxukt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:19:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:19:49 INFO - Running setup.py install for manifestparser 13:19:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:19:50 INFO - Running setup.py install for mozcrash 13:19:50 INFO - Running setup.py install for mozdebug 13:19:50 INFO - Running setup.py install for mozdevice 13:19:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:19:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:19:50 INFO - Running setup.py install for mozfile 13:19:50 INFO - Running setup.py install for mozhttpd 13:19:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:19:50 INFO - Running setup.py install for mozinfo 13:19:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:19:51 INFO - Running setup.py install for mozInstall 13:19:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:19:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:19:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:19:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:19:51 INFO - Running setup.py install for mozleak 13:19:51 INFO - Running setup.py install for mozlog 13:19:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:19:51 INFO - Running setup.py install for moznetwork 13:19:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:19:51 INFO - Running setup.py install for mozprocess 13:19:51 INFO - Running setup.py install for mozprofile 13:19:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:19:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:19:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:19:51 INFO - Running setup.py install for mozrunner 13:19:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:19:52 INFO - Running setup.py install for mozscreenshot 13:19:52 INFO - Running setup.py install for moztest 13:19:52 INFO - Running setup.py install for mozversion 13:19:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:19:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:19:52 INFO - Cleaning up... 13:19:52 INFO - Return code: 0 13:19:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:19:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:19:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:19:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10b77fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7864c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe2d924e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b623d30>, '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 0x10b487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b61ab20>, '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.RnQXiyk2Ul/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.7haknWOEin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:19:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:19:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:19:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnQXiyk2Ul/Render', 13:19:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:19:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:19:52 INFO - 'HOME': '/Users/cltbld', 13:19:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:19:52 INFO - 'LOGNAME': 'cltbld', 13:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:19:52 INFO - 'MOZ_NO_REMOTE': '1', 13:19:52 INFO - 'NO_EM_RESTART': '1', 13:19:52 INFO - 'PAGER': '/bin/cat', 13:19:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:19:52 INFO - 'PWD': '/builds/slave/test', 13:19:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:19:52 INFO - 'SHELL': '/bin/bash', 13:19:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7haknWOEin/Listeners', 13:19:52 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 13:19:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:19:52 INFO - 'USER': 'cltbld', 13:19:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:19:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:19:52 INFO - 'XPC_FLAGS': '0x0', 13:19:52 INFO - 'XPC_SERVICE_NAME': '0', 13:19:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:19:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:19:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-CNn2q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:19:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:19:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-CEXX1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:19:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-J4JSuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:19:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-kc4p8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:19:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-YnlNlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:19:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-uq8bwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:19:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-H_12w3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:19:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-iBSXlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:19:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-GgM6Wt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:19:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-QtLNvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:19:53 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)) 13:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:19:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-9vjaMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:19:54 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-zTwpAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:19:54 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)) 13:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:19:54 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-s1PiAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:19:54 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-S1lAPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:19:54 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)) 13:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:19:54 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-QmibeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:19:54 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-GQyOlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:19:54 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-6We9TC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:19:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:19:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:19:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:19:57 INFO - Downloading blessings-1.6.tar.gz 13:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:19:57 INFO - Installing collected packages: blessings 13:19:57 INFO - Running setup.py install for blessings 13:19:57 INFO - Successfully installed blessings 13:19:57 INFO - Cleaning up... 13:19:57 INFO - Return code: 0 13:19:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:19:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:19:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:19:57 INFO - Reading from file tmpfile_stdout 13:19:57 INFO - Current package versions: 13:19:57 INFO - blessings == 1.6 13:19:57 INFO - blobuploader == 1.2.4 13:19:57 INFO - docopt == 0.6.1 13:19:57 INFO - manifestparser == 1.1 13:19:57 INFO - mozInstall == 1.12 13:19:57 INFO - mozcrash == 0.17 13:19:57 INFO - mozdebug == 0.1 13:19:57 INFO - mozdevice == 0.48 13:19:57 INFO - mozfile == 1.2 13:19:57 INFO - mozhttpd == 0.7 13:19:57 INFO - mozinfo == 0.9 13:19:57 INFO - mozleak == 0.1 13:19:57 INFO - mozlog == 3.1 13:19:57 INFO - moznetwork == 0.27 13:19:57 INFO - mozprocess == 0.22 13:19:57 INFO - mozprofile == 0.28 13:19:57 INFO - mozrunner == 6.11 13:19:57 INFO - mozscreenshot == 0.1 13:19:57 INFO - mozsystemmonitor == 0.0 13:19:57 INFO - moztest == 0.7 13:19:57 INFO - mozversion == 1.4 13:19:57 INFO - psutil == 3.1.1 13:19:57 INFO - requests == 1.2.3 13:19:57 INFO - wsgiref == 0.1.2 13:19:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:19:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:19:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:19:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:19:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:57 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 0x10b77fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7864c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe2d924e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b623d30>, '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 0x10b487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b61ab20>, '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.RnQXiyk2Ul/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.7haknWOEin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:19:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:19:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:19:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnQXiyk2Ul/Render', 13:19:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:19:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:19:57 INFO - 'HOME': '/Users/cltbld', 13:19:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:19:57 INFO - 'LOGNAME': 'cltbld', 13:19:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:19:57 INFO - 'MOZ_NO_REMOTE': '1', 13:19:57 INFO - 'NO_EM_RESTART': '1', 13:19:57 INFO - 'PAGER': '/bin/cat', 13:19:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:19:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:19:57 INFO - 'PWD': '/builds/slave/test', 13:19:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:19:57 INFO - 'SHELL': '/bin/bash', 13:19:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7haknWOEin/Listeners', 13:19:57 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 13:19:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:19:57 INFO - 'USER': 'cltbld', 13:19:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:19:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:19:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:19:57 INFO - 'XPC_FLAGS': '0x0', 13:19:57 INFO - 'XPC_SERVICE_NAME': '0', 13:19:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:19:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:19:57 INFO - Cleaning up... 13:19:57 INFO - Return code: 0 13:19:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:19:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:19:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:19:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:19:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:19:57 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 0x10b77fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7864c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe2d924e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b623d30>, '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 0x10b487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b61ab20>, '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.RnQXiyk2Ul/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.7haknWOEin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:19:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:19:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:19:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnQXiyk2Ul/Render', 13:19:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:19:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:19:57 INFO - 'HOME': '/Users/cltbld', 13:19:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:19:57 INFO - 'LOGNAME': 'cltbld', 13:19:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:19:57 INFO - 'MOZ_NO_REMOTE': '1', 13:19:57 INFO - 'NO_EM_RESTART': '1', 13:19:57 INFO - 'PAGER': '/bin/cat', 13:19:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:19:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:19:57 INFO - 'PWD': '/builds/slave/test', 13:19:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:19:57 INFO - 'SHELL': '/bin/bash', 13:19:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7haknWOEin/Listeners', 13:19:57 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 13:19:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:19:57 INFO - 'USER': 'cltbld', 13:19:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:19:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:19:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:19:57 INFO - 'XPC_FLAGS': '0x0', 13:19:57 INFO - 'XPC_SERVICE_NAME': '0', 13:19:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:19:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:19:58 INFO - Downloading/unpacking jsonschema 13:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:19:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:19:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:19:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:19:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:20:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:20:00 INFO - Downloading/unpacking functools32 (from jsonschema) 13:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:20:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:20:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:20:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:20:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:20:00 INFO - Downloading functools32-3.2.3-2.tar.gz 13:20:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:20:01 INFO - warning: no files found matching '*.txt' 13:20:01 INFO - no previously-included directories found matching 'build' 13:20:01 INFO - no previously-included directories found matching 'dist' 13:20:01 INFO - no previously-included directories found matching '.git*' 13:20:01 INFO - Installing collected packages: jsonschema, functools32 13:20:01 INFO - Running setup.py install for functools32 13:20:01 INFO - warning: no files found matching '*.txt' 13:20:01 INFO - no previously-included directories found matching 'build' 13:20:01 INFO - no previously-included directories found matching 'dist' 13:20:01 INFO - no previously-included directories found matching '.git*' 13:20:01 INFO - Successfully installed jsonschema functools32 13:20:01 INFO - Cleaning up... 13:20:01 INFO - Return code: 0 13:20:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:20:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:20:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:20:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:20:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b77fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7864c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe2d924e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b623d30>, '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 0x10b487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b61ab20>, '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.RnQXiyk2Ul/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.7haknWOEin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:20:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:20:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnQXiyk2Ul/Render', 13:20:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:20:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:20:01 INFO - 'HOME': '/Users/cltbld', 13:20:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:20:01 INFO - 'LOGNAME': 'cltbld', 13:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:20:01 INFO - 'MOZ_NO_REMOTE': '1', 13:20:01 INFO - 'NO_EM_RESTART': '1', 13:20:01 INFO - 'PAGER': '/bin/cat', 13:20:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:20:01 INFO - 'PWD': '/builds/slave/test', 13:20:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:20:01 INFO - 'SHELL': '/bin/bash', 13:20:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7haknWOEin/Listeners', 13:20:01 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 13:20:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:20:01 INFO - 'USER': 'cltbld', 13:20:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:20:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:20:01 INFO - 'XPC_FLAGS': '0x0', 13:20:01 INFO - 'XPC_SERVICE_NAME': '0', 13:20:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:20:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:20:01 INFO - Downloading/unpacking flake8 13:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:20:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:20:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:20:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:20:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:20:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:20:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:20:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:20:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:20:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:20:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:20:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:20:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:20:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:20:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:20:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:20:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:20:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:20:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:20:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:20:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:20:05 INFO - no previously-included directories found matching 'docs/_build' 13:20:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:20:05 INFO - Running setup.py install for pep8 13:20:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:20:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:20:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:20:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:20:05 INFO - no previously-included directories found matching 'docs/_build' 13:20:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:20:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:20:05 INFO - Cleaning up... 13:20:05 INFO - Return code: 0 13:20:05 INFO - Running post-action listener: _resource_record_post_action 13:20:05 INFO - Running post-action listener: _start_resource_monitoring 13:20:05 INFO - Starting resource monitoring. 13:20:05 INFO - ##### 13:20:05 INFO - ##### Running install step. 13:20:05 INFO - ##### 13:20:05 INFO - Running pre-action listener: _resource_record_pre_action 13:20:05 INFO - Running main action method: install 13:20:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:20:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:20:05 INFO - Reading from file tmpfile_stdout 13:20:05 INFO - Detecting whether we're running mozinstall >=1.0... 13:20:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:20:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:20:06 INFO - Reading from file tmpfile_stdout 13:20:06 INFO - Output received: 13:20:06 INFO - Usage: mozinstall [options] installer 13:20:06 INFO - Options: 13:20:06 INFO - -h, --help show this help message and exit 13:20:06 INFO - -d DEST, --destination=DEST 13:20:06 INFO - Directory to install application into. [default: 13:20:06 INFO - "/builds/slave/test"] 13:20:06 INFO - --app=APP Application being installed. [default: firefox] 13:20:06 INFO - mkdir: /builds/slave/test/build/application 13:20:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:20:38 INFO - Reading from file tmpfile_stdout 13:20:38 INFO - Output received: 13:20:38 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:20:38 INFO - Running post-action listener: _resource_record_post_action 13:20:38 INFO - ##### 13:20:38 INFO - ##### Running run-tests step. 13:20:38 INFO - ##### 13:20:38 INFO - Running pre-action listener: _resource_record_pre_action 13:20:38 INFO - Running main action method: run_tests 13:20:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:20:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:20:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:20:38 INFO - 2016-04-22 13:20:38.037 screenresolution[1643:7648] starting screenresolution argv=screenresolution get 13:20:38 INFO - 2016-04-22 13:20:38.040 screenresolution[1643:7648] Display 0: 1600x1200x32@60 13:20:38 INFO - 2016-04-22 13:20:38.046 screenresolution[1644:7651] starting screenresolution argv=screenresolution list 13:20:38 INFO - Available Modes on Display 0 13:20:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:20:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:20:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:20:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:20:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:20:38 INFO - Intel Iris: 13:20:38 INFO - Chipset Model: Intel Iris 13:20:38 INFO - Type: GPU 13:20:38 INFO - Bus: Built-In 13:20:38 INFO - VRAM (Dynamic, Max): 1536 MB 13:20:38 INFO - Vendor: Intel (0x8086) 13:20:38 INFO - Device ID: 0x0a2e 13:20:38 INFO - Revision ID: 0x0009 13:20:38 INFO - Displays: 13:20:38 INFO - Display: 13:20:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:20:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:20:38 INFO - Display Serial Number: 1600x1200 60 13:20:38 INFO - Main Display: Yes 13:20:38 INFO - Mirror: Off 13:20:38 INFO - Online: Yes 13:20:38 INFO - Rotation: Supported 13:20:38 INFO - Return code: 0 13:20:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:20:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:20:38 INFO - Python 2.7.3 13:20:38 INFO - Return code: 0 13:20:38 INFO - grabbing minidump binary from tooltool 13:20:38 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')]} 13:20:38 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 0x10b623d30>, '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 0x10b487db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b61ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:20:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:20:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 13:20:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:20:38 INFO - Return code: 0 13:20:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:20:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:20:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:20:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:20:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:20:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:20:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:20:39 INFO - Return code: 0 13:20:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0184', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:20:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0184 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:20:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RnQXiyk2Ul/Render', 13:20:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:20:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:20:39 INFO - 'HOME': '/Users/cltbld', 13:20:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:20:39 INFO - 'LOGNAME': 'cltbld', 13:20:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:20:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:20:39 INFO - 'MOZ_NO_REMOTE': '1', 13:20:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:20:39 INFO - 'NO_EM_RESTART': '1', 13:20:39 INFO - 'PAGER': '/bin/cat', 13:20:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:20:39 INFO - 'PWD': '/builds/slave/test', 13:20:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:20:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:20:39 INFO - 'SHELL': '/bin/bash', 13:20:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7haknWOEin/Listeners', 13:20:39 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 13:20:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:20:39 INFO - 'USER': 'cltbld', 13:20:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:20:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:20:39 INFO - 'XPC_FLAGS': '0x0', 13:20:39 INFO - 'XPC_SERVICE_NAME': '0', 13:20:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:20:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0184', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:20:39 INFO - mozversion application_buildid: 20160422122844 13:20:39 INFO - mozversion application_changeset: 104bef115f31497dd411582328eef6ed375d0c72 13:20:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:20:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:20:39 INFO - mozversion application_name: Firefox 13:20:39 INFO - mozversion application_remotingname: firefox-dev 13:20:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:20:39 INFO - mozversion application_vendor: Mozilla 13:20:39 INFO - mozversion application_version: 47.0a2 13:20:39 INFO - mozversion platform_buildid: 20160422122844 13:20:39 INFO - mozversion platform_changeset: 104bef115f31497dd411582328eef6ed375d0c72 13:20:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:20:39 INFO - mozversion platform_version: 47.0a2 13:20:39 INFO - using testdate: 1461356439 13:20:39 INFO - actual date: 1461356439 13:20:39 INFO - starting webserver on 'localhost:49277' 13:20:39 INFO - SUITE-START | Running 2 tests 13:20:39 INFO - TEST-START | dromaeo_css 13:20:39 INFO - operating with platform_type : mac_ 13:20:39 INFO - Initialising browser for dromaeo_css test... 13:20:39 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpWRvw9L/profile http://localhost:49277/getInfo.html) 13:20:42 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 13:20:42 INFO - PROCESS | 1662 | colorDepth:24 13:20:42 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 13:20:42 INFO - PROCESS | 1662 | __metrics 13:20:42 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:20:42 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:20:42 INFO - TEST-INFO | 1662: exit 0 13:20:42 INFO - Browser initialized. 13:20:42 INFO - Running cycle 1/1 for dromaeo_css test... 13:20:42 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpWRvw9L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:20:54 INFO - PROCESS | 1664 | RSS: Main: 207896576 13:20:54 INFO - PROCESS | 1664 | 13:21:45 INFO - PROCESS | 1664 | 1461356505605 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:23:55 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 13:23:57 INFO - PROCESS | 1664 | RSS: Main: 348954624 13:23:57 INFO - PROCESS | 1664 | 13:26:59 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 13:27:00 INFO - PROCESS | 1664 | RSS: Main: 346738688 13:27:00 INFO - PROCESS | 1664 | 13:30:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 13:30:02 INFO - PROCESS | 1664 | RSS: Main: 345763840 13:30:02 INFO - PROCESS | 1664 | 13:33:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 13:33:05 INFO - PROCESS | 1664 | RSS: Main: 307728384 13:33:05 INFO - PROCESS | 1664 | 13:36:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 13:36:09 INFO - PROCESS | 1664 | RSS: Main: 307580928 13:36:09 INFO - PROCESS | 1664 | 13:39:11 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 13:39:12 INFO - PROCESS | 1664 | RSS: Main: 354910208 13:39:12 INFO - PROCESS | 1664 | 13:39:12 INFO - PROCESS | 1664 | __start_tp_report 13:39:12 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 13:39:12 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 13:39:12 INFO - PROCESS | 1664 | |i|pagename|runs| 13:39:12 INFO - PROCESS | 1664 | |0;dojo.html;2987.536931775574;3531.9470207946883;3545.0960005198676;3563.6258192889763;3600.4959305697207;5810.273715785527;5815.273090863642;5834.766609335632;5836.6789826559525;5851.122331650255;5788.218590490283;5823.82528524145;5845.941540584604;5846.386129456409;5880.059190529516;5198.480151984809;5225.190095535188;5252.159654455288;5266.210068489719;5318.867028324285;5272.209168624718;5276.709780962045;5276.868078298054;5280.973595132019;5305.363356397238;5229.843104706865;5280.683159010452;5303.893922121555;5313.840584782443;5336.8132115375965;4673.672842900998;5276.973615131919;5315.229291752713;5323.068463018956;5369.19462080691;5188.792448302063;5207.375114986188;5238.73806309683;5269.920951185735;5284.154535274338;5692.231548740909;5702.857428564319;5732.426757324276;5743.282089738783;5759;6160.7227674754295;6281.937180628181;6323.019931910515;6337.461315788163;6339.936600633981;6244.250689917194;6259.624422534663;6290.748370065192;6306.684665766693;6345.206849143857;5951.077582974646;5952.136940143655;5963.373845746134;5966.3138739045135;5970.194023806731;6252.124702541648;6323.494120470351;6342.714577844008;6382.234131904202;6385.48916086712;6102.328743838174;6156.415140561639;6177.4749146322165;6296.811095667137;6297.181366422427;5596.9352009659515;6038.215032045806;6271;6296.212973378328;6344.936550634527;6242.438180563771;6320.336364681642;6321.968390158112;6332.430081292706;6348.904766428507;6181.227346581677;6300.527460440485;6306.842328941813;6309.14826498426;6322;4933.580645645088;4947.060058588929;4996.600271978233;4999.700017998932;5031.320771695774;4461.687681862303;4489.932651010172;4493.707908985971;4501.3022981437925;4520.208963431386;5627.8874422512135;5772.624779389326;5805.941940580625;5840.737166827503;5882.176495290662;6246.3753624637175;6301.338359472281;6303.46420554258;6308.085327627468;6318;5748.166515855261;5767.711614419262;5792.884142317214;5794.101914203306;5794.449527294901;5427.83716488506;5437.592180586472;5448.482394172547;5470.316210473691;5476.452354764491;6255.718492667841;6383.329750376237;6460.676966151674;6464.482841372773;6471.708773105127;6275.121482992476;6311.652859092794;6316.210473690789;6317.5893566918;6345.682715864281;6275.121482992476;6296.653684047421;6392.52056095804;6402.839929001812;6409.775657851953;6175.413335733098;6297.370262973666;6305.8108256752375;6313.273973493107;6314.905276420765;6295.276043255084;6331.430171284606;6332.52506062038;6338.841528961812;6340.397662222082;6098.908516372169;6183.134361189347;6191.4118158774845;6208.627482351073;6215.06773983906;6114.358196334042;6150.507959363151;6209.906851397142;6230.844228894314;6239.78160764371;5017.147084995632;5991.520678345809;6067.150598916067;6094.0945724627045;6116.082587611894;6085.208922840003;6148.200733904564;6244.531660125418;6246.094316324198;6254.124422580933;6045.486133678686;6099.176611157437;6129.537764380637;6130.390533016695;6158.137860699595 13:39:12 INFO - PROCESS | 1664 | |1;ext.html;494.4585678681847;597.6921885229108;599.3407252022773;673.0980486148561;675.0717763075771;26596.60105098425;31995.52006719901;32051.839740801293;32052.679473205262;32203.194920127007;42397.57602423975;42622.57377426225;42746.35880461788;42797.71606851792;42807.3578896316;9768.462734549597;9946.602135914554;9972;9972.451515166664;9972.85040724386;9600.519974001307;9716.076972687619;9741.659041933533;9742.659006934759;9744.976777438373;9712.368696034735;9773;9781.951090244538;9787.070228328297;9795.755106122333;9696.563654635524;9746.853797193047;9749.415035097916;9760.853587196198;9762.511874406251;9890.80218395628;10023.849642255373;10027.047430494164;10031.19750419972;10034.849477257847;42638;43075;43095.784521077505;43100.56899430997;43114.78442607798;3269.9182520436943;3278.344331133783;3359.5968483781858;3416.555847739778;3948.7828169450668;28910.566341504895;28945.986890458942;29340.266493337625;30350;30652.693473065206;2799.9020034298906;2822.1956742328543;2846.558783388578;2903.6660784009896;2937.030779842647;492774;495364;497525;497658.6287754172;497719.5114024443;48344.27483587749;49631.50368496341;72802;73723.52552949019;73941.26058739344;48290;48335;48559.028819423416;48565.5143448561;48731.02537949292;16051;16165;16178.75731864023;16182.948108372919;16186;13485.797713034312;13524.053316267964;13536;13561.118527295694;13571.932140339235;15585.519375659294;15867.52397428079;15892.284847181905;15901.920490397473;15940.68118637644;6100.603460775035;6145.539084568675;6529.836754081186;6569.9343006569325;6653.135092437952;970.3983530211173;1016.6645007147623;1097.1277834121893;1131.4682099413262;1158.5192145259623;31090.067297981095;31100.377992440026;31101;31737;31814;1133.9659810205708;1142.9714257143637;1146.340854008948;1218.670958841108;1259.8425196850394;13049.739005219843;15844.76232856508;15882.68234635324;16296.918515407348;16401.753973690404;531480;538666;539272;539479;540084;63082;63767;63930.6803465989;63963.68018159879;63986.36013639897;1415.143837978029;1508.6605513759537;1537.3543111893032;1557.353698215228;1558.7038462692278;30075.849620751756;30110.512303107676;30518.389632207676;31318.373632526767;31453;41055.58944410518;51726;52130.47869521408;52329;52370.214446782564;372654;372673;374493;375504;376175.11912440264;30362.544561832034;34264.31471370609;35352.292954140255;35442.64557354496;35724.28551429009;37933.91363474925;38858.6114138855;40948;41859.37210941899;44247.33628995632;35577.644223557436;35954;37536.061598459164;38290;39117.60882391254;37624.87125386243;37707.81146094252;38070.61929380671;38103.80948095242;38511.422328664536;37525.81237093797;37763;38050;38651.8404447867;38978.61021389863;32646.020619381456;32648.78143770847;33066.66933330636;33233.00300990974;33578.83210583931;25516.872415637805;25545.358206865978;25595.976160953353;25864.482710346063;26016.219513414628 13:39:12 INFO - PROCESS | 1664 | |2;jquery.html;366.91194113412774;374.1002888054229;376.4560210495833;383.1398510344277;387.3685891996046;20051.99740012999;20540.178392864287;21278.787212127874;21573.46066348342;21599;20499.385018449433;21018.894905525452;21235.787642123574;21260.14959401622;22368.776312236874;5189.766460509286;5723.284589426322;5861.7948371807;5887.528997680197;5913.674747888865;4063.6139566741113;4242.936355954663;5730.169125476803;5841.941580584204;6094.908576371358;6084.300305464884;6087.817365479043;6090.477380654836;6099.57302988791;6106.81679549612;4058.452108965311;4244.554321796229;5642.4357564243455;5908.084246941731;6068.0291153415465;5460.563154947635;5691.260136182311;5701;5701.828945131652;5711.371749107595;13395.732085358239;13663.453461861514;24460.877695611587;25427.87286063576;25455.61816572753;2169.39257008038;2181.9236326728487;2350.5651454480976;2441.9145329913545;2512.6105453654714;9717.805643887154;9975.75060623483;10128.18974482047;10299.53652085658;10473.26687131901;1701.8213087625868;1723.3881971900046;1791.2118667786133;1882.275324000256;1903.2767548331544;93209;105756.41365379526;107424.99999999921;113993;114148;11060.778784424267;11429.885701143048;11489.770204595861;11530.538778448768;11905.642830715093;14683.045602035834;14748.041377310656;14785.704285914437;14792.926035369754;14833.480828170963;8250;8767.605457754416;8954.328425367994;9025.638974440948;9103.271738260923;9206.723798286062;9220.815583688289;9224.49265290402;9241.706161137447;9277.443353398818;3763.946095093378;4597.172508948422;4597.816087356535;4603.884902877455;4646.094011667692;1823.052012953257;1891.914863831131;1928.1901601327477;1955.2667749593902;1958.7061940708722;409.2020559908191;741.2661465149463;1017.2878984710732;1021.9269766745036;1035.4985271356563;9693.951530242304;11832.76334473323;12023.21849079807;12049.638510844688;12302.876971230173;458.51168505541494;458.7866642011481;467.3270490493678;469.17893686049416;471.8131460679774;5990.820275391745;6458.321876203025;6510.641914694643;6542.54202205841;6573.901391479131;248735;486302;492951.81716822827;540512;589866.1013389897;127244;132709;134852;135280;136634;474.42831388177854;477.0387668847276;481.5040508276481;481.88434775653593;481.93734814473896;10469.842952355568;11401.828972565252;11832.46753896094;11865.762684746429;11882;50829;52998.940021200135;53391.7330413361;54854.725726371114;55877;70616.6469167651;74875.62562187154;74939.62530187314;75082;79392.60303698445;8161.183881612029;8174.509529428254;8301.045379781403;8414.621342039745;8458.957705211435;12423.31671758062;12803.551875684307;13422;13790.517331893336;13954.651133721738;11404.429778510876;11641.47613357384;11692.532298707956;11927.582534611247;11944.522219111139;9376.624935002523;11657.941710291394;11695.298282103102;11910.28538287671;11958.402079895797;11732.765344692887;11758.3532905691;11959.429780777049;12059.095567832272;12238.75522489563;8170.346372290083;8223.136570660234;8261.925949626751;8551.57242137903;8565.443246188977;8355.498670079814;8670.956645216733;11822.054235660955;12020;12052.819207711716 13:39:12 INFO - PROCESS | 1664 | |3;mootools.html;1506.593219830645;1643.6383995520982;1664.9750253746192;2028.9391318260448;2100.1284466946217;7705.576193309365;8582.785430364243;9157.175854173123;9164.266858651308;9176.036516165797;8853.557322133884;9130.452172869616;9144.451332920013;9144.4970526621;9150.725478235647;2547.2103647869176;2583.547879121156;2593.662823832899;2595;2595.9221223363234;2558.3092565007437;2640.656714627096;2645.0477827981913;2647.589623608334;2657.4020845309747;2568.1781829814468;2579.522788284164;2591.935201619965;2594.3514121469634;2596.0135148643585;2565.0637517306236;2584.8707564621786;2593.6368908352847;2596.5845464725553;2596.792256619466;2594.792416606667;2607.5958226474927;2623.160588611645;2624.960625590618;2633.855137967411;9197.21823644991;9224.354295199339;9225.584848681758;9248.491332976684;9271.119243671841;5618.6628802272;5730.226419433365;5781.797637082724;5784.421557844224;5799.7100144993;3653.6894363979095;3720.627937206285;3768.3028639701743;3777.1690228149764;3784.299904517673;1066.2802608239408;1081.848541204232;1084.446932064645;1086.5816660585651;1124.2579897267847;12379.195352302071;12555.937220313932;12596.055295852848;12609.684757881034;12613.99088072952;4992.525710057539;5071.619628527875;5109.56568691658;5111.05445492585;5111.8722031948755;10749.78500429987;10760.192985526117;10779.02988731018;10782.245242833083;10785.730356741144;5706.543476521868;5735.397783232784;5743.310802703702;5744.885102298014;5769.163471296639;4596.310553416947;4728.456227533878;4937.728424936591;5129.615278854101;5158.329417175743;4146.979265103655;4156.646685031807;4171.228322760238;4203.096334288142;4244.405783190356;1343.1336787771831;1373.7252549490142;1377.6969066805286;1380.4340220509544;1384.6746014686541;1030.2376241581176;1039.6205385034407;1064.590132798878;1064.8881867403811;1130;8264.173582641688;8290.834183316421;8358.749237522885;8384.455010424303;8432.156784321642;658.4271683635268;671.1979184874126;673.5487223560226;674.1707699529508;809.5749731391066;6307.810765677037;6314.684265786692;6348.111264422892;6385.106085148083;6417.454516366068;10561.947190264153;11013.2841365311;11148.498317575728;11252.831207531894;11336.773264534662;10728.89271107279;11061.834072488919;11103.611373601885;11125.944370278097;11254.43727813606;8590.14098590136;8665.306775457948;8730.65077396897;8733.432326898732;8758.255548278468;8132.349412046904;8134.796630084295;8209.79475513117;8211.096779354386;8247.876281855897;8240.876386854083;9758.268129890144;10025.74935626586;10191.592336306761;10233.181345492492;10278.17774578017;10497.632582859564;10575.259731818698;10658;10668.46657667098;7682.193369696101;7690.500117492345;7763.68945242184;7767.611619419119;7769.611519424119;8516.446430981967;8549.829003419773;8602.397832151939;8604.655813767378;8644.78388040304;7512.361449276873;7557.206493318123;7642;7685.692572297269;7804.492707973964;7413.925860741324;7471.140818805689;7477;7604.961975190088;7618.238176182337;7950.443468957117;7987.16134805837;8016.238457346542;8016.839663206587;8023.4784738991775;7068.29317068289;7274.381677557255;7310.049693539806;7315.268473152642;7330.890036649348;7400.296971787671;7454.217307182885;7457.291557302047;7458.813529661802;7473.327400533978 13:39:12 INFO - PROCESS | 1664 | |4;prototype.html;332.4166088514657;333.1554509318876;341.8342104079521;343.0446207312635;347.6818710879547;6573.244076931154;6577.605343679376;6585.703643336049;6594.2746297907315;6627.1716035495565;5255.894882102365;6588.835279118024;6588.868222635544;6610.173728283965;6615.1069605603225;4195.727277726938;4695.3191787190835;4771.236602143657;4786.688865223767;4931.284963680273;4773.594244489223;4894.9965257122385;4919.803207871681;4936.111499930011;4942.481039490855;4833.564979151875;4877.609791216694;4883.804647814083;4890.559849613534;4904.117258893398;4628.537146285378;4698.882527936795;4817.927731084036;4835.854924352275;4870.293807397925;4145.647619952308;4155.168966206771;4162.729422587522;4164.146349998243;4165.083681590046;6252.937470625281;6314.71583778731;6315.5894866833505;6322.241331040259;6326.019466982625;5826.679532625704;5848.561357898132;5854.18041474194;5857.414258574151;5868.354481007086;7071.186813516409;7074.3633073023675;7092.468064895102;7098.609576473292;7101.076860008166;4436.73379597222;4588.449386073659;4591.6097131743845;4595.425571803525;4602.493725690171;6331.303556608769;7126.180489243751;7559.1480087378395;7571.772846814604;7576.280253375976;3906.7851268180525;4697.13103075932;4701.78841952113;4705.1765940960195;4709.858704238878;4070.5522392536795;4728.4562275338085;4766.451858036301;4776.95223047772;4785.617150627942;5710.514606258432;5736.77052917887;5746.339170995306;5755.769769209269;5756.280464941882;6815.863682726417;7128.322809333105;7150.678219480136;7159.89260161098;7172.569645821268;1625.081828766743;1639.0821140161547;1653.1238443624898;1655.1724137931035;1656;987.027777639025;1034.13132968307;1037.6886933919945;1053.8050460664801;1057.7197042783669;5820.679862607512;6164.907526387108;6362.77730279438;6489.5781774184525;6677.899831502531;7419.406447484188;7423.331900129015;7424.96287518559;7435.40516758658;7443.36731377839;6608.8347791305605;6619.602823830585;6624.8012559623285;6626.966865165643;6647.800565983028;3860.285847118292;3918;3919.647231749156;3924.9607503925167;3932.11527406337;7481.663325150382;7793.298603125746;7802;7805.726799561988;7815.179406162385;7650.42621803367;7696.076470823425;7701.075870895528;7715.652795624098;7719.575423351769;7741.341985931146;7753.65108570127;7754.9224507755325;7778.183290754502;7793.532388056621;7116.712088961561;7376.4836461447185;7418.961345411539;7445.292697193758;7458.545583611361;7247.456440766858;7299.233580473974;7317.341439270491;7318.085239345082;7329.047223861077;6256.562040657111;6996.755113571001;7093.758592246233;7732.961335193288;7749.108852482037;4635.1656701794;4676.649251306234;4691.296305554194;4694;4694.483606803181;5750.568707346882;6277.246730392382;7143.1428228612895;7359.926400735924;7364.226756190737;7046.330598593126;7176.246494118042;7180.5691658500655;7208.9639551804;7220.025296585104;7151.356377926011;7164.104486939133;7167.462440316893;7175.892361614684;7187.712491500491;6992.79021629352;7005.579665220103;7029.61337126463;7034.366906978396;7034.859302814018;6935.202451718117;7139.464540159405;7153.6423178839805;7164;7194.784156475314;6615.636140012345;6840.452763778786;6847.691853866489;7027.789166325018;7053.188883278283 13:39:12 INFO - PROCESS | 1664 | |5;yui.html;741.4550305525439;772.1660606544932;795.7334293011841;802.9879551806724;805.122416565943;15403.537893863187;15705.607359816011;15786;15789.842101578995;15820;11941.761164776699;13138.540151094714;14440.494582689607;16220.513384598451;16300.347986080542;994;1005.2058873489971;1006.079437314856;1010.5907107621425;1011.1657882247139;972.6158167523823;974.7319487141023;979.5347210075192;979.6816034788686;1008.0423597582289;1022.1822541966434;1022.8772547294298;1023.2632504596678;1059.2902755154028;1066.3761699405857;1005.492226425654;1051.3901936876593;1054.699410667956;1057.1437135919891;1059.5549869054978;736.1240124252123;758.29478584916;778.9805254868617;795.7891158842912;798.2655956519958;14031;15074.246287685572;15684.294206760724;15810.683786324324;15820;10511.52698128586;13480.5281815136;13559.186448813103;13699.246541440218;13935.58193254204;9847.655332063416;9945;10142.797144057151;10269.79460410795;10374.481275936248;3481.54739883816;3520.4895290183035;3545.8936231913085;3579.8031108289033;3628.6734193922675;67539.98690019654;70255.64872175657;74955.62522187355;85570.71643925346;86416.27167456616;9872.950635246922;10011.79976400468;10045.146162576117;10056.59773609062;10289.022542858416;22579.322620321418;22583.661245081337;22584.774152258597;22585.4353641157;22607.773922260567;7528.585927774025;7613.885791713129;7623.9618801905635;7680.769576912701;7856.332211762065;4081.183763247303;4112.3420252759415;4126.339785634284;4166.541680415152;4267.317229243306;3794.3739283017994;3825.9426108608395;3826.617338266207;3848.230353929225;3850.210706805098;651.775137577535;677.7831094049857;682.4131247127466;687.4569090418613;691.9550229235084;497.98506044818714;604.4418064005592;606.6996836565839;616.3189675408698;646.9191351081115;8143.18568143181;8207.835843283221;8240.50556966584;8282.254597086292;8447.746567602982;597.026846240625;601.759296281491;605.4006533531772;609.2171559545963;609.6768712582339;3775.7734535927934;3872.0707030312524;3908.785016824102;3917.941230881539;3972.7616343019513;93342.53328733449;94234.58648120283;94397.5280123595;94467.52766236124;95705.5214723922;61938;75350;80650.72664807132;80724;82812;71171;73822;74501.39245126395;74795;74957;7729.536227826348;7906.486078404885;7942.08666003417;7951.960240198993;7989.760307190793;52223.17218897321;52337.69155771136;52885.20672189843;53176.73411632917;53249;69293;73788;74296.62851685708;75042;75730.62134689292;10339.896601033894;10501.212409069198;10571;10769.353838769699;11254.212205145888;10980;11008.724781880517;11246.831297530707;11257.662270131908;11591.710207244887;10337.431441270803;10351.223658225512;10487.10859576914;10508.474576271308;10530;10261.846072308772;10527.210459215743;10632.705083802639;10817.242793004729;10838.783224335626;9974.40153590787;10433.895661043596;10546.419946902994;10648.680539583514;10664.466776661291;6481.060246264171;7832.608369581612;8402.41183117176;8919.171252060882;8925.509096999727;8585.441946273471;8931.866022009544;9027.052159523419;9102.863457048017;9142.222911052635 13:39:12 INFO - PROCESS | 1664 | __end_tp_report 13:39:12 INFO - PROCESS | 1664 | __start_cc_report 13:39:12 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1186 13:39:12 INFO - PROCESS | 1664 | __end_cc_report 13:39:12 INFO - PROCESS | 1664 | __startTimestamp1461357552043__endTimestamp 13:39:12 INFO - PROCESS | 1664 | 13:39:12 INFO - PROCESS | 1664 | ------- Summary: start ------- 13:39:12 INFO - PROCESS | 1664 | Number of tests: 6 13:39:12 INFO - PROCESS | 1664 | 13:39:12 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5814.86 Median:6094.09 stddev:648.34 (10.6%) stddev-sans-first:611.22 13:39:12 INFO - PROCESS | 1664 | Values: 2987.5 3531.9 3545.1 3563.6 3600.5 5810.3 5815.3 5834.8 5836.7 5851.1 5788.2 5823.8 5845.9 5846.4 5880.1 5198.5 5225.2 5252.2 5266.2 5318.9 5272.2 5276.7 5276.9 5281.0 5305.4 5229.8 5280.7 5303.9 5313.8 5336.8 4673.7 5277.0 5315.2 5323.1 5369.2 5188.8 5207.4 5238.7 5269.9 5284.2 5692.2 5702.9 5732.4 5743.3 5759.0 6160.7 6281.9 6323.0 6337.5 6339.9 6244.3 6259.6 6290.7 6306.7 6345.2 5951.1 5952.1 5963.4 5966.3 5970.2 6252.1 6323.5 6342.7 6382.2 6385.5 6102.3 6156.4 6177.5 6296.8 6297.2 5596.9 6038.2 6271.0 6296.2 6344.9 6242.4 6320.3 6322.0 6332.4 6348.9 6181.2 6300.5 6306.8 6309.1 6322.0 4933.6 4947.1 4996.6 4999.7 5031.3 4461.7 4489.9 4493.7 4501.3 4520.2 5627.9 5772.6 5805.9 5840.7 5882.2 6246.4 6301.3 6303.5 6308.1 6318.0 5748.2 5767.7 5792.9 5794.1 5794.4 5427.8 5437.6 5448.5 5470.3 5476.5 6255.7 6383.3 6460.7 6464.5 6471.7 6275.1 6311.7 6316.2 6317.6 6345.7 6275.1 6296.7 6392.5 6402.8 6409.8 6175.4 6297.4 6305.8 6313.3 6314.9 6295.3 6331.4 6332.5 6338.8 6340.4 6098.9 6183.1 6191.4 6208.6 6215.1 6114.4 6150.5 6209.9 6230.8 6239.8 5017.1 5991.5 6067.2 6094.1 6116.1 6085.2 6148.2 6244.5 6246.1 6254.1 6045.5 6099.2 6129.5 6130.4 6158.1 13:39:12 INFO - PROCESS | 1664 | 13:39:12 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:61583.89 Median:206900.00 stddev:126179.07 (61.0%) stddev-sans-first:126449.68 13:39:12 INFO - PROCESS | 1664 | Values: 494.5 597.7 599.3 673.1 675.1 26596.6 31995.5 32051.8 32052.7 32203.2 42397.6 42622.6 42746.4 42797.7 42807.4 9768.5 9946.6 9972.0 9972.5 9972.9 9600.5 9716.1 9741.7 9742.7 9745.0 9712.4 9773.0 9782.0 9787.1 9795.8 9696.6 9746.9 9749.4 9760.9 9762.5 9890.8 10023.8 10027.0 10031.2 10034.8 42638.0 43075.0 43095.8 43100.6 43114.8 3269.9 3278.3 3359.6 3416.6 3948.8 28910.6 28946.0 29340.3 30350.0 30652.7 2799.9 2822.2 2846.6 2903.7 2937.0 492774.0 495364.0 497525.0 497658.6 497719.5 48344.3 49631.5 72802.0 73723.5 73941.3 48290.0 48335.0 48559.0 48565.5 48731.0 16051.0 16165.0 16178.8 16182.9 16186.0 13485.8 13524.1 13536.0 13561.1 13571.9 15585.5 15867.5 15892.3 15901.9 15940.7 6100.6 6145.5 6529.8 6569.9 6653.1 970.4 1016.7 1097.1 1131.5 1158.5 31090.1 31100.4 31101.0 31737.0 31814.0 1134.0 1143.0 1146.3 1218.7 1259.8 13049.7 15844.8 15882.7 16296.9 16401.8 531480.0 538666.0 539272.0 539479.0 540084.0 63082.0 63767.0 63930.7 63963.7 63986.4 1415.1 1508.7 1537.4 1557.4 1558.7 30075.8 30110.5 30518.4 31318.4 31453.0 41055.6 51726.0 52130.5 52329.0 52370.2 372654.0 372673.0 374493.0 375504.0 376175.1 30362.5 34264.3 35352.3 35442.6 35724.3 37933.9 38858.6 40948.0 41859.4 44247.3 35577.6 35954.0 37536.1 38290.0 39117.6 37624.9 37707.8 38070.6 38103.8 38511.4 37525.8 37763.0 38050.0 38651.8 38978.6 32646.0 32648.8 33066.7 33233.0 33578.8 25516.9 25545.4 25596.0 25864.5 26016.2 13:39:12 INFO - PROCESS | 1664 | 13:39:12 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:30643.80 Median:2073.54 stddev:82484.20 (3977.9%) stddev-sans-first:82684.26 13:39:12 INFO - PROCESS | 1664 | Values: 366.9 374.1 376.5 383.1 387.4 20052.0 20540.2 21278.8 21573.5 21599.0 20499.4 21018.9 21235.8 21260.1 22368.8 5189.8 5723.3 5861.8 5887.5 5913.7 4063.6 4242.9 5730.2 5841.9 6094.9 6084.3 6087.8 6090.5 6099.6 6106.8 4058.5 4244.6 5642.4 5908.1 6068.0 5460.6 5691.3 5701.0 5701.8 5711.4 13395.7 13663.5 24460.9 25427.9 25455.6 2169.4 2181.9 2350.6 2441.9 2512.6 9717.8 9975.8 10128.2 10299.5 10473.3 1701.8 1723.4 1791.2 1882.3 1903.3 93209.0 105756.4 107425.0 113993.0 114148.0 11060.8 11429.9 11489.8 11530.5 11905.6 14683.0 14748.0 14785.7 14792.9 14833.5 8250.0 8767.6 8954.3 9025.6 9103.3 9206.7 9220.8 9224.5 9241.7 9277.4 3763.9 4597.2 4597.8 4603.9 4646.1 1823.1 1891.9 1928.2 1955.3 1958.7 409.2 741.3 1017.3 1021.9 1035.5 9694.0 11832.8 12023.2 12049.6 12302.9 458.5 458.8 467.3 469.2 471.8 5990.8 6458.3 6510.6 6542.5 6573.9 248735.0 486302.0 492951.8 540512.0 589866.1 127244.0 132709.0 134852.0 135280.0 136634.0 474.4 477.0 481.5 481.9 481.9 10469.8 11401.8 11832.5 11865.8 11882.0 50829.0 52998.9 53391.7 54854.7 55877.0 70616.6 74875.6 74939.6 75082.0 79392.6 8161.2 8174.5 8301.0 8414.6 8459.0 12423.3 12803.6 13422.0 13790.5 13954.7 11404.4 11641.5 11692.5 11927.6 11944.5 9376.6 11657.9 11695.3 11910.3 11958.4 11732.8 11758.4 11959.4 12059.1 12238.8 8170.3 8223.1 8261.9 8551.6 8565.4 8355.5 8671.0 11822.1 12020.0 12052.8 13:39:12 INFO - PROCESS | 1664 | 13:39:12 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:6303.35 Median:5120.74 stddev:3345.24 (65.3%) stddev-sans-first:3335.20 13:39:12 INFO - PROCESS | 1664 | Values: 1506.6 1643.6 1665.0 2028.9 2100.1 7705.6 8582.8 9157.2 9164.3 9176.0 8853.6 9130.5 9144.5 9144.5 9150.7 2547.2 2583.5 2593.7 2595.0 2595.9 2558.3 2640.7 2645.0 2647.6 2657.4 2568.2 2579.5 2591.9 2594.4 2596.0 2565.1 2584.9 2593.6 2596.6 2596.8 2594.8 2607.6 2623.2 2625.0 2633.9 9197.2 9224.4 9225.6 9248.5 9271.1 5618.7 5730.2 5781.8 5784.4 5799.7 3653.7 3720.6 3768.3 3777.2 3784.3 1066.3 1081.8 1084.4 1086.6 1124.3 12379.2 12555.9 12596.1 12609.7 12614.0 4992.5 5071.6 5109.6 5111.1 5111.9 10749.8 10760.2 10779.0 10782.2 10785.7 5706.5 5735.4 5743.3 5744.9 5769.2 4596.3 4728.5 4937.7 5129.6 5158.3 4147.0 4156.6 4171.2 4203.1 4244.4 1343.1 1373.7 1377.7 1380.4 1384.7 1030.2 1039.6 1064.6 1064.9 1130.0 8264.2 8290.8 8358.7 8384.5 8432.2 658.4 671.2 673.5 674.2 809.6 6307.8 6314.7 6348.1 6385.1 6417.5 10561.9 11013.3 11148.5 11252.8 11336.8 10728.9 11061.8 11103.6 11125.9 11254.4 8590.1 8665.3 8730.7 8733.4 8758.3 8132.3 8134.8 8209.8 8211.1 8247.9 8240.9 9758.3 10025.7 10191.6 10233.2 10278.2 10497.6 10575.3 10658.0 10668.5 7682.2 7690.5 7763.7 7767.6 7769.6 8516.4 8549.8 8602.4 8604.7 8644.8 7512.4 7557.2 7642.0 7685.7 7804.5 7413.9 7471.1 7477.0 7605.0 7618.2 7950.4 7987.2 8016.2 8016.8 8023.5 7068.3 7274.4 7310.0 7315.3 7330.9 7400.3 7454.2 7457.3 7458.8 7473.3 13:39:12 INFO - PROCESS | 1664 | 13:39:12 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:5790.66 Median:6587.27 stddev:1864.62 (28.3%) stddev-sans-first:1824.29 13:39:12 INFO - PROCESS | 1664 | Values: 332.4 333.2 341.8 343.0 347.7 6573.2 6577.6 6585.7 6594.3 6627.2 5255.9 6588.8 6588.9 6610.2 6615.1 4195.7 4695.3 4771.2 4786.7 4931.3 4773.6 4895.0 4919.8 4936.1 4942.5 4833.6 4877.6 4883.8 4890.6 4904.1 4628.5 4698.9 4817.9 4835.9 4870.3 4145.6 4155.2 4162.7 4164.1 4165.1 6252.9 6314.7 6315.6 6322.2 6326.0 5826.7 5848.6 5854.2 5857.4 5868.4 7071.2 7074.4 7092.5 7098.6 7101.1 4436.7 4588.4 4591.6 4595.4 4602.5 6331.3 7126.2 7559.1 7571.8 7576.3 3906.8 4697.1 4701.8 4705.2 4709.9 4070.6 4728.5 4766.5 4777.0 4785.6 5710.5 5736.8 5746.3 5755.8 5756.3 6815.9 7128.3 7150.7 7159.9 7172.6 1625.1 1639.1 1653.1 1655.2 1656.0 987.0 1034.1 1037.7 1053.8 1057.7 5820.7 6164.9 6362.8 6489.6 6677.9 7419.4 7423.3 7425.0 7435.4 7443.4 6608.8 6619.6 6624.8 6627.0 6647.8 3860.3 3918.0 3919.6 3925.0 3932.1 7481.7 7793.3 7802.0 7805.7 7815.2 7650.4 7696.1 7701.1 7715.7 7719.6 7741.3 7753.7 7754.9 7778.2 7793.5 7116.7 7376.5 7419.0 7445.3 7458.5 7247.5 7299.2 7317.3 7318.1 7329.0 6256.6 6996.8 7093.8 7733.0 7749.1 4635.2 4676.6 4691.3 4694.0 4694.5 5750.6 6277.2 7143.1 7359.9 7364.2 7046.3 7176.2 7180.6 7209.0 7220.0 7151.4 7164.1 7167.5 7175.9 7187.7 6992.8 7005.6 7029.6 7034.4 7034.9 6935.2 7139.5 7153.6 7164.0 7194.8 6615.6 6840.5 6847.7 7027.8 7053.2 13:39:12 INFO - PROCESS | 1664 | 13:39:12 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18509.96 Median:2382.65 stddev:26245.94 (1101.5%) stddev-sans-first:26285.66 13:39:12 INFO - PROCESS | 1664 | Values: 741.5 772.2 795.7 803.0 805.1 15403.5 15705.6 15786.0 15789.8 15820.0 11941.8 13138.5 14440.5 16220.5 16300.3 994.0 1005.2 1006.1 1010.6 1011.2 972.6 974.7 979.5 979.7 1008.0 1022.2 1022.9 1023.3 1059.3 1066.4 1005.5 1051.4 1054.7 1057.1 1059.6 736.1 758.3 779.0 795.8 798.3 14031.0 15074.2 15684.3 15810.7 15820.0 10511.5 13480.5 13559.2 13699.2 13935.6 9847.7 9945.0 10142.8 10269.8 10374.5 3481.5 3520.5 3545.9 3579.8 3628.7 67540.0 70255.6 74955.6 85570.7 86416.3 9873.0 10011.8 10045.1 10056.6 10289.0 22579.3 22583.7 22584.8 22585.4 22607.8 7528.6 7613.9 7624.0 7680.8 7856.3 4081.2 4112.3 4126.3 4166.5 4267.3 3794.4 3825.9 3826.6 3848.2 3850.2 651.8 677.8 682.4 687.5 692.0 498.0 604.4 606.7 616.3 646.9 8143.2 8207.8 8240.5 8282.3 8447.7 597.0 601.8 605.4 609.2 609.7 3775.8 3872.1 3908.8 3917.9 3972.8 93342.5 94234.6 94397.5 94467.5 95705.5 61938.0 75350.0 80650.7 80724.0 82812.0 71171.0 73822.0 74501.4 74795.0 74957.0 7729.5 7906.5 7942.1 7952.0 7989.8 52223.2 52337.7 52885.2 53176.7 53249.0 69293.0 73788.0 74296.6 75042.0 75730.6 10339.9 10501.2 10571.0 10769.4 11254.2 10980.0 11008.7 11246.8 11257.7 11591.7 10337.4 10351.2 10487.1 10508.5 10530.0 10261.8 10527.2 10632.7 10817.2 10838.8 9974.4 10433.9 10546.4 10648.7 10664.5 6481.1 7832.6 8402.4 8919.2 8925.5 8585.4 8931.9 9027.1 9102.9 9142.2 13:39:12 INFO - PROCESS | 1664 | -------- Summary: end -------- 13:39:12 INFO - PROCESS | 1664 | 13:39:12 INFO - TEST-INFO | 1664: exit 0 13:39:12 INFO - TEST-OK | dromaeo_css | took 1112805ms 13:39:12 INFO - TEST-START | kraken 13:39:12 INFO - operating with platform_type : mac_ 13:39:12 INFO - Initialising browser for kraken test... 13:39:12 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpeWF2Co/profile http://localhost:49277/getInfo.html) 13:39:14 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 13:39:14 INFO - PROCESS | 1673 | colorDepth:24 13:39:14 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 13:39:14 INFO - PROCESS | 1673 | __metrics 13:39:14 INFO - PROCESS | 1673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:39:14 INFO - PROCESS | 1673 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:39:15 INFO - TEST-INFO | 1673: exit 0 13:39:15 INFO - Browser initialized. 13:39:15 INFO - Running cycle 1/1 for kraken test... 13:39:15 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpeWF2Co/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:39:27 INFO - PROCESS | 1675 | RSS: Main: 207618048 13:39:27 INFO - PROCESS | 1675 | 13:39:30 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 13:39:30 INFO - PROCESS | 1675 | RSS: Main: 263335936 13:39:30 INFO - PROCESS | 1675 | 13:39:33 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 13:39:34 INFO - PROCESS | 1675 | RSS: Main: 273895424 13:39:34 INFO - PROCESS | 1675 | 13:39:37 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 13:39:37 INFO - PROCESS | 1675 | RSS: Main: 264159232 13:39:37 INFO - PROCESS | 1675 | 13:39:39 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 13:39:40 INFO - PROCESS | 1675 | RSS: Main: 265523200 13:39:40 INFO - PROCESS | 1675 | 13:39:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:39:43 INFO - PROCESS | 1675 | RSS: Main: 266207232 13:39:43 INFO - PROCESS | 1675 | 13:39:45 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 13:39:46 INFO - PROCESS | 1675 | RSS: Main: 266100736 13:39:46 INFO - PROCESS | 1675 | 13:39:49 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 13:39:49 INFO - PROCESS | 1675 | RSS: Main: 266125312 13:39:49 INFO - PROCESS | 1675 | 13:39:52 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 13:39:53 INFO - PROCESS | 1675 | RSS: Main: 266440704 13:39:53 INFO - PROCESS | 1675 | 13:39:54 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:39:55 INFO - PROCESS | 1675 | RSS: Main: 299229184 13:39:55 INFO - PROCESS | 1675 | 13:39:57 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:39:57 INFO - PROCESS | 1675 | RSS: Main: 299868160 13:39:57 INFO - PROCESS | 1675 | 13:39:59 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:40:00 INFO - PROCESS | 1675 | RSS: Main: 305647616 13:40:00 INFO - PROCESS | 1675 | 13:40:02 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:40:03 INFO - PROCESS | 1675 | RSS: Main: 307974144 13:40:03 INFO - PROCESS | 1675 | 13:40:05 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:40:06 INFO - PROCESS | 1675 | RSS: Main: 307990528 13:40:06 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 13:40:08 INFO - PROCESS | 1675 | RSS: Main: 370905088 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | __start_tp_report 13:40:08 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 13:40:08 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 13:40:08 INFO - PROCESS | 1675 | |i|pagename|runs| 13:40:08 INFO - PROCESS | 1675 | |0;ai-astar;111;107;103;109;110;126;175;150;110;111 13:40:08 INFO - PROCESS | 1675 | |1;audio-beat-detection;152;119;153;145;117;140;119;149;147;118 13:40:08 INFO - PROCESS | 1675 | |2;audio-dft;185;185;185;187;184;185;183;187;186;183 13:40:08 INFO - PROCESS | 1675 | |3;audio-fft;75;76;72;116;56;57;58;60;155;82 13:40:08 INFO - PROCESS | 1675 | |4;audio-oscillator;91;92;131;77;78;84;120;77;82;151 13:40:08 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;85;82;86;83;85;83;86;82;87;83 13:40:08 INFO - PROCESS | 1675 | |6;imaging-darkroom;94;140;150;112;92;92;92;93;92;92 13:40:08 INFO - PROCESS | 1675 | |7;imaging-desaturate;87;94;92;86;85;86;83;90;84;90 13:40:08 INFO - PROCESS | 1675 | |8;json-parse-financial;55;58;57;57;58;58;57;66;56;54 13:40:08 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;59;59;63;69;70;74;70;66;66;67 13:40:08 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;81;78;80;81;81;80;79;79;80;83 13:40:08 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;129;119;129;131;105;126;123;103;126;128 13:40:08 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;133;123;134;132;131;122;132;133;131;132 13:40:08 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;67;65;67;65;66;66;65;66;65;63 13:40:08 INFO - PROCESS | 1675 | __end_tp_report 13:40:08 INFO - PROCESS | 1675 | __start_cc_report 13:40:08 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1274 13:40:08 INFO - PROCESS | 1675 | __end_cc_report 13:40:08 INFO - PROCESS | 1675 | __startTimestamp1461357608413__endTimestamp 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | ------- Summary: start ------- 13:40:08 INFO - PROCESS | 1675 | Number of tests: 14 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:121.20 Median:111.00 stddev:23.30 (21.0%) stddev-sans-first:24.42 13:40:08 INFO - PROCESS | 1675 | Values: 111.0 107.0 103.0 109.0 110.0 126.0 175.0 150.0 110.0 111.0 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:146.00 stddev:15.62 (10.7%) stddev-sans-first:15.44 13:40:08 INFO - PROCESS | 1675 | Values: 152.0 119.0 153.0 145.0 117.0 140.0 119.0 149.0 147.0 118.0 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.00 stddev:1.41 (0.8%) stddev-sans-first:1.50 13:40:08 INFO - PROCESS | 1675 | Values: 185.0 185.0 185.0 187.0 184.0 185.0 183.0 187.0 186.0 183.0 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:80.70 Median:66.00 stddev:31.65 (48.0%) stddev-sans-first:33.50 13:40:08 INFO - PROCESS | 1675 | Values: 75.0 76.0 72.0 116.0 56.0 57.0 58.0 60.0 155.0 82.0 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:98.30 Median:80.00 stddev:26.25 (32.8%) stddev-sans-first:27.71 13:40:08 INFO - PROCESS | 1675 | Values: 91.0 92.0 131.0 77.0 78.0 84.0 120.0 77.0 82.0 151.0 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:84.20 Median:85.00 stddev:1.81 (2.1%) stddev-sans-first:1.90 13:40:08 INFO - PROCESS | 1675 | Values: 85.0 82.0 86.0 83.0 85.0 83.0 86.0 82.0 87.0 83.0 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:92.00 stddev:22.13 (24.1%) stddev-sans-first:23.12 13:40:08 INFO - PROCESS | 1675 | Values: 94.0 140.0 150.0 112.0 92.0 92.0 92.0 93.0 92.0 92.0 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:87.70 Median:88.50 stddev:3.62 (4.1%) stddev-sans-first:3.83 13:40:08 INFO - PROCESS | 1675 | Values: 87.0 94.0 92.0 86.0 85.0 86.0 83.0 90.0 84.0 90.0 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:57.50 stddev:3.24 (5.6%) stddev-sans-first:3.30 13:40:08 INFO - PROCESS | 1675 | Values: 55.0 58.0 57.0 57.0 58.0 58.0 57.0 66.0 56.0 54.0 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:68.00 stddev:4.85 (7.1%) stddev-sans-first:4.37 13:40:08 INFO - PROCESS | 1675 | Values: 59.0 59.0 63.0 69.0 70.0 74.0 70.0 66.0 66.0 67.0 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:80.50 stddev:1.40 (1.7%) stddev-sans-first:1.45 13:40:08 INFO - PROCESS | 1675 | Values: 81.0 78.0 80.0 81.0 81.0 80.0 79.0 79.0 80.0 83.0 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:121.90 Median:127.00 stddev:10.04 (7.9%) stddev-sans-first:10.31 13:40:08 INFO - PROCESS | 1675 | Values: 129.0 119.0 129.0 131.0 105.0 126.0 123.0 103.0 126.0 128.0 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:130.30 Median:132.00 stddev:4.22 (3.2%) stddev-sans-first:4.36 13:40:08 INFO - PROCESS | 1675 | Values: 133.0 123.0 134.0 132.0 131.0 122.0 132.0 133.0 131.0 132.0 13:40:08 INFO - PROCESS | 1675 | 13:40:08 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:1.18 (1.8%) stddev-sans-first:1.12 13:40:08 INFO - PROCESS | 1675 | Values: 67.0 65.0 67.0 65.0 66.0 66.0 65.0 66.0 65.0 63.0 13:40:08 INFO - PROCESS | 1675 | -------- Summary: end -------- 13:40:08 INFO - PROCESS | 1675 | 13:40:09 INFO - TEST-INFO | 1675: exit 0 13:40:09 INFO - TEST-OK | kraken | took 56695ms 13:40:09 INFO - SUITE-END | took 1169s 13:40:09 INFO - Completed test suite (00:19:29) 13:40:09 INFO - javascript benchmark 13:40:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2987.536931775574, 3531.9470207946883, 3545.0960005198676, 3563.6258192889763, 3600.4959305697207, 5810.273715785527, 5815.273090863642, 5834.766609335632, 5836.6789826559525, 5851.122331650255, 5788.218590490283, 5823.82528524145, 5845.941540584604, 5846.386129456409, 5880.059190529516, 5198.480151984809, 5225.190095535188, 5252.159654455288, 5266.210068489719, 5318.867028324285, 5272.209168624718, 5276.709780962045, 5276.868078298054, 5280.973595132019, 5305.363356397238, 5229.843104706865, 5280.683159010452, 5303.893922121555, 5313.840584782443, 5336.8132115375965, 4673.672842900998, 5276.973615131919, 5315.229291752713, 5323.068463018956, 5369.19462080691, 5188.792448302063, 5207.375114986188, 5238.73806309683, 5269.920951185735, 5284.154535274338, 5692.231548740909, 5702.857428564319, 5732.426757324276, 5743.282089738783, 5759.0, 6160.7227674754295, 6281.937180628181, 6323.019931910515, 6337.461315788163, 6339.936600633981, 6244.250689917194, 6259.624422534663, 6290.748370065192, 6306.684665766693, 6345.206849143857, 5951.077582974646, 5952.136940143655, 5963.373845746134, 5966.3138739045135, 5970.194023806731, 6252.124702541648, 6323.494120470351, 6342.714577844008, 6382.234131904202, 6385.48916086712, 6102.328743838174, 6156.415140561639, 6177.4749146322165, 6296.811095667137, 6297.181366422427, 5596.9352009659515, 6038.215032045806, 6271.0, 6296.212973378328, 6344.936550634527, 6242.438180563771, 6320.336364681642, 6321.968390158112, 6332.430081292706, 6348.904766428507, 6181.227346581677, 6300.527460440485, 6306.842328941813, 6309.14826498426, 6322.0, 4933.580645645088, 4947.060058588929, 4996.600271978233, 4999.700017998932, 5031.320771695774, 4461.687681862303, 4489.932651010172, 4493.707908985971, 4501.3022981437925, 4520.208963431386, 5627.8874422512135, 5772.624779389326, 5805.941940580625, 5840.737166827503, 5882.176495290662, 6246.3753624637175, 6301.338359472281, 6303.46420554258, 6308.085327627468, 6318.0, 5748.166515855261, 5767.711614419262, 5792.884142317214, 5794.101914203306, 5794.449527294901, 5427.83716488506, 5437.592180586472, 5448.482394172547, 5470.316210473691, 5476.452354764491, 6255.718492667841, 6383.329750376237, 6460.676966151674, 6464.482841372773, 6471.708773105127, 6275.121482992476, 6311.652859092794, 6316.210473690789, 6317.5893566918, 6345.682715864281, 6275.121482992476, 6296.653684047421, 6392.52056095804, 6402.839929001812, 6409.775657851953, 6175.413335733098, 6297.370262973666, 6305.8108256752375, 6313.273973493107, 6314.905276420765, 6295.276043255084, 6331.430171284606, 6332.52506062038, 6338.841528961812, 6340.397662222082, 6098.908516372169, 6183.134361189347, 6191.4118158774845, 6208.627482351073, 6215.06773983906, 6114.358196334042, 6150.507959363151, 6209.906851397142, 6230.844228894314, 6239.78160764371, 5017.147084995632, 5991.520678345809, 6067.150598916067, 6094.0945724627045, 6116.082587611894, 6085.208922840003, 6148.200733904564, 6244.531660125418, 6246.094316324198, 6254.124422580933, 6045.486133678686, 6099.176611157437, 6129.537764380637, 6130.390533016695, 6158.137860699595], "unit": "score", "name": "dojo.html", "value": 5773.574977913886}, {"lowerIsBetter": false, "replicates": [494.4585678681847, 597.6921885229108, 599.3407252022773, 673.0980486148561, 675.0717763075771, 26596.60105098425, 31995.52006719901, 32051.839740801293, 32052.679473205262, 32203.194920127007, 42397.57602423975, 42622.57377426225, 42746.35880461788, 42797.71606851792, 42807.3578896316, 9768.462734549597, 9946.602135914554, 9972.0, 9972.451515166664, 9972.85040724386, 9600.519974001307, 9716.076972687619, 9741.659041933533, 9742.659006934759, 9744.976777438373, 9712.368696034735, 9773.0, 9781.951090244538, 9787.070228328297, 9795.755106122333, 9696.563654635524, 9746.853797193047, 9749.415035097916, 9760.853587196198, 9762.511874406251, 9890.80218395628, 10023.849642255373, 10027.047430494164, 10031.19750419972, 10034.849477257847, 42638.0, 43075.0, 43095.784521077505, 43100.56899430997, 43114.78442607798, 3269.9182520436943, 3278.344331133783, 3359.5968483781858, 3416.555847739778, 3948.7828169450668, 28910.566341504895, 28945.986890458942, 29340.266493337625, 30350.0, 30652.693473065206, 2799.9020034298906, 2822.1956742328543, 2846.558783388578, 2903.6660784009896, 2937.030779842647, 492774.0, 495364.0, 497525.0, 497658.6287754172, 497719.5114024443, 48344.27483587749, 49631.50368496341, 72802.0, 73723.52552949019, 73941.26058739344, 48290.0, 48335.0, 48559.028819423416, 48565.5143448561, 48731.02537949292, 16051.0, 16165.0, 16178.75731864023, 16182.948108372919, 16186.0, 13485.797713034312, 13524.053316267964, 13536.0, 13561.118527295694, 13571.932140339235, 15585.519375659294, 15867.52397428079, 15892.284847181905, 15901.920490397473, 15940.68118637644, 6100.603460775035, 6145.539084568675, 6529.836754081186, 6569.9343006569325, 6653.135092437952, 970.3983530211173, 1016.6645007147623, 1097.1277834121893, 1131.4682099413262, 1158.5192145259623, 31090.067297981095, 31100.377992440026, 31101.0, 31737.0, 31814.0, 1133.9659810205708, 1142.9714257143637, 1146.340854008948, 1218.670958841108, 1259.8425196850394, 13049.739005219843, 15844.76232856508, 15882.68234635324, 16296.918515407348, 16401.753973690404, 531480.0, 538666.0, 539272.0, 539479.0, 540084.0, 63082.0, 63767.0, 63930.6803465989, 63963.68018159879, 63986.36013639897, 1415.143837978029, 1508.6605513759537, 1537.3543111893032, 1557.353698215228, 1558.7038462692278, 30075.849620751756, 30110.512303107676, 30518.389632207676, 31318.373632526767, 31453.0, 41055.58944410518, 51726.0, 52130.47869521408, 52329.0, 52370.214446782564, 372654.0, 372673.0, 374493.0, 375504.0, 376175.11912440264, 30362.544561832034, 34264.31471370609, 35352.292954140255, 35442.64557354496, 35724.28551429009, 37933.91363474925, 38858.6114138855, 40948.0, 41859.37210941899, 44247.33628995632, 35577.644223557436, 35954.0, 37536.061598459164, 38290.0, 39117.60882391254, 37624.87125386243, 37707.81146094252, 38070.61929380671, 38103.80948095242, 38511.422328664536, 37525.81237093797, 37763.0, 38050.0, 38651.8404447867, 38978.61021389863, 32646.020619381456, 32648.78143770847, 33066.66933330636, 33233.00300990974, 33578.83210583931, 25516.872415637805, 25545.358206865978, 25595.976160953353, 25864.482710346063, 26016.219513414628], "unit": "score", "name": "ext.html", "value": 19664.449970917234}, {"lowerIsBetter": false, "replicates": [366.91194113412774, 374.1002888054229, 376.4560210495833, 383.1398510344277, 387.3685891996046, 20051.99740012999, 20540.178392864287, 21278.787212127874, 21573.46066348342, 21599.0, 20499.385018449433, 21018.894905525452, 21235.787642123574, 21260.14959401622, 22368.776312236874, 5189.766460509286, 5723.284589426322, 5861.7948371807, 5887.528997680197, 5913.674747888865, 4063.6139566741113, 4242.936355954663, 5730.169125476803, 5841.941580584204, 6094.908576371358, 6084.300305464884, 6087.817365479043, 6090.477380654836, 6099.57302988791, 6106.81679549612, 4058.452108965311, 4244.554321796229, 5642.4357564243455, 5908.084246941731, 6068.0291153415465, 5460.563154947635, 5691.260136182311, 5701.0, 5701.828945131652, 5711.371749107595, 13395.732085358239, 13663.453461861514, 24460.877695611587, 25427.87286063576, 25455.61816572753, 2169.39257008038, 2181.9236326728487, 2350.5651454480976, 2441.9145329913545, 2512.6105453654714, 9717.805643887154, 9975.75060623483, 10128.18974482047, 10299.53652085658, 10473.26687131901, 1701.8213087625868, 1723.3881971900046, 1791.2118667786133, 1882.275324000256, 1903.2767548331544, 93209.0, 105756.41365379526, 107424.99999999921, 113993.0, 114148.0, 11060.778784424267, 11429.885701143048, 11489.770204595861, 11530.538778448768, 11905.642830715093, 14683.045602035834, 14748.041377310656, 14785.704285914437, 14792.926035369754, 14833.480828170963, 8250.0, 8767.605457754416, 8954.328425367994, 9025.638974440948, 9103.271738260923, 9206.723798286062, 9220.815583688289, 9224.49265290402, 9241.706161137447, 9277.443353398818, 3763.946095093378, 4597.172508948422, 4597.816087356535, 4603.884902877455, 4646.094011667692, 1823.052012953257, 1891.914863831131, 1928.1901601327477, 1955.2667749593902, 1958.7061940708722, 409.2020559908191, 741.2661465149463, 1017.2878984710732, 1021.9269766745036, 1035.4985271356563, 9693.951530242304, 11832.76334473323, 12023.21849079807, 12049.638510844688, 12302.876971230173, 458.51168505541494, 458.7866642011481, 467.3270490493678, 469.17893686049416, 471.8131460679774, 5990.820275391745, 6458.321876203025, 6510.641914694643, 6542.54202205841, 6573.901391479131, 248735.0, 486302.0, 492951.81716822827, 540512.0, 589866.1013389897, 127244.0, 132709.0, 134852.0, 135280.0, 136634.0, 474.42831388177854, 477.0387668847276, 481.5040508276481, 481.88434775653593, 481.93734814473896, 10469.842952355568, 11401.828972565252, 11832.46753896094, 11865.762684746429, 11882.0, 50829.0, 52998.940021200135, 53391.7330413361, 54854.725726371114, 55877.0, 70616.6469167651, 74875.62562187154, 74939.62530187314, 75082.0, 79392.60303698445, 8161.183881612029, 8174.509529428254, 8301.045379781403, 8414.621342039745, 8458.957705211435, 12423.31671758062, 12803.551875684307, 13422.0, 13790.517331893336, 13954.651133721738, 11404.429778510876, 11641.47613357384, 11692.532298707956, 11927.582534611247, 11944.522219111139, 9376.624935002523, 11657.941710291394, 11695.298282103102, 11910.28538287671, 11958.402079895797, 11732.765344692887, 11758.3532905691, 11959.429780777049, 12059.095567832272, 12238.75522489563, 8170.346372290083, 8223.136570660234, 8261.925949626751, 8551.57242137903, 8565.443246188977, 8355.498670079814, 8670.956645216733, 11822.054235660955, 12020.0, 12052.819207711716], "unit": "score", "name": "jquery.html", "value": 8682.47195102884}, {"lowerIsBetter": false, "replicates": [1506.593219830645, 1643.6383995520982, 1664.9750253746192, 2028.9391318260448, 2100.1284466946217, 7705.576193309365, 8582.785430364243, 9157.175854173123, 9164.266858651308, 9176.036516165797, 8853.557322133884, 9130.452172869616, 9144.451332920013, 9144.4970526621, 9150.725478235647, 2547.2103647869176, 2583.547879121156, 2593.662823832899, 2595.0, 2595.9221223363234, 2558.3092565007437, 2640.656714627096, 2645.0477827981913, 2647.589623608334, 2657.4020845309747, 2568.1781829814468, 2579.522788284164, 2591.935201619965, 2594.3514121469634, 2596.0135148643585, 2565.0637517306236, 2584.8707564621786, 2593.6368908352847, 2596.5845464725553, 2596.792256619466, 2594.792416606667, 2607.5958226474927, 2623.160588611645, 2624.960625590618, 2633.855137967411, 9197.21823644991, 9224.354295199339, 9225.584848681758, 9248.491332976684, 9271.119243671841, 5618.6628802272, 5730.226419433365, 5781.797637082724, 5784.421557844224, 5799.7100144993, 3653.6894363979095, 3720.627937206285, 3768.3028639701743, 3777.1690228149764, 3784.299904517673, 1066.2802608239408, 1081.848541204232, 1084.446932064645, 1086.5816660585651, 1124.2579897267847, 12379.195352302071, 12555.937220313932, 12596.055295852848, 12609.684757881034, 12613.99088072952, 4992.525710057539, 5071.619628527875, 5109.56568691658, 5111.05445492585, 5111.8722031948755, 10749.78500429987, 10760.192985526117, 10779.02988731018, 10782.245242833083, 10785.730356741144, 5706.543476521868, 5735.397783232784, 5743.310802703702, 5744.885102298014, 5769.163471296639, 4596.310553416947, 4728.456227533878, 4937.728424936591, 5129.615278854101, 5158.329417175743, 4146.979265103655, 4156.646685031807, 4171.228322760238, 4203.096334288142, 4244.405783190356, 1343.1336787771831, 1373.7252549490142, 1377.6969066805286, 1380.4340220509544, 1384.6746014686541, 1030.2376241581176, 1039.6205385034407, 1064.590132798878, 1064.8881867403811, 1130.0, 8264.173582641688, 8290.834183316421, 8358.749237522885, 8384.455010424303, 8432.156784321642, 658.4271683635268, 671.1979184874126, 673.5487223560226, 674.1707699529508, 809.5749731391066, 6307.810765677037, 6314.684265786692, 6348.111264422892, 6385.106085148083, 6417.454516366068, 10561.947190264153, 11013.2841365311, 11148.498317575728, 11252.831207531894, 11336.773264534662, 10728.89271107279, 11061.834072488919, 11103.611373601885, 11125.944370278097, 11254.43727813606, 8590.14098590136, 8665.306775457948, 8730.65077396897, 8733.432326898732, 8758.255548278468, 8132.349412046904, 8134.796630084295, 8209.79475513117, 8211.096779354386, 8247.876281855897, 8240.876386854083, 9758.268129890144, 10025.74935626586, 10191.592336306761, 10233.181345492492, 10278.17774578017, 10497.632582859564, 10575.259731818698, 10658.0, 10668.46657667098, 7682.193369696101, 7690.500117492345, 7763.68945242184, 7767.611619419119, 7769.611519424119, 8516.446430981967, 8549.829003419773, 8602.397832151939, 8604.655813767378, 8644.78388040304, 7512.361449276873, 7557.206493318123, 7642.0, 7685.692572297269, 7804.492707973964, 7413.925860741324, 7471.140818805689, 7477.0, 7604.961975190088, 7618.238176182337, 7950.443468957117, 7987.16134805837, 8016.238457346542, 8016.839663206587, 8023.4784738991775, 7068.29317068289, 7274.381677557255, 7310.049693539806, 7315.268473152642, 7330.890036649348, 7400.296971787671, 7454.217307182885, 7457.291557302047, 7458.813529661802, 7473.327400533978], "unit": "score", "name": "mootools.html", "value": 5067.259617418278}, {"lowerIsBetter": false, "replicates": [332.4166088514657, 333.1554509318876, 341.8342104079521, 343.0446207312635, 347.6818710879547, 6573.244076931154, 6577.605343679376, 6585.703643336049, 6594.2746297907315, 6627.1716035495565, 5255.894882102365, 6588.835279118024, 6588.868222635544, 6610.173728283965, 6615.1069605603225, 4195.727277726938, 4695.3191787190835, 4771.236602143657, 4786.688865223767, 4931.284963680273, 4773.594244489223, 4894.9965257122385, 4919.803207871681, 4936.111499930011, 4942.481039490855, 4833.564979151875, 4877.609791216694, 4883.804647814083, 4890.559849613534, 4904.117258893398, 4628.537146285378, 4698.882527936795, 4817.927731084036, 4835.854924352275, 4870.293807397925, 4145.647619952308, 4155.168966206771, 4162.729422587522, 4164.146349998243, 4165.083681590046, 6252.937470625281, 6314.71583778731, 6315.5894866833505, 6322.241331040259, 6326.019466982625, 5826.679532625704, 5848.561357898132, 5854.18041474194, 5857.414258574151, 5868.354481007086, 7071.186813516409, 7074.3633073023675, 7092.468064895102, 7098.609576473292, 7101.076860008166, 4436.73379597222, 4588.449386073659, 4591.6097131743845, 4595.425571803525, 4602.493725690171, 6331.303556608769, 7126.180489243751, 7559.1480087378395, 7571.772846814604, 7576.280253375976, 3906.7851268180525, 4697.13103075932, 4701.78841952113, 4705.1765940960195, 4709.858704238878, 4070.5522392536795, 4728.4562275338085, 4766.451858036301, 4776.95223047772, 4785.617150627942, 5710.514606258432, 5736.77052917887, 5746.339170995306, 5755.769769209269, 5756.280464941882, 6815.863682726417, 7128.322809333105, 7150.678219480136, 7159.89260161098, 7172.569645821268, 1625.081828766743, 1639.0821140161547, 1653.1238443624898, 1655.1724137931035, 1656.0, 987.027777639025, 1034.13132968307, 1037.6886933919945, 1053.8050460664801, 1057.7197042783669, 5820.679862607512, 6164.907526387108, 6362.77730279438, 6489.5781774184525, 6677.899831502531, 7419.406447484188, 7423.331900129015, 7424.96287518559, 7435.40516758658, 7443.36731377839, 6608.8347791305605, 6619.602823830585, 6624.8012559623285, 6626.966865165643, 6647.800565983028, 3860.285847118292, 3918.0, 3919.647231749156, 3924.9607503925167, 3932.11527406337, 7481.663325150382, 7793.298603125746, 7802.0, 7805.726799561988, 7815.179406162385, 7650.42621803367, 7696.076470823425, 7701.075870895528, 7715.652795624098, 7719.575423351769, 7741.341985931146, 7753.65108570127, 7754.9224507755325, 7778.183290754502, 7793.532388056621, 7116.712088961561, 7376.4836461447185, 7418.961345411539, 7445.292697193758, 7458.545583611361, 7247.456440766858, 7299.233580473974, 7317.341439270491, 7318.085239345082, 7329.047223861077, 6256.562040657111, 6996.755113571001, 7093.758592246233, 7732.961335193288, 7749.108852482037, 4635.1656701794, 4676.649251306234, 4691.296305554194, 4694.0, 4694.483606803181, 5750.568707346882, 6277.246730392382, 7143.1428228612895, 7359.926400735924, 7364.226756190737, 7046.330598593126, 7176.246494118042, 7180.5691658500655, 7208.9639551804, 7220.025296585104, 7151.356377926011, 7164.104486939133, 7167.462440316893, 7175.892361614684, 7187.712491500491, 6992.79021629352, 7005.579665220103, 7029.61337126463, 7034.366906978396, 7034.859302814018, 6935.202451718117, 7139.464540159405, 7153.6423178839805, 7164.0, 7194.784156475314, 6615.636140012345, 6840.452763778786, 6847.691853866489, 7027.789166325018, 7053.188883278283], "unit": "score", "name": "prototype.html", "value": 5168.129962883371}, {"lowerIsBetter": false, "replicates": [741.4550305525439, 772.1660606544932, 795.7334293011841, 802.9879551806724, 805.122416565943, 15403.537893863187, 15705.607359816011, 15786.0, 15789.842101578995, 15820.0, 11941.761164776699, 13138.540151094714, 14440.494582689607, 16220.513384598451, 16300.347986080542, 994.0, 1005.2058873489971, 1006.079437314856, 1010.5907107621425, 1011.1657882247139, 972.6158167523823, 974.7319487141023, 979.5347210075192, 979.6816034788686, 1008.0423597582289, 1022.1822541966434, 1022.8772547294298, 1023.2632504596678, 1059.2902755154028, 1066.3761699405857, 1005.492226425654, 1051.3901936876593, 1054.699410667956, 1057.1437135919891, 1059.5549869054978, 736.1240124252123, 758.29478584916, 778.9805254868617, 795.7891158842912, 798.2655956519958, 14031.0, 15074.246287685572, 15684.294206760724, 15810.683786324324, 15820.0, 10511.52698128586, 13480.5281815136, 13559.186448813103, 13699.246541440218, 13935.58193254204, 9847.655332063416, 9945.0, 10142.797144057151, 10269.79460410795, 10374.481275936248, 3481.54739883816, 3520.4895290183035, 3545.8936231913085, 3579.8031108289033, 3628.6734193922675, 67539.98690019654, 70255.64872175657, 74955.62522187355, 85570.71643925346, 86416.27167456616, 9872.950635246922, 10011.79976400468, 10045.146162576117, 10056.59773609062, 10289.022542858416, 22579.322620321418, 22583.661245081337, 22584.774152258597, 22585.4353641157, 22607.773922260567, 7528.585927774025, 7613.885791713129, 7623.9618801905635, 7680.769576912701, 7856.332211762065, 4081.183763247303, 4112.3420252759415, 4126.339785634284, 4166.541680415152, 4267.317229243306, 3794.3739283017994, 3825.9426108608395, 3826.617338266207, 3848.230353929225, 3850.210706805098, 651.775137577535, 677.7831094049857, 682.4131247127466, 687.4569090418613, 691.9550229235084, 497.98506044818714, 604.4418064005592, 606.6996836565839, 616.3189675408698, 646.9191351081115, 8143.18568143181, 8207.835843283221, 8240.50556966584, 8282.254597086292, 8447.746567602982, 597.026846240625, 601.759296281491, 605.4006533531772, 609.2171559545963, 609.6768712582339, 3775.7734535927934, 3872.0707030312524, 3908.785016824102, 3917.941230881539, 3972.7616343019513, 93342.53328733449, 94234.58648120283, 94397.5280123595, 94467.52766236124, 95705.5214723922, 61938.0, 75350.0, 80650.72664807132, 80724.0, 82812.0, 71171.0, 73822.0, 74501.39245126395, 74795.0, 74957.0, 7729.536227826348, 7906.486078404885, 7942.08666003417, 7951.960240198993, 7989.760307190793, 52223.17218897321, 52337.69155771136, 52885.20672189843, 53176.73411632917, 53249.0, 69293.0, 73788.0, 74296.62851685708, 75042.0, 75730.62134689292, 10339.896601033894, 10501.212409069198, 10571.0, 10769.353838769699, 11254.212205145888, 10980.0, 11008.724781880517, 11246.831297530707, 11257.662270131908, 11591.710207244887, 10337.431441270803, 10351.223658225512, 10487.10859576914, 10508.474576271308, 10530.0, 10261.846072308772, 10527.210459215743, 10632.705083802639, 10817.242793004729, 10838.783224335626, 9974.40153590787, 10433.895661043596, 10546.419946902994, 10648.680539583514, 10664.466776661291, 6481.060246264171, 7832.608369581612, 8402.41183117176, 8919.171252060882, 8925.509096999727, 8585.441946273471, 8931.866022009544, 9027.052159523419, 9102.863457048017, 9142.222911052635], "unit": "score", "name": "yui.html", "value": 7019.0126931249915}], "name": "dromaeo_css", "value": 7522.534556470379}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 107.0, 103.0, 109.0, 110.0, 126.0, 175.0, 150.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 121.2}, {"lowerIsBetter": true, "replicates": [152.0, 119.0, 153.0, 145.0, 117.0, 140.0, 119.0, 149.0, 147.0, 118.0], "unit": "score", "name": "audio-beat-detection", "value": 135.9}, {"lowerIsBetter": true, "replicates": [185.0, 185.0, 185.0, 187.0, 184.0, 185.0, 183.0, 187.0, 186.0, 183.0], "unit": "score", "name": "audio-dft", "value": 185.0}, {"lowerIsBetter": true, "replicates": [75.0, 76.0, 72.0, 116.0, 56.0, 57.0, 58.0, 60.0, 155.0, 82.0], "unit": "score", "name": "audio-fft", "value": 80.7}, {"lowerIsBetter": true, "replicates": [91.0, 92.0, 131.0, 77.0, 78.0, 84.0, 120.0, 77.0, 82.0, 151.0], "unit": "score", "name": "audio-oscillator", "value": 98.3}, {"lowerIsBetter": true, "replicates": [85.0, 82.0, 86.0, 83.0, 85.0, 83.0, 86.0, 82.0, 87.0, 83.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 84.2}, {"lowerIsBetter": true, "replicates": [94.0, 140.0, 150.0, 112.0, 92.0, 92.0, 92.0, 93.0, 92.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 104.9}, {"lowerIsBetter": true, "replicates": [87.0, 94.0, 92.0, 86.0, 85.0, 86.0, 83.0, 90.0, 84.0, 90.0], "unit": "score", "name": "imaging-desaturate", "value": 87.7}, {"lowerIsBetter": true, "replicates": [55.0, 58.0, 57.0, 57.0, 58.0, 58.0, 57.0, 66.0, 56.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 57.6}, {"lowerIsBetter": true, "replicates": [59.0, 59.0, 63.0, 69.0, 70.0, 74.0, 70.0, 66.0, 66.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.3}, {"lowerIsBetter": true, "replicates": [81.0, 78.0, 80.0, 81.0, 81.0, 80.0, 79.0, 79.0, 80.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.2}, {"lowerIsBetter": true, "replicates": [129.0, 119.0, 129.0, 131.0, 105.0, 126.0, 123.0, 103.0, 126.0, 128.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.9}, {"lowerIsBetter": true, "replicates": [133.0, 123.0, 134.0, 132.0, 131.0, 122.0, 132.0, 133.0, 131.0, 132.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 130.3}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 67.0, 65.0, 66.0, 66.0, 65.0, 66.0, 65.0, 63.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.5}], "name": "kraken", "value": 1419.7}]} 13:40:09 INFO - Return code: 0 13:40:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:40:09 INFO - # TBPL SUCCESS # 13:40:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:40:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:40:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:40:09 INFO - 2016-04-22 13:40:09.734 screenresolution[1679:9734] starting screenresolution argv=screenresolution get 13:40:09 INFO - 2016-04-22 13:40:09.737 screenresolution[1679:9734] Display 0: 1600x1200x32@60 13:40:09 INFO - 2016-04-22 13:40:09.742 screenresolution[1680:9737] starting screenresolution argv=screenresolution list 13:40:09 INFO - Available Modes on Display 0 13:40:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:40:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:40:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:40:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:40:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:40:09 INFO - Intel Iris: 13:40:09 INFO - Chipset Model: Intel Iris 13:40:09 INFO - Type: GPU 13:40:09 INFO - Bus: Built-In 13:40:09 INFO - VRAM (Dynamic, Max): 1536 MB 13:40:09 INFO - Vendor: Intel (0x8086) 13:40:09 INFO - Device ID: 0x0a2e 13:40:09 INFO - Revision ID: 0x0009 13:40:09 INFO - Displays: 13:40:09 INFO - Display: 13:40:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:40:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:40:09 INFO - Display Serial Number: 1600x1200 60 13:40:09 INFO - Main Display: Yes 13:40:09 INFO - Mirror: Off 13:40:09 INFO - Online: Yes 13:40:09 INFO - Rotation: Supported 13:40:09 INFO - Return code: 0 13:40:09 INFO - Running post-action listener: _resource_record_post_action 13:40:09 INFO - Running post-run listener: _resource_record_post_run 13:40:11 INFO - Total resource usage - Wall time: 1203s; CPU: 52.0%; Read bytes: 37660160; Write bytes: 662823424; Read time: 386; Write time: 1968 13:40:11 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 364562944; Write bytes: 355358720; Read time: 27839; Write time: 690 13:40:11 INFO - run-tests - Wall time: 1172s; CPU: 52.0%; Read bytes: 30551552; Write bytes: 296286720; Read time: 302; Write time: 1247 13:40:11 INFO - Running post-run listener: _upload_blobber_files 13:40:11 INFO - Blob upload gear active. 13:40:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:40:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:40:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:40:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:40:11 INFO - (blobuploader) - INFO - Open directory for files ... 13:40:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:40:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:40:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:40:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:40:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:40:12 INFO - (blobuploader) - INFO - Done attempting. 13:40:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:40:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:40:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:40:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:40:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:40:13 INFO - (blobuploader) - INFO - Done attempting. 13:40:13 INFO - (blobuploader) - INFO - Iteration through files over. 13:40:13 INFO - Return code: 0 13:40:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:40:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:40:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c61bab490906c346ab9d3437e8b8f7c4b74de64ce5ca39e091d644117b282005795acf6639cc1b14922bc19addc237fa2aa4f03bc37a42f468fbdac71933f2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0b13c2445af7ee4a091d29dafda2e135f2055f540b8e0ef2a4c414491acd46fa6b1b2f1eea059cd4bb58a50d3dffda45d01fb5175b23651f8c097923a0ee544"} 13:40:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:40:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:40:13 INFO - Contents: 13:40:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c61bab490906c346ab9d3437e8b8f7c4b74de64ce5ca39e091d644117b282005795acf6639cc1b14922bc19addc237fa2aa4f03bc37a42f468fbdac71933f2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0b13c2445af7ee4a091d29dafda2e135f2055f540b8e0ef2a4c414491acd46fa6b1b2f1eea059cd4bb58a50d3dffda45d01fb5175b23651f8c097923a0ee544"} 13:40:13 INFO - Running post-run listener: copy_logs_to_upload_dir 13:40:13 INFO - Copying logs to upload dir... 13:40:13 INFO - mkdir: /builds/slave/test/build/upload/logs 13:40:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.920127 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-22 13:40:13.264107) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 13:40:13.267306) ========= 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.RnQXiyk2Ul/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.7haknWOEin/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c61bab490906c346ab9d3437e8b8f7c4b74de64ce5ca39e091d644117b282005795acf6639cc1b14922bc19addc237fa2aa4f03bc37a42f468fbdac71933f2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0b13c2445af7ee4a091d29dafda2e135f2055f540b8e0ef2a4c414491acd46fa6b1b2f1eea059cd4bb58a50d3dffda45d01fb5175b23651f8c097923a0ee544"} build_url:https://queue.taskcluster.net/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010993 build_url: 'https://queue.taskcluster.net/v1/task/ZpsF7APqSsSJ8EIFw1fBEA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c61bab490906c346ab9d3437e8b8f7c4b74de64ce5ca39e091d644117b282005795acf6639cc1b14922bc19addc237fa2aa4f03bc37a42f468fbdac71933f2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0b13c2445af7ee4a091d29dafda2e135f2055f540b8e0ef2a4c414491acd46fa6b1b2f1eea059cd4bb58a50d3dffda45d01fb5175b23651f8c097923a0ee544"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 13:40:13.297409) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 13:40:13.297791) ========= 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.RnQXiyk2Ul/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.7haknWOEin/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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.004948 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 13:40:13.346681) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 13:40:13.347011) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 13:40:13.347698) ========= ========= Total master_lag: 9.16 =========