builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0096 starttime: 1461712123.46 results: success (0) buildid: 20160426141800 builduid: 590d7c51f5c246609990c3f5874b3abc revision: 6a441b2b2997cdbd47b5d80283f6f60d5a9e7489 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 16:08:43.462305) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 16:08:43.462735) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 16:08:43.463008) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 16:08:43.482439) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:08:43.482702) ========= 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.LEhybUyChU/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.2zjZseR9YR/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-26 16:08:43-- 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% 14.6M=0.001s 2016-04-26 16:08:43 (14.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121470 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:08:43.621429) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:08:43.621747) ========= 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.LEhybUyChU/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.2zjZseR9YR/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.025680 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:08:43.669644) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-26 16:08:43.669975) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6a441b2b2997cdbd47b5d80283f6f60d5a9e7489 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 6a441b2b2997cdbd47b5d80283f6f60d5a9e7489 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LEhybUyChU/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.2zjZseR9YR/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-26 16:08:43,744 truncating revision to first 12 chars 2016-04-26 16:08:43,744 Setting DEBUG logging. 2016-04-26 16:08:43,744 attempt 1/10 2016-04-26 16:08:43,744 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6a441b2b2997?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 16:08:45,523 unpacking tar archive at: mozilla-central-6a441b2b2997/testing/mozharness/ program finished with exit code 0 elapsedTime=2.029633 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-26 16:08:45.717847) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 16:08:45.718469) ========= script_repo_revision: 6a441b2b2997cdbd47b5d80283f6f60d5a9e7489 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 16:08:45.718887) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 16:08:45.719276) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 16:08:45.734170) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-26 16:08:45.734618) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LEhybUyChU/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.2zjZseR9YR/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:08:45 INFO - MultiFileLogger online at 20160426 16:08:45 in /builds/slave/test 16:08:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:08:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:08:45 INFO - {'append_to_log': False, 16:08:45 INFO - 'base_work_dir': '/builds/slave/test', 16:08:45 INFO - 'blob_upload_branch': 'Firefox', 16:08:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:08:45 INFO - 'branch': 'Firefox', 16:08:45 INFO - 'buildbot_json_path': 'buildprops.json', 16:08:45 INFO - 'config_files': ('talos/mac_config.py',), 16:08:45 INFO - 'default_actions': ('clobber', 16:08:45 INFO - 'read-buildbot-config', 16:08:45 INFO - 'download-and-extract', 16:08:45 INFO - 'populate-webroot', 16:08:45 INFO - 'create-virtualenv', 16:08:45 INFO - 'install', 16:08:45 INFO - 'run-tests'), 16:08:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:08:45 INFO - 'download_minidump_stackwalk': True, 16:08:45 INFO - 'download_symbols': 'ondemand', 16:08:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:08:45 INFO - 'tooltool.py': '/tools/tooltool.py', 16:08:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:08:45 INFO - '/tools/misc-python/virtualenv.py')}, 16:08:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:08:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:08:45 INFO - 'installer_path': 'installer.exe', 16:08:45 INFO - 'log_level': 'info', 16:08:45 INFO - 'log_name': 'talos', 16:08:45 INFO - 'log_to_console': True, 16:08:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:08:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:08:45 INFO - 'opt_config_files': (), 16:08:45 INFO - 'pip_index': False, 16:08:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:08:45 INFO - 'cmd': ('bash', 16:08:45 INFO - '-c', 16:08:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:08:45 INFO - 'enabled': True, 16:08:45 INFO - 'halt_on_failure': False, 16:08:45 INFO - 'name': 'check_screen_resolution'},), 16:08:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:08:45 INFO - 'cmd': ('bash', 16:08:45 INFO - '-c', 16:08:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:08:45 INFO - 'enabled': True, 16:08:45 INFO - 'halt_on_failure': False, 16:08:45 INFO - 'name': 'check_screen_resolution'},), 16:08:45 INFO - 'run_cmd_checks_enabled': True, 16:08:45 INFO - 'sps_profile': False, 16:08:45 INFO - 'sps_profile_interval': 0, 16:08:45 INFO - 'suite': 'dromaeojs', 16:08:45 INFO - 'system_bits': '32', 16:08:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:08:45 INFO - 'title': 't-yosemite-r7-0096', 16:08:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:08:45 INFO - 'use_talos_json': True, 16:08:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:08:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:08:45 INFO - 'work_dir': 'build'} 16:08:45 INFO - ##### 16:08:45 INFO - ##### Running clobber step. 16:08:45 INFO - ##### 16:08:45 INFO - Running pre-action listener: _resource_record_pre_action 16:08:45 INFO - Running main action method: clobber 16:08:45 INFO - rmtree: /builds/slave/test/build 16:08:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:08:47 INFO - Running post-action listener: _resource_record_post_action 16:08:47 INFO - ##### 16:08:47 INFO - ##### Running read-buildbot-config step. 16:08:47 INFO - ##### 16:08:47 INFO - Running pre-action listener: _resource_record_pre_action 16:08:47 INFO - Running main action method: read_buildbot_config 16:08:47 INFO - Using buildbot properties: 16:08:47 INFO - { 16:08:47 INFO - "project": "", 16:08:47 INFO - "product": "firefox", 16:08:47 INFO - "script_repo_revision": "production", 16:08:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:08:47 INFO - "repository": "", 16:08:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:08:47 INFO - "buildid": "20160426141800", 16:08:47 INFO - "pgo_build": "False", 16:08:47 INFO - "basedir": "/builds/slave/test", 16:08:47 INFO - "buildnumber": 239, 16:08:47 INFO - "slavename": "t-yosemite-r7-0096", 16:08:47 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:08:47 INFO - "platform": "yosemite_r7", 16:08:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:08:47 INFO - "branch": "mozilla-central", 16:08:47 INFO - "repo_path": "mozilla-central", 16:08:47 INFO - "revision": "6a441b2b2997cdbd47b5d80283f6f60d5a9e7489", 16:08:47 INFO - "stage_platform": "macosx64", 16:08:47 INFO - "builduid": "590d7c51f5c246609990c3f5874b3abc", 16:08:47 INFO - "slavebuilddir": "test" 16:08:47 INFO - } 16:08:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 16:08:47 INFO - Running post-action listener: _resource_record_post_action 16:08:47 INFO - ##### 16:08:47 INFO - ##### Running download-and-extract step. 16:08:47 INFO - ##### 16:08:47 INFO - Running pre-action listener: _resource_record_pre_action 16:08:47 INFO - Running main action method: download_and_extract 16:08:47 INFO - mkdir: /builds/slave/test/build/tests 16:08: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')]} 16:08:47 INFO - https://queue.taskcluster.net/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:08:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:08:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:08:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:08:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 16:08:48 INFO - Downloaded 1199 bytes. 16:08:48 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:08:48 INFO - Using the following test package requirements: 16:08:48 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:08:48 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:08:48 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 16:08:48 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:08:48 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 16:08:48 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:08:48 INFO - u'jsshell-mac.zip'], 16:08:48 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:08:48 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 16:08:48 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:08:48 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:08:48 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 16:08:48 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:08:48 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 16:08:48 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:08:48 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 16:08:48 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:08:48 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 16:08:48 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:08: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')]} 16:08:48 INFO - https://queue.taskcluster.net/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:08:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:08:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:08:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:08:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:08:50 INFO - Downloaded 18114149 bytes. 16:08:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:08:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:08:53 INFO - Return code: 0 16:08:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:08:53 INFO - https://queue.taskcluster.net/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:08:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:08:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:08:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:08:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:08:56 INFO - Downloaded 18114149 bytes. 16:08:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:08:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:09:00 INFO - Return code: 0 16:09: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')]} 16:09:00 INFO - https://queue.taskcluster.net/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:09:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:09:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:09:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:09:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:09:01 INFO - Downloaded 10840145 bytes. 16:09:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:09:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:09:01 INFO - Return code: 0 16:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:09:01 INFO - https://queue.taskcluster.net/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:09:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:09:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:09:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 16:09:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 16:09:04 INFO - Downloaded 110137776 bytes. 16:09:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:09:04 INFO - mkdir: /builds/slave/test/properties 16:09:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:09:04 INFO - Writing to file /builds/slave/test/properties/build_url 16:09:04 INFO - Contents: 16:09:04 INFO - build_url:https://queue.taskcluster.net/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:09:05 INFO - Running post-action listener: _resource_record_post_action 16:09:05 INFO - Running post-action listener: set_extra_try_arguments 16:09:05 INFO - ##### 16:09:05 INFO - ##### Running populate-webroot step. 16:09:05 INFO - ##### 16:09:05 INFO - Running pre-action listener: _resource_record_pre_action 16:09:05 INFO - Running main action method: populate_webroot 16:09:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:09:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:09:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:09:05 INFO - '--tppagecycles', 16:09:05 INFO - '7'], 16:09:05 INFO - 'tests': ['tsvgm']}}, 16:09:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:09:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:09:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:09:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:09:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:09:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:09:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:05 INFO - 'talos_options': ['--disable-e10s'], 16:09:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:09:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:09:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:05 INFO - 'talos_options': ['--disable-e10s'], 16:09:05 INFO - 'tests': ['damp', 'tps']}, 16:09:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:05 INFO - 'tests': ['damp', 'tps']}, 16:09:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:09:05 INFO - 'tests': ['dromaeo_dom']}, 16:09:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:09:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:09:05 INFO - 'tests': ['a11yr', 16:09:05 INFO - 'ts_paint', 16:09:05 INFO - 'tpaint', 16:09:05 INFO - 'sessionrestore', 16:09:05 INFO - 'sessionrestore_no_auto_restore', 16:09:05 INFO - 'tabpaint']}, 16:09:05 INFO - 'other-e10s': {'tests': ['a11yr', 16:09:05 INFO - 'ts_paint', 16:09:05 INFO - 'tpaint', 16:09:05 INFO - 'sessionrestore', 16:09:05 INFO - 'sessionrestore_no_auto_restore', 16:09:05 INFO - 'tabpaint']}, 16:09:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:09:05 INFO - 'ts_paint', 16:09:05 INFO - 'tpaint', 16:09:05 INFO - 'sessionrestore', 16:09:05 INFO - 'sessionrestore_no_auto_restore', 16:09:05 INFO - 'tabpaint']}, 16:09:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:09:05 INFO - 'ts_paint', 16:09:05 INFO - 'tpaint', 16:09:05 INFO - 'sessionrestore', 16:09:05 INFO - 'sessionrestore_no_auto_restore', 16:09:05 INFO - 'tabpaint']}, 16:09:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:09:05 INFO - 'tests': ['a11yr', 16:09:05 INFO - 'ts_paint', 16:09:05 INFO - 'tpaint', 16:09:05 INFO - 'sessionrestore', 16:09:05 INFO - 'sessionrestore_no_auto_restore', 16:09:05 INFO - 'tabpaint']}, 16:09:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:09:05 INFO - 'tests': ['a11yr', 16:09:05 INFO - 'ts_paint', 16:09:05 INFO - 'tpaint', 16:09:05 INFO - 'sessionrestore', 16:09:05 INFO - 'sessionrestore_no_auto_restore', 16:09:05 INFO - 'tabpaint']}, 16:09:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:09:05 INFO - 'tests': ['tsvgx', 16:09:05 INFO - 'tsvgr_opacity', 16:09:05 INFO - 'tart', 16:09:05 INFO - 'tscrollx', 16:09:05 INFO - 'cart']}, 16:09:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:09:05 INFO - 'tsvgr_opacity', 16:09:05 INFO - 'tart', 16:09:05 INFO - 'tscrollx', 16:09:05 INFO - 'cart']}, 16:09:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:05 INFO - 'talos_options': ['--disable-e10s'], 16:09:05 INFO - 'tests': ['tp5o']}, 16:09:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:05 INFO - 'tests': ['tp5o']}, 16:09:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:05 INFO - 'talos_options': ['--disable-e10s', 16:09:05 INFO - '--xperf_path', 16:09:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:09:05 INFO - 'tests': ['tp5n']}, 16:09:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:05 INFO - 'talos_options': ['--xperf_path', 16:09:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:09:05 INFO - 'tests': ['tp5n']}}, 16:09:05 INFO - 'talos.zip': {'path': '', 16:09:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:09:05 INFO - Running post-action listener: _resource_record_post_action 16:09:05 INFO - ##### 16:09:05 INFO - ##### Running create-virtualenv step. 16:09:05 INFO - ##### 16:09:05 INFO - Running pre-action listener: _resource_record_pre_action 16:09:05 INFO - Running main action method: create_virtualenv 16:09:05 INFO - Creating virtualenv /builds/slave/test/build/venv 16:09:05 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 16:09:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:09:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:09:05 INFO - Using real prefix '/tools/python27' 16:09:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:09:06 INFO - Installing distribute.............................................................................................................................................................................................done. 16:09:09 INFO - Installing pip.................done. 16:09:09 INFO - Return code: 0 16:09:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:09: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')]} 16:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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')]} 16:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:09 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 0x10f754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f764420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4bad62230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5f3e00>, '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 0x10f41cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5eeb20>, '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.LEhybUyChU/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.2zjZseR9YR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:09:09 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 16:09:09 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 16:09:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LEhybUyChU/Render', 16:09:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:09 INFO - 'HOME': '/Users/cltbld', 16:09:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:09 INFO - 'LOGNAME': 'cltbld', 16:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:09 INFO - 'MOZ_NO_REMOTE': '1', 16:09:09 INFO - 'NO_EM_RESTART': '1', 16:09:09 INFO - 'PAGER': '/bin/cat', 16:09:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:09 INFO - 'PWD': '/builds/slave/test', 16:09:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:09 INFO - 'SHELL': '/bin/bash', 16:09:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zjZseR9YR/Listeners', 16:09:09 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:09:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:09 INFO - 'USER': 'cltbld', 16:09:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:09 INFO - 'XPC_FLAGS': '0x0', 16:09:09 INFO - 'XPC_SERVICE_NAME': '0', 16:09:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:09 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 16:09:09 INFO - Cleaning up... 16:09:09 INFO - Return code: 0 16:09:09 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:09: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')]} 16:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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')]} 16:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:09 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 0x10f754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f764420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4bad62230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5f3e00>, '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 0x10f41cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5eeb20>, '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.LEhybUyChU/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.2zjZseR9YR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:09:09 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 16:09:09 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 16:09:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LEhybUyChU/Render', 16:09:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:09 INFO - 'HOME': '/Users/cltbld', 16:09:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:09 INFO - 'LOGNAME': 'cltbld', 16:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:09 INFO - 'MOZ_NO_REMOTE': '1', 16:09:09 INFO - 'NO_EM_RESTART': '1', 16:09:09 INFO - 'PAGER': '/bin/cat', 16:09:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:09 INFO - 'PWD': '/builds/slave/test', 16:09:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:09 INFO - 'SHELL': '/bin/bash', 16:09:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zjZseR9YR/Listeners', 16:09:09 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:09:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:09 INFO - 'USER': 'cltbld', 16:09:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:09 INFO - 'XPC_FLAGS': '0x0', 16:09:09 INFO - 'XPC_SERVICE_NAME': '0', 16:09:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:09 INFO - Downloading/unpacking psutil>=0.7.1 16:09:09 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 16:09:09 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 16:09:09 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 16:09:09 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 16:09:09 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 16:09:09 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 16:09:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:09:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:09:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:09:12 INFO - Installing collected packages: psutil 16:09:12 INFO - Running setup.py install for psutil 16:09:12 INFO - building 'psutil._psutil_osx' extension 16:09:12 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 16:09:13 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 16:09:13 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 16:09:13 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 16:09:13 INFO - building 'psutil._psutil_posix' extension 16:09:13 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 16:09:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:09:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:09:13 INFO - ^ 16:09:13 INFO - 1 warning generated. 16:09:13 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 16:09:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:09:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:09:13 INFO - Successfully installed psutil 16:09:13 INFO - Cleaning up... 16:09:13 INFO - Return code: 0 16:09:13 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:09: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')]} 16:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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')]} 16:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09: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', '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 0x10f754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f764420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4bad62230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5f3e00>, '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 0x10f41cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5eeb20>, '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.LEhybUyChU/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.2zjZseR9YR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:09: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 16:09: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 mozsystemmonitor==0.0.0 16:09:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LEhybUyChU/Render', 16:09:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:13 INFO - 'HOME': '/Users/cltbld', 16:09:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:13 INFO - 'LOGNAME': 'cltbld', 16:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:13 INFO - 'MOZ_NO_REMOTE': '1', 16:09:13 INFO - 'NO_EM_RESTART': '1', 16:09:13 INFO - 'PAGER': '/bin/cat', 16:09:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:13 INFO - 'PWD': '/builds/slave/test', 16:09:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:13 INFO - 'SHELL': '/bin/bash', 16:09:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zjZseR9YR/Listeners', 16:09:13 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:09:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:13 INFO - 'USER': 'cltbld', 16:09:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:13 INFO - 'XPC_FLAGS': '0x0', 16:09:13 INFO - 'XPC_SERVICE_NAME': '0', 16:09:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:13 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:09: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 16:09: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 16:09: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 16:09: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 16:09: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 16:09: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 16:09:16 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:09:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:09:16 INFO - Installing collected packages: mozsystemmonitor 16:09:16 INFO - Running setup.py install for mozsystemmonitor 16:09:16 INFO - Successfully installed mozsystemmonitor 16:09:16 INFO - Cleaning up... 16:09:16 INFO - Return code: 0 16:09:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:09:16 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')]} 16:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:16 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')]} 16:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:16 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 0x10f754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f764420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4bad62230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5f3e00>, '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 0x10f41cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5eeb20>, '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.LEhybUyChU/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.2zjZseR9YR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:09:16 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 16:09:16 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 16:09:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LEhybUyChU/Render', 16:09:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:16 INFO - 'HOME': '/Users/cltbld', 16:09:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:16 INFO - 'LOGNAME': 'cltbld', 16:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:16 INFO - 'MOZ_NO_REMOTE': '1', 16:09:16 INFO - 'NO_EM_RESTART': '1', 16:09:16 INFO - 'PAGER': '/bin/cat', 16:09:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:16 INFO - 'PWD': '/builds/slave/test', 16:09:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:16 INFO - 'SHELL': '/bin/bash', 16:09:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zjZseR9YR/Listeners', 16:09:16 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:09:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:16 INFO - 'USER': 'cltbld', 16:09:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:16 INFO - 'XPC_FLAGS': '0x0', 16:09:16 INFO - 'XPC_SERVICE_NAME': '0', 16:09:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:16 INFO - Downloading/unpacking blobuploader==1.2.4 16:09: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 16:09: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 16:09: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 16:09: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 16:09: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 16:09: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 16:09:19 INFO - Downloading blobuploader-1.2.4.tar.gz 16:09:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:09:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:09:19 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 16:09:19 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 16:09:19 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 16:09:19 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 16:09:19 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 16:09:19 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 16:09:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:09:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:09:20 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 16:09:20 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 16:09:20 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 16:09:20 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 16:09:20 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 16:09:20 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 16:09:20 INFO - Downloading docopt-0.6.1.tar.gz 16:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:09:20 INFO - Installing collected packages: blobuploader, requests, docopt 16:09:20 INFO - Running setup.py install for blobuploader 16:09:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:09:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:09:20 INFO - Running setup.py install for requests 16:09:21 INFO - Running setup.py install for docopt 16:09:21 INFO - Successfully installed blobuploader requests docopt 16:09:21 INFO - Cleaning up... 16:09:21 INFO - Return code: 0 16:09:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:09:21 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')]} 16:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:21 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')]} 16:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:21 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 0x10f754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f764420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4bad62230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5f3e00>, '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 0x10f41cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5eeb20>, '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.LEhybUyChU/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.2zjZseR9YR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:09:21 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 16:09:21 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 16:09:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LEhybUyChU/Render', 16:09:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:21 INFO - 'HOME': '/Users/cltbld', 16:09:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:21 INFO - 'LOGNAME': 'cltbld', 16:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:21 INFO - 'MOZ_NO_REMOTE': '1', 16:09:21 INFO - 'NO_EM_RESTART': '1', 16:09:21 INFO - 'PAGER': '/bin/cat', 16:09:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:21 INFO - 'PWD': '/builds/slave/test', 16:09:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:21 INFO - 'SHELL': '/bin/bash', 16:09:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zjZseR9YR/Listeners', 16:09:21 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:09:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:21 INFO - 'USER': 'cltbld', 16:09:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:21 INFO - 'XPC_FLAGS': '0x0', 16:09:21 INFO - 'XPC_SERVICE_NAME': '0', 16:09:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:09:21 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-KXETfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:09:21 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-jayD6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:09:21 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-D_FMT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:09:22 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-olaIyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:09:22 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-5HbQpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:09:22 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-236xWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:09:22 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-rPmkJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:09:22 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-L_Jdbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:09:22 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ob47ID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:09:22 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-2foPPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:09:22 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-hMNNco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:09:22 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-GR74b4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:09:23 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-GocW6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:09:23 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-EdPHK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:23 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-EtbqoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:09:23 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-PUp9YC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:09:23 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-KBQIfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:09:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:09:23 INFO - Running setup.py install for manifestparser 16:09:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:09:23 INFO - Running setup.py install for mozcrash 16:09:23 INFO - Running setup.py install for mozdebug 16:09:23 INFO - Running setup.py install for mozdevice 16:09:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:09:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:09:24 INFO - Running setup.py install for mozfile 16:09:24 INFO - Running setup.py install for mozhttpd 16:09:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:09:24 INFO - Running setup.py install for mozinfo 16:09:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:09:24 INFO - Running setup.py install for mozInstall 16:09:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:09:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:09:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:09:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:09:24 INFO - Running setup.py install for mozleak 16:09:24 INFO - Running setup.py install for mozlog 16:09:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:09:25 INFO - Running setup.py install for moznetwork 16:09:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:09:25 INFO - Running setup.py install for mozprocess 16:09:25 INFO - Running setup.py install for mozprofile 16:09:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:09:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:09:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:09:25 INFO - Running setup.py install for mozrunner 16:09:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:09:25 INFO - Running setup.py install for mozscreenshot 16:09:25 INFO - Running setup.py install for moztest 16:09:25 INFO - Running setup.py install for mozversion 16:09:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:09:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:09:26 INFO - Cleaning up... 16:09:26 INFO - Return code: 0 16:09:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:26 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 0x10f754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f764420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4bad62230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5f3e00>, '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 0x10f41cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5eeb20>, '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.LEhybUyChU/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.2zjZseR9YR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:09:26 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 16:09:26 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 16:09:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LEhybUyChU/Render', 16:09:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:26 INFO - 'HOME': '/Users/cltbld', 16:09:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:26 INFO - 'LOGNAME': 'cltbld', 16:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:26 INFO - 'MOZ_NO_REMOTE': '1', 16:09:26 INFO - 'NO_EM_RESTART': '1', 16:09:26 INFO - 'PAGER': '/bin/cat', 16:09:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:26 INFO - 'PWD': '/builds/slave/test', 16:09:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:26 INFO - 'SHELL': '/bin/bash', 16:09:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zjZseR9YR/Listeners', 16:09:26 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:09:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:26 INFO - 'USER': 'cltbld', 16:09:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:26 INFO - 'XPC_FLAGS': '0x0', 16:09:26 INFO - 'XPC_SERVICE_NAME': '0', 16:09:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:09:26 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-mVfHIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:09:26 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)) 16:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:09:26 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-6ILipB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:09:26 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)) 16:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:09:26 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-SKvbJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:09:26 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)) 16:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:09:26 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ZOTIar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:09:26 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)) 16:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:09:26 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-CNGFtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:09:26 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)) 16:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:09:26 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-lxdNOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:09:27 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)) 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:09:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-rWML78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:09:27 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)) 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:09:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-JQe81w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:09:27 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)) 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:09:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-HdToTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:09:27 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)) 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:09:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-aZflen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:09:27 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)) 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:09:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-JhR2_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:09:27 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)) 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:09:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-NNbZCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:09:27 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)) 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:09:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-3582l4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:09:27 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)) 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:09:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-aapFfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:09:27 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)) 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-RYzJrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:09:27 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)) 16:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:09:27 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-JPw6u7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:09:28 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)) 16:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:09:28 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-FiVhHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:09:28 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)) 16:09:28 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)) 16:09:28 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)) 16:09:28 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)) 16:09:28 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)) 16:09:28 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)) 16:09:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:09:28 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 16:09:28 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 16:09:28 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 16:09:28 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 16:09:28 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 16:09:28 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 16:09:30 INFO - Downloading blessings-1.6.tar.gz 16:09:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:09:30 INFO - Installing collected packages: blessings 16:09:30 INFO - Running setup.py install for blessings 16:09:30 INFO - Successfully installed blessings 16:09:30 INFO - Cleaning up... 16:09:30 INFO - Return code: 0 16:09:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:09:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:09:31 INFO - Reading from file tmpfile_stdout 16:09:31 INFO - Current package versions: 16:09:31 INFO - blessings == 1.6 16:09:31 INFO - blobuploader == 1.2.4 16:09:31 INFO - docopt == 0.6.1 16:09:31 INFO - manifestparser == 1.1 16:09:31 INFO - mozInstall == 1.12 16:09:31 INFO - mozcrash == 0.17 16:09:31 INFO - mozdebug == 0.1 16:09:31 INFO - mozdevice == 0.48 16:09:31 INFO - mozfile == 1.2 16:09:31 INFO - mozhttpd == 0.7 16:09:31 INFO - mozinfo == 0.9 16:09:31 INFO - mozleak == 0.1 16:09:31 INFO - mozlog == 3.1 16:09:31 INFO - moznetwork == 0.27 16:09:31 INFO - mozprocess == 0.22 16:09:31 INFO - mozprofile == 0.28 16:09:31 INFO - mozrunner == 6.11 16:09:31 INFO - mozscreenshot == 0.1 16:09:31 INFO - mozsystemmonitor == 0.0 16:09:31 INFO - moztest == 0.7 16:09:31 INFO - mozversion == 1.4 16:09:31 INFO - psutil == 3.1.1 16:09:31 INFO - requests == 1.2.3 16:09:31 INFO - wsgiref == 0.1.2 16:09:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:09:31 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')]} 16:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:31 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')]} 16:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:31 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 0x10f754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f764420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4bad62230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5f3e00>, '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 0x10f41cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5eeb20>, '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.LEhybUyChU/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.2zjZseR9YR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:09:31 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 16:09:31 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 16:09:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LEhybUyChU/Render', 16:09:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:31 INFO - 'HOME': '/Users/cltbld', 16:09:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:31 INFO - 'LOGNAME': 'cltbld', 16:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:31 INFO - 'MOZ_NO_REMOTE': '1', 16:09:31 INFO - 'NO_EM_RESTART': '1', 16:09:31 INFO - 'PAGER': '/bin/cat', 16:09:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:31 INFO - 'PWD': '/builds/slave/test', 16:09:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:31 INFO - 'SHELL': '/bin/bash', 16:09:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zjZseR9YR/Listeners', 16:09:31 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:09:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:31 INFO - 'USER': 'cltbld', 16:09:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:31 INFO - 'XPC_FLAGS': '0x0', 16:09:31 INFO - 'XPC_SERVICE_NAME': '0', 16:09:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:31 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)) 16:09:31 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)) 16:09:31 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)) 16:09:31 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)) 16:09:31 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)) 16:09:31 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)) 16:09:31 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)) 16:09:31 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)) 16:09:31 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)) 16:09:31 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)) 16:09:31 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)) 16:09:31 INFO - Cleaning up... 16:09:31 INFO - Return code: 0 16:09:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:09:31 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')]} 16:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:31 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')]} 16:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:31 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 0x10f754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f764420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4bad62230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5f3e00>, '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 0x10f41cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5eeb20>, '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.LEhybUyChU/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.2zjZseR9YR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:09:31 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 16:09:31 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 16:09:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LEhybUyChU/Render', 16:09:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:31 INFO - 'HOME': '/Users/cltbld', 16:09:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:31 INFO - 'LOGNAME': 'cltbld', 16:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:31 INFO - 'MOZ_NO_REMOTE': '1', 16:09:31 INFO - 'NO_EM_RESTART': '1', 16:09:31 INFO - 'PAGER': '/bin/cat', 16:09:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:31 INFO - 'PWD': '/builds/slave/test', 16:09:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:31 INFO - 'SHELL': '/bin/bash', 16:09:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zjZseR9YR/Listeners', 16:09:31 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:09:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:31 INFO - 'USER': 'cltbld', 16:09:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:31 INFO - 'XPC_FLAGS': '0x0', 16:09:31 INFO - 'XPC_SERVICE_NAME': '0', 16:09:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:31 INFO - Downloading/unpacking jsonschema 16:09:31 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 16:09:31 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 16:09:31 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 16:09:31 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 16:09:31 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 16:09:31 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 16:09:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:09:34 INFO - Downloading/unpacking functools32 (from jsonschema) 16:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:34 INFO - Downloading functools32-3.2.3-2.tar.gz 16:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:09:34 INFO - warning: no files found matching '*.txt' 16:09:34 INFO - no previously-included directories found matching 'build' 16:09:34 INFO - no previously-included directories found matching 'dist' 16:09:34 INFO - no previously-included directories found matching '.git*' 16:09:34 INFO - Installing collected packages: jsonschema, functools32 16:09:34 INFO - Running setup.py install for functools32 16:09:34 INFO - warning: no files found matching '*.txt' 16:09:34 INFO - no previously-included directories found matching 'build' 16:09:34 INFO - no previously-included directories found matching 'dist' 16:09:34 INFO - no previously-included directories found matching '.git*' 16:09:34 INFO - Successfully installed jsonschema functools32 16:09:34 INFO - Cleaning up... 16:09:34 INFO - Return code: 0 16:09:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f764420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4bad62230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5f3e00>, '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 0x10f41cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5eeb20>, '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.LEhybUyChU/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.2zjZseR9YR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:09:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:09:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LEhybUyChU/Render', 16:09:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:34 INFO - 'HOME': '/Users/cltbld', 16:09:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:34 INFO - 'LOGNAME': 'cltbld', 16:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:34 INFO - 'MOZ_NO_REMOTE': '1', 16:09:34 INFO - 'NO_EM_RESTART': '1', 16:09:34 INFO - 'PAGER': '/bin/cat', 16:09:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:34 INFO - 'PWD': '/builds/slave/test', 16:09:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:34 INFO - 'SHELL': '/bin/bash', 16:09:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zjZseR9YR/Listeners', 16:09:34 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:09:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:34 INFO - 'USER': 'cltbld', 16:09:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:34 INFO - 'XPC_FLAGS': '0x0', 16:09:34 INFO - 'XPC_SERVICE_NAME': '0', 16:09:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:35 INFO - Downloading/unpacking flake8 16:09:35 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 16:09:35 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 16:09:35 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 16:09:35 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 16:09:35 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 16:09:35 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 16:09:37 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:09:37 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:09:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:09:38 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:09:38 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 16:09:38 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 16:09:38 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 16:09:38 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 16:09:38 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 16:09:38 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 16:09:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:09:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:09:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:09:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:09:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:09:38 INFO - no previously-included directories found matching 'docs/_build' 16:09:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:09:38 INFO - Running setup.py install for pep8 16:09:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:09:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:09:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:09:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:09:39 INFO - no previously-included directories found matching 'docs/_build' 16:09:39 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:09:39 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:09:39 INFO - Cleaning up... 16:09:39 INFO - Return code: 0 16:09:39 INFO - Running post-action listener: _resource_record_post_action 16:09:39 INFO - Running post-action listener: _start_resource_monitoring 16:09:39 INFO - Starting resource monitoring. 16:09:39 INFO - ##### 16:09:39 INFO - ##### Running install step. 16:09:39 INFO - ##### 16:09:39 INFO - Running pre-action listener: _resource_record_pre_action 16:09:39 INFO - Running main action method: install 16:09:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:09:39 INFO - Reading from file tmpfile_stdout 16:09:39 INFO - Detecting whether we're running mozinstall >=1.0... 16:09:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:09:39 INFO - Reading from file tmpfile_stdout 16:09:39 INFO - Output received: 16:09:39 INFO - Usage: mozinstall [options] installer 16:09:39 INFO - Options: 16:09:39 INFO - -h, --help show this help message and exit 16:09:39 INFO - -d DEST, --destination=DEST 16:09:39 INFO - Directory to install application into. [default: 16:09:39 INFO - "/builds/slave/test"] 16:09:39 INFO - --app=APP Application being installed. [default: firefox] 16:09:39 INFO - mkdir: /builds/slave/test/build/application 16:09:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:10:13 INFO - Reading from file tmpfile_stdout 16:10:13 INFO - Output received: 16:10:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:10:13 INFO - Running post-action listener: _resource_record_post_action 16:10:13 INFO - ##### 16:10:13 INFO - ##### Running run-tests step. 16:10:13 INFO - ##### 16:10:13 INFO - Running pre-action listener: _resource_record_pre_action 16:10:13 INFO - Running main action method: run_tests 16:10:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:10:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:10:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:10:13 INFO - 2016-04-26 16:10:13.451 screenresolution[1639:7575] starting screenresolution argv=screenresolution get 16:10:13 INFO - 2016-04-26 16:10:13.453 screenresolution[1639:7575] Display 0: 1600x1200x32@60 16:10:13 INFO - 2016-04-26 16:10:13.459 screenresolution[1640:7577] starting screenresolution argv=screenresolution list 16:10:13 INFO - Available Modes on Display 0 16:10:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:10:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:10:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:10:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:10:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:10:13 INFO - Intel Iris: 16:10:13 INFO - Chipset Model: Intel Iris 16:10:13 INFO - Type: GPU 16:10:13 INFO - Bus: Built-In 16:10:13 INFO - VRAM (Dynamic, Max): 1536 MB 16:10:13 INFO - Vendor: Intel (0x8086) 16:10:13 INFO - Device ID: 0x0a2e 16:10:13 INFO - Revision ID: 0x0009 16:10:13 INFO - Displays: 16:10:13 INFO - Display: 16:10:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:10:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:10:13 INFO - Display Serial Number: 1600x1200 60 16:10:13 INFO - Main Display: Yes 16:10:13 INFO - Mirror: Off 16:10:13 INFO - Online: Yes 16:10:13 INFO - Rotation: Supported 16:10:13 INFO - Return code: 0 16:10:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:10:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:10:13 INFO - Python 2.7.3 16:10:13 INFO - Return code: 0 16:10:13 INFO - grabbing minidump binary from tooltool 16:10: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')]} 16:10:13 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 0x10f5f3e00>, '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 0x10f41cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5eeb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:10:13 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 16:10:13 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 16:10:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:10:13 INFO - Return code: 0 16:10:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:10:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:10:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:10:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:10:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:10:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:10:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:10:14 INFO - Return code: 0 16:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--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 16:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0096 --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 16:10:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LEhybUyChU/Render', 16:10:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:14 INFO - 'HOME': '/Users/cltbld', 16:10:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:14 INFO - 'LOGNAME': 'cltbld', 16:10:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:10:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:14 INFO - 'MOZ_NO_REMOTE': '1', 16:10:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:10:14 INFO - 'NO_EM_RESTART': '1', 16:10:14 INFO - 'PAGER': '/bin/cat', 16:10:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:14 INFO - 'PWD': '/builds/slave/test', 16:10:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:10:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:14 INFO - 'SHELL': '/bin/bash', 16:10:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2zjZseR9YR/Listeners', 16:10:14 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:10:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:14 INFO - 'USER': 'cltbld', 16:10:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:14 INFO - 'XPC_FLAGS': '0x0', 16:10:14 INFO - 'XPC_SERVICE_NAME': '0', 16:10:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--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 16:10:15 INFO - mozversion application_buildid: 20160426141800 16:10:15 INFO - mozversion application_changeset: 6a441b2b2997cdbd47b5d80283f6f60d5a9e7489 16:10:15 INFO - mozversion application_display_name: Nightly 16:10:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:10:15 INFO - mozversion application_name: Firefox 16:10:15 INFO - mozversion application_remotingname: firefox 16:10:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:10:15 INFO - mozversion application_vendor: Mozilla 16:10:15 INFO - mozversion application_version: 49.0a1 16:10:15 INFO - mozversion platform_buildid: 20160426141800 16:10:15 INFO - mozversion platform_changeset: 6a441b2b2997cdbd47b5d80283f6f60d5a9e7489 16:10:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:10:15 INFO - mozversion platform_version: 49.0a1 16:10:15 INFO - using testdate: 1461712215 16:10:15 INFO - actual date: 1461712215 16:10:15 INFO - starting webserver on 'localhost:49283' 16:10:15 INFO - SUITE-START | Running 2 tests 16:10:15 INFO - TEST-START | dromaeo_css 16:10:15 INFO - operating with platform_type : mac_ 16:10:15 INFO - Initialising browser for dromaeo_css test... 16:10:15 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpw6FtXc/profile http://localhost:49283/getInfo.html) 16:10:17 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 16:10:17 INFO - PROCESS | 1658 | colorDepth:24 16:10:17 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 16:10:17 INFO - PROCESS | 1658 | __metrics 16:10:17 INFO - PROCESS | 1658 | 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" 16:10:17 ERROR - PROCESS | 1658 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:10:18 INFO - TEST-INFO | 1658: exit 0 16:10:18 INFO - Browser initialized. 16:10:18 INFO - Running cycle 1/1 for dromaeo_css test... 16:10:18 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpw6FtXc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:10:30 INFO - PROCESS | 1660 | RSS: Main: 239235072 16:10:30 INFO - PROCESS | 1660 | 16:11:21 INFO - PROCESS | 1660 | 1461712281699 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] 16:13:32 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 16:13:33 INFO - PROCESS | 1660 | RSS: Main: 367763456 16:13:33 INFO - PROCESS | 1660 | 16:16:36 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 16:16:37 INFO - PROCESS | 1660 | RSS: Main: 374607872 16:16:37 INFO - PROCESS | 1660 | 16:19:39 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 16:19:40 INFO - PROCESS | 1660 | RSS: Main: 372178944 16:19:40 INFO - PROCESS | 1660 | 16:22:42 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 16:22:43 INFO - PROCESS | 1660 | RSS: Main: 380370944 16:22:43 INFO - PROCESS | 1660 | 16:25:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 16:25:47 INFO - PROCESS | 1660 | RSS: Main: 346992640 16:25:47 INFO - PROCESS | 1660 | 16:28:50 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 16:28:50 INFO - PROCESS | 1660 | RSS: Main: 386969600 16:28:50 INFO - PROCESS | 1660 | 16:28:50 INFO - PROCESS | 1660 | __start_tp_report 16:28:50 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 16:28:50 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 16:28:50 INFO - PROCESS | 1660 | |i|pagename|runs| 16:28:50 INFO - PROCESS | 1660 | |0;dojo.html;2826.4488424757174;3443.225274313278;3468.2023134679025;3469.2714529948707;3514.2971405718845;5542.861428464291;5597.776088956447;5698.088305871056;5704.087346024637;5747.51146152577;5603;5671.7731290748425;5672.716364181795;5682.744276507557;5706.514946229565;5146.742662866851;5171.793128274864;5196.8700782480555;5214.426413094557;5260.079486089919;5271.815486457975;5272.367315922094;5279.86800329991;5284.524392804647;5285.02227087988;5198.45416231296;5254.028004819102;5266.52601265886;5268.394134674504;5292.523672869441;5282.286891269687;5321.467853214647;5326.094563924142;5331.600129990227;5340.465953404629;5077.822276220351;5140.306058682105;5206.739663016834;5242.973785131088;5245.396779370383;5473.945260547383;5535.363433205192;5554.361248456439;5595.832125036215;5646.463585959368;6152.437537631678;6190.442860142563;6201.007838745779;6204.503639708858;6206.317305096436;6224.719887605069;6280.937190628127;6289.245290565115;6290.811275661737;6293.9370606293805;4867.756612169377;5663;5762.4525670061275;5768;5893.44012318829;5915.669606418374;6232;6249.562530622813;6279.529035322369;6281.309056003836;6139.84650383744;6180.165677633462;6181.752729890754;6187.133801267826;6199.659018753921;6094.116353128771;6148.538859585459;6277.654728989949;6280.183576135073;6299.874002519924;5283.630145889828;5932.970335148297;6081.787137450168;6091.14723938649;6095.817125486243;6088.543359248074;6090.451859332681;6145.8156255312415;6152.600080994811;6167.414095660905;4917.557419832232;4927;4944.035912997003;4966.950330496648;5012.323336349619;4417.668674849399;4420.447444069491;4425.402570652985;4443.266860967969;4484.304932735431;4697.389339385926;5679.886402271932;5710.571707121982;5724.627899186539;5771.278590176228;6002.369751176151;6136.764169924325;6145.661988590581;6205.813825585149;6237.781677641261;5740.971295143498;5775.047117225695;5775.9133612995;5785.652860828363;5789.3052833659385;5421.26812880264;5430.945690543123;5432.592555558349;5436.21174929633;5443.129099344165;5331.680099194061;6216.471599913967;6232.25212974446;6264.968675156595;6311.305756366704;6294.4964402846745;6304.022876454157;6318;6318.115463834974;6323.462505687068;6257.374262573706;6286.65423401699;6309.779157729457;6310.6529140895855;6320.304766475776;5914.67469289176;6101.298350689727;6101.641069425165;6179.783707570214;6207.410296021871;6291.402316779899;6298.842528936813;6300.558960872695;6314.273858506331;6318.178636777189;5251.975864706498;6124.0201567748945;6124.050772130327;6127.448529632355;6143.508519318354;5718.571107166896;5832.854178645568;5842.094475356326;6008.639481631116;6091.42131497507;5946.672932988728;5994.310654274815;6008.759649614141;6012.669303188367;6028.638281703112;6120.588748038681;6153.507719382528;6176.3514830943905;6180.072989051679;6195.659238741732;5802.332731735904;5940.316863560746;5945.078512830518;6010.398960103955;6078.3921607838865 16:28:50 INFO - PROCESS | 1660 | |1;ext.html;524.1220954900541;546.811350084221;579;587.4243241623209;616.78801155729;25683;29859.55210671839;36596.81701591495;37630.62369376305;37867.431988520126;41052.79473602635;41442;41454.58545414545;41506.37740433896;41577.16845663085;8271.17288271174;9392.107749763762;9646.469444180568;9648.083432073941;9658.806823863553;8343.374246931475;8525.658973641079;10989.010989010987;11244.100471962223;11246.325220486797;11157.163212759035;11331.546738130464;11354.43227838609;11355.716107097305;11357.829632555518;8352.457090289172;11120.499577519031;11221.270617409884;11339.2629479084;11352.94323528377;11190.160737944687;11344;11345.829812552818;11348.319100853892;11349.886501135048;36549.45175822365;37031.889043328745;37058;37190.81404592987;37203.25593488115;3020.75833933287;3123.500239961595;3401.812900290483;3414.453687410027;3731.589525152231;22009.779902201095;26668.59997100045;27048.729512704816;27513.724862751318;28902;2566.127516644331;2598.753118453744;2631.2911915862273;2650.112212408845;2705.269577214141;278232;362201;369002.9999999973;369842;370172;32623.347533049207;33282.3343533128;33353.49969750456;57426.7128664354;57913.71043144842;34226;34309.142271443416;34355;34357.82821085928;34409.655903440645;14272.857271427152;14432;14438.494652687315;14445.205513696848;14448.133112013313;9165.358424910326;11209.71975700614;12032.458539365574;12259.693507662201;12349;7943.642536085775;7957.283844454027;7968.721094761656;8049.315808156372;8052.758417247492;3355.194753259249;3388.186835159544;3390.9321813564084;3409.62494125646;3418.888811181813;903.915301638037;928.9117533834275;992.8212921674168;1041.9218558608134;1095.780843831237;21404.892975535022;24336.87831560866;27369.589456157777;28172.577411338847;28272.15183544497;1057.0909018244304;1060.3796778884284;1078.2075174746556;1095.7453715495712;1160.5241850841114;11020.944895275632;13821.861781382258;14373.496927607484;14686.678198962145;14765.33555989983;460141;463489;465549;466090;466208;45113.54886451159;55629;55803.162952555744;55854.72072639692;56045.719771401695;1352.8241328627216;1458.4749490183526;1481.5555333399936;1497.655539225984;1500.932458039369;28369.007084751935;28906.710932891245;28972;29539.409211816073;29742.405151896408;46404.53595464002;47171.52828471671;47235;47238.291425627955;47260;386202;396845;396981;397420;397626;30807.383852323277;30854;36742.63257367392;36838;38108.61891381162;39284.6071539281;39458.80270598629;39577;40899;40959.59040409677;33733.83133084319;34229.48655770116;34359.141021474665;34372;35067.82466087653;33384.831530897274;33396.33207335791;33872.66127338695;33905.15237119092;34175.31649366949;33151.83968561089;35396.46905296371;35624.28751424905;35629.93110206698;35827;22927.65608515907;28768.712312876603;29588.260293492836;29769.702302976693;30986.760529579467;23183.5552114297;23361.76638233664;23558.882205588863;23592.646110308015;23609.173678921154 16:28:50 INFO - PROCESS | 1660 | |2;jquery.html;338.55480043742494;384.3638777822704;389.6006593241926;394.5265681182582;403.247942587075;14598.562043138696;15682.451114210977;19418.611627767437;21135;21196.788032119675;20231;20506.794932050674;20515.384538463833;21208.36374908751;21269.149234030658;5265.526102650771;6044.184035155264;6063.848403789919;6075.362086980847;6077.39226077391;4152.480939882515;5736.741846616912;5890.764369425217;6625.337466253361;6645.368689974444;6492.675366231693;6556.18047744032;6589.406953374195;6596.307387724291;6597.93402065978;6486.837829054288;6693.1298931138645;6698.028785826053;6700.463962882957;6703.095082163943;5676.602637815355;5746.856328591777;5816.418358164192;5870.178175055496;5880.9411905880825;25696.48607027849;26145.816669332817;26403.73596264032;26465;26845.194644160707;2065.163608738463;2126.223928266187;2164.6861205125324;2183.912643494258;2361.244401791427;14557.12657240569;14622.926885365505;14857.777133343008;14989.400423983028;15059.774103388458;1709.2223038517445;1749.2157998841237;1751.6058886750507;1759.1380223690428;1857.1457129720345;94038;100633;102987.48506257495;104226.95773042248;104600.47699761529;10833.620823271147;10856.294340867818;11189;11202.709994603934;11460.369679667532;12686.302253376152;13367.197968121944;14230;14236.217008064448;14242.786358204636;7918.841623167619;8240.876386854203;8382.371322150862;8433.578321083922;8490.787730306793;6721.932780672229;8098.190180981855;8137.796555086052;8151.5109093454585;8251.051129120227;6893.276205998398;7031.718731250693;7033.7538186164265;7059.011738356634;7076.433885289164;1714.2542993797738;1846.4091490723108;1854.6290741851683;1930.1025023364202;1941.9708704369446;900.5002223765873;948.9620415183315;984.4093543873619;1047.539082803564;1067.0983019348637;20998.370048898556;21427.89286053591;21531.354059378245;21752.456188595093;21758.456038598844;916.9862452063225;978.192990782593;1019.50553981319;1036.4713995862014;1079.2445288298065;11348.011314207855;11523.53905843757;11630.53477860876;11686.415679216005;11757.000654944426;563925;574687;602724;603344;605876;101409;104609.47695261474;105420.94579054111;106718.46640766747;108046.3793043105;1031.515187861704;1032.2361452525076;1038.6157121864958;1090.2041509697965;1094.5348227003492;17286.04926729042;17540.73688894642;17594.64810703753;17682.823171768632;17942.013189274192;33737.83131084329;40199.79900100481;44636.55363446454;45314.546854531036;45982;54059.72970135124;59268;60131;65003;66380;7326.450516211199;7748.263914928073;7879.172686867796;8060.959695201486;8133.34933205354;7316.524425912298;10725.570977160825;12044.879551204376;12079.75840483203;12438.626841194779;10399;10439.37363758177;10652.09457196147;10731.40977246259;10857;10278.640247591598;10484.79030419372;10498.705987363981;10762.623308184176;10855.294405863593;9087.500187489752;9384.404651209174;10724.89275107239;10937.398443085707;11039.558417663204;7762.68949242024;8019.91980080215;8100.0684921234815;8376.162383761575;8407.411481196257;10377.325473844176;10754.569817207537;10976.771888028652;11107.611233606785;11117.777644447227 16:28:50 INFO - PROCESS | 1660 | |3;mootools.html;1427.814384130063;1620.4004518328218;1634.1502418742255;1890.1777726688892;2017.263698749956;7732.768016959493;8485.448445851023;8657.177568131034;8670.566471676406;8671.653133874654;8335.95832020839;8556.229939305462;8565.143485651432;8568.271696905755;8572.22849943505;2121.9787802122014;2352.5530149271667;2374.7625237476286;2423.781859632633;2438.3050830513307;2424.599941009731;2444.669969554105;2453.7423570525107;2454.7054353477606;2455.58255096634;2409.819263555232;2433.221369161869;2448.3634255093716;2452.3256104571296;2456.938576535592;2120.68189771534;2346.2843832631042;2418.3470462974988;2430.6597076409316;2433.1848830641757;2378.2746262389965;2402.159244264511;2404.579198640231;2405.9654348630083;2407.9277621671376;8567.228949394523;8637.222649961472;8642.913570864273;8650.43772154808;8659.220670139717;5024.447310795809;5029.145045342264;5240.973795131038;5304.496072873071;5311.681299122065;3415.3681568909833;3478.095695119262;3561.287742451494;3571.2678900825267;3594.712423006153;938.8028514012066;978.6868202175307;983.1446641421937;1018.7096677446892;1032.5043978890176;11812.468438920268;11836.526538938431;11837.704057398549;11859.932606065495;11867.703307417298;4640.187967105743;4719.174144524694;4773.952260477351;4796.400449943757;4834.758262086882;9986.001399859955;10065.647702330383;10069.244806639532;10078.14335781452;10102.848457273147;5288.68267903927;5293.5765138789575;5322.733863306819;5335.813246536352;5347.946520534745;4121.62905338515;4302.247106756305;4436.866893993185;4450.977745111254;4460.888477788081;3963.722539422213;3989.2819292527624;4062.025113972625;4087.9182416351505;4091.3044782387065;1305.6670549009941;1337.5519201067752;1340.1490053815896;1342.2416334770824;1354.837419509665;887.1970866365821;907.686848037426;926.4626516620344;929.1451864284874;929.8605209218671;5865.94134058654;5899.233099697012;5941.435563621449;6065.727042283108;6094.786682466181;911.8905731312285;953.4363643624462;957.3011701457974;958.0706714486937;987.9950600246952;3667.3582123128344;3770.490983717218;3837.9808100959317;3865.40086286626;3874.341361968472;10172.593096276067;10618.469076546142;10713.517891694737;10720.517576709066;10755.408452535186;10412.271141020055;10661.946690266654;10748.785024299626;10838.620648277272;11021.008109270044;8110.067342255598;8184.959075204705;8359.373047021376;8360.83278334442;8374.665013399517;5561.749721262634;5672.347680016851;5763.971180144073;5804.709764511842;5807.274090738658;9897.65358212488;10057.094861462503;10126.797464050826;10150.644727434505;10167;8445.282151016987;9719.56261968199;10193.74515637115;10332.276740628084;10340.948295258475;6421.486281097595;6628.27089020217;6848.897266541106;6928.896066559107;7051.717931282691;8230.876536852073;8240;8328.958355208186;8331.791705207419;8338.916610833814;7189.352958233784;7361.521501102411;7425.9628701856145;7453.9254607455405;7549;6098.969505152446;7236.457265704988;7263.5641861486365;7317.743878964424;7387.445941554298;7026.437884969088;7081.681324340314;7144.607046612487;7147.249538798351;7157.856842863218;4749.976250118727;4789.521047895182;4849.612031037579;4923.163062279349;5032.471590482946;4807.663463557517;4870.318155458168;4880.292357608198;4927.876803079808;5127.256732710896 16:28:50 INFO - PROCESS | 1660 | |4;prototype.html;283.9219214715953;312.83732459121256;313.71451978699383;321.8036997431567;325.5507399788292;4669.902572895371;4923.138450771115;5337.599680023999;5374.758135883885;5389.218563308318;5290.444503327143;5315.627906046579;5328.387235467922;5330.493603107699;5387.676739395639;3231.6929891660257;3920.549136849263;3953.5058117735284;4054.0472988847596;4065.125997910449;3460.913477163066;4051.372037334203;4109.198706252282;4127.215828992482;4141.544430112685;4076.266272071026;4096.631303182713;4100.282450571138;4100.5489396166395;4105.261053010457;3173.8417875844416;3362.714169295613;3917.6278253565865;4092.1968451610446;4122.587741225883;3537.398642230827;3587.7488575799703;3588.3540962626716;3592.8383222755037;3602.4596310553366;4537.705049171794;5114.394351981881;5138.460461651547;5161.148410512261;5179.892064761151;4559.498455169929;4625.653076019312;4655.7439340836245;4724.007958328755;4740.573348398626;4719.103370359655;5053.8231161909525;5111.207762796773;5643.520300774439;5722.542196624302;3369.792785725354;3375.261740203797;3792.317382871082;3980.3034468968103;4008.198360327917;5216.921746173811;5466.1527463243265;5556.833295001156;5917.260342457193;5941.375760785917;3843.3466310727244;3988.142549351903;4026.5369482509886;4041.2523683118716;4055.452513910643;3633.3096711624707;3962.9405558916637;4080.489938757655;4121.567235440296;4132.669386449077;3904.2972264992572;4184.497860256789;4216.683748718797;4435.119270638192;4700.811967521261;4695.5069717679835;5160.509751573594;5489.780408783684;5655.688937108499;5677.971610142005;1264.9557265495848;1331.6870535424168;1502.2338607310132;1505.3226048278318;1508.5398953319343;858.2576071697937;942.7407462947745;994.9751256218652;1008.5915204342253;1020.5815615597569;5418.376886658006;5683.346415162227;5684.8863022739315;5739.253896993364;5832.737526811219;5231.869203269949;6326.49388048955;6331.366863313724;6336.904946425807;6342.682865856688;4519.0058187198765;5120.769565369567;5424.810131645373;5446.537044351196;5757.65454072757;2691.838489690625;2935.8532073396245;3250.7886987345746;3388.8644454221553;3406.7785593936314;5838.883222335529;6160.907586386208;6679.432248258952;6739.629320387426;6752.864942701119;4915.11527924977;5775.740091695884;5805.216295800013;6009.609375390585;6083.65019011409;5091.770870310845;5506.504414602705;5642.633228840113;6018.916595012852;6129.938700612936;5125.001873903808;5375.704336261543;5678.2050512929045;5743.8564035899435;5884.911726324194;4672.262190247856;5781.248437703071;5790.652560846363;5791.565632577658;5803.836908884584;4700.835470758507;5628.8742251550675;5943.405659434022;6107.969460152671;6117.541184411098;3438.5529881115294;3873.941890871583;3910.472086268431;3910.804459776943;3965.728274173876;4827.468978412442;5776.306843178845;5852.678102704393;5873.916193998014;5913.379095194942;5467.644603100785;5509.834704958697;5582.972085139549;5704.80033198836;5714.685692286964;4941.950580494149;5166.689998600096;5307.256984022317;5639.746211420578;5651.124075768263;5062.848289477031;5343.465653434625;5470.261514695465;5574.554035677217;5597.888042239214;5014.623903207201;5329.520343169133;5481.013417584873;5751.798687045934;5752.223449834219;4782.971661092882;4849.927251091166;5466.808661696821;5583.218349431164;5627.915581266366 16:28:50 INFO - PROCESS | 1660 | |5;yui.html;653.2194028136378;709.8402859356645;738.1326940844509;751.1286907187662;754.445482570311;14565.635859103515;14963.925180374083;15135;15350.46273380432;15673.294701738421;14312.856871431282;14450.566483005527;14497.130172189649;14519.854801451982;14519.927400362983;722.902408174895;750.3696894608528;917.811848571043;935.3826474526813;937.3110763588791;900.9009009009004;936.9718908432758;937.4609599480292;937.4656445825882;939.1500691873865;975.9902400976006;985.6205360936056;986.4919566423298;986.7138529826342;987.6197663899378;750.84983003399;935.050923312838;973.090160699748;979.6816034788686;979.8677178580907;721.462510429728;721.8123287945119;722.4653756220412;722.6350692900052;722.833748237903;14817.70364592702;14914.776278355834;14915.850841491663;14918.776218356734;14927.25363731809;12049.156559040783;12271.447784849703;12351.197172183869;12427.37863106841;12479.625611231677;9944.651937182163;9998;10020.248481363855;10355.637552685695;10747.408892510986;3499.562554680665;3506.912327191815;3533.1873669056004;3561.6972557332656;3585.515955346021;75028;77085.61457192678;77170.61414692947;77212.22787772163;77256.45487090283;9273.119053689888;9436.150746432853;9448.244140468745;9624.133827955518;9625.807483850283;21362.679559806616;21438.78561214399;21576.892115539322;21625;21640.675389869164;7324.707011719472;7349.0078839357075;7439.404847612178;7511.32398084175;7733.690652373954;3977.025628720982;4027.496562929634;4063.89840253996;4076.449679293317;4309.913801723948;3354.26206234628;3380.509826075205;3440.346334196495;3440.690337869604;3443.1908501502126;604.0938592111833;605.2071785960318;606.8452544601098;615.793709107445;626.7305058824749;557.842476860508;601.2364297342351;606.1846816032429;606.918066061076;608.996955015222;7818.530888146729;7966;7981.382434166815;8029.397795165386;8308.54303013328;506.73902939985936;523.7433657507794;525.4430303877901;530.1385248970423;606.0454783715666;3062.754979601671;3745.831437585315;3752.7185461090526;3779.6976241900584;3854.094287842355;91722.08277917269;92900;94626.5268673666;98508.50745746226;98780.50609746906;74784.62607687035;76444;79480;79819.20180798233;80565.19434805577;67655.66172169108;67975.66012169908;68076.6596167016;68887;71460.57078858394;7506.512076714996;7624.351930086005;7642.4650274480255;7653.043369578802;7735.9613201933635;41933.37099943564;42157;43106.353404698326;43266;43527.78236108799;46469.76765116288;60057.399426005184;60389;60884.69557652184;61647;10000.899991000288;10331.741706457398;10419;10505.737356566147;10605.204609654153;11205.215634905479;11277.830832537682;11316.20786544934;11371.97652211305;11868.762624747284;10326.535305911402;10366.429846358522;10478.266521343432;10478.947605261925;10491.527881245514;8298.460600061218;10120.493975301059;10463.790724185626;10496.265261431627;10803.945980270048;10015.949920250643;10081.092701656886;10124.291299608956;10188.490575471049;10456.843147352947;8690.826183476422;8769;8919.286457083288;8932.46405215689;9090.454572725657;8571.65538730236;8590;8646.43798153118;8723.302135828992;8822.2060014599 16:28:50 INFO - PROCESS | 1660 | __end_tp_report 16:28:50 INFO - PROCESS | 1660 | __start_cc_report 16:28:50 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1260 16:28:50 INFO - PROCESS | 1660 | __end_cc_report 16:28:50 INFO - PROCESS | 1660 | __startTimestamp1461713330275__endTimestamp 16:28:50 INFO - PROCESS | 1660 | 16:28:50 INFO - PROCESS | 1660 | ------- Summary: start ------- 16:28:50 INFO - PROCESS | 1660 | Number of tests: 6 16:28:50 INFO - PROCESS | 1660 | 16:28:50 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5710.83 Median:5914.67 stddev:636.93 (10.8%) stddev-sans-first:597.34 16:28:50 INFO - PROCESS | 1660 | Values: 2826.4 3443.2 3468.2 3469.3 3514.3 5542.9 5597.8 5698.1 5704.1 5747.5 5603.0 5671.8 5672.7 5682.7 5706.5 5146.7 5171.8 5196.9 5214.4 5260.1 5271.8 5272.4 5279.9 5284.5 5285.0 5198.5 5254.0 5266.5 5268.4 5292.5 5282.3 5321.5 5326.1 5331.6 5340.5 5077.8 5140.3 5206.7 5243.0 5245.4 5473.9 5535.4 5554.4 5595.8 5646.5 6152.4 6190.4 6201.0 6204.5 6206.3 6224.7 6280.9 6289.2 6290.8 6293.9 4867.8 5663.0 5762.5 5768.0 5893.4 5915.7 6232.0 6249.6 6279.5 6281.3 6139.8 6180.2 6181.8 6187.1 6199.7 6094.1 6148.5 6277.7 6280.2 6299.9 5283.6 5933.0 6081.8 6091.1 6095.8 6088.5 6090.5 6145.8 6152.6 6167.4 4917.6 4927.0 4944.0 4967.0 5012.3 4417.7 4420.4 4425.4 4443.3 4484.3 4697.4 5679.9 5710.6 5724.6 5771.3 6002.4 6136.8 6145.7 6205.8 6237.8 5741.0 5775.0 5775.9 5785.7 5789.3 5421.3 5430.9 5432.6 5436.2 5443.1 5331.7 6216.5 6232.3 6265.0 6311.3 6294.5 6304.0 6318.0 6318.1 6323.5 6257.4 6286.7 6309.8 6310.7 6320.3 5914.7 6101.3 6101.6 6179.8 6207.4 6291.4 6298.8 6300.6 6314.3 6318.2 5252.0 6124.0 6124.1 6127.4 6143.5 5718.6 5832.9 5842.1 6008.6 6091.4 5946.7 5994.3 6008.8 6012.7 6028.6 6120.6 6153.5 6176.4 6180.1 6195.7 5802.3 5940.3 5945.1 6010.4 6078.4 16:28:50 INFO - PROCESS | 1660 | 16:28:50 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:53447.20 Median:18648.04 stddev:107819.63 (578.2%) stddev-sans-first:108048.87 16:28:50 INFO - PROCESS | 1660 | Values: 524.1 546.8 579.0 587.4 616.8 25683.0 29859.6 36596.8 37630.6 37867.4 41052.8 41442.0 41454.6 41506.4 41577.2 8271.2 9392.1 9646.5 9648.1 9658.8 8343.4 8525.7 10989.0 11244.1 11246.3 11157.2 11331.5 11354.4 11355.7 11357.8 8352.5 11120.5 11221.3 11339.3 11352.9 11190.2 11344.0 11345.8 11348.3 11349.9 36549.5 37031.9 37058.0 37190.8 37203.3 3020.8 3123.5 3401.8 3414.5 3731.6 22009.8 26668.6 27048.7 27513.7 28902.0 2566.1 2598.8 2631.3 2650.1 2705.3 278232.0 362201.0 369003.0 369842.0 370172.0 32623.3 33282.3 33353.5 57426.7 57913.7 34226.0 34309.1 34355.0 34357.8 34409.7 14272.9 14432.0 14438.5 14445.2 14448.1 9165.4 11209.7 12032.5 12259.7 12349.0 7943.6 7957.3 7968.7 8049.3 8052.8 3355.2 3388.2 3390.9 3409.6 3418.9 903.9 928.9 992.8 1041.9 1095.8 21404.9 24336.9 27369.6 28172.6 28272.2 1057.1 1060.4 1078.2 1095.7 1160.5 11020.9 13821.9 14373.5 14686.7 14765.3 460141.0 463489.0 465549.0 466090.0 466208.0 45113.5 55629.0 55803.2 55854.7 56045.7 1352.8 1458.5 1481.6 1497.7 1500.9 28369.0 28906.7 28972.0 29539.4 29742.4 46404.5 47171.5 47235.0 47238.3 47260.0 386202.0 396845.0 396981.0 397420.0 397626.0 30807.4 30854.0 36742.6 36838.0 38108.6 39284.6 39458.8 39577.0 40899.0 40959.6 33733.8 34229.5 34359.1 34372.0 35067.8 33384.8 33396.3 33872.7 33905.2 34175.3 33151.8 35396.5 35624.3 35629.9 35827.0 22927.7 28768.7 29588.3 29769.7 30986.8 23183.6 23361.8 23558.9 23592.6 23609.2 16:28:50 INFO - PROCESS | 1660 | 16:28:50 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:32993.39 Median:11848.02 stddev:97484.18 (822.8%) stddev-sans-first:97726.81 16:28:50 INFO - PROCESS | 1660 | Values: 338.6 384.4 389.6 394.5 403.2 14598.6 15682.5 19418.6 21135.0 21196.8 20231.0 20506.8 20515.4 21208.4 21269.1 5265.5 6044.2 6063.8 6075.4 6077.4 4152.5 5736.7 5890.8 6625.3 6645.4 6492.7 6556.2 6589.4 6596.3 6597.9 6486.8 6693.1 6698.0 6700.5 6703.1 5676.6 5746.9 5816.4 5870.2 5880.9 25696.5 26145.8 26403.7 26465.0 26845.2 2065.2 2126.2 2164.7 2183.9 2361.2 14557.1 14622.9 14857.8 14989.4 15059.8 1709.2 1749.2 1751.6 1759.1 1857.1 94038.0 100633.0 102987.5 104227.0 104600.5 10833.6 10856.3 11189.0 11202.7 11460.4 12686.3 13367.2 14230.0 14236.2 14242.8 7918.8 8240.9 8382.4 8433.6 8490.8 6721.9 8098.2 8137.8 8151.5 8251.1 6893.3 7031.7 7033.8 7059.0 7076.4 1714.3 1846.4 1854.6 1930.1 1942.0 900.5 949.0 984.4 1047.5 1067.1 20998.4 21427.9 21531.4 21752.5 21758.5 917.0 978.2 1019.5 1036.5 1079.2 11348.0 11523.5 11630.5 11686.4 11757.0 563925.0 574687.0 602724.0 603344.0 605876.0 101409.0 104609.5 105420.9 106718.5 108046.4 1031.5 1032.2 1038.6 1090.2 1094.5 17286.0 17540.7 17594.6 17682.8 17942.0 33737.8 40199.8 44636.6 45314.5 45982.0 54059.7 59268.0 60131.0 65003.0 66380.0 7326.5 7748.3 7879.2 8061.0 8133.3 7316.5 10725.6 12044.9 12079.8 12438.6 10399.0 10439.4 10652.1 10731.4 10857.0 10278.6 10484.8 10498.7 10762.6 10855.3 9087.5 9384.4 10724.9 10937.4 11039.6 7762.7 8019.9 8100.1 8376.2 8407.4 10377.3 10754.6 10976.8 11107.6 11117.8 16:28:50 INFO - PROCESS | 1660 | 16:28:50 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5666.29 Median:4842.19 stddev:3176.94 (65.6%) stddev-sans-first:3169.88 16:28:50 INFO - PROCESS | 1660 | Values: 1427.8 1620.4 1634.2 1890.2 2017.3 7732.8 8485.4 8657.2 8670.6 8671.7 8336.0 8556.2 8565.1 8568.3 8572.2 2122.0 2352.6 2374.8 2423.8 2438.3 2424.6 2444.7 2453.7 2454.7 2455.6 2409.8 2433.2 2448.4 2452.3 2456.9 2120.7 2346.3 2418.3 2430.7 2433.2 2378.3 2402.2 2404.6 2406.0 2407.9 8567.2 8637.2 8642.9 8650.4 8659.2 5024.4 5029.1 5241.0 5304.5 5311.7 3415.4 3478.1 3561.3 3571.3 3594.7 938.8 978.7 983.1 1018.7 1032.5 11812.5 11836.5 11837.7 11859.9 11867.7 4640.2 4719.2 4774.0 4796.4 4834.8 9986.0 10065.6 10069.2 10078.1 10102.8 5288.7 5293.6 5322.7 5335.8 5347.9 4121.6 4302.2 4436.9 4451.0 4460.9 3963.7 3989.3 4062.0 4087.9 4091.3 1305.7 1337.6 1340.1 1342.2 1354.8 887.2 907.7 926.5 929.1 929.9 5865.9 5899.2 5941.4 6065.7 6094.8 911.9 953.4 957.3 958.1 988.0 3667.4 3770.5 3838.0 3865.4 3874.3 10172.6 10618.5 10713.5 10720.5 10755.4 10412.3 10661.9 10748.8 10838.6 11021.0 8110.1 8185.0 8359.4 8360.8 8374.7 5561.7 5672.3 5764.0 5804.7 5807.3 9897.7 10057.1 10126.8 10150.6 10167.0 8445.3 9719.6 10193.7 10332.3 10340.9 6421.5 6628.3 6848.9 6928.9 7051.7 8230.9 8240.0 8329.0 8331.8 8338.9 7189.4 7361.5 7426.0 7453.9 7549.0 6099.0 7236.5 7263.6 7317.7 7387.4 7026.4 7081.7 7144.6 7147.2 7157.9 4750.0 4789.5 4849.6 4923.2 5032.5 4807.7 4870.3 4880.3 4927.9 5127.3 16:28:50 INFO - PROCESS | 1660 | 16:28:50 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4601.22 Median:5117.58 stddev:1421.72 (27.8%) stddev-sans-first:1388.29 16:28:50 INFO - PROCESS | 1660 | Values: 283.9 312.8 313.7 321.8 325.6 4669.9 4923.1 5337.6 5374.8 5389.2 5290.4 5315.6 5328.4 5330.5 5387.7 3231.7 3920.5 3953.5 4054.0 4065.1 3460.9 4051.4 4109.2 4127.2 4141.5 4076.3 4096.6 4100.3 4100.5 4105.3 3173.8 3362.7 3917.6 4092.2 4122.6 3537.4 3587.7 3588.4 3592.8 3602.5 4537.7 5114.4 5138.5 5161.1 5179.9 4559.5 4625.7 4655.7 4724.0 4740.6 4719.1 5053.8 5111.2 5643.5 5722.5 3369.8 3375.3 3792.3 3980.3 4008.2 5216.9 5466.2 5556.8 5917.3 5941.4 3843.3 3988.1 4026.5 4041.3 4055.5 3633.3 3962.9 4080.5 4121.6 4132.7 3904.3 4184.5 4216.7 4435.1 4700.8 4695.5 5160.5 5489.8 5655.7 5678.0 1265.0 1331.7 1502.2 1505.3 1508.5 858.3 942.7 995.0 1008.6 1020.6 5418.4 5683.3 5684.9 5739.3 5832.7 5231.9 6326.5 6331.4 6336.9 6342.7 4519.0 5120.8 5424.8 5446.5 5757.7 2691.8 2935.9 3250.8 3388.9 3406.8 5838.9 6160.9 6679.4 6739.6 6752.9 4915.1 5775.7 5805.2 6009.6 6083.7 5091.8 5506.5 5642.6 6018.9 6129.9 5125.0 5375.7 5678.2 5743.9 5884.9 4672.3 5781.2 5790.7 5791.6 5803.8 4700.8 5628.9 5943.4 6108.0 6117.5 3438.6 3873.9 3910.5 3910.8 3965.7 4827.5 5776.3 5852.7 5873.9 5913.4 5467.6 5509.8 5583.0 5704.8 5714.7 4942.0 5166.7 5307.3 5639.7 5651.1 5062.8 5343.5 5470.3 5574.6 5597.9 5014.6 5329.5 5481.0 5751.8 5752.2 4783.0 4849.9 5466.8 5583.2 5627.9 16:28:50 INFO - PROCESS | 1660 | 16:28:50 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:17568.52 Median:606.88 stddev:24992.53 (4118.2%) stddev-sans-first:25030.36 16:28:50 INFO - PROCESS | 1660 | Values: 653.2 709.8 738.1 751.1 754.4 14565.6 14963.9 15135.0 15350.5 15673.3 14312.9 14450.6 14497.1 14519.9 14519.9 722.9 750.4 917.8 935.4 937.3 900.9 937.0 937.5 937.5 939.2 976.0 985.6 986.5 986.7 987.6 750.8 935.1 973.1 979.7 979.9 721.5 721.8 722.5 722.6 722.8 14817.7 14914.8 14915.9 14918.8 14927.3 12049.2 12271.4 12351.2 12427.4 12479.6 9944.7 9998.0 10020.2 10355.6 10747.4 3499.6 3506.9 3533.2 3561.7 3585.5 75028.0 77085.6 77170.6 77212.2 77256.5 9273.1 9436.2 9448.2 9624.1 9625.8 21362.7 21438.8 21576.9 21625.0 21640.7 7324.7 7349.0 7439.4 7511.3 7733.7 3977.0 4027.5 4063.9 4076.4 4309.9 3354.3 3380.5 3440.3 3440.7 3443.2 604.1 605.2 606.8 615.8 626.7 557.8 601.2 606.2 606.9 609.0 7818.5 7966.0 7981.4 8029.4 8308.5 506.7 523.7 525.4 530.1 606.0 3062.8 3745.8 3752.7 3779.7 3854.1 91722.1 92900.0 94626.5 98508.5 98780.5 74784.6 76444.0 79480.0 79819.2 80565.2 67655.7 67975.7 68076.7 68887.0 71460.6 7506.5 7624.4 7642.5 7653.0 7736.0 41933.4 42157.0 43106.4 43266.0 43527.8 46469.8 60057.4 60389.0 60884.7 61647.0 10000.9 10331.7 10419.0 10505.7 10605.2 11205.2 11277.8 11316.2 11372.0 11868.8 10326.5 10366.4 10478.3 10478.9 10491.5 8298.5 10120.5 10463.8 10496.3 10803.9 10015.9 10081.1 10124.3 10188.5 10456.8 8690.8 8769.0 8919.3 8932.5 9090.5 8571.7 8590.0 8646.4 8723.3 8822.2 16:28:50 INFO - PROCESS | 1660 | -------- Summary: end -------- 16:28:50 INFO - PROCESS | 1660 | 16:28:50 INFO - TEST-INFO | 1660: exit 0 16:28:50 INFO - TEST-OK | dromaeo_css | took 1115760ms 16:28:50 INFO - TEST-START | kraken 16:28:50 INFO - operating with platform_type : mac_ 16:28:50 INFO - Initialising browser for kraken test... 16:28:50 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpD9ndqQ/profile http://localhost:49283/getInfo.html) 16:28:52 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 16:28:52 INFO - PROCESS | 1669 | colorDepth:24 16:28:52 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 16:28:52 INFO - PROCESS | 1669 | __metrics 16:28:53 INFO - PROCESS | 1669 | 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" 16:28:53 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:28:53 INFO - TEST-INFO | 1669: exit 0 16:28:53 INFO - Browser initialized. 16:28:53 INFO - Running cycle 1/1 for kraken test... 16:28:53 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpD9ndqQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:29:05 INFO - PROCESS | 1671 | RSS: Main: 239161344 16:29:05 INFO - PROCESS | 1671 | 16:29:08 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 16:29:09 INFO - PROCESS | 1671 | RSS: Main: 286785536 16:29:09 INFO - PROCESS | 1671 | 16:29:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 16:29:12 INFO - PROCESS | 1671 | RSS: Main: 301056000 16:29:12 INFO - PROCESS | 1671 | 16:29:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 16:29:17 INFO - PROCESS | 1671 | RSS: Main: 387121152 16:29:17 INFO - PROCESS | 1671 | 16:29:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 16:29:19 INFO - PROCESS | 1671 | RSS: Main: 288567296 16:29:19 INFO - PROCESS | 1671 | 16:29:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:29:22 INFO - PROCESS | 1671 | RSS: Main: 292667392 16:29:22 INFO - PROCESS | 1671 | 16:29:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 16:29:25 INFO - PROCESS | 1671 | RSS: Main: 328892416 16:29:25 INFO - PROCESS | 1671 | 16:29:28 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 16:29:29 INFO - PROCESS | 1671 | RSS: Main: 332124160 16:29:29 INFO - PROCESS | 1671 | 16:29:31 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 16:29:32 INFO - PROCESS | 1671 | RSS: Main: 329834496 16:29:32 INFO - PROCESS | 1671 | 16:29:34 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:29:34 INFO - PROCESS | 1671 | RSS: Main: 321839104 16:29:34 INFO - PROCESS | 1671 | 16:29:36 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:29:37 INFO - PROCESS | 1671 | RSS: Main: 328679424 16:29:37 INFO - PROCESS | 1671 | 16:29:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:29:40 INFO - PROCESS | 1671 | RSS: Main: 330833920 16:29:40 INFO - PROCESS | 1671 | 16:29:43 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:29:43 INFO - PROCESS | 1671 | RSS: Main: 327081984 16:29:43 INFO - PROCESS | 1671 | 16:29:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:29:47 INFO - PROCESS | 1671 | RSS: Main: 327680000 16:29:47 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 16:29:49 INFO - PROCESS | 1671 | RSS: Main: 391995392 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | __start_tp_report 16:29:49 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 16:29:49 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 16:29:49 INFO - PROCESS | 1671 | |i|pagename|runs| 16:29:49 INFO - PROCESS | 1671 | |0;ai-astar;113;114;108;114;112;112;113;114;107;112 16:29:49 INFO - PROCESS | 1671 | |1;audio-beat-detection;159;121;146;167;120;141;151;151;210;151 16:29:49 INFO - PROCESS | 1671 | |2;audio-dft;185;186;182;196;219;182;186;185;186;185 16:29:49 INFO - PROCESS | 1671 | |3;audio-fft;72;74;87;91;56;59;58;79;85;59 16:29:49 INFO - PROCESS | 1671 | |4;audio-oscillator;94;98;139;78;81;80;122;80;78;125 16:29:49 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;90;87;91;88;89;87;90;87;90;87 16:29:49 INFO - PROCESS | 1671 | |6;imaging-darkroom;95;95;96;95;100;95;98;137;122;95 16:29:49 INFO - PROCESS | 1671 | |7;imaging-desaturate;83;88;84;85;85;84;84;86;86;86 16:29:49 INFO - PROCESS | 1671 | |8;json-parse-financial;58;58;57;58;58;58;59;76;57;58 16:29:49 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;64;62;66;80;71;70;68;68;67;73 16:29:49 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;82;97;104;79;81;83;97;104;105;83 16:29:49 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;141;138;140;136;137;137;149;144;196;144 16:29:49 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;198;205;198;204;197;197;195;198;194;268 16:29:49 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;69;69;71;69;71;80;100;99;75;69 16:29:49 INFO - PROCESS | 1671 | __end_tp_report 16:29:49 INFO - PROCESS | 1671 | __start_cc_report 16:29:49 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1978 16:29:49 INFO - PROCESS | 1671 | __end_cc_report 16:29:49 INFO - PROCESS | 1671 | __startTimestamp1461713389783__endTimestamp 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | ------- Summary: start ------- 16:29:49 INFO - PROCESS | 1671 | Number of tests: 14 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:2.47 (2.2%) stddev-sans-first:2.59 16:29:49 INFO - PROCESS | 1671 | Values: 113.0 114.0 108.0 114.0 112.0 112.0 113.0 114.0 107.0 112.0 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:151.70 Median:151.00 stddev:25.35 (16.8%) stddev-sans-first:26.75 16:29:49 INFO - PROCESS | 1671 | Values: 159.0 121.0 146.0 167.0 120.0 141.0 151.0 151.0 210.0 151.0 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:189.20 Median:186.00 stddev:11.16 (6.0%) stddev-sans-first:11.74 16:29:49 INFO - PROCESS | 1671 | Values: 185.0 186.0 182.0 196.0 219.0 182.0 186.0 185.0 186.0 185.0 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:72.00 Median:76.50 stddev:13.32 (17.4%) stddev-sans-first:14.13 16:29:49 INFO - PROCESS | 1671 | Values: 72.0 74.0 87.0 91.0 56.0 59.0 58.0 79.0 85.0 59.0 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:80.00 stddev:22.94 (28.7%) stddev-sans-first:24.30 16:29:49 INFO - PROCESS | 1671 | Values: 94.0 98.0 139.0 78.0 81.0 80.0 122.0 80.0 78.0 125.0 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:89.50 stddev:1.58 (1.8%) stddev-sans-first:1.59 16:29:49 INFO - PROCESS | 1671 | Values: 90.0 87.0 91.0 88.0 89.0 87.0 90.0 87.0 90.0 87.0 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:102.80 Median:95.00 stddev:14.60 (15.4%) stddev-sans-first:15.22 16:29:49 INFO - PROCESS | 1671 | Values: 95.0 95.0 96.0 95.0 100.0 95.0 98.0 137.0 122.0 95.0 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.45 (1.7%) stddev-sans-first:1.32 16:29:49 INFO - PROCESS | 1671 | Values: 83.0 88.0 84.0 85.0 85.0 84.0 84.0 86.0 86.0 86.0 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:58.00 stddev:5.76 (9.9%) stddev-sans-first:6.07 16:29:49 INFO - PROCESS | 1671 | Values: 58.0 58.0 57.0 58.0 58.0 58.0 59.0 76.0 57.0 58.0 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:69.00 stddev:5.07 (7.3%) stddev-sans-first:5.05 16:29:49 INFO - PROCESS | 1671 | Values: 64.0 62.0 66.0 80.0 71.0 70.0 68.0 68.0 67.0 73.0 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:82.50 stddev:10.83 (13.1%) stddev-sans-first:10.93 16:29:49 INFO - PROCESS | 1671 | Values: 82.0 97.0 104.0 79.0 81.0 83.0 97.0 104.0 105.0 83.0 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:142.50 stddev:17.96 (12.6%) stddev-sans-first:18.95 16:29:49 INFO - PROCESS | 1671 | Values: 141.0 138.0 140.0 136.0 137.0 137.0 149.0 144.0 196.0 144.0 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.40 Median:198.00 stddev:22.27 (11.2%) stddev-sans-first:23.46 16:29:49 INFO - PROCESS | 1671 | Values: 198.0 205.0 198.0 204.0 197.0 197.0 195.0 198.0 194.0 268.0 16:29:49 INFO - PROCESS | 1671 | 16:29:49 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.20 Median:71.00 stddev:12.26 (17.3%) stddev-sans-first:12.64 16:29:49 INFO - PROCESS | 1671 | Values: 69.0 69.0 71.0 69.0 71.0 80.0 100.0 99.0 75.0 69.0 16:29:49 INFO - PROCESS | 1671 | -------- Summary: end -------- 16:29:49 INFO - PROCESS | 1671 | 16:29:50 INFO - TEST-INFO | 1671: exit 0 16:29:50 INFO - TEST-OK | kraken | took 59709ms 16:29:50 INFO - SUITE-END | took 1175s 16:29:50 INFO - Completed test suite (00:19:35) 16:29:50 INFO - javascript benchmark 16:29:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2826.4488424757174, 3443.225274313278, 3468.2023134679025, 3469.2714529948707, 3514.2971405718845, 5542.861428464291, 5597.776088956447, 5698.088305871056, 5704.087346024637, 5747.51146152577, 5603.0, 5671.7731290748425, 5672.716364181795, 5682.744276507557, 5706.514946229565, 5146.742662866851, 5171.793128274864, 5196.8700782480555, 5214.426413094557, 5260.079486089919, 5271.815486457975, 5272.367315922094, 5279.86800329991, 5284.524392804647, 5285.02227087988, 5198.45416231296, 5254.028004819102, 5266.52601265886, 5268.394134674504, 5292.523672869441, 5282.286891269687, 5321.467853214647, 5326.094563924142, 5331.600129990227, 5340.465953404629, 5077.822276220351, 5140.306058682105, 5206.739663016834, 5242.973785131088, 5245.396779370383, 5473.945260547383, 5535.363433205192, 5554.361248456439, 5595.832125036215, 5646.463585959368, 6152.437537631678, 6190.442860142563, 6201.007838745779, 6204.503639708858, 6206.317305096436, 6224.719887605069, 6280.937190628127, 6289.245290565115, 6290.811275661737, 6293.9370606293805, 4867.756612169377, 5663.0, 5762.4525670061275, 5768.0, 5893.44012318829, 5915.669606418374, 6232.0, 6249.562530622813, 6279.529035322369, 6281.309056003836, 6139.84650383744, 6180.165677633462, 6181.752729890754, 6187.133801267826, 6199.659018753921, 6094.116353128771, 6148.538859585459, 6277.654728989949, 6280.183576135073, 6299.874002519924, 5283.630145889828, 5932.970335148297, 6081.787137450168, 6091.14723938649, 6095.817125486243, 6088.543359248074, 6090.451859332681, 6145.8156255312415, 6152.600080994811, 6167.414095660905, 4917.557419832232, 4927.0, 4944.035912997003, 4966.950330496648, 5012.323336349619, 4417.668674849399, 4420.447444069491, 4425.402570652985, 4443.266860967969, 4484.304932735431, 4697.389339385926, 5679.886402271932, 5710.571707121982, 5724.627899186539, 5771.278590176228, 6002.369751176151, 6136.764169924325, 6145.661988590581, 6205.813825585149, 6237.781677641261, 5740.971295143498, 5775.047117225695, 5775.9133612995, 5785.652860828363, 5789.3052833659385, 5421.26812880264, 5430.945690543123, 5432.592555558349, 5436.21174929633, 5443.129099344165, 5331.680099194061, 6216.471599913967, 6232.25212974446, 6264.968675156595, 6311.305756366704, 6294.4964402846745, 6304.022876454157, 6318.0, 6318.115463834974, 6323.462505687068, 6257.374262573706, 6286.65423401699, 6309.779157729457, 6310.6529140895855, 6320.304766475776, 5914.67469289176, 6101.298350689727, 6101.641069425165, 6179.783707570214, 6207.410296021871, 6291.402316779899, 6298.842528936813, 6300.558960872695, 6314.273858506331, 6318.178636777189, 5251.975864706498, 6124.0201567748945, 6124.050772130327, 6127.448529632355, 6143.508519318354, 5718.571107166896, 5832.854178645568, 5842.094475356326, 6008.639481631116, 6091.42131497507, 5946.672932988728, 5994.310654274815, 6008.759649614141, 6012.669303188367, 6028.638281703112, 6120.588748038681, 6153.507719382528, 6176.3514830943905, 6180.072989051679, 6195.659238741732, 5802.332731735904, 5940.316863560746, 5945.078512830518, 6010.398960103955, 6078.3921607838865], "value": 5671.222450113401, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.1220954900541, 546.811350084221, 579.0, 587.4243241623209, 616.78801155729, 25683.0, 29859.55210671839, 36596.81701591495, 37630.62369376305, 37867.431988520126, 41052.79473602635, 41442.0, 41454.58545414545, 41506.37740433896, 41577.16845663085, 8271.17288271174, 9392.107749763762, 9646.469444180568, 9648.083432073941, 9658.806823863553, 8343.374246931475, 8525.658973641079, 10989.010989010987, 11244.100471962223, 11246.325220486797, 11157.163212759035, 11331.546738130464, 11354.43227838609, 11355.716107097305, 11357.829632555518, 8352.457090289172, 11120.499577519031, 11221.270617409884, 11339.2629479084, 11352.94323528377, 11190.160737944687, 11344.0, 11345.829812552818, 11348.319100853892, 11349.886501135048, 36549.45175822365, 37031.889043328745, 37058.0, 37190.81404592987, 37203.25593488115, 3020.75833933287, 3123.500239961595, 3401.812900290483, 3414.453687410027, 3731.589525152231, 22009.779902201095, 26668.59997100045, 27048.729512704816, 27513.724862751318, 28902.0, 2566.127516644331, 2598.753118453744, 2631.2911915862273, 2650.112212408845, 2705.269577214141, 278232.0, 362201.0, 369002.9999999973, 369842.0, 370172.0, 32623.347533049207, 33282.3343533128, 33353.49969750456, 57426.7128664354, 57913.71043144842, 34226.0, 34309.142271443416, 34355.0, 34357.82821085928, 34409.655903440645, 14272.857271427152, 14432.0, 14438.494652687315, 14445.205513696848, 14448.133112013313, 9165.358424910326, 11209.71975700614, 12032.458539365574, 12259.693507662201, 12349.0, 7943.642536085775, 7957.283844454027, 7968.721094761656, 8049.315808156372, 8052.758417247492, 3355.194753259249, 3388.186835159544, 3390.9321813564084, 3409.62494125646, 3418.888811181813, 903.915301638037, 928.9117533834275, 992.8212921674168, 1041.9218558608134, 1095.780843831237, 21404.892975535022, 24336.87831560866, 27369.589456157777, 28172.577411338847, 28272.15183544497, 1057.0909018244304, 1060.3796778884284, 1078.2075174746556, 1095.7453715495712, 1160.5241850841114, 11020.944895275632, 13821.861781382258, 14373.496927607484, 14686.678198962145, 14765.33555989983, 460141.0, 463489.0, 465549.0, 466090.0, 466208.0, 45113.54886451159, 55629.0, 55803.162952555744, 55854.72072639692, 56045.719771401695, 1352.8241328627216, 1458.4749490183526, 1481.5555333399936, 1497.655539225984, 1500.932458039369, 28369.007084751935, 28906.710932891245, 28972.0, 29539.409211816073, 29742.405151896408, 46404.53595464002, 47171.52828471671, 47235.0, 47238.291425627955, 47260.0, 386202.0, 396845.0, 396981.0, 397420.0, 397626.0, 30807.383852323277, 30854.0, 36742.63257367392, 36838.0, 38108.61891381162, 39284.6071539281, 39458.80270598629, 39577.0, 40899.0, 40959.59040409677, 33733.83133084319, 34229.48655770116, 34359.141021474665, 34372.0, 35067.82466087653, 33384.831530897274, 33396.33207335791, 33872.66127338695, 33905.15237119092, 34175.31649366949, 33151.83968561089, 35396.46905296371, 35624.28751424905, 35629.93110206698, 35827.0, 22927.65608515907, 28768.712312876603, 29588.260293492836, 29769.702302976693, 30986.760529579467, 23183.5552114297, 23361.76638233664, 23558.882205588863, 23592.646110308015, 23609.173678921154], "value": 17511.678542494097, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.55480043742494, 384.3638777822704, 389.6006593241926, 394.5265681182582, 403.247942587075, 14598.562043138696, 15682.451114210977, 19418.611627767437, 21135.0, 21196.788032119675, 20231.0, 20506.794932050674, 20515.384538463833, 21208.36374908751, 21269.149234030658, 5265.526102650771, 6044.184035155264, 6063.848403789919, 6075.362086980847, 6077.39226077391, 4152.480939882515, 5736.741846616912, 5890.764369425217, 6625.337466253361, 6645.368689974444, 6492.675366231693, 6556.18047744032, 6589.406953374195, 6596.307387724291, 6597.93402065978, 6486.837829054288, 6693.1298931138645, 6698.028785826053, 6700.463962882957, 6703.095082163943, 5676.602637815355, 5746.856328591777, 5816.418358164192, 5870.178175055496, 5880.9411905880825, 25696.48607027849, 26145.816669332817, 26403.73596264032, 26465.0, 26845.194644160707, 2065.163608738463, 2126.223928266187, 2164.6861205125324, 2183.912643494258, 2361.244401791427, 14557.12657240569, 14622.926885365505, 14857.777133343008, 14989.400423983028, 15059.774103388458, 1709.2223038517445, 1749.2157998841237, 1751.6058886750507, 1759.1380223690428, 1857.1457129720345, 94038.0, 100633.0, 102987.48506257495, 104226.95773042248, 104600.47699761529, 10833.620823271147, 10856.294340867818, 11189.0, 11202.709994603934, 11460.369679667532, 12686.302253376152, 13367.197968121944, 14230.0, 14236.217008064448, 14242.786358204636, 7918.841623167619, 8240.876386854203, 8382.371322150862, 8433.578321083922, 8490.787730306793, 6721.932780672229, 8098.190180981855, 8137.796555086052, 8151.5109093454585, 8251.051129120227, 6893.276205998398, 7031.718731250693, 7033.7538186164265, 7059.011738356634, 7076.433885289164, 1714.2542993797738, 1846.4091490723108, 1854.6290741851683, 1930.1025023364202, 1941.9708704369446, 900.5002223765873, 948.9620415183315, 984.4093543873619, 1047.539082803564, 1067.0983019348637, 20998.370048898556, 21427.89286053591, 21531.354059378245, 21752.456188595093, 21758.456038598844, 916.9862452063225, 978.192990782593, 1019.50553981319, 1036.4713995862014, 1079.2445288298065, 11348.011314207855, 11523.53905843757, 11630.53477860876, 11686.415679216005, 11757.000654944426, 563925.0, 574687.0, 602724.0, 603344.0, 605876.0, 101409.0, 104609.47695261474, 105420.94579054111, 106718.46640766747, 108046.3793043105, 1031.515187861704, 1032.2361452525076, 1038.6157121864958, 1090.2041509697965, 1094.5348227003492, 17286.04926729042, 17540.73688894642, 17594.64810703753, 17682.823171768632, 17942.013189274192, 33737.83131084329, 40199.79900100481, 44636.55363446454, 45314.546854531036, 45982.0, 54059.72970135124, 59268.0, 60131.0, 65003.0, 66380.0, 7326.450516211199, 7748.263914928073, 7879.172686867796, 8060.959695201486, 8133.34933205354, 7316.524425912298, 10725.570977160825, 12044.879551204376, 12079.75840483203, 12438.626841194779, 10399.0, 10439.37363758177, 10652.09457196147, 10731.40977246259, 10857.0, 10278.640247591598, 10484.79030419372, 10498.705987363981, 10762.623308184176, 10855.294405863593, 9087.500187489752, 9384.404651209174, 10724.89275107239, 10937.398443085707, 11039.558417663204, 7762.68949242024, 8019.91980080215, 8100.0684921234815, 8376.162383761575, 8407.411481196257, 10377.325473844176, 10754.569817207537, 10976.771888028652, 11107.611233606785, 11117.777644447227], "value": 9504.927464067709, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.814384130063, 1620.4004518328218, 1634.1502418742255, 1890.1777726688892, 2017.263698749956, 7732.768016959493, 8485.448445851023, 8657.177568131034, 8670.566471676406, 8671.653133874654, 8335.95832020839, 8556.229939305462, 8565.143485651432, 8568.271696905755, 8572.22849943505, 2121.9787802122014, 2352.5530149271667, 2374.7625237476286, 2423.781859632633, 2438.3050830513307, 2424.599941009731, 2444.669969554105, 2453.7423570525107, 2454.7054353477606, 2455.58255096634, 2409.819263555232, 2433.221369161869, 2448.3634255093716, 2452.3256104571296, 2456.938576535592, 2120.68189771534, 2346.2843832631042, 2418.3470462974988, 2430.6597076409316, 2433.1848830641757, 2378.2746262389965, 2402.159244264511, 2404.579198640231, 2405.9654348630083, 2407.9277621671376, 8567.228949394523, 8637.222649961472, 8642.913570864273, 8650.43772154808, 8659.220670139717, 5024.447310795809, 5029.145045342264, 5240.973795131038, 5304.496072873071, 5311.681299122065, 3415.3681568909833, 3478.095695119262, 3561.287742451494, 3571.2678900825267, 3594.712423006153, 938.8028514012066, 978.6868202175307, 983.1446641421937, 1018.7096677446892, 1032.5043978890176, 11812.468438920268, 11836.526538938431, 11837.704057398549, 11859.932606065495, 11867.703307417298, 4640.187967105743, 4719.174144524694, 4773.952260477351, 4796.400449943757, 4834.758262086882, 9986.001399859955, 10065.647702330383, 10069.244806639532, 10078.14335781452, 10102.848457273147, 5288.68267903927, 5293.5765138789575, 5322.733863306819, 5335.813246536352, 5347.946520534745, 4121.62905338515, 4302.247106756305, 4436.866893993185, 4450.977745111254, 4460.888477788081, 3963.722539422213, 3989.2819292527624, 4062.025113972625, 4087.9182416351505, 4091.3044782387065, 1305.6670549009941, 1337.5519201067752, 1340.1490053815896, 1342.2416334770824, 1354.837419509665, 887.1970866365821, 907.686848037426, 926.4626516620344, 929.1451864284874, 929.8605209218671, 5865.94134058654, 5899.233099697012, 5941.435563621449, 6065.727042283108, 6094.786682466181, 911.8905731312285, 953.4363643624462, 957.3011701457974, 958.0706714486937, 987.9950600246952, 3667.3582123128344, 3770.490983717218, 3837.9808100959317, 3865.40086286626, 3874.341361968472, 10172.593096276067, 10618.469076546142, 10713.517891694737, 10720.517576709066, 10755.408452535186, 10412.271141020055, 10661.946690266654, 10748.785024299626, 10838.620648277272, 11021.008109270044, 8110.067342255598, 8184.959075204705, 8359.373047021376, 8360.83278334442, 8374.665013399517, 5561.749721262634, 5672.347680016851, 5763.971180144073, 5804.709764511842, 5807.274090738658, 9897.65358212488, 10057.094861462503, 10126.797464050826, 10150.644727434505, 10167.0, 8445.282151016987, 9719.56261968199, 10193.74515637115, 10332.276740628084, 10340.948295258475, 6421.486281097595, 6628.27089020217, 6848.897266541106, 6928.896066559107, 7051.717931282691, 8230.876536852073, 8240.0, 8328.958355208186, 8331.791705207419, 8338.916610833814, 7189.352958233784, 7361.521501102411, 7425.9628701856145, 7453.9254607455405, 7549.0, 6098.969505152446, 7236.457265704988, 7263.5641861486365, 7317.743878964424, 7387.445941554298, 7026.437884969088, 7081.681324340314, 7144.607046612487, 7147.249538798351, 7157.856842863218, 4749.976250118727, 4789.521047895182, 4849.612031037579, 4923.163062279349, 5032.471590482946, 4807.663463557517, 4870.318155458168, 4880.292357608198, 4927.876803079808, 5127.256732710896], "value": 4569.883088732294, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.9219214715953, 312.83732459121256, 313.71451978699383, 321.8036997431567, 325.5507399788292, 4669.902572895371, 4923.138450771115, 5337.599680023999, 5374.758135883885, 5389.218563308318, 5290.444503327143, 5315.627906046579, 5328.387235467922, 5330.493603107699, 5387.676739395639, 3231.6929891660257, 3920.549136849263, 3953.5058117735284, 4054.0472988847596, 4065.125997910449, 3460.913477163066, 4051.372037334203, 4109.198706252282, 4127.215828992482, 4141.544430112685, 4076.266272071026, 4096.631303182713, 4100.282450571138, 4100.5489396166395, 4105.261053010457, 3173.8417875844416, 3362.714169295613, 3917.6278253565865, 4092.1968451610446, 4122.587741225883, 3537.398642230827, 3587.7488575799703, 3588.3540962626716, 3592.8383222755037, 3602.4596310553366, 4537.705049171794, 5114.394351981881, 5138.460461651547, 5161.148410512261, 5179.892064761151, 4559.498455169929, 4625.653076019312, 4655.7439340836245, 4724.007958328755, 4740.573348398626, 4719.103370359655, 5053.8231161909525, 5111.207762796773, 5643.520300774439, 5722.542196624302, 3369.792785725354, 3375.261740203797, 3792.317382871082, 3980.3034468968103, 4008.198360327917, 5216.921746173811, 5466.1527463243265, 5556.833295001156, 5917.260342457193, 5941.375760785917, 3843.3466310727244, 3988.142549351903, 4026.5369482509886, 4041.2523683118716, 4055.452513910643, 3633.3096711624707, 3962.9405558916637, 4080.489938757655, 4121.567235440296, 4132.669386449077, 3904.2972264992572, 4184.497860256789, 4216.683748718797, 4435.119270638192, 4700.811967521261, 4695.5069717679835, 5160.509751573594, 5489.780408783684, 5655.688937108499, 5677.971610142005, 1264.9557265495848, 1331.6870535424168, 1502.2338607310132, 1505.3226048278318, 1508.5398953319343, 858.2576071697937, 942.7407462947745, 994.9751256218652, 1008.5915204342253, 1020.5815615597569, 5418.376886658006, 5683.346415162227, 5684.8863022739315, 5739.253896993364, 5832.737526811219, 5231.869203269949, 6326.49388048955, 6331.366863313724, 6336.904946425807, 6342.682865856688, 4519.0058187198765, 5120.769565369567, 5424.810131645373, 5446.537044351196, 5757.65454072757, 2691.838489690625, 2935.8532073396245, 3250.7886987345746, 3388.8644454221553, 3406.7785593936314, 5838.883222335529, 6160.907586386208, 6679.432248258952, 6739.629320387426, 6752.864942701119, 4915.11527924977, 5775.740091695884, 5805.216295800013, 6009.609375390585, 6083.65019011409, 5091.770870310845, 5506.504414602705, 5642.633228840113, 6018.916595012852, 6129.938700612936, 5125.001873903808, 5375.704336261543, 5678.2050512929045, 5743.8564035899435, 5884.911726324194, 4672.262190247856, 5781.248437703071, 5790.652560846363, 5791.565632577658, 5803.836908884584, 4700.835470758507, 5628.8742251550675, 5943.405659434022, 6107.969460152671, 6117.541184411098, 3438.5529881115294, 3873.941890871583, 3910.472086268431, 3910.804459776943, 3965.728274173876, 4827.468978412442, 5776.306843178845, 5852.678102704393, 5873.916193998014, 5913.379095194942, 5467.644603100785, 5509.834704958697, 5582.972085139549, 5704.80033198836, 5714.685692286964, 4941.950580494149, 5166.689998600096, 5307.256984022317, 5639.746211420578, 5651.124075768263, 5062.848289477031, 5343.465653434625, 5470.261514695465, 5574.554035677217, 5597.888042239214, 5014.623903207201, 5329.520343169133, 5481.013417584873, 5751.798687045934, 5752.223449834219, 4782.971661092882, 4849.927251091166, 5466.808661696821, 5583.218349431164, 5627.915581266366], "value": 4160.119074317473, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.2194028136378, 709.8402859356645, 738.1326940844509, 751.1286907187662, 754.445482570311, 14565.635859103515, 14963.925180374083, 15135.0, 15350.46273380432, 15673.294701738421, 14312.856871431282, 14450.566483005527, 14497.130172189649, 14519.854801451982, 14519.927400362983, 722.902408174895, 750.3696894608528, 917.811848571043, 935.3826474526813, 937.3110763588791, 900.9009009009004, 936.9718908432758, 937.4609599480292, 937.4656445825882, 939.1500691873865, 975.9902400976006, 985.6205360936056, 986.4919566423298, 986.7138529826342, 987.6197663899378, 750.84983003399, 935.050923312838, 973.090160699748, 979.6816034788686, 979.8677178580907, 721.462510429728, 721.8123287945119, 722.4653756220412, 722.6350692900052, 722.833748237903, 14817.70364592702, 14914.776278355834, 14915.850841491663, 14918.776218356734, 14927.25363731809, 12049.156559040783, 12271.447784849703, 12351.197172183869, 12427.37863106841, 12479.625611231677, 9944.651937182163, 9998.0, 10020.248481363855, 10355.637552685695, 10747.408892510986, 3499.562554680665, 3506.912327191815, 3533.1873669056004, 3561.6972557332656, 3585.515955346021, 75028.0, 77085.61457192678, 77170.61414692947, 77212.22787772163, 77256.45487090283, 9273.119053689888, 9436.150746432853, 9448.244140468745, 9624.133827955518, 9625.807483850283, 21362.679559806616, 21438.78561214399, 21576.892115539322, 21625.0, 21640.675389869164, 7324.707011719472, 7349.0078839357075, 7439.404847612178, 7511.32398084175, 7733.690652373954, 3977.025628720982, 4027.496562929634, 4063.89840253996, 4076.449679293317, 4309.913801723948, 3354.26206234628, 3380.509826075205, 3440.346334196495, 3440.690337869604, 3443.1908501502126, 604.0938592111833, 605.2071785960318, 606.8452544601098, 615.793709107445, 626.7305058824749, 557.842476860508, 601.2364297342351, 606.1846816032429, 606.918066061076, 608.996955015222, 7818.530888146729, 7966.0, 7981.382434166815, 8029.397795165386, 8308.54303013328, 506.73902939985936, 523.7433657507794, 525.4430303877901, 530.1385248970423, 606.0454783715666, 3062.754979601671, 3745.831437585315, 3752.7185461090526, 3779.6976241900584, 3854.094287842355, 91722.08277917269, 92900.0, 94626.5268673666, 98508.50745746226, 98780.50609746906, 74784.62607687035, 76444.0, 79480.0, 79819.20180798233, 80565.19434805577, 67655.66172169108, 67975.66012169908, 68076.6596167016, 68887.0, 71460.57078858394, 7506.512076714996, 7624.351930086005, 7642.4650274480255, 7653.043369578802, 7735.9613201933635, 41933.37099943564, 42157.0, 43106.353404698326, 43266.0, 43527.78236108799, 46469.76765116288, 60057.399426005184, 60389.0, 60884.69557652184, 61647.0, 10000.899991000288, 10331.741706457398, 10419.0, 10505.737356566147, 10605.204609654153, 11205.215634905479, 11277.830832537682, 11316.20786544934, 11371.97652211305, 11868.762624747284, 10326.535305911402, 10366.429846358522, 10478.266521343432, 10478.947605261925, 10491.527881245514, 8298.460600061218, 10120.493975301059, 10463.790724185626, 10496.265261431627, 10803.945980270048, 10015.949920250643, 10081.092701656886, 10124.291299608956, 10188.490575471049, 10456.843147352947, 8690.826183476422, 8769.0, 8919.286457083288, 8932.46405215689, 9090.454572725657, 8571.65538730236, 8590.0, 8646.43798153118, 8723.302135828992, 8822.2060014599], "value": 6677.500849787477, "unit": "score"}], "name": "dromaeo_css", "value": 7021.605325602451, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 108.0, 114.0, 112.0, 112.0, 113.0, 114.0, 107.0, 112.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 121.0, 146.0, 167.0, 120.0, 141.0, 151.0, 151.0, 210.0, 151.0], "value": 151.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 182.0, 196.0, 219.0, 182.0, 186.0, 185.0, 186.0, 185.0], "value": 189.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 87.0, 91.0, 56.0, 59.0, 58.0, 79.0, 85.0, 59.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 98.0, 139.0, 78.0, 81.0, 80.0, 122.0, 80.0, 78.0, 125.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 88.0, 89.0, 87.0, 90.0, 87.0, 90.0, 87.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 100.0, 95.0, 98.0, 137.0, 122.0, 95.0], "value": 102.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 84.0, 85.0, 85.0, 84.0, 84.0, 86.0, 86.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 58.0, 58.0, 58.0, 59.0, 76.0, 57.0, 58.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 66.0, 80.0, 71.0, 70.0, 68.0, 68.0, 67.0, 73.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 97.0, 104.0, 79.0, 81.0, 83.0, 97.0, 104.0, 105.0, 83.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 138.0, 140.0, 136.0, 137.0, 137.0, 149.0, 144.0, 196.0, 144.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 205.0, 198.0, 204.0, 197.0, 197.0, 195.0, 198.0, 194.0, 268.0], "value": 205.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 71.0, 69.0, 71.0, 80.0, 100.0, 99.0, 75.0, 69.0], "value": 77.2, "unit": "score"}], "name": "kraken", "value": 1547.7000000000003, "alertThreshold": 2.0}]} 16:29:51 INFO - Return code: 0 16:29:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:29:51 ERROR - # TBPL SUCCESS # 16:29:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:29:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:29:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:29:51 INFO - 2016-04-26 16:29:51.076 screenresolution[1675:9882] starting screenresolution argv=screenresolution get 16:29:51 INFO - 2016-04-26 16:29:51.079 screenresolution[1675:9882] Display 0: 1600x1200x32@60 16:29:51 INFO - 2016-04-26 16:29:51.085 screenresolution[1676:9885] starting screenresolution argv=screenresolution list 16:29:51 INFO - Available Modes on Display 0 16:29:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:29:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:29:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:29:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:29:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:29:51 INFO - Intel Iris: 16:29:51 INFO - Chipset Model: Intel Iris 16:29:51 INFO - Type: GPU 16:29:51 INFO - Bus: Built-In 16:29:51 INFO - VRAM (Dynamic, Max): 1536 MB 16:29:51 INFO - Vendor: Intel (0x8086) 16:29:51 INFO - Device ID: 0x0a2e 16:29:51 INFO - Revision ID: 0x0009 16:29:51 INFO - Displays: 16:29:51 INFO - Display: 16:29:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:29:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:29:51 INFO - Display Serial Number: 1600x1200 60 16:29:51 INFO - Main Display: Yes 16:29:51 INFO - Mirror: Off 16:29:51 INFO - Online: Yes 16:29:51 INFO - Rotation: Supported 16:29:51 INFO - Return code: 0 16:29:51 INFO - Running post-action listener: _resource_record_post_action 16:29:51 INFO - Running post-run listener: _resource_record_post_run 16:29:52 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37787136; Write bytes: 697215488; Read time: 395; Write time: 2043 16:29:52 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 393210368; Write bytes: 388401152; Read time: 29783; Write time: 738 16:29:52 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 35902976; Write bytes: 297382400; Read time: 373; Write time: 1273 16:29:52 INFO - Running post-run listener: _upload_blobber_files 16:29:52 INFO - Blob upload gear active. 16:29:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:29:52 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 16:29:52 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:29:52 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:29:52 INFO - (blobuploader) - INFO - Open directory for files ... 16:29:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:29:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:29:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:29:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:29:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:29:54 INFO - (blobuploader) - INFO - Done attempting. 16:29:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:29:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:29:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:29:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:29:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:29:55 INFO - (blobuploader) - INFO - Done attempting. 16:29:55 INFO - (blobuploader) - INFO - Iteration through files over. 16:29:55 INFO - Return code: 0 16:29:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:29:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:29:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15c6a0de5db7d33205364c216d576c8a31af9f68567026568cbb4db0814461ac817451b7f733ac40df7601b84039f9ca0b36abd46c7cfe74276fbcba036231e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/763ea3e88ca5411fb94ba48e75c1391c22fc0805b0919536f0e359a1fdd94081cacf7427c9b682fcff6d47aacfaca5fba3412a206ac126e6d04b1f071bd2602d"} 16:29:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:29:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:29:55 INFO - Contents: 16:29:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15c6a0de5db7d33205364c216d576c8a31af9f68567026568cbb4db0814461ac817451b7f733ac40df7601b84039f9ca0b36abd46c7cfe74276fbcba036231e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/763ea3e88ca5411fb94ba48e75c1391c22fc0805b0919536f0e359a1fdd94081cacf7427c9b682fcff6d47aacfaca5fba3412a206ac126e6d04b1f071bd2602d"} 16:29:55 INFO - Running post-run listener: copy_logs_to_upload_dir 16:29:55 INFO - Copying logs to upload dir... 16:29:55 INFO - mkdir: /builds/slave/test/build/upload/logs 16:29:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.372445 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-26 16:29:55.226346) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 16:29:55.230829) ========= 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.LEhybUyChU/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.2zjZseR9YR/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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/Firefox/sha512/15c6a0de5db7d33205364c216d576c8a31af9f68567026568cbb4db0814461ac817451b7f733ac40df7601b84039f9ca0b36abd46c7cfe74276fbcba036231e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/763ea3e88ca5411fb94ba48e75c1391c22fc0805b0919536f0e359a1fdd94081cacf7427c9b682fcff6d47aacfaca5fba3412a206ac126e6d04b1f071bd2602d"} build_url:https://queue.taskcluster.net/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011000 build_url: 'https://queue.taskcluster.net/v1/task/WvhkmkHHRl2_ascsJWaYKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15c6a0de5db7d33205364c216d576c8a31af9f68567026568cbb4db0814461ac817451b7f733ac40df7601b84039f9ca0b36abd46c7cfe74276fbcba036231e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/763ea3e88ca5411fb94ba48e75c1391c22fc0805b0919536f0e359a1fdd94081cacf7427c9b682fcff6d47aacfaca5fba3412a206ac126e6d04b1f071bd2602d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 16:29:55.266819) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:29:55.267157) ========= 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.LEhybUyChU/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.2zjZseR9YR/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.005132 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:29:55.318670) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 16:29:55.319051) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 16:29:55.319965) ========= ========= Total master_lag: 0.25 =========