builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0327 starttime: 1464625311.57 results: success (0) buildid: 20160530075343 builduid: 347dbc64cac343d6901446652388f9f8 revision: de472f31c8a3f1711bdf65beecc8dfa983950b64 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 09:21:51.573617) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 09:21:51.574178) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 09:21:51.574528) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 09:21:51.598469) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:21:51.598936) ========= 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.2BLWnMrCjS/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.Om812WuUE6/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-30 09:21:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 71.5M=0s 2016-05-30 09:21:51 (71.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131916 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:21:51.752340) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:21:51.752924) ========= 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.2BLWnMrCjS/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.Om812WuUE6/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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.020634 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:21:51.804055) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-30 09:21:51.804710) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev de472f31c8a3f1711bdf65beecc8dfa983950b64 --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 de472f31c8a3f1711bdf65beecc8dfa983950b64 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2BLWnMrCjS/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.Om812WuUE6/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-30 09:21:51,882 truncating revision to first 12 chars 2016-05-30 09:21:51,882 Setting DEBUG logging. 2016-05-30 09:21:51,883 attempt 1/10 2016-05-30 09:21:51,883 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/de472f31c8a3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 09:21:53,687 unpacking tar archive at: mozilla-aurora-de472f31c8a3/testing/mozharness/ program finished with exit code 0 elapsedTime=2.077715 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-30 09:21:53.905192) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 09:21:53.905766) ========= script_repo_revision: de472f31c8a3f1711bdf65beecc8dfa983950b64 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 09:21:53.906439) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 09:21:53.906906) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 09:21:53.925632) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-05-30 09:21:53.926262) ========= /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.2BLWnMrCjS/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.Om812WuUE6/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 09:21:54 INFO - MultiFileLogger online at 20160530 09:21:54 in /builds/slave/test 09:21:54 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 09:21:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:21:54 INFO - {'append_to_log': False, 09:21:54 INFO - 'base_work_dir': '/builds/slave/test', 09:21:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:21:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:21:54 INFO - 'branch': 'Mozilla-Aurora', 09:21:54 INFO - 'buildbot_json_path': 'buildprops.json', 09:21:54 INFO - 'config_files': ('talos/mac_config.py',), 09:21:54 INFO - 'default_actions': ('clobber', 09:21:54 INFO - 'read-buildbot-config', 09:21:54 INFO - 'download-and-extract', 09:21:54 INFO - 'populate-webroot', 09:21:54 INFO - 'create-virtualenv', 09:21:54 INFO - 'install', 09:21:54 INFO - 'run-tests'), 09:21:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:21:54 INFO - 'download_minidump_stackwalk': True, 09:21:54 INFO - 'download_symbols': 'ondemand', 09:21:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:21:54 INFO - 'tooltool.py': '/tools/tooltool.py', 09:21:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:21:54 INFO - '/tools/misc-python/virtualenv.py')}, 09:21:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:21:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:21:54 INFO - 'installer_path': 'installer.exe', 09:21:54 INFO - 'log_level': 'info', 09:21:54 INFO - 'log_name': 'talos', 09:21:54 INFO - 'log_to_console': True, 09:21:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:21:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:21:54 INFO - 'opt_config_files': (), 09:21:54 INFO - 'pip_index': False, 09:21:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:21:54 INFO - 'cmd': ('bash', 09:21:54 INFO - '-c', 09:21:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:21:54 INFO - 'enabled': True, 09:21:54 INFO - 'halt_on_failure': False, 09:21:54 INFO - 'name': 'check_screen_resolution'},), 09:21:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:21:54 INFO - 'cmd': ('bash', 09:21:54 INFO - '-c', 09:21:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:21:54 INFO - 'enabled': True, 09:21:54 INFO - 'halt_on_failure': False, 09:21:54 INFO - 'name': 'check_screen_resolution'},), 09:21:54 INFO - 'run_cmd_checks_enabled': True, 09:21:54 INFO - 'sps_profile': False, 09:21:54 INFO - 'sps_profile_interval': 0, 09:21:54 INFO - 'suite': 'dromaeojs', 09:21:54 INFO - 'system_bits': '32', 09:21:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:21:54 INFO - 'title': 't-yosemite-r7-0327', 09:21:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:21:54 INFO - 'use_talos_json': True, 09:21:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:21:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:21:54 INFO - 'work_dir': 'build'} 09:21:54 INFO - ##### 09:21:54 INFO - ##### Running clobber step. 09:21:54 INFO - ##### 09:21:54 INFO - Running pre-action listener: _resource_record_pre_action 09:21:54 INFO - Running main action method: clobber 09:21:54 INFO - rmtree: /builds/slave/test/build 09:21:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:21:55 INFO - Running post-action listener: _resource_record_post_action 09:21:55 INFO - ##### 09:21:55 INFO - ##### Running read-buildbot-config step. 09:21:55 INFO - ##### 09:21:55 INFO - Running pre-action listener: _resource_record_pre_action 09:21:55 INFO - Running main action method: read_buildbot_config 09:21:55 INFO - Using buildbot properties: 09:21:55 INFO - { 09:21:55 INFO - "project": "", 09:21:55 INFO - "product": "firefox", 09:21:55 INFO - "script_repo_revision": "production", 09:21:55 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:21:55 INFO - "repository": "", 09:21:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:21:55 INFO - "buildid": "20160530075343", 09:21:55 INFO - "pgo_build": "False", 09:21:55 INFO - "basedir": "/builds/slave/test", 09:21:55 INFO - "buildnumber": 12, 09:21:55 INFO - "slavename": "t-yosemite-r7-0327", 09:21:55 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:21:55 INFO - "platform": "yosemite_r7", 09:21:55 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:21:55 INFO - "branch": "mozilla-aurora", 09:21:55 INFO - "repo_path": "releases/mozilla-aurora", 09:21:55 INFO - "revision": "de472f31c8a3f1711bdf65beecc8dfa983950b64", 09:21:55 INFO - "stage_platform": "macosx64", 09:21:55 INFO - "builduid": "347dbc64cac343d6901446652388f9f8", 09:21:55 INFO - "slavebuilddir": "test" 09:21:55 INFO - } 09:21:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 09:21:55 INFO - Running post-action listener: _resource_record_post_action 09:21:55 INFO - ##### 09:21:55 INFO - ##### Running download-and-extract step. 09:21:55 INFO - ##### 09:21:55 INFO - Running pre-action listener: _resource_record_pre_action 09:21:55 INFO - Running main action method: download_and_extract 09:21:55 INFO - mkdir: /builds/slave/test/build/tests 09:21:55 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')]} 09:21:55 INFO - https://queue.taskcluster.net/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:21:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:21:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:21:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 09:21:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 09:22:05 INFO - Downloaded 1199 bytes. 09:22:05 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 09:22:05 INFO - Using the following test package requirements: 09:22:05 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:22:05 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:22:05 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 09:22:05 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:22:05 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 09:22:05 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:22:05 INFO - u'jsshell-mac.zip'], 09:22:05 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:22:05 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 09:22:05 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:22:05 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:22:05 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 09:22:05 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:22:05 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 09:22:05 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:22:05 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 09:22:05 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:22:05 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 09:22:05 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:22:05 INFO - https://queue.taskcluster.net/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:22:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:22:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:22:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:22:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:22:29 INFO - Downloaded 18463809 bytes. 09:22:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:22:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:22:32 INFO - Return code: 0 09:22:32 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:32 INFO - https://queue.taskcluster.net/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:22:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:22:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:22:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:22:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:22:33 INFO - Downloaded 18463809 bytes. 09:22:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:22:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:22:37 INFO - Return code: 0 09:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:37 INFO - https://queue.taskcluster.net/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:22:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:22:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:22:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:22:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:22:38 INFO - Downloaded 10840145 bytes. 09:22:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:22:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:22:38 INFO - Return code: 0 09:22: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')]} 09:22:38 INFO - https://queue.taskcluster.net/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 09:22:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:22:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:22:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 09:22:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 09:22:43 INFO - Downloaded 114131615 bytes. 09:22:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:22:43 INFO - mkdir: /builds/slave/test/properties 09:22:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:22:43 INFO - Writing to file /builds/slave/test/properties/build_url 09:22:43 INFO - Contents: 09:22:43 INFO - build_url:https://queue.taskcluster.net/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:22:44 INFO - Running post-action listener: _resource_record_post_action 09:22:44 INFO - Running post-action listener: set_extra_try_arguments 09:22:44 INFO - ##### 09:22:44 INFO - ##### Running populate-webroot step. 09:22:44 INFO - ##### 09:22:44 INFO - Running pre-action listener: _resource_record_pre_action 09:22:44 INFO - Running main action method: populate_webroot 09:22:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:22:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:22:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:22:44 INFO - '--tppagecycles', 09:22:44 INFO - '7'], 09:22:44 INFO - 'tests': ['tsvgm']}}, 09:22:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:22:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:22:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:22:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:22:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:22:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:22:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:44 INFO - 'talos_options': ['--disable-e10s'], 09:22:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:22:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:22:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:44 INFO - 'talos_options': ['--disable-e10s'], 09:22:44 INFO - 'tests': ['damp', 'tps']}, 09:22:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:44 INFO - 'tests': ['damp', 'tps']}, 09:22:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:22:44 INFO - 'tests': ['dromaeo_dom']}, 09:22:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:22:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:22:44 INFO - 'tests': ['a11yr', 09:22:44 INFO - 'ts_paint', 09:22:44 INFO - 'tpaint', 09:22:44 INFO - 'sessionrestore', 09:22:44 INFO - 'sessionrestore_no_auto_restore', 09:22:44 INFO - 'tabpaint']}, 09:22:44 INFO - 'other-e10s': {'tests': ['a11yr', 09:22:44 INFO - 'ts_paint', 09:22:44 INFO - 'tpaint', 09:22:44 INFO - 'sessionrestore', 09:22:44 INFO - 'sessionrestore_no_auto_restore', 09:22:44 INFO - 'tabpaint']}, 09:22:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:22:44 INFO - 'ts_paint', 09:22:44 INFO - 'tpaint', 09:22:44 INFO - 'sessionrestore', 09:22:44 INFO - 'sessionrestore_no_auto_restore', 09:22:44 INFO - 'tabpaint']}, 09:22:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:22:44 INFO - 'ts_paint', 09:22:44 INFO - 'tpaint', 09:22:44 INFO - 'sessionrestore', 09:22:44 INFO - 'sessionrestore_no_auto_restore', 09:22:44 INFO - 'tabpaint']}, 09:22:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:22:44 INFO - 'tests': ['a11yr', 09:22:44 INFO - 'ts_paint', 09:22:44 INFO - 'tpaint', 09:22:44 INFO - 'sessionrestore', 09:22:44 INFO - 'sessionrestore_no_auto_restore', 09:22:44 INFO - 'tabpaint']}, 09:22:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:22:44 INFO - 'tests': ['a11yr', 09:22:44 INFO - 'ts_paint', 09:22:44 INFO - 'tpaint', 09:22:44 INFO - 'sessionrestore', 09:22:44 INFO - 'sessionrestore_no_auto_restore', 09:22:44 INFO - 'tabpaint']}, 09:22:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:22:44 INFO - 'tests': ['tsvgx', 09:22:44 INFO - 'tsvgr_opacity', 09:22:44 INFO - 'tart', 09:22:44 INFO - 'tscrollx', 09:22:44 INFO - 'cart']}, 09:22:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:22:44 INFO - 'tsvgr_opacity', 09:22:44 INFO - 'tart', 09:22:44 INFO - 'tscrollx', 09:22:44 INFO - 'cart']}, 09:22:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:44 INFO - 'talos_options': ['--disable-e10s'], 09:22:44 INFO - 'tests': ['tp5o']}, 09:22:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:44 INFO - 'tests': ['tp5o']}, 09:22:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:44 INFO - 'talos_options': ['--disable-e10s', 09:22:44 INFO - '--xperf_path', 09:22:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:22:44 INFO - 'tests': ['tp5n']}, 09:22:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:22:44 INFO - 'talos_options': ['--xperf_path', 09:22:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:22:44 INFO - 'tests': ['tp5n']}}, 09:22:44 INFO - 'talos.zip': {'path': '', 09:22:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:22:44 INFO - Running post-action listener: _resource_record_post_action 09:22:44 INFO - ##### 09:22:44 INFO - ##### Running create-virtualenv step. 09:22:44 INFO - ##### 09:22:44 INFO - Running pre-action listener: _resource_record_pre_action 09:22:44 INFO - Running main action method: create_virtualenv 09:22:44 INFO - Creating virtualenv /builds/slave/test/build/venv 09:22:44 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 09:22:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:22:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:22:44 INFO - Using real prefix '/tools/python27' 09:22:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:22:44 INFO - Installing distribute.............................................................................................................................................................................................done. 09:22:47 INFO - Installing pip.................done. 09:22:47 INFO - Return code: 0 09:22:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:22: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')]} 09:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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')]} 09:22:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b5daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8179cc22d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b479e00>, '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 0x10b2e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b474b20>, '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.2BLWnMrCjS/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.Om812WuUE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 09:22:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:22:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:22:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BLWnMrCjS/Render', 09:22:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:47 INFO - 'HOME': '/Users/cltbld', 09:22:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:47 INFO - 'LOGNAME': 'cltbld', 09:22:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:47 INFO - 'MOZ_NO_REMOTE': '1', 09:22:47 INFO - 'NO_EM_RESTART': '1', 09:22:47 INFO - 'PAGER': '/bin/cat', 09:22:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:47 INFO - 'PWD': '/builds/slave/test', 09:22:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:47 INFO - 'SHELL': '/bin/bash', 09:22:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Om812WuUE6/Listeners', 09:22:47 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:22:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:47 INFO - 'USER': 'cltbld', 09:22:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:47 INFO - 'XPC_FLAGS': '0x0', 09:22:47 INFO - 'XPC_SERVICE_NAME': '0', 09:22:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:48 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 09:22:48 INFO - Cleaning up... 09:22:48 INFO - Return code: 0 09:22:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:22:48 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')]} 09:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:48 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')]} 09:22:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:48 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 0x10b5daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8179cc22d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b479e00>, '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 0x10b2e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b474b20>, '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.2BLWnMrCjS/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.Om812WuUE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 09:22:48 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 09:22:48 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 09:22:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BLWnMrCjS/Render', 09:22:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:48 INFO - 'HOME': '/Users/cltbld', 09:22:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:48 INFO - 'LOGNAME': 'cltbld', 09:22:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:48 INFO - 'MOZ_NO_REMOTE': '1', 09:22:48 INFO - 'NO_EM_RESTART': '1', 09:22:48 INFO - 'PAGER': '/bin/cat', 09:22:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:48 INFO - 'PWD': '/builds/slave/test', 09:22:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:48 INFO - 'SHELL': '/bin/bash', 09:22:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Om812WuUE6/Listeners', 09:22:48 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:22:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:48 INFO - 'USER': 'cltbld', 09:22:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:48 INFO - 'XPC_FLAGS': '0x0', 09:22:48 INFO - 'XPC_SERVICE_NAME': '0', 09:22:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:48 INFO - Downloading/unpacking psutil>=0.7.1 09:22:48 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 09:22:48 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 09:22:48 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 09:22:48 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 09:22:48 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 09:22:48 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 09:22:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:22:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:22:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:22:51 INFO - Installing collected packages: psutil 09:22:51 INFO - Running setup.py install for psutil 09:22:51 INFO - building 'psutil._psutil_osx' extension 09:22:51 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 09:22:51 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 09:22:51 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 09:22:52 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 09:22:52 INFO - building 'psutil._psutil_posix' extension 09:22:52 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 09:22:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:22:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:22:52 INFO - ^ 09:22:52 INFO - 1 warning generated. 09:22:52 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 09:22:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:22:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:22:52 INFO - Successfully installed psutil 09:22:52 INFO - Cleaning up... 09:22:52 INFO - Return code: 0 09:22:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:22: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')]} 09:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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')]} 09:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:52 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 0x10b5daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8179cc22d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b479e00>, '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 0x10b2e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b474b20>, '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.2BLWnMrCjS/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.Om812WuUE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 09:22:52 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 09:22:52 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 09:22:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BLWnMrCjS/Render', 09:22:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:52 INFO - 'HOME': '/Users/cltbld', 09:22:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:52 INFO - 'LOGNAME': 'cltbld', 09:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:52 INFO - 'MOZ_NO_REMOTE': '1', 09:22:52 INFO - 'NO_EM_RESTART': '1', 09:22:52 INFO - 'PAGER': '/bin/cat', 09:22:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:52 INFO - 'PWD': '/builds/slave/test', 09:22:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:52 INFO - 'SHELL': '/bin/bash', 09:22:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Om812WuUE6/Listeners', 09:22:52 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:22:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:52 INFO - 'USER': 'cltbld', 09:22:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:52 INFO - 'XPC_FLAGS': '0x0', 09:22:52 INFO - 'XPC_SERVICE_NAME': '0', 09:22:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:22:52 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 09:22:52 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 09:22:52 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 09:22:52 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 09:22:52 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 09:22:52 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 09:22:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:22:55 INFO - Installing collected packages: mozsystemmonitor 09:22:55 INFO - Running setup.py install for mozsystemmonitor 09:22:55 INFO - Successfully installed mozsystemmonitor 09:22:55 INFO - Cleaning up... 09:22:55 INFO - Return code: 0 09:22:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:22:55 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')]} 09:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:55 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')]} 09:22:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:55 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 0x10b5daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8179cc22d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b479e00>, '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 0x10b2e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b474b20>, '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.2BLWnMrCjS/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.Om812WuUE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 09:22:55 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 09:22:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:22:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BLWnMrCjS/Render', 09:22:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:55 INFO - 'HOME': '/Users/cltbld', 09:22:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:55 INFO - 'LOGNAME': 'cltbld', 09:22:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:55 INFO - 'MOZ_NO_REMOTE': '1', 09:22:55 INFO - 'NO_EM_RESTART': '1', 09:22:55 INFO - 'PAGER': '/bin/cat', 09:22:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:55 INFO - 'PWD': '/builds/slave/test', 09:22:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:55 INFO - 'SHELL': '/bin/bash', 09:22:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Om812WuUE6/Listeners', 09:22:55 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:22:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:55 INFO - 'USER': 'cltbld', 09:22:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:55 INFO - 'XPC_FLAGS': '0x0', 09:22:55 INFO - 'XPC_SERVICE_NAME': '0', 09:22:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:55 INFO - Downloading/unpacking blobuploader==1.2.4 09:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:57 INFO - Downloading blobuploader-1.2.4.tar.gz 09:22:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:22:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:22:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22:59 INFO - Downloading docopt-0.6.1.tar.gz 09:22:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:22:59 INFO - Installing collected packages: blobuploader, requests, docopt 09:22:59 INFO - Running setup.py install for blobuploader 09:22:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:22:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:22:59 INFO - Running setup.py install for requests 09:22:59 INFO - Running setup.py install for docopt 09:22:59 INFO - Successfully installed blobuploader requests docopt 09:22:59 INFO - Cleaning up... 09:23:00 INFO - Return code: 0 09:23:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:23:00 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')]} 09:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:00 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')]} 09:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:00 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 0x10b5daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8179cc22d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b479e00>, '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 0x10b2e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b474b20>, '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.2BLWnMrCjS/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.Om812WuUE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 09:23:00 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 09:23:00 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 09:23:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BLWnMrCjS/Render', 09:23:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:00 INFO - 'HOME': '/Users/cltbld', 09:23:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:00 INFO - 'LOGNAME': 'cltbld', 09:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:00 INFO - 'MOZ_NO_REMOTE': '1', 09:23:00 INFO - 'NO_EM_RESTART': '1', 09:23:00 INFO - 'PAGER': '/bin/cat', 09:23:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:00 INFO - 'PWD': '/builds/slave/test', 09:23:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:00 INFO - 'SHELL': '/bin/bash', 09:23:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Om812WuUE6/Listeners', 09:23:00 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:23:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:00 INFO - 'USER': 'cltbld', 09:23:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:00 INFO - 'XPC_FLAGS': '0x0', 09:23:00 INFO - 'XPC_SERVICE_NAME': '0', 09:23:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:23:00 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-sCquF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:23:00 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-4uy5Qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:23:00 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-8uuCm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:23:00 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-N9d8xO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:23:00 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-6aYoUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:23:00 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-vNxdKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:23:00 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-j9aP4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:23:01 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-0EDHYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:23:01 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-cjMiPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:23:01 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-nWqGRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:23:01 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-coCFhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:23:01 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-o78U2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:23:01 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-tMCFn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:23:01 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-ES3bdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:01 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-ba2K7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:23:01 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-bgZLJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:23:02 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-miyLkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:23:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:23:02 INFO - Running setup.py install for manifestparser 09:23:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:23:02 INFO - Running setup.py install for mozcrash 09:23:02 INFO - Running setup.py install for mozdebug 09:23:02 INFO - Running setup.py install for mozdevice 09:23:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:23:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:23:02 INFO - Running setup.py install for mozfile 09:23:02 INFO - Running setup.py install for mozhttpd 09:23:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:23:02 INFO - Running setup.py install for mozinfo 09:23:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:23:03 INFO - Running setup.py install for mozInstall 09:23:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:23:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:23:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:23:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:23:03 INFO - Running setup.py install for mozleak 09:23:03 INFO - Running setup.py install for mozlog 09:23:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:23:03 INFO - Running setup.py install for moznetwork 09:23:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:23:03 INFO - Running setup.py install for mozprocess 09:23:03 INFO - Running setup.py install for mozprofile 09:23:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:23:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:23:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:23:04 INFO - Running setup.py install for mozrunner 09:23:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:23:04 INFO - Running setup.py install for mozscreenshot 09:23:04 INFO - Running setup.py install for moztest 09:23:04 INFO - Running setup.py install for mozversion 09:23:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:23:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:23:04 INFO - Cleaning up... 09:23:04 INFO - Return code: 0 09:23:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:23:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:04 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 0x10b5daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8179cc22d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b479e00>, '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 0x10b2e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b474b20>, '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.2BLWnMrCjS/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.Om812WuUE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 09:23:04 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 09:23:04 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 09:23:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BLWnMrCjS/Render', 09:23:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:04 INFO - 'HOME': '/Users/cltbld', 09:23:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:04 INFO - 'LOGNAME': 'cltbld', 09:23:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:04 INFO - 'MOZ_NO_REMOTE': '1', 09:23:04 INFO - 'NO_EM_RESTART': '1', 09:23:04 INFO - 'PAGER': '/bin/cat', 09:23:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:04 INFO - 'PWD': '/builds/slave/test', 09:23:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:04 INFO - 'SHELL': '/bin/bash', 09:23:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Om812WuUE6/Listeners', 09:23:04 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:23:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:04 INFO - 'USER': 'cltbld', 09:23:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:04 INFO - 'XPC_FLAGS': '0x0', 09:23:04 INFO - 'XPC_SERVICE_NAME': '0', 09:23:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:23:04 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-MNdvLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:23:05 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)) 09:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:23:05 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-OrKqPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:23:05 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)) 09:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:23:05 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-0ZV82r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:23:05 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)) 09:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:23:05 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-4T_Dgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:23:05 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)) 09:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:23:05 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-zmqrGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:23:05 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)) 09:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:23:05 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-jjVpyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:23:05 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)) 09:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:23:05 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-aRO9IM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:23:05 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)) 09:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:23:05 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-JrnhiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:23:05 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)) 09:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:23:05 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-VvBiGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:23:05 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)) 09:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:23:05 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-9UyHVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:23:06 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)) 09:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:23:06 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-TsMpj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:23:06 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)) 09:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:23:06 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-8xuHHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:23:06 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)) 09:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:23:06 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-j5402a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:23:06 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)) 09:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:23:06 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-j7Z1My-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:23:06 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)) 09:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:06 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-BcbUuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:23:06 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)) 09:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:23:06 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-RusNmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:23:06 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)) 09:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:23:06 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-Fpe0JJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:23:06 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)) 09:23:06 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)) 09:23:06 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)) 09:23:06 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)) 09:23:06 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)) 09:23:06 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)) 09:23:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:23:06 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 09:23:06 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 09:23:06 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 09:23:06 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 09:23:06 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 09:23:06 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 09:23:09 INFO - Downloading blessings-1.6.tar.gz 09:23:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:23:09 INFO - Installing collected packages: blessings 09:23:09 INFO - Running setup.py install for blessings 09:23:09 INFO - Successfully installed blessings 09:23:09 INFO - Cleaning up... 09:23:09 INFO - Return code: 0 09:23:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:23:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:23:09 INFO - Reading from file tmpfile_stdout 09:23:09 INFO - Current package versions: 09:23:09 INFO - blessings == 1.6 09:23:09 INFO - blobuploader == 1.2.4 09:23:09 INFO - docopt == 0.6.1 09:23:09 INFO - manifestparser == 1.1 09:23:09 INFO - mozInstall == 1.12 09:23:09 INFO - mozcrash == 0.17 09:23:09 INFO - mozdebug == 0.1 09:23:09 INFO - mozdevice == 0.48 09:23:09 INFO - mozfile == 1.2 09:23:09 INFO - mozhttpd == 0.7 09:23:09 INFO - mozinfo == 0.9 09:23:09 INFO - mozleak == 0.1 09:23:09 INFO - mozlog == 3.1 09:23:09 INFO - moznetwork == 0.27 09:23:09 INFO - mozprocess == 0.22 09:23:09 INFO - mozprofile == 0.28 09:23:09 INFO - mozrunner == 6.11 09:23:09 INFO - mozscreenshot == 0.1 09:23:09 INFO - mozsystemmonitor == 0.0 09:23:09 INFO - moztest == 0.7 09:23:09 INFO - mozversion == 1.4 09:23:09 INFO - psutil == 3.1.1 09:23:09 INFO - requests == 1.2.3 09:23:09 INFO - wsgiref == 0.1.2 09:23:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:09 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 0x10b5daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8179cc22d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b479e00>, '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 0x10b2e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b474b20>, '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.2BLWnMrCjS/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.Om812WuUE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 09:23:09 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 09:23:09 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 09:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BLWnMrCjS/Render', 09:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:09 INFO - 'HOME': '/Users/cltbld', 09:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:09 INFO - 'LOGNAME': 'cltbld', 09:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:09 INFO - 'MOZ_NO_REMOTE': '1', 09:23:09 INFO - 'NO_EM_RESTART': '1', 09:23:09 INFO - 'PAGER': '/bin/cat', 09:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:09 INFO - 'PWD': '/builds/slave/test', 09:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:09 INFO - 'SHELL': '/bin/bash', 09:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Om812WuUE6/Listeners', 09:23:09 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:09 INFO - 'USER': 'cltbld', 09:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:09 INFO - 'XPC_FLAGS': '0x0', 09:23:09 INFO - 'XPC_SERVICE_NAME': '0', 09:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:10 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)) 09:23:10 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)) 09:23:10 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)) 09:23:10 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)) 09:23:10 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)) 09:23:10 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)) 09:23:10 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)) 09:23:10 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)) 09:23:10 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)) 09:23:10 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)) 09:23:10 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)) 09:23:10 INFO - Cleaning up... 09:23:10 INFO - Return code: 0 09:23:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:23:10 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')]} 09:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:10 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')]} 09:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:10 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 0x10b5daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8179cc22d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b479e00>, '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 0x10b2e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b474b20>, '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.2BLWnMrCjS/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.Om812WuUE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 09:23:10 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 09:23:10 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 09:23:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BLWnMrCjS/Render', 09:23:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:10 INFO - 'HOME': '/Users/cltbld', 09:23:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:10 INFO - 'LOGNAME': 'cltbld', 09:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:10 INFO - 'MOZ_NO_REMOTE': '1', 09:23:10 INFO - 'NO_EM_RESTART': '1', 09:23:10 INFO - 'PAGER': '/bin/cat', 09:23:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:10 INFO - 'PWD': '/builds/slave/test', 09:23:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:10 INFO - 'SHELL': '/bin/bash', 09:23:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Om812WuUE6/Listeners', 09:23:10 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:23:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:10 INFO - 'USER': 'cltbld', 09:23:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:10 INFO - 'XPC_FLAGS': '0x0', 09:23:10 INFO - 'XPC_SERVICE_NAME': '0', 09:23:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:10 INFO - Downloading/unpacking jsonschema 09:23:10 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 09:23:10 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 09:23:10 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 09:23:10 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 09:23:10 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 09:23:10 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 09:23:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:23:12 INFO - Downloading/unpacking functools32 (from jsonschema) 09:23:12 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 09:23:12 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 09:23:12 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 09:23:12 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 09:23:12 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 09:23:12 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 09:23:13 INFO - Downloading functools32-3.2.3-2.tar.gz 09:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:23:13 INFO - warning: no files found matching '*.txt' 09:23:13 INFO - no previously-included directories found matching 'build' 09:23:13 INFO - no previously-included directories found matching 'dist' 09:23:13 INFO - no previously-included directories found matching '.git*' 09:23:13 INFO - Installing collected packages: jsonschema, functools32 09:23:13 INFO - Running setup.py install for functools32 09:23:13 INFO - warning: no files found matching '*.txt' 09:23:13 INFO - no previously-included directories found matching 'build' 09:23:13 INFO - no previously-included directories found matching 'dist' 09:23:13 INFO - no previously-included directories found matching '.git*' 09:23:13 INFO - Successfully installed jsonschema functools32 09:23:13 INFO - Cleaning up... 09:23:13 INFO - Return code: 0 09:23:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:23:13 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')]} 09:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:13 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')]} 09:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:23:13 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 0x10b5daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8179cc22d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b479e00>, '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 0x10b2e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b474b20>, '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.2BLWnMrCjS/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.Om812WuUE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 09:23:13 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 09:23:13 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 09:23:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BLWnMrCjS/Render', 09:23:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:13 INFO - 'HOME': '/Users/cltbld', 09:23:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:13 INFO - 'LOGNAME': 'cltbld', 09:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:13 INFO - 'MOZ_NO_REMOTE': '1', 09:23:13 INFO - 'NO_EM_RESTART': '1', 09:23:13 INFO - 'PAGER': '/bin/cat', 09:23:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:13 INFO - 'PWD': '/builds/slave/test', 09:23:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:13 INFO - 'SHELL': '/bin/bash', 09:23:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Om812WuUE6/Listeners', 09:23:13 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:23:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:13 INFO - 'USER': 'cltbld', 09:23:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:13 INFO - 'XPC_FLAGS': '0x0', 09:23:13 INFO - 'XPC_SERVICE_NAME': '0', 09:23:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:23:13 INFO - Downloading/unpacking flake8 09:23:13 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 09:23:13 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 09:23:13 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 09:23:13 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 09:23:13 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 09:23:13 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 09:23:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:23:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:23:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:23:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:23:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:23:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:23:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:23:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:23:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:23:17 INFO - no previously-included directories found matching 'docs/_build' 09:23:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:23:17 INFO - Running setup.py install for pep8 09:23:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:23:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:23:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:23:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:23:17 INFO - no previously-included directories found matching 'docs/_build' 09:23:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:23:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:23:17 INFO - Cleaning up... 09:23:17 INFO - Return code: 0 09:23:17 INFO - Running post-action listener: _resource_record_post_action 09:23:17 INFO - Running post-action listener: _start_resource_monitoring 09:23:17 INFO - Starting resource monitoring. 09:23:17 INFO - ##### 09:23:17 INFO - ##### Running install step. 09:23:17 INFO - ##### 09:23:17 INFO - Running pre-action listener: _resource_record_pre_action 09:23:17 INFO - Running main action method: install 09:23:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:23:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:23:18 INFO - Reading from file tmpfile_stdout 09:23:18 INFO - Detecting whether we're running mozinstall >=1.0... 09:23:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:23:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:23:18 INFO - Reading from file tmpfile_stdout 09:23:18 INFO - Output received: 09:23:18 INFO - Usage: mozinstall [options] installer 09:23:18 INFO - Options: 09:23:18 INFO - -h, --help show this help message and exit 09:23:18 INFO - -d DEST, --destination=DEST 09:23:18 INFO - Directory to install application into. [default: 09:23:18 INFO - "/builds/slave/test"] 09:23:18 INFO - --app=APP Application being installed. [default: firefox] 09:23:18 INFO - mkdir: /builds/slave/test/build/application 09:23:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:23:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:23:50 INFO - Reading from file tmpfile_stdout 09:23:50 INFO - Output received: 09:23:50 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:23:50 INFO - Running post-action listener: _resource_record_post_action 09:23:50 INFO - ##### 09:23:50 INFO - ##### Running run-tests step. 09:23:50 INFO - ##### 09:23:50 INFO - Running pre-action listener: _resource_record_pre_action 09:23:50 INFO - Running main action method: run_tests 09:23:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:23:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:23:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:23:50 INFO - 2016-05-30 09:23:50.197 screenresolution[1931:12153] starting screenresolution argv=screenresolution get 09:23:50 INFO - 2016-05-30 09:23:50.200 screenresolution[1931:12153] Display 0: 1600x1200x32@60 09:23:50 INFO - 2016-05-30 09:23:50.207 screenresolution[1932:12157] starting screenresolution argv=screenresolution list 09:23:50 INFO - Available Modes on Display 0 09:23:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:23:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:23:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:23:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:23:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:23:50 INFO - Intel Iris: 09:23:50 INFO - Chipset Model: Intel Iris 09:23:50 INFO - Type: GPU 09:23:50 INFO - Bus: Built-In 09:23:50 INFO - VRAM (Dynamic, Max): 1536 MB 09:23:50 INFO - Vendor: Intel (0x8086) 09:23:50 INFO - Device ID: 0x0a2e 09:23:50 INFO - Revision ID: 0x0009 09:23:50 INFO - Displays: 09:23:50 INFO - Display: 09:23:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:23:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:23:50 INFO - Display Serial Number: 1600x1200 60 09:23:50 INFO - Main Display: Yes 09:23:50 INFO - Mirror: Off 09:23:50 INFO - Online: Yes 09:23:50 INFO - Rotation: Supported 09:23:50 INFO - Return code: 0 09:23:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:23:50 INFO - Python 2.7.3 09:23:50 INFO - Return code: 0 09:23:50 INFO - grabbing minidump binary from tooltool 09:23:50 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')]} 09:23:50 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 0x10b479e00>, '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 0x10b2e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b474b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:23:50 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 09:23:50 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 09:23:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:23:50 INFO - Return code: 0 09:23:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:23:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:23:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:23:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:23:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:23:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:23:51 INFO - Return code: 0 09:23:51 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/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0327', '--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 09:23:51 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/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0327 --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 09:23:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BLWnMrCjS/Render', 09:23:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:23:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:23:51 INFO - 'HOME': '/Users/cltbld', 09:23:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:23:51 INFO - 'LOGNAME': 'cltbld', 09:23:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:23:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:23:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:23:51 INFO - 'MOZ_NO_REMOTE': '1', 09:23:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:23:51 INFO - 'NO_EM_RESTART': '1', 09:23:51 INFO - 'PAGER': '/bin/cat', 09:23:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:23:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:23:51 INFO - 'PWD': '/builds/slave/test', 09:23:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:23:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:23:51 INFO - 'SHELL': '/bin/bash', 09:23:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Om812WuUE6/Listeners', 09:23:51 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:23:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:23:51 INFO - 'USER': 'cltbld', 09:23:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:23:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:23:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:23:51 INFO - 'XPC_FLAGS': '0x0', 09:23:51 INFO - 'XPC_SERVICE_NAME': '0', 09:23:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:23:51 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/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0327', '--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 09:23:51 INFO - mozversion application_buildid: 20160530075343 09:23:51 INFO - mozversion application_changeset: de472f31c8a3f1711bdf65beecc8dfa983950b64 09:23:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:23:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:23:51 INFO - mozversion application_name: Firefox 09:23:51 INFO - mozversion application_remotingname: firefox-dev 09:23:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:23:51 INFO - mozversion application_vendor: Mozilla 09:23:51 INFO - mozversion application_version: 48.0a2 09:23:51 INFO - mozversion platform_buildid: 20160530075343 09:23:51 INFO - mozversion platform_changeset: de472f31c8a3f1711bdf65beecc8dfa983950b64 09:23:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:23:51 INFO - mozversion platform_version: 48.0a2 09:23:51 INFO - using testdate: 1464625431 09:23:51 INFO - actual date: 1464625431 09:23:51 INFO - starting webserver on 'localhost:49401' 09:23:51 INFO - SUITE-START | Running 2 tests 09:23:51 INFO - TEST-START | dromaeo_css 09:23:51 INFO - operating with platform_type : mac_ 09:23:51 INFO - Initialising browser for dromaeo_css test... 09:23:51 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmpZa4oMk/profile http://localhost:49401/getInfo.html) 09:23:54 INFO - PROCESS | 1950 | __metrics Screen width/height:1600/1200 09:23:54 INFO - PROCESS | 1950 | colorDepth:24 09:23:54 INFO - PROCESS | 1950 | Browser inner width/height: 1024/689 09:23:54 INFO - PROCESS | 1950 | __metrics 09:23:54 INFO - PROCESS | 1950 | 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" 09:23:54 ERROR - PROCESS | 1950 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:23:54 INFO - TEST-INFO | 1950: exit 0 09:23:54 INFO - Browser initialized. 09:23:54 INFO - Running cycle 1/1 for dromaeo_css test... 09:23:54 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmpZa4oMk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:24:06 INFO - PROCESS | 1952 | RSS: Main: 211222528 09:24:06 INFO - PROCESS | 1952 | 09:24:58 INFO - PROCESS | 1952 | 1464625498248 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] 09:27:08 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49401/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49401/tests/dromaeo/cssquery-ext.html) 09:27:10 INFO - PROCESS | 1952 | RSS: Main: 408711168 09:27:10 INFO - PROCESS | 1952 | 09:30:13 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49401/tests/dromaeo/cssquery-ext.html (next: http://localhost:49401/tests/dromaeo/cssquery-jquery.html) 09:30:14 INFO - PROCESS | 1952 | RSS: Main: 361508864 09:30:14 INFO - PROCESS | 1952 | 09:33:16 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49401/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49401/tests/dromaeo/cssquery-mootools.html) 09:33:17 INFO - PROCESS | 1952 | RSS: Main: 361418752 09:33:17 INFO - PROCESS | 1952 | 09:36:19 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49401/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49401/tests/dromaeo/cssquery-prototype.html) 09:36:19 INFO - PROCESS | 1952 | RSS: Main: 361738240 09:36:19 INFO - PROCESS | 1952 | 09:39:23 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49401/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49401/tests/dromaeo/cssquery-yui.html) 09:39:23 INFO - PROCESS | 1952 | RSS: Main: 325591040 09:39:23 INFO - PROCESS | 1952 | 09:42:26 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49401/tests/dromaeo/cssquery-yui.html (next: http://localhost:49401/tests/dromaeo/cssquery-dojo.html) 09:42:26 INFO - PROCESS | 1952 | RSS: Main: 379240448 09:42:26 INFO - PROCESS | 1952 | 09:42:26 INFO - PROCESS | 1952 | __start_tp_report 09:42:26 INFO - PROCESS | 1952 | _x_x_mozilla_page_load 09:42:26 INFO - PROCESS | 1952 | _x_x_mozilla_page_load_details 09:42:26 INFO - PROCESS | 1952 | |i|pagename|runs| 09:42:26 INFO - PROCESS | 1952 | |0;dojo.html;3309.056918778148;3385.390629686656;3389.525466434698;3397.4903764435317;3438.8796392126283;5590.888182236358;5594.328680558338;5596.944030559698;5605.934872374251;5625.353084395296;5603.691796951166;5631.8592035199035;5633.0987042073275;5651.180578816069;5651.886962260753;5008.749562521877;5050.974745126287;5137.998090372379;5186.118359878829;5212.895742085175;5129.7948082076855;5149.150390185615;5150.04724126036;5151.81968631098;5153.613478989072;5074.0866644004245;5104.259882317062;5111.284420181178;5122.410922743877;5130.230465430178;5149.45930677281;5181.06740786662;5191.636585439021;5203.427622961509;5236.7905283788605;4998.850034498971;5045.445001049881;5099.949000509984;5104.336436263262;5114.693118412907;5331.253624492574;5399.568034557226;5439.864003399907;5441.455854414527;5453.7818487260465;6059.606125601822;6073.271207455134;6074.848128796772;6081.11823785553;6090.086487026982;6004.669743164124;6127.87744245117;6158.058494088364;6185.536084793614;6194.566380353377;5601.411851755548;5612.326520817446;5620.269364982567;5667.858303542362;5668.178114173422;6242.750289988349;6248;6252.687365631791;6261.311255761862;6274.247090349188;5022.196448568285;5912.142739302863;6057.151998720183;6059.818205453844;6062.151298818169;6032.638041717511;6113.537117903944;6199.287081985539;6220.25356957159;6225.252969643671;6003.729832157476;6050.6369617823075;6085.786997455157;6085.96538588441;6120.265568131853;5920.822375328661;6112.174856394418;6114.327423983358;6125.877482450326;6131.019036954155;4776.617870570346;4786.8085276589245;4787.880302992453;4795.424549054136;4801.567858892647;4325.2214601371425;4340.218760623118;4355.564443555682;4375.409319741858;4393.75834329115;5424.864378390572;5429.239906413105;5431.456854314537;5436.021516127135;5444.50999410055;6070.514358851281;6086.878262434727;6106.551676171891;6111.052786818051;6162.952298109332;5503.669779813144;5510.586705997066;5511.3937466878615;5526.861828454321;5531.668099914018;5280.683159010472;5290.1800220965815;5298.470152984671;5305.230741542455;5307.2569840222395;6146.647737497743;6162.568620196544;6222.408871157233;6248.625082494974;6288.308286088618;6182.227221597301;6210.378962103753;6212.968935155476;6223.097650840693;6238.376162383725;6210.223722034746;6210.813675589739;6230.345813689679;6240;6240.095186198067;5965.403459654;5978.730957106854;6015.278166619859;6090.69546522681;6112.755489780359;5939.524838012862;6039.456448919618;6051.273847138372;6071.058985857199;6111.755529778759;5865.5600829937075;5872.031114865999;5874.089516125007;5889.970550147222;5894.70526473666;4939.53074457926;5835.270591176103;5945.197398351167;5956.910646340221;6017.699115044318;5729.369769325288;5754.4533269339345;5757.712114394348;5764.884702305846;5782.335031471266;4845.515448455127;5821.359650438533;5842.824715258549;5865.472107510344;5925.496332811759;5824.417558244142;5840.591158618856;5844.94155058444;5863.501602363847;5886.440788125293 09:42:26 INFO - PROCESS | 1952 | |1;ext.html;504.5635525270642;510.95145961133693;589.9203607512984;595.4908553187028;616.23586752427;27087.32281692956;31626.841865790666;36596.817015914916;37492.62507374925;37552.248955020885;40551;40767;40974;40983.38524922128;41071.79464102683;9381.859272110907;9596.328257021976;9603.087706667855;9605.90394096057;9622.75943101421;8498.575071246443;8624.396292259544;8625.309975202;8649.65401383944;11460.71348216293;9781.755456113582;11394.601188958388;11463.713407164803;11471.885281147206;11494.19540632152;11350.886491135065;11447.885521144848;11451.713707157305;11453.484593193243;11454.770904581861;8476.449030812977;9735.90264097357;11344.319340839493;11568.652940411801;11613.477393517313;37317;37679.81160094209;37812.62187378146;37817.43273850894;37839.810800945816;3195.137312925497;3205.246767009751;3478.9478157827652;3529.1353618363664;3830.942535861964;27365.45269094627;27402.862985684944;27463.313417164532;27500.17499475019;28618.570721439195;2703.256604433777;2740.4500600844544;2756.297144228229;2779.749822515963;2818.055951256332;447630;449689;449788;449877;450692;60795.69602151961;61269;61397.07904381438;61502.38497615056;61587.692061539405;52838;62734.68632656807;62780;62832.05751913725;62875;16984.66030679404;17413.042282674574;17434.738478922827;17447.65104697899;17453.12734363277;13382.866171338163;13511.797323040162;13609.659758506117;13610.795838062437;13630.591082267549;14322.427102915975;14555.92722036383;15995.520134395987;16136.435224767078;16291.348346066025;6727.9571666391785;7070.575765454089;7140.571565706075;7233.204347521769;7258.564486130849;879.1120967822545;919.4896832258161;993.2500961773959;999.9250056245811;1008.9658100446953;27157.86421067882;28006;28257.858710706314;28276;28564.428711425655;970.5875003123645;1096.71485413793;1134.750354921916;1137.2039572299423;1143.1255089856268;13975.720485590231;14088.71822563543;14098.718025639635;14417.78373324401;14486.565403037926;590055.0497247486;596851;598190;598854.0114598885;599809;58323.4167658318;59947.40052599506;60035.399646003854;60094.798104037676;60321;1332.6068809701162;1373.3476598615578;1488.7245859516024;1504.127605988524;1506.0963421946963;27577.03480378177;27688.584671229546;28261.858690706416;28460.573091403232;28612.856935715186;46708.299375510076;48329.03341933212;48426.51573484361;48515;48534.271985919535;419444;430509;430555;430772;431164;32158.19604509906;32326.737128400786;32851.34297314088;33127;33329.00012999614;35849;43030.35454468248;43965;44698;45505.54494455013;34457;34633.826830865684;34700.47949280813;34927.9521614352;35280.94157175289;34128.48807267943;34259.486107707904;34631.480527792606;34772.304553909285;35005.82497087498;34560.82719586486;34704.479432809036;34792.47811282782;35270.64729352674;35892;31656.626954763527;31855.522167167048;32096.839515803207;32123.518147227343;32246.19384515312;23943.401414964766;24033.639495407235;24502.14242501575;24570.14004509905;24868.005279788606 09:42:26 INFO - PROCESS | 1952 | |2;jquery.html;360.6483678413546;401.39389521822056;402.64567180880823;403.62261285697895;414.98547550835724;13508.72982540351;15400.614984625361;19889.3038743644;20598.48503787404;20607.690884636722;13137.540186093489;16358;19630.705539416886;20124.899375503137;20388.184472621113;5364.141737322029;5765.827025189251;5791.884162316751;5796;5802.158686990405;3317.286783341581;5693.715314234292;5802.158686990405;5802.332731735841;5802.825915222529;6162.291336496315;6425.7108430120525;6432.77485288015;6438.195225596801;6438.5171112166545;6266.373362663743;6373.394527519901;6384.48924086072;6399;6406.423421892029;5383.057964856134;5497.587680923907;5538.307711536058;5542.806001789958;5544.473275038865;23992;24296.271111866674;25255.49489010228;25576;25626.974921003137;2188.3434969509085;2308.7229532456213;2338.4504641409253;2385.7375688674233;2615.4246065865477;11308.773824523463;14379.065360751518;14592.197429141394;14686.192259425727;14951.028183168059;1631.7715519827234;1692.3822804676327;1720.4236580745464;1721.6212433264661;1778.662054209696;83946.58026709888;113024;113607;114193;115080.27379589312;9097.590608422504;10647.574097036188;10832.187585931088;11094.724106727668;11258.38078905668;14077;14086.577402677936;14101.083429577046;14111.788323174955;14118.576442706735;6732.427743641747;7674.270944260286;8011.479253848481;8015.799605009922;8586.527740974181;7831.490953088031;8005.039395272604;8008.839823203503;8559.700410485602;8654.567271636393;6422.6467544285515;6554.754596626721;6564.6389448580785;6581.835454113685;6587.110740050128;1681.865450763936;1823.042902476184;1884.7549818523778;1889.2983034260865;1894.6116046210493;749.1609397474754;848.6393282854762;897.9012308646044;1000.5247507433911;1010.9191264698807;17209.7418538722;19007.80992190088;20592.073356698984;20671.58656826855;20854.478638033866;1027.2295778166376;1041.9479026048666;1045.3727763341933;1058.9886658241362;1069.534752382716;10840.241183117263;11200.607978720705;11250.718732031764;11260;11331.773364532663;503754;581676;596473;602018;609487.7628059245;128804.75943715921;132147.67852321354;133731;137019.94470082957;138250;961.3510880155867;990.5889056041806;1005.2109094361037;1044.6604853422668;1047.8009178256107;17781.288748450435;17846.91076544661;18110.63778724392;18379.17293721759;18473.44579662612;51806.48193518016;52763;53179.734101329246;53292.73353633207;53348.46651533435;72279;73791;75864;78414;78842.6057869707;7788.766337009899;8213.425060245725;8274.83450330978;8306.792330191794;8599.95700021496;11674.70813229642;12482.812757808822;12644.431000605178;12787.488500459878;12905.870941290466;10905.672829815117;10965.013148816644;11131.998120169224;11319.981201691558;11390.25963312382;10577.841332379867;10624.256302058782;10673.039426451656;10890.891091089305;11025.17311201647;11022.559097636326;11265.155113366367;11286.717832054264;11442.885571144181;11467.311961282016;8165.550894700611;8207.835843283221;8292.958535207526;8372.497650141011;8636.093210213088;10573.154147668321;11024.779504409706;11310;11339.09287257034;11349.262297950607 09:42:26 INFO - PROCESS | 1952 | |3;mootools.html;1460.9196494192815;1592.9681406371872;1631.4289998500522;1654.9255283512248;1971.1425529894498;7947.920520794791;8554.016288126868;8728.781780455492;8736.51949142797;8747.081556436578;8479.491230526173;8734.170253825892;8758.211760941515;8760.561971901396;8772;2179.2699445685716;2225.9332220033425;2399.4841109161525;2443.535728211643;2480.367506285904;2471.752824717532;2472.221250306157;2472.8763561821925;2478.182199874046;2480.0203919451897;2392.7807979590075;2419.8185136114776;2443.9389015274674;2472.4560596668703;2475.418276704982;2143.0998980428258;2396.0894859953282;2450.816188785839;2454.2391858523915;2462.8768561571937;2416.50461655362;2455.693038370204;2458.188797696756;2459.9508009839883;2466.1738317663426;8686.956565217197;8739.868901966476;8749.912500874974;8769.070804423016;8770.386072974894;5341.759620817073;5442.727863606804;5453.454654534515;5492.862678433071;5498;3395.6043956043954;3453.879114231015;3574.1422058705984;3584.623614520464;3586.659267369596;945.664289177346;1047.0890325416935;1047.9895201047968;1068.0974576482859;1081.2647399768155;12045.879541204564;12112.6366209013;12120;12121.27272363661;12144.02847772185;4794.113089078532;4840.75796210188;4860.1737704590305;4914.115459217375;4951.430585482643;8561.78595535104;10113.29206955506;10194.490275486196;10207.285490015627;10208.591656333663;5477.534409575231;5480.588955828329;5513;5518.779248830002;5531.3915469298345;4327.675424343187;4439.64482841372;4794.664373493892;4913.803447862117;5015.971725796203;3637.217998130415;3882.3400021995762;3998.440608162819;4071.511418629724;4125.546189919107;1349.3320806200993;1351.4729255590328;1355.9389827457787;1367.3573420492353;1367.4940272099386;794.2732399854184;915.0894859614593;920.8987011428737;934.5654270764128;940.2618944128826;5701.971490142606;5725.856853578611;5862.560307976919;5867.2959244890035;5938.257717785277;469.51405295518975;471.5002097776368;473.486267399875;910.5476764560516;1020.5662593397776;3855.132595166121;3894.7078969077434;3940.9014774630864;3971.9801400993388;3972.145988612462;10363.896361036444;10706.78586428267;10716.249862509705;10837.295575787548;10843.1867609928;10158;10633.149348052291;10751.462426878625;10841.67474975752;10855.891441085647;8176.877346839802;8218.342532597497;8338.415701016787;8345.165483451729;8429.789255268668;5575.693336866511;5649.6327738696855;5682.602217844877;5792.391798861059;5813.476787089182;9436.575354109218;9448.149666530046;9526.428414295164;9614.855777163208;9638.180754635934;9429.15137637616;9763.218942484726;9852.901470985198;9877.802443951225;9900.653477128553;6279.560430769985;6431.678416079271;6890.138732658418;7018;7147.785566433015;8324.084350721294;8351.832963340821;8414.158584141536;8423.8736418955;8436.198561136682;7333.4499912507845;7451.441141914486;7455.0681164854395;7579.166291708018;7608.31525162738;7292.525985810906;7320.597367144858;7329.083864516935;7376.077990251219;7438.925610743823;6947.478939079489;6979.30206979298;6982.825429364307;7001.36987671112;7023.754168604074;4337.481881341505;4808.687435316693;4842.4189097308545;4851.878703032452;5042.344495215599;4966.106100901872;4995.525425084611;5004.87487812793;5048.772805223847;5235.424103348552 09:42:26 INFO - PROCESS | 1952 | |4;prototype.html;319.23065412356226;325.07840272826536;329.615997363072;334.84429740170816;337.95944486661574;6391.265004524475;6409.198850143732;6452.870942581146;6486.837829054264;6495.8376040599005;5242.947570524303;6350.650714210728;6375.553711240215;6379.968100159493;6416.358364163593;4590.701604395712;4709.717416954976;4717.339572459859;4799.208130658437;4847.1517484440255;4759.643026772988;4805.759712014403;4816.903661926759;4827.613790896738;4828.951710482886;4149.958500414988;4526.094781043788;4732.2428411454175;4762.238041913294;4775.212090005145;4561.270196768501;4683.859484215479;4685.672002959795;4688.320193571913;4742.97628511857;4008.979955100235;4043.454133691973;4102.220578090153;4103.774292413916;4134.131832315217;6070.301915279755;6101.542384321194;6105.419985101408;6124.693765311761;6126.9387306127255;4965.900681986376;5363.9463605363835;5567.248421463091;5582;5624.493795558378;6895.241523432418;6904.7928562142715;6944.097267355262;6945.791626251221;6959.582425054513;4329.718568293082;4506.301523263899;4536.773161341953;4547.795349209294;4644.442666879962;7167.462440316997;7201.207867134611;7255.45584081196;7259;7266.4550158737775;4527.754867411422;4608.239640459354;4609.838655647036;4614.74618895964;4626.629869610423;4017.6986725995666;4654.953450465452;4658.953410465852;4662.603678687349;4663.510331415153;5522.779088836482;5523.8895222096135;5531.917021244684;5554.944450555443;5556.333240011225;5752.223449834219;6615.437687796495;6860.794176174722;6901.551399159039;6960.060391847137;1392.324722509582;1559.970419523132;1594.4817934171208;1602.7115119278408;1618.34457044897;1014.3457469932;1018.1701912940929;1035.1046344911597;1040.77623310987;1040.8907064758243;6318.241810982711;6411.67941602918;6431.74273029083;6447.677616119176;6469.902951455732;7261.491695581259;7283.562986220843;7284.089488813898;7290.453216008715;7300.4159667226495;6550.868982620416;6553.2136143663065;6570.77002304917;6633.867322653617;6706.564073335315;3804.087019115392;3868.207017561393;3870.013146647643;3872.361060425055;3877.728559000899;7245.166805817293;7542.396608271435;7575.507592006502;7602.961985190149;7617.809554761176;7460.738874139379;7467.738629147953;7472.813179670552;7473.775786726407;7485.663145158481;7520.360769334558;7541.321281084729;7555.9244407555225;7582.696692132253;7628.275313845176;6299.590526615939;6461.386168314003;7295.708171673074;7301.707931682673;7320.377767889789;5725.2843394575675;6860.451163906975;7136.607486588287;7141.785746427616;7198.640067996684;7473.476856619985;7524.2851929066655;7528.096628404626;7530.698772049276;7548.4716069874585;4612.146752850734;4643.2802915548145;4645.6051235644;4648.093621743727;4651.2557990721325;5825.475707186373;7260.782176534713;7269.7455589054125;7274.527155734861;7274.8908766369605;6641.302663220292;6968.128983877015;6985.615791131537;7035.894461582978;7055.858882822417;6906.205786334435;6912.274211207952;6933.445324374139;6941.965290173517;6949.2008419030435;6812.65936703153;6836.350546698056;6852.533088088185;6854.143232095988;6906.965465172642;7005.859882802213;7014.088168538057;7016.26329235423;7021.227664956953;7027.402670773042;4925.556699896883;6528.053432252202;6553.246376666744;6785.355391237825;6807.115075040213 09:42:26 INFO - PROCESS | 1952 | |5;yui.html;646.8285904235379;686.3513979289571;693.2734672079672;697.6825544377309;728.5810658871151;14857.40570377185;14901;15015.699686006274;15025.92487037566;15045.548633540997;13701.246431446267;13702.109362891406;13721.451141954334;13741.65645858854;13766.862331376684;705.4708968273794;725.4196642685856;725.4522835259372;868.6221493650282;902.3367824648877;896.0680891872445;897.7486303834937;898.1063841477738;898.6630013744846;910.3218102513637;955.5174636808403;955.6798472511682;957.8371676814958;958.2046901072093;959.1942768074816;746.4401698725956;942.0579420579421;948.2982592881287;952.4808979106404;969.6654654144309;701.8666453373843;701.9368256856908;702.3152426384286;708.9751858684921;716.4340171264695;14445.06107103035;14510.927445362811;14524.782128268085;14535.200563968978;14543.636409089751;12424.440900159514;12531.373431328397;12694.98440124797;12878.356082195947;12879.356032198353;9979.950100249525;10041.598336066549;10120.190384769201;10231.795364092752;10356.844647330296;3533.699635530983;3543.255916257589;3554.5023696682483;3561.7684850484634;3591.5331006969186;74807;76164.23835761682;76302.23697763006;80221.59889200516;80250;9208.52663573825;9458.90541094594;9500;9681;10207.7448063799;20230.49423764388;20464.59070818575;20479;20508.794912050987;20509.487262818548;7222.169450513154;7253.746118885919;7313.634318284171;7463.776086717407;7495.1005879294835;4025.3156963316305;4087.816048277835;4186.874393768192;4194.161167766459;4202.222588821078;3570.303790760777;3640.781553106769;3662.285854258394;3662.6520480554304;3664.1022949377575;540.2274747111668;608.6621924831672;612.3937302070917;634.7651368993502;637.5696404926749;512.26745753572;579.0445764488543;588.7703795519751;606.3390903914755;637.9696789684626;8129.959350203329;8153.510789352657;8230.876536851953;8334.458260213067;8340.124286949904;544.3386285662939;577.3014652270715;585.1719816459662;611.1047315682547;612.7579606055583;3717.8698745543775;3847.0565323559094;3911.2177564487215;3967.5238971323624;4087.1008378156757;88803;89740;89845;91187.54406227927;91617;75979;76098;76411.61794191104;76844;78443.60778196073;66172;66534.00198997019;67581.66209168923;67926.66036669884;70255.64872175606;8265.256126948603;8330.958345208235;8417.452865563719;8422.284105851071;8559.22966932979;44547.554524455634;45731;47401;48636.5136348632;49073.5092649069;65628.67185664042;65860.67069664813;68864.93405197852;69687;69869.65065174641;10605.734856628646;10799.67600971972;10804.67585972422;10834.512446939749;10980.286281391684;11348.829767553329;11564.884351156381;11700.94691477771;11704.94147529291;12112.394380281126;9412.67055653049;10084.84872726895;10106.19150467946;10331.793364132825;11081.944590277319;10758.354498730101;10803.72990675208;11475.655730328104;11550.01824844864;11595.768084638428;10885.23803333759;11000.22998390105;11280.774384512428;11333.829992550283;11391.487383067617;9432.386894851812;9504.619815207314;9561.426314421156;9660.420374777535;9929.851052234368;9484.573194206416;9619.422834629944;9701.6604418845;9786.902130978599;9790.216782657513 09:42:26 INFO - PROCESS | 1952 | __end_tp_report 09:42:26 INFO - PROCESS | 1952 | __start_cc_report 09:42:26 INFO - PROCESS | 1952 | _x_x_mozilla_cycle_collect,1878 09:42:26 INFO - PROCESS | 1952 | __end_cc_report 09:42:26 INFO - PROCESS | 1952 | __startTimestamp1464626546831__endTimestamp 09:42:26 INFO - PROCESS | 1952 | 09:42:26 INFO - PROCESS | 1952 | ------- Summary: start ------- 09:42:26 INFO - PROCESS | 1952 | Number of tests: 6 09:42:26 INFO - PROCESS | 1952 | 09:42:26 INFO - PROCESS | 1952 | [#0] dojo.html Cycles:165 Average:5606.76 Median:5782.34 stddev:621.35 (10.7%) stddev-sans-first:596.54 09:42:26 INFO - PROCESS | 1952 | Values: 3309.1 3385.4 3389.5 3397.5 3438.9 5590.9 5594.3 5596.9 5605.9 5625.4 5603.7 5631.9 5633.1 5651.2 5651.9 5008.7 5051.0 5138.0 5186.1 5212.9 5129.8 5149.2 5150.0 5151.8 5153.6 5074.1 5104.3 5111.3 5122.4 5130.2 5149.5 5181.1 5191.6 5203.4 5236.8 4998.9 5045.4 5099.9 5104.3 5114.7 5331.3 5399.6 5439.9 5441.5 5453.8 6059.6 6073.3 6074.8 6081.1 6090.1 6004.7 6127.9 6158.1 6185.5 6194.6 5601.4 5612.3 5620.3 5667.9 5668.2 6242.8 6248.0 6252.7 6261.3 6274.2 5022.2 5912.1 6057.2 6059.8 6062.2 6032.6 6113.5 6199.3 6220.3 6225.3 6003.7 6050.6 6085.8 6086.0 6120.3 5920.8 6112.2 6114.3 6125.9 6131.0 4776.6 4786.8 4787.9 4795.4 4801.6 4325.2 4340.2 4355.6 4375.4 4393.8 5424.9 5429.2 5431.5 5436.0 5444.5 6070.5 6086.9 6106.6 6111.1 6163.0 5503.7 5510.6 5511.4 5526.9 5531.7 5280.7 5290.2 5298.5 5305.2 5307.3 6146.6 6162.6 6222.4 6248.6 6288.3 6182.2 6210.4 6213.0 6223.1 6238.4 6210.2 6210.8 6230.3 6240.0 6240.1 5965.4 5978.7 6015.3 6090.7 6112.8 5939.5 6039.5 6051.3 6071.1 6111.8 5865.6 5872.0 5874.1 5890.0 5894.7 4939.5 5835.3 5945.2 5956.9 6017.7 5729.4 5754.5 5757.7 5764.9 5782.3 4845.5 5821.4 5842.8 5865.5 5925.5 5824.4 5840.6 5844.9 5863.5 5886.4 09:42:26 INFO - PROCESS | 1952 | 09:42:26 INFO - PROCESS | 1952 | [#1] ext.html Cycles:180 Average:62975.87 Median:19125.63 stddev:132522.28 (692.9%) stddev-sans-first:132811.03 09:42:26 INFO - PROCESS | 1952 | Values: 504.6 511.0 589.9 595.5 616.2 27087.3 31626.8 36596.8 37492.6 37552.2 40551.0 40767.0 40974.0 40983.4 41071.8 9381.9 9596.3 9603.1 9605.9 9622.8 8498.6 8624.4 8625.3 8649.7 11460.7 9781.8 11394.6 11463.7 11471.9 11494.2 11350.9 11447.9 11451.7 11453.5 11454.8 8476.4 9735.9 11344.3 11568.7 11613.5 37317.0 37679.8 37812.6 37817.4 37839.8 3195.1 3205.2 3478.9 3529.1 3830.9 27365.5 27402.9 27463.3 27500.2 28618.6 2703.3 2740.5 2756.3 2779.7 2818.1 447630.0 449689.0 449788.0 449877.0 450692.0 60795.7 61269.0 61397.1 61502.4 61587.7 52838.0 62734.7 62780.0 62832.1 62875.0 16984.7 17413.0 17434.7 17447.7 17453.1 13382.9 13511.8 13609.7 13610.8 13630.6 14322.4 14555.9 15995.5 16136.4 16291.3 6728.0 7070.6 7140.6 7233.2 7258.6 879.1 919.5 993.3 999.9 1009.0 27157.9 28006.0 28257.9 28276.0 28564.4 970.6 1096.7 1134.8 1137.2 1143.1 13975.7 14088.7 14098.7 14417.8 14486.6 590055.0 596851.0 598190.0 598854.0 599809.0 58323.4 59947.4 60035.4 60094.8 60321.0 1332.6 1373.3 1488.7 1504.1 1506.1 27577.0 27688.6 28261.9 28460.6 28612.9 46708.3 48329.0 48426.5 48515.0 48534.3 419444.0 430509.0 430555.0 430772.0 431164.0 32158.2 32326.7 32851.3 33127.0 33329.0 35849.0 43030.4 43965.0 44698.0 45505.5 34457.0 34633.8 34700.5 34928.0 35280.9 34128.5 34259.5 34631.5 34772.3 35005.8 34560.8 34704.5 34792.5 35270.6 35892.0 31656.6 31855.5 32096.8 32123.5 32246.2 23943.4 24033.6 24502.1 24570.1 24868.0 09:42:26 INFO - PROCESS | 1952 | 09:42:26 INFO - PROCESS | 1952 | [#2] jquery.html Cycles:180 Average:34230.99 Median:20639.64 stddev:96918.75 (469.6%) stddev-sans-first:97157.27 09:42:26 INFO - PROCESS | 1952 | Values: 360.6 401.4 402.6 403.6 415.0 13508.7 15400.6 19889.3 20598.5 20607.7 13137.5 16358.0 19630.7 20124.9 20388.2 5364.1 5765.8 5791.9 5796.0 5802.2 3317.3 5693.7 5802.2 5802.3 5802.8 6162.3 6425.7 6432.8 6438.2 6438.5 6266.4 6373.4 6384.5 6399.0 6406.4 5383.1 5497.6 5538.3 5542.8 5544.5 23992.0 24296.3 25255.5 25576.0 25627.0 2188.3 2308.7 2338.5 2385.7 2615.4 11308.8 14379.1 14592.2 14686.2 14951.0 1631.8 1692.4 1720.4 1721.6 1778.7 83946.6 113024.0 113607.0 114193.0 115080.3 9097.6 10647.6 10832.2 11094.7 11258.4 14077.0 14086.6 14101.1 14111.8 14118.6 6732.4 7674.3 8011.5 8015.8 8586.5 7831.5 8005.0 8008.8 8559.7 8654.6 6422.6 6554.8 6564.6 6581.8 6587.1 1681.9 1823.0 1884.8 1889.3 1894.6 749.2 848.6 897.9 1000.5 1010.9 17209.7 19007.8 20592.1 20671.6 20854.5 1027.2 1041.9 1045.4 1059.0 1069.5 10840.2 11200.6 11250.7 11260.0 11331.8 503754.0 581676.0 596473.0 602018.0 609487.8 128804.8 132147.7 133731.0 137019.9 138250.0 961.4 990.6 1005.2 1044.7 1047.8 17781.3 17846.9 18110.6 18379.2 18473.4 51806.5 52763.0 53179.7 53292.7 53348.5 72279.0 73791.0 75864.0 78414.0 78842.6 7788.8 8213.4 8274.8 8306.8 8600.0 11674.7 12482.8 12644.4 12787.5 12905.9 10905.7 10965.0 11132.0 11320.0 11390.3 10577.8 10624.3 10673.0 10890.9 11025.2 11022.6 11265.2 11286.7 11442.9 11467.3 8165.6 8207.8 8293.0 8372.5 8636.1 10573.2 11024.8 11310.0 11339.1 11349.3 09:42:26 INFO - PROCESS | 1952 | 09:42:26 INFO - PROCESS | 1952 | [#3] mootools.html Cycles:180 Average:5690.06 Median:4980.82 stddev:3181.10 (63.9%) stddev-sans-first:3174.15 09:42:26 INFO - PROCESS | 1952 | Values: 1460.9 1593.0 1631.4 1654.9 1971.1 7947.9 8554.0 8728.8 8736.5 8747.1 8479.5 8734.2 8758.2 8760.6 8772.0 2179.3 2225.9 2399.5 2443.5 2480.4 2471.8 2472.2 2472.9 2478.2 2480.0 2392.8 2419.8 2443.9 2472.5 2475.4 2143.1 2396.1 2450.8 2454.2 2462.9 2416.5 2455.7 2458.2 2460.0 2466.2 8687.0 8739.9 8749.9 8769.1 8770.4 5341.8 5442.7 5453.5 5492.9 5498.0 3395.6 3453.9 3574.1 3584.6 3586.7 945.7 1047.1 1048.0 1068.1 1081.3 12045.9 12112.6 12120.0 12121.3 12144.0 4794.1 4840.8 4860.2 4914.1 4951.4 8561.8 10113.3 10194.5 10207.3 10208.6 5477.5 5480.6 5513.0 5518.8 5531.4 4327.7 4439.6 4794.7 4913.8 5016.0 3637.2 3882.3 3998.4 4071.5 4125.5 1349.3 1351.5 1355.9 1367.4 1367.5 794.3 915.1 920.9 934.6 940.3 5702.0 5725.9 5862.6 5867.3 5938.3 469.5 471.5 473.5 910.5 1020.6 3855.1 3894.7 3940.9 3972.0 3972.1 10363.9 10706.8 10716.2 10837.3 10843.2 10158.0 10633.1 10751.5 10841.7 10855.9 8176.9 8218.3 8338.4 8345.2 8429.8 5575.7 5649.6 5682.6 5792.4 5813.5 9436.6 9448.1 9526.4 9614.9 9638.2 9429.2 9763.2 9852.9 9877.8 9900.7 6279.6 6431.7 6890.1 7018.0 7147.8 8324.1 8351.8 8414.2 8423.9 8436.2 7333.4 7451.4 7455.1 7579.2 7608.3 7292.5 7320.6 7329.1 7376.1 7438.9 6947.5 6979.3 6982.8 7001.4 7023.8 4337.5 4808.7 4842.4 4851.9 5042.3 4966.1 4995.5 5004.9 5048.8 5235.4 09:42:26 INFO - PROCESS | 1952 | 09:42:26 INFO - PROCESS | 1952 | [#4] prototype.html Cycles:180 Average:5653.89 Median:6400.23 stddev:1820.54 (28.4%) stddev-sans-first:1781.07 09:42:26 INFO - PROCESS | 1952 | Values: 319.2 325.1 329.6 334.8 338.0 6391.3 6409.2 6452.9 6486.8 6495.8 5242.9 6350.7 6375.6 6380.0 6416.4 4590.7 4709.7 4717.3 4799.2 4847.2 4759.6 4805.8 4816.9 4827.6 4829.0 4150.0 4526.1 4732.2 4762.2 4775.2 4561.3 4683.9 4685.7 4688.3 4743.0 4009.0 4043.5 4102.2 4103.8 4134.1 6070.3 6101.5 6105.4 6124.7 6126.9 4965.9 5363.9 5567.2 5582.0 5624.5 6895.2 6904.8 6944.1 6945.8 6959.6 4329.7 4506.3 4536.8 4547.8 4644.4 7167.5 7201.2 7255.5 7259.0 7266.5 4527.8 4608.2 4609.8 4614.7 4626.6 4017.7 4655.0 4659.0 4662.6 4663.5 5522.8 5523.9 5531.9 5554.9 5556.3 5752.2 6615.4 6860.8 6901.6 6960.1 1392.3 1560.0 1594.5 1602.7 1618.3 1014.3 1018.2 1035.1 1040.8 1040.9 6318.2 6411.7 6431.7 6447.7 6469.9 7261.5 7283.6 7284.1 7290.5 7300.4 6550.9 6553.2 6570.8 6633.9 6706.6 3804.1 3868.2 3870.0 3872.4 3877.7 7245.2 7542.4 7575.5 7603.0 7617.8 7460.7 7467.7 7472.8 7473.8 7485.7 7520.4 7541.3 7555.9 7582.7 7628.3 6299.6 6461.4 7295.7 7301.7 7320.4 5725.3 6860.5 7136.6 7141.8 7198.6 7473.5 7524.3 7528.1 7530.7 7548.5 4612.1 4643.3 4645.6 4648.1 4651.3 5825.5 7260.8 7269.7 7274.5 7274.9 6641.3 6968.1 6985.6 7035.9 7055.9 6906.2 6912.3 6933.4 6942.0 6949.2 6812.7 6836.4 6852.5 6854.1 6907.0 7005.9 7014.1 7016.3 7021.2 7027.4 4925.6 6528.1 6553.2 6785.4 6807.1 09:42:26 INFO - PROCESS | 1952 | 09:42:26 INFO - PROCESS | 1952 | [#5] yui.html Cycles:180 Average:17881.79 Median:623.76 stddev:25025.80 (4012.1%) stddev-sans-first:25062.54 09:42:26 INFO - PROCESS | 1952 | Values: 646.8 686.4 693.3 697.7 728.6 14857.4 14901.0 15015.7 15025.9 15045.5 13701.2 13702.1 13721.5 13741.7 13766.9 705.5 725.4 725.5 868.6 902.3 896.1 897.7 898.1 898.7 910.3 955.5 955.7 957.8 958.2 959.2 746.4 942.1 948.3 952.5 969.7 701.9 701.9 702.3 709.0 716.4 14445.1 14510.9 14524.8 14535.2 14543.6 12424.4 12531.4 12695.0 12878.4 12879.4 9980.0 10041.6 10120.2 10231.8 10356.8 3533.7 3543.3 3554.5 3561.8 3591.5 74807.0 76164.2 76302.2 80221.6 80250.0 9208.5 9458.9 9500.0 9681.0 10207.7 20230.5 20464.6 20479.0 20508.8 20509.5 7222.2 7253.7 7313.6 7463.8 7495.1 4025.3 4087.8 4186.9 4194.2 4202.2 3570.3 3640.8 3662.3 3662.7 3664.1 540.2 608.7 612.4 634.8 637.6 512.3 579.0 588.8 606.3 638.0 8130.0 8153.5 8230.9 8334.5 8340.1 544.3 577.3 585.2 611.1 612.8 3717.9 3847.1 3911.2 3967.5 4087.1 88803.0 89740.0 89845.0 91187.5 91617.0 75979.0 76098.0 76411.6 76844.0 78443.6 66172.0 66534.0 67581.7 67926.7 70255.6 8265.3 8331.0 8417.5 8422.3 8559.2 44547.6 45731.0 47401.0 48636.5 49073.5 65628.7 65860.7 68864.9 69687.0 69869.7 10605.7 10799.7 10804.7 10834.5 10980.3 11348.8 11564.9 11700.9 11704.9 12112.4 9412.7 10084.8 10106.2 10331.8 11081.9 10758.4 10803.7 11475.7 11550.0 11595.8 10885.2 11000.2 11280.8 11333.8 11391.5 9432.4 9504.6 9561.4 9660.4 9929.9 9484.6 9619.4 9701.7 9786.9 9790.2 09:42:26 INFO - PROCESS | 1952 | -------- Summary: end -------- 09:42:26 INFO - PROCESS | 1952 | 09:42:27 INFO - TEST-INFO | 1952: exit 0 09:42:27 INFO - TEST-OK | dromaeo_css | took 1115520ms 09:42:27 INFO - TEST-START | kraken 09:42:27 INFO - operating with platform_type : mac_ 09:42:27 INFO - Initialising browser for kraken test... 09:42:27 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmpz1Er0L/profile http://localhost:49401/getInfo.html) 09:42:29 INFO - PROCESS | 1955 | __metrics Screen width/height:1600/1200 09:42:29 INFO - PROCESS | 1955 | colorDepth:24 09:42:29 INFO - PROCESS | 1955 | Browser inner width/height: 1024/689 09:42:29 INFO - PROCESS | 1955 | __metrics 09:42:29 INFO - PROCESS | 1955 | 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" 09:42:29 ERROR - PROCESS | 1955 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:42:30 INFO - TEST-INFO | 1955: exit 0 09:42:30 INFO - Browser initialized. 09:42:30 INFO - Running cycle 1/1 for kraken test... 09:42:30 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmpz1Er0L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:42:42 INFO - PROCESS | 1957 | RSS: Main: 211308544 09:42:42 INFO - PROCESS | 1957 | 09:42:44 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49401/tests/kraken/driver.html?testName=audio-beat-detection) 09:42:45 INFO - PROCESS | 1957 | RSS: Main: 272052224 09:42:45 INFO - PROCESS | 1957 | 09:42:48 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49401/tests/kraken/driver.html?testName=audio-dft) 09:42:49 INFO - PROCESS | 1957 | RSS: Main: 285945856 09:42:49 INFO - PROCESS | 1957 | 09:42:52 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49401/tests/kraken/driver.html?testName=audio-fft) 09:42:53 INFO - PROCESS | 1957 | RSS: Main: 375009280 09:42:53 INFO - PROCESS | 1957 | 09:42:55 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49401/tests/kraken/driver.html?testName=audio-oscillator) 09:42:55 INFO - PROCESS | 1957 | RSS: Main: 280010752 09:42:55 INFO - PROCESS | 1957 | 09:42:57 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49401/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:42:58 INFO - PROCESS | 1957 | RSS: Main: 280399872 09:42:58 INFO - PROCESS | 1957 | 09:43:01 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49401/tests/kraken/driver.html?testName=imaging-darkroom) 09:43:01 INFO - PROCESS | 1957 | RSS: Main: 318590976 09:43:01 INFO - PROCESS | 1957 | 09:43:04 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49401/tests/kraken/driver.html?testName=imaging-desaturate) 09:43:05 INFO - PROCESS | 1957 | RSS: Main: 319303680 09:43:05 INFO - PROCESS | 1957 | 09:43:08 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49401/tests/kraken/driver.html?testName=json-parse-financial) 09:43:08 INFO - PROCESS | 1957 | RSS: Main: 318349312 09:43:08 INFO - PROCESS | 1957 | 09:43:10 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49401/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:43:10 INFO - PROCESS | 1957 | RSS: Main: 309604352 09:43:10 INFO - PROCESS | 1957 | 09:43:12 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49401/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:43:13 INFO - PROCESS | 1957 | RSS: Main: 316252160 09:43:13 INFO - PROCESS | 1957 | 09:43:15 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49401/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:43:16 INFO - PROCESS | 1957 | RSS: Main: 319557632 09:43:16 INFO - PROCESS | 1957 | 09:43:19 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49401/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:43:19 INFO - PROCESS | 1957 | RSS: Main: 324419584 09:43:19 INFO - PROCESS | 1957 | 09:43:22 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49401/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:43:23 INFO - PROCESS | 1957 | RSS: Main: 322465792 09:43:23 INFO - PROCESS | 1957 | 09:43:24 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49401/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49401/tests/kraken/driver.html?testName=ai-astar) 09:43:25 INFO - PROCESS | 1957 | RSS: Main: 387411968 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | __start_tp_report 09:43:25 INFO - PROCESS | 1957 | _x_x_mozilla_page_load 09:43:25 INFO - PROCESS | 1957 | _x_x_mozilla_page_load_details 09:43:25 INFO - PROCESS | 1957 | |i|pagename|runs| 09:43:25 INFO - PROCESS | 1957 | |0;ai-astar;115;114;119;113;113;113;115;114;115;134 09:43:25 INFO - PROCESS | 1957 | |1;audio-beat-detection;154;122;143;163;143;169;165;117;170;122 09:43:25 INFO - PROCESS | 1957 | |2;audio-dft;192;185;186;184;186;185;183;185;185;185 09:43:25 INFO - PROCESS | 1957 | |3;audio-fft;69;80;75;152;56;55;57;63;142;75 09:43:25 INFO - PROCESS | 1957 | |4;audio-oscillator;92;90;136;76;79;92;124;79;77;122 09:43:25 INFO - PROCESS | 1957 | |5;imaging-gaussian-blur;90;87;89;86;89;86;91;86;89;86 09:43:25 INFO - PROCESS | 1957 | |6;imaging-darkroom;95;95;95;117;146;116;96;95;95;94 09:43:25 INFO - PROCESS | 1957 | |7;imaging-desaturate;89;85;81;86;84;86;86;85;87;85 09:43:25 INFO - PROCESS | 1957 | |8;json-parse-financial;59;63;65;64;63;66;64;85;58;58 09:43:25 INFO - PROCESS | 1957 | |9;json-stringify-tinderbox;87;90;101;108;76;70;68;68;69;68 09:43:25 INFO - PROCESS | 1957 | |10;stanford-crypto-aes;81;81;83;81;81;80;82;81;81;85 09:43:25 INFO - PROCESS | 1957 | |11;stanford-crypto-ccm;130;129;130;129;126;130;127;129;155;144 09:43:25 INFO - PROCESS | 1957 | |12;stanford-crypto-pbkdf2;166;167;166;167;166;165;161;165;162;166 09:43:25 INFO - PROCESS | 1957 | |13;stanford-crypto-sha256-iterative;67;67;67;67;64;64;63;67;66;68 09:43:25 INFO - PROCESS | 1957 | __end_tp_report 09:43:25 INFO - PROCESS | 1957 | __start_cc_report 09:43:25 INFO - PROCESS | 1957 | _x_x_mozilla_cycle_collect,2082 09:43:25 INFO - PROCESS | 1957 | __end_cc_report 09:43:25 INFO - PROCESS | 1957 | __startTimestamp1464626605136__endTimestamp 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | ------- Summary: start ------- 09:43:25 INFO - PROCESS | 1957 | Number of tests: 14 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#0] ai-astar Cycles:10 Average:116.50 Median:115.00 stddev:6.40 (5.6%) stddev-sans-first:6.76 09:43:25 INFO - PROCESS | 1957 | Values: 115.0 114.0 119.0 113.0 113.0 113.0 115.0 114.0 115.0 134.0 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#1] audio-beat-detection Cycles:10 Average:146.80 Median:158.50 stddev:20.61 (13.0%) stddev-sans-first:21.70 09:43:25 INFO - PROCESS | 1957 | Values: 154.0 122.0 143.0 163.0 143.0 169.0 165.0 117.0 170.0 122.0 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#2] audio-dft Cycles:10 Average:185.60 Median:185.00 stddev:2.41 (1.3%) stddev-sans-first:0.93 09:43:25 INFO - PROCESS | 1957 | Values: 192.0 185.0 186.0 184.0 186.0 185.0 183.0 185.0 185.0 185.0 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#3] audio-fft Cycles:10 Average:82.40 Median:66.00 stddev:35.21 (53.4%) stddev-sans-first:37.02 09:43:25 INFO - PROCESS | 1957 | Values: 69.0 80.0 75.0 152.0 56.0 55.0 57.0 63.0 142.0 75.0 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:79.00 stddev:22.27 (28.2%) stddev-sans-first:23.55 09:43:25 INFO - PROCESS | 1957 | Values: 92.0 90.0 136.0 76.0 79.0 92.0 124.0 79.0 77.0 122.0 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:1.91 (2.1%) stddev-sans-first:1.87 09:43:25 INFO - PROCESS | 1957 | Values: 90.0 87.0 89.0 86.0 89.0 86.0 91.0 86.0 89.0 86.0 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#6] imaging-darkroom Cycles:10 Average:104.40 Median:95.00 stddev:17.14 (18.0%) stddev-sans-first:17.84 09:43:25 INFO - PROCESS | 1957 | Values: 95.0 95.0 95.0 117.0 146.0 116.0 96.0 95.0 95.0 94.0 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:2.07 (2.4%) stddev-sans-first:1.73 09:43:25 INFO - PROCESS | 1957 | Values: 89.0 85.0 81.0 86.0 84.0 86.0 86.0 85.0 87.0 85.0 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:64.00 stddev:7.76 (12.1%) stddev-sans-first:7.98 09:43:25 INFO - PROCESS | 1957 | Values: 59.0 63.0 65.0 64.0 63.0 66.0 64.0 85.0 58.0 58.0 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#9] json-stringify-tinderbox Cycles:10 Average:80.50 Median:69.50 stddev:15.06 (21.7%) stddev-sans-first:15.79 09:43:25 INFO - PROCESS | 1957 | Values: 87.0 90.0 101.0 108.0 76.0 70.0 68.0 68.0 69.0 68.0 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:81.00 stddev:1.43 (1.8%) stddev-sans-first:1.50 09:43:25 INFO - PROCESS | 1957 | Values: 81.0 81.0 83.0 81.0 81.0 80.0 82.0 81.0 81.0 85.0 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#11] stanford-crypto-ccm Cycles:10 Average:132.90 Median:130.00 stddev:9.22 (7.1%) stddev-sans-first:9.72 09:43:25 INFO - PROCESS | 1957 | Values: 130.0 129.0 130.0 129.0 126.0 130.0 127.0 129.0 155.0 144.0 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.10 Median:166.00 stddev:2.02 (1.2%) stddev-sans-first:2.12 09:43:25 INFO - PROCESS | 1957 | Values: 166.0 167.0 166.0 167.0 166.0 165.0 161.0 165.0 162.0 166.0 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - PROCESS | 1957 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:67.00 stddev:1.70 (2.5%) stddev-sans-first:1.76 09:43:25 INFO - PROCESS | 1957 | Values: 67.0 67.0 67.0 67.0 64.0 64.0 63.0 67.0 66.0 68.0 09:43:25 INFO - PROCESS | 1957 | -------- Summary: end -------- 09:43:25 INFO - PROCESS | 1957 | 09:43:25 INFO - TEST-INFO | 1957: exit 0 09:43:25 INFO - TEST-OK | kraken | took 58538ms 09:43:25 INFO - SUITE-END | took 1174s 09:43:26 INFO - Completed test suite (00:19:34) 09:43:26 INFO - javascript benchmark 09:43:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3309.056918778148, 3385.390629686656, 3389.525466434698, 3397.4903764435317, 3438.8796392126283, 5590.888182236358, 5594.328680558338, 5596.944030559698, 5605.934872374251, 5625.353084395296, 5603.691796951166, 5631.8592035199035, 5633.0987042073275, 5651.180578816069, 5651.886962260753, 5008.749562521877, 5050.974745126287, 5137.998090372379, 5186.118359878829, 5212.895742085175, 5129.7948082076855, 5149.150390185615, 5150.04724126036, 5151.81968631098, 5153.613478989072, 5074.0866644004245, 5104.259882317062, 5111.284420181178, 5122.410922743877, 5130.230465430178, 5149.45930677281, 5181.06740786662, 5191.636585439021, 5203.427622961509, 5236.7905283788605, 4998.850034498971, 5045.445001049881, 5099.949000509984, 5104.336436263262, 5114.693118412907, 5331.253624492574, 5399.568034557226, 5439.864003399907, 5441.455854414527, 5453.7818487260465, 6059.606125601822, 6073.271207455134, 6074.848128796772, 6081.11823785553, 6090.086487026982, 6004.669743164124, 6127.87744245117, 6158.058494088364, 6185.536084793614, 6194.566380353377, 5601.411851755548, 5612.326520817446, 5620.269364982567, 5667.858303542362, 5668.178114173422, 6242.750289988349, 6248.0, 6252.687365631791, 6261.311255761862, 6274.247090349188, 5022.196448568285, 5912.142739302863, 6057.151998720183, 6059.818205453844, 6062.151298818169, 6032.638041717511, 6113.537117903944, 6199.287081985539, 6220.25356957159, 6225.252969643671, 6003.729832157476, 6050.6369617823075, 6085.786997455157, 6085.96538588441, 6120.265568131853, 5920.822375328661, 6112.174856394418, 6114.327423983358, 6125.877482450326, 6131.019036954155, 4776.617870570346, 4786.8085276589245, 4787.880302992453, 4795.424549054136, 4801.567858892647, 4325.2214601371425, 4340.218760623118, 4355.564443555682, 4375.409319741858, 4393.75834329115, 5424.864378390572, 5429.239906413105, 5431.456854314537, 5436.021516127135, 5444.50999410055, 6070.514358851281, 6086.878262434727, 6106.551676171891, 6111.052786818051, 6162.952298109332, 5503.669779813144, 5510.586705997066, 5511.3937466878615, 5526.861828454321, 5531.668099914018, 5280.683159010472, 5290.1800220965815, 5298.470152984671, 5305.230741542455, 5307.2569840222395, 6146.647737497743, 6162.568620196544, 6222.408871157233, 6248.625082494974, 6288.308286088618, 6182.227221597301, 6210.378962103753, 6212.968935155476, 6223.097650840693, 6238.376162383725, 6210.223722034746, 6210.813675589739, 6230.345813689679, 6240.0, 6240.095186198067, 5965.403459654, 5978.730957106854, 6015.278166619859, 6090.69546522681, 6112.755489780359, 5939.524838012862, 6039.456448919618, 6051.273847138372, 6071.058985857199, 6111.755529778759, 5865.5600829937075, 5872.031114865999, 5874.089516125007, 5889.970550147222, 5894.70526473666, 4939.53074457926, 5835.270591176103, 5945.197398351167, 5956.910646340221, 6017.699115044318, 5729.369769325288, 5754.4533269339345, 5757.712114394348, 5764.884702305846, 5782.335031471266, 4845.515448455127, 5821.359650438533, 5842.824715258549, 5865.472107510344, 5925.496332811759, 5824.417558244142, 5840.591158618856, 5844.94155058444, 5863.501602363847, 5886.440788125293], "value": 5568.480319423675, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.5635525270642, 510.95145961133693, 589.9203607512984, 595.4908553187028, 616.23586752427, 27087.32281692956, 31626.841865790666, 36596.817015914916, 37492.62507374925, 37552.248955020885, 40551.0, 40767.0, 40974.0, 40983.38524922128, 41071.79464102683, 9381.859272110907, 9596.328257021976, 9603.087706667855, 9605.90394096057, 9622.75943101421, 8498.575071246443, 8624.396292259544, 8625.309975202, 8649.65401383944, 11460.71348216293, 9781.755456113582, 11394.601188958388, 11463.713407164803, 11471.885281147206, 11494.19540632152, 11350.886491135065, 11447.885521144848, 11451.713707157305, 11453.484593193243, 11454.770904581861, 8476.449030812977, 9735.90264097357, 11344.319340839493, 11568.652940411801, 11613.477393517313, 37317.0, 37679.81160094209, 37812.62187378146, 37817.43273850894, 37839.810800945816, 3195.137312925497, 3205.246767009751, 3478.9478157827652, 3529.1353618363664, 3830.942535861964, 27365.45269094627, 27402.862985684944, 27463.313417164532, 27500.17499475019, 28618.570721439195, 2703.256604433777, 2740.4500600844544, 2756.297144228229, 2779.749822515963, 2818.055951256332, 447630.0, 449689.0, 449788.0, 449877.0, 450692.0, 60795.69602151961, 61269.0, 61397.07904381438, 61502.38497615056, 61587.692061539405, 52838.0, 62734.68632656807, 62780.0, 62832.05751913725, 62875.0, 16984.66030679404, 17413.042282674574, 17434.738478922827, 17447.65104697899, 17453.12734363277, 13382.866171338163, 13511.797323040162, 13609.659758506117, 13610.795838062437, 13630.591082267549, 14322.427102915975, 14555.92722036383, 15995.520134395987, 16136.435224767078, 16291.348346066025, 6727.9571666391785, 7070.575765454089, 7140.571565706075, 7233.204347521769, 7258.564486130849, 879.1120967822545, 919.4896832258161, 993.2500961773959, 999.9250056245811, 1008.9658100446953, 27157.86421067882, 28006.0, 28257.858710706314, 28276.0, 28564.428711425655, 970.5875003123645, 1096.71485413793, 1134.750354921916, 1137.2039572299423, 1143.1255089856268, 13975.720485590231, 14088.71822563543, 14098.718025639635, 14417.78373324401, 14486.565403037926, 590055.0497247486, 596851.0, 598190.0, 598854.0114598885, 599809.0, 58323.4167658318, 59947.40052599506, 60035.399646003854, 60094.798104037676, 60321.0, 1332.6068809701162, 1373.3476598615578, 1488.7245859516024, 1504.127605988524, 1506.0963421946963, 27577.03480378177, 27688.584671229546, 28261.858690706416, 28460.573091403232, 28612.856935715186, 46708.299375510076, 48329.03341933212, 48426.51573484361, 48515.0, 48534.271985919535, 419444.0, 430509.0, 430555.0, 430772.0, 431164.0, 32158.19604509906, 32326.737128400786, 32851.34297314088, 33127.0, 33329.00012999614, 35849.0, 43030.35454468248, 43965.0, 44698.0, 45505.54494455013, 34457.0, 34633.826830865684, 34700.47949280813, 34927.9521614352, 35280.94157175289, 34128.48807267943, 34259.486107707904, 34631.480527792606, 34772.304553909285, 35005.82497087498, 34560.82719586486, 34704.479432809036, 34792.47811282782, 35270.64729352674, 35892.0, 31656.626954763527, 31855.522167167048, 32096.839515803207, 32123.518147227343, 32246.19384515312, 23943.401414964766, 24033.639495407235, 24502.14242501575, 24570.14004509905, 24868.005279788606], "value": 19395.730918739748, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.6483678413546, 401.39389521822056, 402.64567180880823, 403.62261285697895, 414.98547550835724, 13508.72982540351, 15400.614984625361, 19889.3038743644, 20598.48503787404, 20607.690884636722, 13137.540186093489, 16358.0, 19630.705539416886, 20124.899375503137, 20388.184472621113, 5364.141737322029, 5765.827025189251, 5791.884162316751, 5796.0, 5802.158686990405, 3317.286783341581, 5693.715314234292, 5802.158686990405, 5802.332731735841, 5802.825915222529, 6162.291336496315, 6425.7108430120525, 6432.77485288015, 6438.195225596801, 6438.5171112166545, 6266.373362663743, 6373.394527519901, 6384.48924086072, 6399.0, 6406.423421892029, 5383.057964856134, 5497.587680923907, 5538.307711536058, 5542.806001789958, 5544.473275038865, 23992.0, 24296.271111866674, 25255.49489010228, 25576.0, 25626.974921003137, 2188.3434969509085, 2308.7229532456213, 2338.4504641409253, 2385.7375688674233, 2615.4246065865477, 11308.773824523463, 14379.065360751518, 14592.197429141394, 14686.192259425727, 14951.028183168059, 1631.7715519827234, 1692.3822804676327, 1720.4236580745464, 1721.6212433264661, 1778.662054209696, 83946.58026709888, 113024.0, 113607.0, 114193.0, 115080.27379589312, 9097.590608422504, 10647.574097036188, 10832.187585931088, 11094.724106727668, 11258.38078905668, 14077.0, 14086.577402677936, 14101.083429577046, 14111.788323174955, 14118.576442706735, 6732.427743641747, 7674.270944260286, 8011.479253848481, 8015.799605009922, 8586.527740974181, 7831.490953088031, 8005.039395272604, 8008.839823203503, 8559.700410485602, 8654.567271636393, 6422.6467544285515, 6554.754596626721, 6564.6389448580785, 6581.835454113685, 6587.110740050128, 1681.865450763936, 1823.042902476184, 1884.7549818523778, 1889.2983034260865, 1894.6116046210493, 749.1609397474754, 848.6393282854762, 897.9012308646044, 1000.5247507433911, 1010.9191264698807, 17209.7418538722, 19007.80992190088, 20592.073356698984, 20671.58656826855, 20854.478638033866, 1027.2295778166376, 1041.9479026048666, 1045.3727763341933, 1058.9886658241362, 1069.534752382716, 10840.241183117263, 11200.607978720705, 11250.718732031764, 11260.0, 11331.773364532663, 503754.0, 581676.0, 596473.0, 602018.0, 609487.7628059245, 128804.75943715921, 132147.67852321354, 133731.0, 137019.94470082957, 138250.0, 961.3510880155867, 990.5889056041806, 1005.2109094361037, 1044.6604853422668, 1047.8009178256107, 17781.288748450435, 17846.91076544661, 18110.63778724392, 18379.17293721759, 18473.44579662612, 51806.48193518016, 52763.0, 53179.734101329246, 53292.73353633207, 53348.46651533435, 72279.0, 73791.0, 75864.0, 78414.0, 78842.6057869707, 7788.766337009899, 8213.425060245725, 8274.83450330978, 8306.792330191794, 8599.95700021496, 11674.70813229642, 12482.812757808822, 12644.431000605178, 12787.488500459878, 12905.870941290466, 10905.672829815117, 10965.013148816644, 11131.998120169224, 11319.981201691558, 11390.25963312382, 10577.841332379867, 10624.256302058782, 10673.039426451656, 10890.891091089305, 11025.17311201647, 11022.559097636326, 11265.155113366367, 11286.717832054264, 11442.885571144181, 11467.311961282016, 8165.550894700611, 8207.835843283221, 8292.958535207526, 8372.497650141011, 8636.093210213088, 10573.154147668321, 11024.779504409706, 11310.0, 11339.09287257034, 11349.262297950607], "value": 9601.983257299822, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1460.9196494192815, 1592.9681406371872, 1631.4289998500522, 1654.9255283512248, 1971.1425529894498, 7947.920520794791, 8554.016288126868, 8728.781780455492, 8736.51949142797, 8747.081556436578, 8479.491230526173, 8734.170253825892, 8758.211760941515, 8760.561971901396, 8772.0, 2179.2699445685716, 2225.9332220033425, 2399.4841109161525, 2443.535728211643, 2480.367506285904, 2471.752824717532, 2472.221250306157, 2472.8763561821925, 2478.182199874046, 2480.0203919451897, 2392.7807979590075, 2419.8185136114776, 2443.9389015274674, 2472.4560596668703, 2475.418276704982, 2143.0998980428258, 2396.0894859953282, 2450.816188785839, 2454.2391858523915, 2462.8768561571937, 2416.50461655362, 2455.693038370204, 2458.188797696756, 2459.9508009839883, 2466.1738317663426, 8686.956565217197, 8739.868901966476, 8749.912500874974, 8769.070804423016, 8770.386072974894, 5341.759620817073, 5442.727863606804, 5453.454654534515, 5492.862678433071, 5498.0, 3395.6043956043954, 3453.879114231015, 3574.1422058705984, 3584.623614520464, 3586.659267369596, 945.664289177346, 1047.0890325416935, 1047.9895201047968, 1068.0974576482859, 1081.2647399768155, 12045.879541204564, 12112.6366209013, 12120.0, 12121.27272363661, 12144.02847772185, 4794.113089078532, 4840.75796210188, 4860.1737704590305, 4914.115459217375, 4951.430585482643, 8561.78595535104, 10113.29206955506, 10194.490275486196, 10207.285490015627, 10208.591656333663, 5477.534409575231, 5480.588955828329, 5513.0, 5518.779248830002, 5531.3915469298345, 4327.675424343187, 4439.64482841372, 4794.664373493892, 4913.803447862117, 5015.971725796203, 3637.217998130415, 3882.3400021995762, 3998.440608162819, 4071.511418629724, 4125.546189919107, 1349.3320806200993, 1351.4729255590328, 1355.9389827457787, 1367.3573420492353, 1367.4940272099386, 794.2732399854184, 915.0894859614593, 920.8987011428737, 934.5654270764128, 940.2618944128826, 5701.971490142606, 5725.856853578611, 5862.560307976919, 5867.2959244890035, 5938.257717785277, 469.51405295518975, 471.5002097776368, 473.486267399875, 910.5476764560516, 1020.5662593397776, 3855.132595166121, 3894.7078969077434, 3940.9014774630864, 3971.9801400993388, 3972.145988612462, 10363.896361036444, 10706.78586428267, 10716.249862509705, 10837.295575787548, 10843.1867609928, 10158.0, 10633.149348052291, 10751.462426878625, 10841.67474975752, 10855.891441085647, 8176.877346839802, 8218.342532597497, 8338.415701016787, 8345.165483451729, 8429.789255268668, 5575.693336866511, 5649.6327738696855, 5682.602217844877, 5792.391798861059, 5813.476787089182, 9436.575354109218, 9448.149666530046, 9526.428414295164, 9614.855777163208, 9638.180754635934, 9429.15137637616, 9763.218942484726, 9852.901470985198, 9877.802443951225, 9900.653477128553, 6279.560430769985, 6431.678416079271, 6890.138732658418, 7018.0, 7147.785566433015, 8324.084350721294, 8351.832963340821, 8414.158584141536, 8423.8736418955, 8436.198561136682, 7333.4499912507845, 7451.441141914486, 7455.0681164854395, 7579.166291708018, 7608.31525162738, 7292.525985810906, 7320.597367144858, 7329.083864516935, 7376.077990251219, 7438.925610743823, 6947.478939079489, 6979.30206979298, 6982.825429364307, 7001.36987671112, 7023.754168604074, 4337.481881341505, 4808.687435316693, 4842.4189097308545, 4851.878703032452, 5042.344495215599, 4966.106100901872, 4995.525425084611, 5004.87487812793, 5048.772805223847, 5235.424103348552], "value": 4558.48207183436, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.23065412356226, 325.07840272826536, 329.615997363072, 334.84429740170816, 337.95944486661574, 6391.265004524475, 6409.198850143732, 6452.870942581146, 6486.837829054264, 6495.8376040599005, 5242.947570524303, 6350.650714210728, 6375.553711240215, 6379.968100159493, 6416.358364163593, 4590.701604395712, 4709.717416954976, 4717.339572459859, 4799.208130658437, 4847.1517484440255, 4759.643026772988, 4805.759712014403, 4816.903661926759, 4827.613790896738, 4828.951710482886, 4149.958500414988, 4526.094781043788, 4732.2428411454175, 4762.238041913294, 4775.212090005145, 4561.270196768501, 4683.859484215479, 4685.672002959795, 4688.320193571913, 4742.97628511857, 4008.979955100235, 4043.454133691973, 4102.220578090153, 4103.774292413916, 4134.131832315217, 6070.301915279755, 6101.542384321194, 6105.419985101408, 6124.693765311761, 6126.9387306127255, 4965.900681986376, 5363.9463605363835, 5567.248421463091, 5582.0, 5624.493795558378, 6895.241523432418, 6904.7928562142715, 6944.097267355262, 6945.791626251221, 6959.582425054513, 4329.718568293082, 4506.301523263899, 4536.773161341953, 4547.795349209294, 4644.442666879962, 7167.462440316997, 7201.207867134611, 7255.45584081196, 7259.0, 7266.4550158737775, 4527.754867411422, 4608.239640459354, 4609.838655647036, 4614.74618895964, 4626.629869610423, 4017.6986725995666, 4654.953450465452, 4658.953410465852, 4662.603678687349, 4663.510331415153, 5522.779088836482, 5523.8895222096135, 5531.917021244684, 5554.944450555443, 5556.333240011225, 5752.223449834219, 6615.437687796495, 6860.794176174722, 6901.551399159039, 6960.060391847137, 1392.324722509582, 1559.970419523132, 1594.4817934171208, 1602.7115119278408, 1618.34457044897, 1014.3457469932, 1018.1701912940929, 1035.1046344911597, 1040.77623310987, 1040.8907064758243, 6318.241810982711, 6411.67941602918, 6431.74273029083, 6447.677616119176, 6469.902951455732, 7261.491695581259, 7283.562986220843, 7284.089488813898, 7290.453216008715, 7300.4159667226495, 6550.868982620416, 6553.2136143663065, 6570.77002304917, 6633.867322653617, 6706.564073335315, 3804.087019115392, 3868.207017561393, 3870.013146647643, 3872.361060425055, 3877.728559000899, 7245.166805817293, 7542.396608271435, 7575.507592006502, 7602.961985190149, 7617.809554761176, 7460.738874139379, 7467.738629147953, 7472.813179670552, 7473.775786726407, 7485.663145158481, 7520.360769334558, 7541.321281084729, 7555.9244407555225, 7582.696692132253, 7628.275313845176, 6299.590526615939, 6461.386168314003, 7295.708171673074, 7301.707931682673, 7320.377767889789, 5725.2843394575675, 6860.451163906975, 7136.607486588287, 7141.785746427616, 7198.640067996684, 7473.476856619985, 7524.2851929066655, 7528.096628404626, 7530.698772049276, 7548.4716069874585, 4612.146752850734, 4643.2802915548145, 4645.6051235644, 4648.093621743727, 4651.2557990721325, 5825.475707186373, 7260.782176534713, 7269.7455589054125, 7274.527155734861, 7274.8908766369605, 6641.302663220292, 6968.128983877015, 6985.615791131537, 7035.894461582978, 7055.858882822417, 6906.205786334435, 6912.274211207952, 6933.445324374139, 6941.965290173517, 6949.2008419030435, 6812.65936703153, 6836.350546698056, 6852.533088088185, 6854.143232095988, 6906.965465172642, 7005.859882802213, 7014.088168538057, 7016.26329235423, 7021.227664956953, 7027.402670773042, 4925.556699896883, 6528.053432252202, 6553.246376666744, 6785.355391237825, 6807.115075040213], "value": 5046.352913394102, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.8285904235379, 686.3513979289571, 693.2734672079672, 697.6825544377309, 728.5810658871151, 14857.40570377185, 14901.0, 15015.699686006274, 15025.92487037566, 15045.548633540997, 13701.246431446267, 13702.109362891406, 13721.451141954334, 13741.65645858854, 13766.862331376684, 705.4708968273794, 725.4196642685856, 725.4522835259372, 868.6221493650282, 902.3367824648877, 896.0680891872445, 897.7486303834937, 898.1063841477738, 898.6630013744846, 910.3218102513637, 955.5174636808403, 955.6798472511682, 957.8371676814958, 958.2046901072093, 959.1942768074816, 746.4401698725956, 942.0579420579421, 948.2982592881287, 952.4808979106404, 969.6654654144309, 701.8666453373843, 701.9368256856908, 702.3152426384286, 708.9751858684921, 716.4340171264695, 14445.06107103035, 14510.927445362811, 14524.782128268085, 14535.200563968978, 14543.636409089751, 12424.440900159514, 12531.373431328397, 12694.98440124797, 12878.356082195947, 12879.356032198353, 9979.950100249525, 10041.598336066549, 10120.190384769201, 10231.795364092752, 10356.844647330296, 3533.699635530983, 3543.255916257589, 3554.5023696682483, 3561.7684850484634, 3591.5331006969186, 74807.0, 76164.23835761682, 76302.23697763006, 80221.59889200516, 80250.0, 9208.52663573825, 9458.90541094594, 9500.0, 9681.0, 10207.7448063799, 20230.49423764388, 20464.59070818575, 20479.0, 20508.794912050987, 20509.487262818548, 7222.169450513154, 7253.746118885919, 7313.634318284171, 7463.776086717407, 7495.1005879294835, 4025.3156963316305, 4087.816048277835, 4186.874393768192, 4194.161167766459, 4202.222588821078, 3570.303790760777, 3640.781553106769, 3662.285854258394, 3662.6520480554304, 3664.1022949377575, 540.2274747111668, 608.6621924831672, 612.3937302070917, 634.7651368993502, 637.5696404926749, 512.26745753572, 579.0445764488543, 588.7703795519751, 606.3390903914755, 637.9696789684626, 8129.959350203329, 8153.510789352657, 8230.876536851953, 8334.458260213067, 8340.124286949904, 544.3386285662939, 577.3014652270715, 585.1719816459662, 611.1047315682547, 612.7579606055583, 3717.8698745543775, 3847.0565323559094, 3911.2177564487215, 3967.5238971323624, 4087.1008378156757, 88803.0, 89740.0, 89845.0, 91187.54406227927, 91617.0, 75979.0, 76098.0, 76411.61794191104, 76844.0, 78443.60778196073, 66172.0, 66534.00198997019, 67581.66209168923, 67926.66036669884, 70255.64872175606, 8265.256126948603, 8330.958345208235, 8417.452865563719, 8422.284105851071, 8559.22966932979, 44547.554524455634, 45731.0, 47401.0, 48636.5136348632, 49073.5092649069, 65628.67185664042, 65860.67069664813, 68864.93405197852, 69687.0, 69869.65065174641, 10605.734856628646, 10799.67600971972, 10804.67585972422, 10834.512446939749, 10980.286281391684, 11348.829767553329, 11564.884351156381, 11700.94691477771, 11704.94147529291, 12112.394380281126, 9412.67055653049, 10084.84872726895, 10106.19150467946, 10331.793364132825, 11081.944590277319, 10758.354498730101, 10803.72990675208, 11475.655730328104, 11550.01824844864, 11595.768084638428, 10885.23803333759, 11000.22998390105, 11280.774384512428, 11333.829992550283, 11391.487383067617, 9432.386894851812, 9504.619815207314, 9561.426314421156, 9660.420374777535, 9929.851052234368, 9484.573194206416, 9619.422834629944, 9701.6604418845, 9786.902130978599, 9790.216782657513], "value": 6790.718701078701, "unit": "score"}], "name": "dromaeo_css", "value": 7383.448116218656, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 119.0, 113.0, 113.0, 113.0, 115.0, 114.0, 115.0, 134.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 122.0, 143.0, 163.0, 143.0, 169.0, 165.0, 117.0, 170.0, 122.0], "value": 146.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 185.0, 186.0, 184.0, 186.0, 185.0, 183.0, 185.0, 185.0, 185.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 80.0, 75.0, 152.0, 56.0, 55.0, 57.0, 63.0, 142.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 136.0, 76.0, 79.0, 92.0, 124.0, 79.0, 77.0, 122.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 86.0, 89.0, 86.0, 91.0, 86.0, 89.0, 86.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 117.0, 146.0, 116.0, 96.0, 95.0, 95.0, 94.0], "value": 104.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 81.0, 86.0, 84.0, 86.0, 86.0, 85.0, 87.0, 85.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 65.0, 64.0, 63.0, 66.0, 64.0, 85.0, 58.0, 58.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 101.0, 108.0, 76.0, 70.0, 68.0, 68.0, 69.0, 68.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 83.0, 81.0, 81.0, 80.0, 82.0, 81.0, 81.0, 85.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 129.0, 130.0, 129.0, 126.0, 130.0, 127.0, 129.0, 155.0, 144.0], "value": 132.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 166.0, 167.0, 166.0, 165.0, 161.0, 165.0, 162.0, 166.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 64.0, 64.0, 63.0, 67.0, 66.0, 68.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1496.2999999999997, "alertThreshold": 2.0}]} 09:43:26 INFO - Return code: 0 09:43:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:43:26 ERROR - # TBPL SUCCESS # 09:43:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:43:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:43:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:43:26 INFO - 2016-05-30 09:43:26.448 screenresolution[1961:14162] starting screenresolution argv=screenresolution get 09:43:26 INFO - 2016-05-30 09:43:26.451 screenresolution[1961:14162] Display 0: 1600x1200x32@60 09:43:26 INFO - 2016-05-30 09:43:26.457 screenresolution[1962:14165] starting screenresolution argv=screenresolution list 09:43:26 INFO - Available Modes on Display 0 09:43:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:43:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:43:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:43:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:43:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:43:26 INFO - Intel Iris: 09:43:26 INFO - Chipset Model: Intel Iris 09:43:26 INFO - Type: GPU 09:43:26 INFO - Bus: Built-In 09:43:26 INFO - VRAM (Dynamic, Max): 1536 MB 09:43:26 INFO - Vendor: Intel (0x8086) 09:43:26 INFO - Device ID: 0x0a2e 09:43:26 INFO - Revision ID: 0x0009 09:43:26 INFO - Displays: 09:43:26 INFO - Display: 09:43:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:43:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:43:26 INFO - Display Serial Number: 1600x1200 60 09:43:26 INFO - Main Display: Yes 09:43:26 INFO - Mirror: Off 09:43:26 INFO - Online: Yes 09:43:26 INFO - Rotation: Supported 09:43:26 INFO - Return code: 0 09:43:26 INFO - Running post-action listener: _resource_record_post_action 09:43:26 INFO - Running post-run listener: _resource_record_post_run 09:43:27 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 716800; Write bytes: 651314688; Read time: 6; Write time: 1775 09:43:27 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 348881408; Write bytes: 345732608; Read time: 27337; Write time: 685 09:43:27 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 716800; Write bytes: 293337088; Read time: 6; Write time: 1047 09:43:27 INFO - Running post-run listener: _upload_blobber_files 09:43:27 INFO - Blob upload gear active. 09:43:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:43:27 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 09:43:27 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'] 09:43:27 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 09:43:28 INFO - (blobuploader) - INFO - Open directory for files ... 09:43:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:43:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:43:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:43:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:43:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:43:29 INFO - (blobuploader) - INFO - Done attempting. 09:43:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:43:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:43:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:43:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:43:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:43:30 INFO - (blobuploader) - INFO - Done attempting. 09:43:30 INFO - (blobuploader) - INFO - Iteration through files over. 09:43:30 INFO - Return code: 0 09:43:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:43:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:43:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/844cdbfea6b1aa59863959f77967e1c40a656ac373c6ec34a93e09afb42c6eed28fe1be0c2b58ade98b07a82a2c4524c4f4d4d7ffd62dbcbec8531d45d82a38c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81d46c8041db57797ad52d788b9a9ce04181942b79b748034122250a9c3ff44e9791be0b2a33eb46936dac1ef98b48e719c69f556bca666f2129a8a0b7a2d831"} 09:43:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:43:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:43:30 INFO - Contents: 09:43:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/844cdbfea6b1aa59863959f77967e1c40a656ac373c6ec34a93e09afb42c6eed28fe1be0c2b58ade98b07a82a2c4524c4f4d4d7ffd62dbcbec8531d45d82a38c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81d46c8041db57797ad52d788b9a9ce04181942b79b748034122250a9c3ff44e9791be0b2a33eb46936dac1ef98b48e719c69f556bca666f2129a8a0b7a2d831"} 09:43:30 INFO - Running post-run listener: copy_logs_to_upload_dir 09:43:30 INFO - Copying logs to upload dir... 09:43:30 INFO - mkdir: /builds/slave/test/build/upload/logs 09:43:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1296.310514 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-05-30 09:43:30.276855) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 09:43:30.280944) ========= 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.2BLWnMrCjS/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.Om812WuUE6/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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/844cdbfea6b1aa59863959f77967e1c40a656ac373c6ec34a93e09afb42c6eed28fe1be0c2b58ade98b07a82a2c4524c4f4d4d7ffd62dbcbec8531d45d82a38c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81d46c8041db57797ad52d788b9a9ce04181942b79b748034122250a9c3ff44e9791be0b2a33eb46936dac1ef98b48e719c69f556bca666f2129a8a0b7a2d831"} build_url:https://queue.taskcluster.net/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011258 build_url: 'https://queue.taskcluster.net/v1/task/JEdtvJKuSweBG3Oe4mxFEg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/844cdbfea6b1aa59863959f77967e1c40a656ac373c6ec34a93e09afb42c6eed28fe1be0c2b58ade98b07a82a2c4524c4f4d4d7ffd62dbcbec8531d45d82a38c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81d46c8041db57797ad52d788b9a9ce04181942b79b748034122250a9c3ff44e9791be0b2a33eb46936dac1ef98b48e719c69f556bca666f2129a8a0b7a2d831"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 09:43:30.311947) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:43:30.312365) ========= 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.2BLWnMrCjS/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.Om812WuUE6/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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.005218 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:43:30.361274) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 09:43:30.361595) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 09:43:30.361925) ========= ========= Total master_lag: 0.18 =========