builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0150 starttime: 1469191210.46 results: success (0) buildid: 20160722032726 builduid: ccda849e745c45db9f5fa53e5a98309b revision: 68ca20cf8042a14e44e826e6e7985677c138bfd6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 05:40:10.456408) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 05:40:10.456845) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 05:40:10.457159) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 05:40:10.480582) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 05:40:10.480987) ========= 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.KwIsgyJZRp/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.YT899LOX7E/Listeners TMPDIR=/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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-07-22 05:40:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2016-07-22 05:40:10 (18.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123812 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 05:40:10.626682) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 05:40:10.627011) ========= 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.KwIsgyJZRp/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.YT899LOX7E/Listeners TMPDIR=/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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.020969 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 05:40:10.675736) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 05:40:10.676099) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 68ca20cf8042a14e44e826e6e7985677c138bfd6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 68ca20cf8042a14e44e826e6e7985677c138bfd6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KwIsgyJZRp/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.YT899LOX7E/Listeners TMPDIR=/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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-07-22 05:40:10,751 truncating revision to first 12 chars 2016-07-22 05:40:10,751 Setting DEBUG logging. 2016-07-22 05:40:10,751 attempt 1/10 2016-07-22 05:40:10,751 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/68ca20cf8042?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 05:40:11,045 unpacking tar archive at: mozilla-beta-68ca20cf8042/testing/mozharness/ program finished with exit code 0 elapsedTime=0.557310 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 05:40:11.258555) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 05:40:11.258917) ========= script_repo_revision: 68ca20cf8042a14e44e826e6e7985677c138bfd6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 05:40:11.259320) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-07-22 05:40:11.259600) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-07-22 05:40:14.190116) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-07-22 05:40:14.190503) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KwIsgyJZRp/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.YT899LOX7E/Listeners TMPDIR=/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 05:40:14 INFO - MultiFileLogger online at 20160722 05:40:14 in /builds/slave/test 05:40:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:40:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:40:14 INFO - {'append_to_log': False, 05:40:14 INFO - 'base_work_dir': '/builds/slave/test', 05:40:14 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:40:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:40:14 INFO - 'branch': 'Mozilla-Beta', 05:40:14 INFO - 'buildbot_json_path': 'buildprops.json', 05:40:14 INFO - 'config_files': ('talos/mac_config.py',), 05:40:14 INFO - 'default_actions': ('clobber', 05:40:14 INFO - 'read-buildbot-config', 05:40:14 INFO - 'download-and-extract', 05:40:14 INFO - 'populate-webroot', 05:40:14 INFO - 'create-virtualenv', 05:40:14 INFO - 'install', 05:40:14 INFO - 'run-tests'), 05:40:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:40:14 INFO - 'download_minidump_stackwalk': True, 05:40:14 INFO - 'download_symbols': 'ondemand', 05:40:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:40:14 INFO - 'tooltool.py': '/tools/tooltool.py', 05:40:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:40:14 INFO - '/tools/misc-python/virtualenv.py')}, 05:40:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:40:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:40:14 INFO - 'installer_path': 'installer.exe', 05:40:14 INFO - 'log_level': 'info', 05:40:14 INFO - 'log_name': 'talos', 05:40:14 INFO - 'log_to_console': True, 05:40:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:40:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:40:14 INFO - 'opt_config_files': (), 05:40:14 INFO - 'pip_index': False, 05:40:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:40:14 INFO - 'cmd': ('bash', 05:40:14 INFO - '-c', 05:40:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:40:14 INFO - 'enabled': True, 05:40:14 INFO - 'halt_on_failure': False, 05:40:14 INFO - 'name': 'check_screen_resolution'},), 05:40:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:40:14 INFO - 'cmd': ('bash', 05:40:14 INFO - '-c', 05:40:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:40:14 INFO - 'enabled': True, 05:40:14 INFO - 'halt_on_failure': False, 05:40:14 INFO - 'name': 'check_screen_resolution'},), 05:40:14 INFO - 'run_cmd_checks_enabled': True, 05:40:14 INFO - 'sps_profile': False, 05:40:14 INFO - 'sps_profile_interval': 0, 05:40:14 INFO - 'suite': 'dromaeojs', 05:40:14 INFO - 'system_bits': '32', 05:40:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:40:14 INFO - 'title': 't-yosemite-r7-0150', 05:40:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:40:14 INFO - 'use_talos_json': True, 05:40:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:40:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:40:14 INFO - 'work_dir': 'build'} 05:40:14 INFO - ##### 05:40:14 INFO - ##### Running clobber step. 05:40:14 INFO - ##### 05:40:14 INFO - Running pre-action listener: _resource_record_pre_action 05:40:14 INFO - Running main action method: clobber 05:40:14 INFO - rmtree: /builds/slave/test/build 05:40:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:40:15 INFO - Running post-action listener: _resource_record_post_action 05:40:15 INFO - ##### 05:40:15 INFO - ##### Running read-buildbot-config step. 05:40:15 INFO - ##### 05:40:15 INFO - Running pre-action listener: _resource_record_pre_action 05:40:15 INFO - Running main action method: read_buildbot_config 05:40:15 INFO - Using buildbot properties: 05:40:15 INFO - { 05:40:15 INFO - "project": "", 05:40:15 INFO - "product": "firefox", 05:40:15 INFO - "script_repo_revision": "production", 05:40:15 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 05:40:15 INFO - "repository": "", 05:40:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 05:40:15 INFO - "buildid": "20160722032726", 05:40:15 INFO - "pgo_build": "False", 05:40:15 INFO - "basedir": "/builds/slave/test", 05:40:15 INFO - "buildnumber": 12, 05:40:15 INFO - "slavename": "t-yosemite-r7-0150", 05:40:15 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:40:15 INFO - "platform": "yosemite_r7", 05:40:15 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:40:15 INFO - "branch": "mozilla-beta", 05:40:15 INFO - "repo_path": "releases/mozilla-beta", 05:40:15 INFO - "revision": "68ca20cf8042a14e44e826e6e7985677c138bfd6", 05:40:15 INFO - "stage_platform": "macosx64", 05:40:15 INFO - "builduid": "ccda849e745c45db9f5fa53e5a98309b", 05:40:15 INFO - "slavebuilddir": "test" 05:40:15 INFO - } 05:40:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 05:40:15 INFO - Running post-action listener: _resource_record_post_action 05:40:15 INFO - ##### 05:40:15 INFO - ##### Running download-and-extract step. 05:40:15 INFO - ##### 05:40:15 INFO - Running pre-action listener: _resource_record_pre_action 05:40:15 INFO - Running main action method: download_and_extract 05:40:15 INFO - mkdir: /builds/slave/test/build/tests 05:40:15 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')]} 05:40:15 INFO - https://queue.taskcluster.net/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:40:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 05:40:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 05:40:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 05:40:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 05:40:18 INFO - Downloaded 1165 bytes. 05:40:18 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 05:40:18 INFO - Using the following test package requirements: 05:40:18 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 05:40:18 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:40:18 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 05:40:18 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:40:18 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 05:40:18 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:40:18 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:40:18 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 05:40:18 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 05:40:18 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:40:18 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 05:40:18 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:40:18 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 05:40:18 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:40:18 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 05:40:18 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:40:18 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 05:40:18 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 05:40:18 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')]} 05:40:18 INFO - https://queue.taskcluster.net/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:40:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:40:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:40:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 05:40:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 05:40:20 INFO - Downloaded 18314923 bytes. 05:40:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:40:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:40:23 INFO - Return code: 0 05:40:23 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 05:40:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:23 INFO - https://queue.taskcluster.net/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:40:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:40:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:40:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 05:40:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 05:40:24 INFO - Downloaded 18314923 bytes. 05:40:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:40:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:40:28 INFO - Return code: 0 05:40:28 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')]} 05:40:28 INFO - https://queue.taskcluster.net/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:40:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 05:40:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 05:40:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 05:40:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 05:40:29 INFO - Downloaded 10840145 bytes. 05:40:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:40:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:40:29 INFO - Return code: 0 05:40:29 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')]} 05:40:29 INFO - https://queue.taskcluster.net/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 05:40:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:40:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:40:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 05:40:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 05:40:32 INFO - Downloaded 86191631 bytes. 05:40:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:40:32 INFO - mkdir: /builds/slave/test/properties 05:40:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:40:32 INFO - Writing to file /builds/slave/test/properties/build_url 05:40:32 INFO - Contents: 05:40:32 INFO - build_url:https://queue.taskcluster.net/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:40:33 INFO - Running post-action listener: _resource_record_post_action 05:40:33 INFO - Running post-action listener: set_extra_try_arguments 05:40:33 INFO - ##### 05:40:33 INFO - ##### Running populate-webroot step. 05:40:33 INFO - ##### 05:40:33 INFO - Running pre-action listener: _resource_record_pre_action 05:40:33 INFO - Running main action method: populate_webroot 05:40:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:40:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:40:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:40:33 INFO - '--tppagecycles', 05:40:33 INFO - '7'], 05:40:33 INFO - 'tests': ['tsvgm']}}, 05:40:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:40:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:40:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:40:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:40:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:40:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:40:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:33 INFO - 'talos_options': ['--disable-e10s'], 05:40:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:33 INFO - 'talos_options': ['--disable-e10s'], 05:40:33 INFO - 'tests': ['damp', 'tps']}, 05:40:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:33 INFO - 'tests': ['damp', 'tps']}, 05:40:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:40:33 INFO - 'tests': ['dromaeo_dom']}, 05:40:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:40:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:40:33 INFO - 'tests': ['a11yr', 05:40:33 INFO - 'ts_paint', 05:40:33 INFO - 'tpaint', 05:40:33 INFO - 'sessionrestore', 05:40:33 INFO - 'sessionrestore_no_auto_restore', 05:40:33 INFO - 'tabpaint']}, 05:40:33 INFO - 'other-e10s': {'tests': ['a11yr', 05:40:33 INFO - 'ts_paint', 05:40:33 INFO - 'tpaint', 05:40:33 INFO - 'sessionrestore', 05:40:33 INFO - 'sessionrestore_no_auto_restore', 05:40:33 INFO - 'tabpaint']}, 05:40:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:40:33 INFO - 'ts_paint', 05:40:33 INFO - 'tpaint', 05:40:33 INFO - 'sessionrestore', 05:40:33 INFO - 'sessionrestore_no_auto_restore', 05:40:33 INFO - 'tabpaint']}, 05:40:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:40:33 INFO - 'ts_paint', 05:40:33 INFO - 'tpaint', 05:40:33 INFO - 'sessionrestore', 05:40:33 INFO - 'sessionrestore_no_auto_restore', 05:40:33 INFO - 'tabpaint']}, 05:40:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:40:33 INFO - 'tests': ['a11yr', 05:40:33 INFO - 'ts_paint', 05:40:33 INFO - 'tpaint', 05:40:33 INFO - 'sessionrestore', 05:40:33 INFO - 'sessionrestore_no_auto_restore', 05:40:33 INFO - 'tabpaint']}, 05:40:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:40:33 INFO - 'tests': ['a11yr', 05:40:33 INFO - 'ts_paint', 05:40:33 INFO - 'tpaint', 05:40:33 INFO - 'sessionrestore', 05:40:33 INFO - 'sessionrestore_no_auto_restore', 05:40:33 INFO - 'tabpaint']}, 05:40:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:40:33 INFO - 'tests': ['tsvgx', 05:40:33 INFO - 'tsvgr_opacity', 05:40:33 INFO - 'tart', 05:40:33 INFO - 'tscrollx', 05:40:33 INFO - 'cart']}, 05:40:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:40:33 INFO - 'tsvgr_opacity', 05:40:33 INFO - 'tart', 05:40:33 INFO - 'tscrollx', 05:40:33 INFO - 'cart']}, 05:40:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:33 INFO - 'talos_options': ['--disable-e10s'], 05:40:33 INFO - 'tests': ['tp5o']}, 05:40:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:33 INFO - 'tests': ['tp5o']}, 05:40:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:33 INFO - 'talos_options': ['--disable-e10s', 05:40:33 INFO - '--xperf_path', 05:40:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:33 INFO - 'tests': ['tp5n']}, 05:40:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:33 INFO - 'talos_options': ['--xperf_path', 05:40:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:33 INFO - 'tests': ['tp5n']}}, 05:40:33 INFO - 'talos.zip': {'path': '', 05:40:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:40:33 INFO - Running post-action listener: _resource_record_post_action 05:40:33 INFO - ##### 05:40:33 INFO - ##### Running create-virtualenv step. 05:40:33 INFO - ##### 05:40:33 INFO - Running pre-action listener: _resource_record_pre_action 05:40:33 INFO - Running main action method: create_virtualenv 05:40:33 INFO - Creating virtualenv /builds/slave/test/build/venv 05:40:33 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 05:40:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:40:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:40:33 INFO - Using real prefix '/tools/python27' 05:40:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:40:34 INFO - Installing distribute.............................................................................................................................................................................................done. 05:40:37 INFO - Installing pip.................done. 05:40:37 INFO - Return code: 0 05:40:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f81aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f82a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d94a0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b9e00>, '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 0x10f522db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b4b20>, '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.KwIsgyJZRp/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.YT899LOX7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 05:40:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:40:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:40:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIsgyJZRp/Render', 05:40:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:37 INFO - 'HOME': '/Users/cltbld', 05:40:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:37 INFO - 'LOGNAME': 'cltbld', 05:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:37 INFO - 'MOZ_NO_REMOTE': '1', 05:40:37 INFO - 'NO_EM_RESTART': '1', 05:40:37 INFO - 'PAGER': '/bin/cat', 05:40:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:37 INFO - 'PWD': '/builds/slave/test', 05:40:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:37 INFO - 'SHELL': '/bin/bash', 05:40:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YT899LOX7E/Listeners', 05:40:37 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 05:40:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:37 INFO - 'USER': 'cltbld', 05:40:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:37 INFO - 'XPC_FLAGS': '0x0', 05:40:37 INFO - 'XPC_SERVICE_NAME': '0', 05:40:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:37 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 05:40:37 INFO - Cleaning up... 05:40:37 INFO - Return code: 0 05:40:37 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f81aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f82a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d94a0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b9e00>, '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 0x10f522db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b4b20>, '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.KwIsgyJZRp/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.YT899LOX7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 05:40:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:40:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:40:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIsgyJZRp/Render', 05:40:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:37 INFO - 'HOME': '/Users/cltbld', 05:40:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:37 INFO - 'LOGNAME': 'cltbld', 05:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:37 INFO - 'MOZ_NO_REMOTE': '1', 05:40:37 INFO - 'NO_EM_RESTART': '1', 05:40:37 INFO - 'PAGER': '/bin/cat', 05:40:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:37 INFO - 'PWD': '/builds/slave/test', 05:40:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:37 INFO - 'SHELL': '/bin/bash', 05:40:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YT899LOX7E/Listeners', 05:40:37 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 05:40:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:37 INFO - 'USER': 'cltbld', 05:40:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:37 INFO - 'XPC_FLAGS': '0x0', 05:40:37 INFO - 'XPC_SERVICE_NAME': '0', 05:40:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:37 INFO - Downloading/unpacking psutil>=0.7.1 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:40: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 05:40:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:40:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:40 INFO - Installing collected packages: psutil 05:40:40 INFO - Running setup.py install for psutil 05:40:40 INFO - building 'psutil._psutil_osx' extension 05:40:40 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 05:40:40 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 05:40:40 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 05:40:40 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 05:40:40 INFO - building 'psutil._psutil_posix' extension 05:40:40 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 05:40:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:40:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:40:41 INFO - ^ 05:40:41 INFO - 1 warning generated. 05:40:41 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 05:40:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:41 INFO - Successfully installed psutil 05:40:41 INFO - Cleaning up... 05:40:41 INFO - Return code: 0 05:40:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:40:41 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')]} 05:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:41 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')]} 05:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:41 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 0x10f81aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f82a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d94a0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b9e00>, '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 0x10f522db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b4b20>, '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.KwIsgyJZRp/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.YT899LOX7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 05:40:41 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 05:40:41 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 05:40:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIsgyJZRp/Render', 05:40:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:41 INFO - 'HOME': '/Users/cltbld', 05:40:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:41 INFO - 'LOGNAME': 'cltbld', 05:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:41 INFO - 'MOZ_NO_REMOTE': '1', 05:40:41 INFO - 'NO_EM_RESTART': '1', 05:40:41 INFO - 'PAGER': '/bin/cat', 05:40:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:41 INFO - 'PWD': '/builds/slave/test', 05:40:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:41 INFO - 'SHELL': '/bin/bash', 05:40:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YT899LOX7E/Listeners', 05:40:41 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 05:40:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:41 INFO - 'USER': 'cltbld', 05:40:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:41 INFO - 'XPC_FLAGS': '0x0', 05:40:41 INFO - 'XPC_SERVICE_NAME': '0', 05:40:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:40:41 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 05:40:41 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 05:40:41 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 05:40:41 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 05:40:41 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 05:40:41 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 05:40:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:40:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:40:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:40:44 INFO - Installing collected packages: mozsystemmonitor 05:40:44 INFO - Running setup.py install for mozsystemmonitor 05:40:44 INFO - Successfully installed mozsystemmonitor 05:40:44 INFO - Cleaning up... 05:40:44 INFO - Return code: 0 05:40:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:44 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 0x10f81aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f82a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d94a0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b9e00>, '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 0x10f522db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b4b20>, '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.KwIsgyJZRp/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.YT899LOX7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 05:40:44 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 05:40:44 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 05:40:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIsgyJZRp/Render', 05:40:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:44 INFO - 'HOME': '/Users/cltbld', 05:40:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:44 INFO - 'LOGNAME': 'cltbld', 05:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:44 INFO - 'MOZ_NO_REMOTE': '1', 05:40:44 INFO - 'NO_EM_RESTART': '1', 05:40:44 INFO - 'PAGER': '/bin/cat', 05:40:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:44 INFO - 'PWD': '/builds/slave/test', 05:40:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:44 INFO - 'SHELL': '/bin/bash', 05:40:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YT899LOX7E/Listeners', 05:40:44 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 05:40:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:44 INFO - 'USER': 'cltbld', 05:40:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:44 INFO - 'XPC_FLAGS': '0x0', 05:40:44 INFO - 'XPC_SERVICE_NAME': '0', 05:40:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:44 INFO - Downloading/unpacking blobuploader==1.2.4 05:40:44 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 05:40:44 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 05:40:44 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 05:40:44 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 05:40:44 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 05:40:44 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 05:40:47 INFO - Downloading blobuploader-1.2.4.tar.gz 05:40:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:40:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:40:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:48 INFO - Downloading docopt-0.6.1.tar.gz 05:40:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:40:48 INFO - Installing collected packages: blobuploader, requests, docopt 05:40:48 INFO - Running setup.py install for blobuploader 05:40:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:40:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:40:48 INFO - Running setup.py install for requests 05:40:49 INFO - Running setup.py install for docopt 05:40:49 INFO - Successfully installed blobuploader requests docopt 05:40:49 INFO - Cleaning up... 05:40:49 INFO - Return code: 0 05:40:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:49 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 0x10f81aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f82a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d94a0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b9e00>, '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 0x10f522db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b4b20>, '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.KwIsgyJZRp/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.YT899LOX7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 05:40:49 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 05:40:49 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 05:40:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIsgyJZRp/Render', 05:40:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:49 INFO - 'HOME': '/Users/cltbld', 05:40:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:49 INFO - 'LOGNAME': 'cltbld', 05:40:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:49 INFO - 'MOZ_NO_REMOTE': '1', 05:40:49 INFO - 'NO_EM_RESTART': '1', 05:40:49 INFO - 'PAGER': '/bin/cat', 05:40:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:49 INFO - 'PWD': '/builds/slave/test', 05:40:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:49 INFO - 'SHELL': '/bin/bash', 05:40:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YT899LOX7E/Listeners', 05:40:49 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 05:40:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:49 INFO - 'USER': 'cltbld', 05:40:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:49 INFO - 'XPC_FLAGS': '0x0', 05:40:49 INFO - 'XPC_SERVICE_NAME': '0', 05:40:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:49 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-Il9QOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:49 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-UVVdR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:49 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-2YlY4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:49 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-6MSc5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:50 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-DyiEwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:50 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-IcXvLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:50 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-Qjg001-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:50 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-pqcLRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:50 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-_MvULT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:50 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-6PcXc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:50 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-sIRALO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:50 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-ztuADG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:50 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-PTS9El-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:51 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-5A9v5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:51 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-GqfxJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:51 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-BUaGtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:51 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-fvDvpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:40:51 INFO - Running setup.py install for manifestparser 05:40:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:40:51 INFO - Running setup.py install for mozcrash 05:40:51 INFO - Running setup.py install for mozdebug 05:40:51 INFO - Running setup.py install for mozdevice 05:40:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:40:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:40:52 INFO - Running setup.py install for mozfile 05:40:52 INFO - Running setup.py install for mozhttpd 05:40:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:40:52 INFO - Running setup.py install for mozinfo 05:40:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:40:52 INFO - Running setup.py install for mozInstall 05:40:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:40:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:40:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:40:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:40:52 INFO - Running setup.py install for mozleak 05:40:52 INFO - Running setup.py install for mozlog 05:40:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:40:52 INFO - Running setup.py install for moznetwork 05:40:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:40:53 INFO - Running setup.py install for mozprocess 05:40:53 INFO - Running setup.py install for mozprofile 05:40:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:40:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:40:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:40:53 INFO - Running setup.py install for mozrunner 05:40:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:40:53 INFO - Running setup.py install for mozscreenshot 05:40:53 INFO - Running setup.py install for moztest 05:40:53 INFO - Running setup.py install for mozversion 05:40:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:40:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:40:53 INFO - Cleaning up... 05:40:53 INFO - Return code: 0 05:40:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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')]} 05:40:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:53 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 0x10f81aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f82a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d94a0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b9e00>, '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 0x10f522db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b4b20>, '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.KwIsgyJZRp/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.YT899LOX7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 05:40:53 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 05:40:53 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 05:40:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIsgyJZRp/Render', 05:40:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:53 INFO - 'HOME': '/Users/cltbld', 05:40:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:53 INFO - 'LOGNAME': 'cltbld', 05:40:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:53 INFO - 'MOZ_NO_REMOTE': '1', 05:40:53 INFO - 'NO_EM_RESTART': '1', 05:40:53 INFO - 'PAGER': '/bin/cat', 05:40:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:53 INFO - 'PWD': '/builds/slave/test', 05:40:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:53 INFO - 'SHELL': '/bin/bash', 05:40:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YT899LOX7E/Listeners', 05:40:53 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 05:40:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:53 INFO - 'USER': 'cltbld', 05:40:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:53 INFO - 'XPC_FLAGS': '0x0', 05:40:53 INFO - 'XPC_SERVICE_NAME': '0', 05:40:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:54 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-J_uYwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:54 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)) 05:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:54 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-8kyJHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:54 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)) 05:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:54 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-oZYsnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:54 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)) 05:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:54 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-89_XRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:54 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)) 05:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:54 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-qyYcjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:54 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)) 05:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:54 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-PWtLt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:54 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)) 05:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:54 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-v5jd4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:55 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)) 05:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:55 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-c5M40b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:55 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)) 05:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:55 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-scG28A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:55 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)) 05:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:55 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-3ZRRN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:55 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)) 05:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:55 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-iMjCk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:55 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)) 05:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:55 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-fL5RAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:55 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)) 05:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:55 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-Onjsw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:55 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)) 05:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:55 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-rZz95k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:55 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)) 05:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:55 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-zwa8_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:55 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)) 05:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:55 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-UMFIua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:55 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)) 05:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:55 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-KOkeQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:56 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)) 05:40:56 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)) 05:40:56 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)) 05:40:56 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)) 05:40:56 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)) 05:40:56 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)) 05:40:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:40:56 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 05:40:56 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 05:40:56 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 05:40:56 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 05:40:56 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 05:40:56 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 05:40:58 INFO - Downloading blessings-1.6.tar.gz 05:40:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:40:58 INFO - Installing collected packages: blessings 05:40:58 INFO - Running setup.py install for blessings 05:40:58 INFO - Successfully installed blessings 05:40:58 INFO - Cleaning up... 05:40:58 INFO - Return code: 0 05:40:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:40:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:59 INFO - Reading from file tmpfile_stdout 05:40:59 INFO - Current package versions: 05:40:59 INFO - blessings == 1.6 05:40:59 INFO - blobuploader == 1.2.4 05:40:59 INFO - docopt == 0.6.1 05:40:59 INFO - manifestparser == 1.1 05:40:59 INFO - mozInstall == 1.12 05:40:59 INFO - mozcrash == 0.17 05:40:59 INFO - mozdebug == 0.1 05:40:59 INFO - mozdevice == 0.48 05:40:59 INFO - mozfile == 1.2 05:40:59 INFO - mozhttpd == 0.7 05:40:59 INFO - mozinfo == 0.9 05:40:59 INFO - mozleak == 0.1 05:40:59 INFO - mozlog == 3.1 05:40:59 INFO - moznetwork == 0.27 05:40:59 INFO - mozprocess == 0.22 05:40:59 INFO - mozprofile == 0.28 05:40:59 INFO - mozrunner == 6.11 05:40:59 INFO - mozscreenshot == 0.1 05:40:59 INFO - mozsystemmonitor == 0.0 05:40:59 INFO - moztest == 0.7 05:40:59 INFO - mozversion == 1.4 05:40:59 INFO - psutil == 3.1.1 05:40:59 INFO - requests == 1.2.3 05:40:59 INFO - wsgiref == 0.1.2 05:40:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:59 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')]} 05:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:59 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')]} 05:40:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:59 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 0x10f81aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f82a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d94a0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b9e00>, '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 0x10f522db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b4b20>, '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.KwIsgyJZRp/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.YT899LOX7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 05:40:59 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 05:40:59 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 05:40:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIsgyJZRp/Render', 05:40:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:59 INFO - 'HOME': '/Users/cltbld', 05:40:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:59 INFO - 'LOGNAME': 'cltbld', 05:40:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:59 INFO - 'MOZ_NO_REMOTE': '1', 05:40:59 INFO - 'NO_EM_RESTART': '1', 05:40:59 INFO - 'PAGER': '/bin/cat', 05:40:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:59 INFO - 'PWD': '/builds/slave/test', 05:40:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:59 INFO - 'SHELL': '/bin/bash', 05:40:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YT899LOX7E/Listeners', 05:40:59 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 05:40:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:59 INFO - 'USER': 'cltbld', 05:40:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:59 INFO - 'XPC_FLAGS': '0x0', 05:40:59 INFO - 'XPC_SERVICE_NAME': '0', 05:40:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:59 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)) 05:40:59 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)) 05:40:59 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)) 05:40:59 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)) 05:40:59 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)) 05:40:59 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)) 05:40:59 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)) 05:40:59 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)) 05:40:59 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)) 05:40:59 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)) 05:40:59 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)) 05:40:59 INFO - Cleaning up... 05:40:59 INFO - Return code: 0 05:40:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:40:59 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')]} 05:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:59 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')]} 05:40:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:59 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 0x10f81aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f82a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d94a0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b9e00>, '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 0x10f522db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b4b20>, '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.KwIsgyJZRp/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.YT899LOX7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 05:40:59 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 05:40:59 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 05:40:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIsgyJZRp/Render', 05:40:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:59 INFO - 'HOME': '/Users/cltbld', 05:40:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:59 INFO - 'LOGNAME': 'cltbld', 05:40:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:59 INFO - 'MOZ_NO_REMOTE': '1', 05:40:59 INFO - 'NO_EM_RESTART': '1', 05:40:59 INFO - 'PAGER': '/bin/cat', 05:40:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:59 INFO - 'PWD': '/builds/slave/test', 05:40:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:59 INFO - 'SHELL': '/bin/bash', 05:40:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YT899LOX7E/Listeners', 05:40:59 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 05:40:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:59 INFO - 'USER': 'cltbld', 05:40:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:59 INFO - 'XPC_FLAGS': '0x0', 05:40:59 INFO - 'XPC_SERVICE_NAME': '0', 05:40:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:59 INFO - Downloading/unpacking jsonschema 05:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:41:02 INFO - Downloading/unpacking functools32 (from jsonschema) 05:41:02 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 05:41:02 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 05:41:02 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 05:41:02 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 05:41:02 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 05:41:02 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 05:41:02 INFO - Downloading functools32-3.2.3-2.tar.gz 05:41:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:41:02 INFO - warning: no files found matching '*.txt' 05:41:02 INFO - no previously-included directories found matching 'build' 05:41:02 INFO - no previously-included directories found matching 'dist' 05:41:02 INFO - no previously-included directories found matching '.git*' 05:41:02 INFO - Installing collected packages: jsonschema, functools32 05:41:02 INFO - Running setup.py install for functools32 05:41:02 INFO - warning: no files found matching '*.txt' 05:41:02 INFO - no previously-included directories found matching 'build' 05:41:02 INFO - no previously-included directories found matching 'dist' 05:41:02 INFO - no previously-included directories found matching '.git*' 05:41:02 INFO - Successfully installed jsonschema functools32 05:41:02 INFO - Cleaning up... 05:41:02 INFO - Return code: 0 05:41:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:41:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:02 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 0x10f81aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f82a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9d94a0cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b9e00>, '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 0x10f522db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b4b20>, '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.KwIsgyJZRp/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.YT899LOX7E/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 05:41:02 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 05:41:02 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 05:41:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIsgyJZRp/Render', 05:41:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:02 INFO - 'HOME': '/Users/cltbld', 05:41:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:02 INFO - 'LOGNAME': 'cltbld', 05:41:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:02 INFO - 'MOZ_NO_REMOTE': '1', 05:41:02 INFO - 'NO_EM_RESTART': '1', 05:41:02 INFO - 'PAGER': '/bin/cat', 05:41:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:02 INFO - 'PWD': '/builds/slave/test', 05:41:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:02 INFO - 'SHELL': '/bin/bash', 05:41:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YT899LOX7E/Listeners', 05:41:02 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 05:41:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:02 INFO - 'USER': 'cltbld', 05:41:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:02 INFO - 'XPC_FLAGS': '0x0', 05:41:02 INFO - 'XPC_SERVICE_NAME': '0', 05:41:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:03 INFO - Downloading/unpacking flake8 05:41:03 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 05:41:03 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 05:41:03 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 05:41:03 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 05:41:03 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 05:41:03 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 05:41:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:41:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:41:05 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 05:41:05 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 05:41:05 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 05:41:05 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 05:41:05 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 05:41:05 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 05:41:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:41:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:41:05 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 05:41:05 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 05:41:05 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 05:41:05 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 05:41:05 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 05:41:05 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 05:41:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:41:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:41:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:41:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:41:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:41:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:41:06 INFO - no previously-included directories found matching 'docs/_build' 05:41:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:41:06 INFO - Running setup.py install for pep8 05:41:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:41:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:41:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:41:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:41:07 INFO - no previously-included directories found matching 'docs/_build' 05:41:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:41:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:41:07 INFO - Cleaning up... 05:41:07 INFO - Return code: 0 05:41:07 INFO - Running post-action listener: _resource_record_post_action 05:41:07 INFO - Running post-action listener: _start_resource_monitoring 05:41:07 INFO - Starting resource monitoring. 05:41:07 INFO - ##### 05:41:07 INFO - ##### Running install step. 05:41:07 INFO - ##### 05:41:07 INFO - Running pre-action listener: _resource_record_pre_action 05:41:07 INFO - Running main action method: install 05:41:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:41:07 INFO - Reading from file tmpfile_stdout 05:41:07 INFO - Detecting whether we're running mozinstall >=1.0... 05:41:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:41:07 INFO - Reading from file tmpfile_stdout 05:41:07 INFO - Output received: 05:41:07 INFO - Usage: mozinstall [options] installer 05:41:07 INFO - Options: 05:41:07 INFO - -h, --help show this help message and exit 05:41:07 INFO - -d DEST, --destination=DEST 05:41:07 INFO - Directory to install application into. [default: 05:41:07 INFO - "/builds/slave/test"] 05:41:07 INFO - --app=APP Application being installed. [default: firefox] 05:41:07 INFO - mkdir: /builds/slave/test/build/application 05:41:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 05:41:29 INFO - Reading from file tmpfile_stdout 05:41:29 INFO - Output received: 05:41:29 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:41:29 INFO - Running post-action listener: _resource_record_post_action 05:41:29 INFO - ##### 05:41:29 INFO - ##### Running run-tests step. 05:41:29 INFO - ##### 05:41:29 INFO - Running pre-action listener: _resource_record_pre_action 05:41:29 INFO - Running main action method: run_tests 05:41:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:41:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:41:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:41:29 INFO - 2016-07-22 05:41:29.701 screenresolution[1923:14547] starting screenresolution argv=screenresolution get 05:41:29 INFO - 2016-07-22 05:41:29.703 screenresolution[1923:14547] Display 0: 1600x1200x32@60 05:41:29 INFO - 2016-07-22 05:41:29.709 screenresolution[1924:14550] starting screenresolution argv=screenresolution list 05:41:29 INFO - Available Modes on Display 0 05:41:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:41:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:41:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:41:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:41:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:41:29 INFO - Intel Iris: 05:41:29 INFO - Chipset Model: Intel Iris 05:41:29 INFO - Type: GPU 05:41:29 INFO - Bus: Built-In 05:41:29 INFO - VRAM (Dynamic, Max): 1536 MB 05:41:29 INFO - Vendor: Intel (0x8086) 05:41:29 INFO - Device ID: 0x0a2e 05:41:29 INFO - Revision ID: 0x0009 05:41:29 INFO - Displays: 05:41:29 INFO - Display: 05:41:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:41:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:41:29 INFO - Display Serial Number: 1600x1200 60 05:41:29 INFO - Main Display: Yes 05:41:29 INFO - Mirror: Off 05:41:29 INFO - Online: Yes 05:41:29 INFO - Rotation: Supported 05:41:29 INFO - Return code: 0 05:41:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:41:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:41:29 INFO - Python 2.7.3 05:41:29 INFO - Return code: 0 05:41:29 INFO - grabbing minidump binary from tooltool 05:41:29 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')]} 05:41:29 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 0x10f6b9e00>, '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 0x10f522db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f6b4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:41:29 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 05:41:29 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 05:41:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:41:30 INFO - Return code: 0 05:41:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:41:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:41:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:41:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:41:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:41:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:41:31 INFO - Return code: 0 05:41:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0150', '--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 05:41:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0150 --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 05:41:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIsgyJZRp/Render', 05:41:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:31 INFO - 'HOME': '/Users/cltbld', 05:41:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:31 INFO - 'LOGNAME': 'cltbld', 05:41:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:41:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:41:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:31 INFO - 'MOZ_NO_REMOTE': '1', 05:41:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:41:31 INFO - 'NO_EM_RESTART': '1', 05:41:31 INFO - 'PAGER': '/bin/cat', 05:41:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:31 INFO - 'PWD': '/builds/slave/test', 05:41:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:41:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:31 INFO - 'SHELL': '/bin/bash', 05:41:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YT899LOX7E/Listeners', 05:41:31 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 05:41:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:31 INFO - 'USER': 'cltbld', 05:41:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:31 INFO - 'XPC_FLAGS': '0x0', 05:41:31 INFO - 'XPC_SERVICE_NAME': '0', 05:41:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0150', '--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 05:41:31 INFO - mozversion application_buildid: 20160722032726 05:41:31 INFO - mozversion application_changeset: 68ca20cf8042a14e44e826e6e7985677c138bfd6 05:41:31 INFO - mozversion application_display_name: Firefox 05:41:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:41:31 INFO - mozversion application_name: Firefox 05:41:31 INFO - mozversion application_remotingname: firefox 05:41:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:41:31 INFO - mozversion application_vendor: Mozilla 05:41:31 INFO - mozversion application_version: 48.0 05:41:31 INFO - mozversion platform_buildid: 20160722032726 05:41:31 INFO - mozversion platform_changeset: 68ca20cf8042a14e44e826e6e7985677c138bfd6 05:41:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:41:31 INFO - mozversion platform_version: 48.0 05:41:31 INFO - using testdate: 1469191291 05:41:31 INFO - actual date: 1469191291 05:41:31 INFO - starting webserver on 'localhost:49398' 05:41:31 INFO - SUITE-START | Running 2 tests 05:41:31 INFO - TEST-START | dromaeo_css 05:41:31 INFO - operating with platform_type : mac_ 05:41:31 INFO - Initialising browser for dromaeo_css test... 05:41:31 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/tmp_C9Dgn/profile http://localhost:49398/getInfo.html) 05:41:33 INFO - PROCESS | 1942 | __metrics Screen width/height:1600/1200 05:41:33 INFO - PROCESS | 1942 | colorDepth:24 05:41:33 INFO - PROCESS | 1942 | Browser inner width/height: 1024/689 05:41:33 INFO - PROCESS | 1942 | __metrics 05:41:33 INFO - PROCESS | 1942 | 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" 05:41:33 ERROR - PROCESS | 1942 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:41:34 INFO - TEST-INFO | 1942: exit 0 05:41:34 INFO - Browser initialized. 05:41:34 INFO - Running cycle 1/1 for dromaeo_css test... 05:41:34 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/tmp_C9Dgn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:46 INFO - PROCESS | 1945 | RSS: Main: 160645120 05:41:46 INFO - PROCESS | 1945 | 05:42:37 INFO - PROCESS | 1945 | 1469191357715 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] 05:44:47 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49398/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49398/tests/dromaeo/cssquery-ext.html) 05:44:49 INFO - PROCESS | 1945 | RSS: Main: 297807872 05:44:49 INFO - PROCESS | 1945 | 05:47:51 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49398/tests/dromaeo/cssquery-ext.html (next: http://localhost:49398/tests/dromaeo/cssquery-jquery.html) 05:47:52 INFO - PROCESS | 1945 | RSS: Main: 300339200 05:47:52 INFO - PROCESS | 1945 | 05:50:54 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49398/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49398/tests/dromaeo/cssquery-mootools.html) 05:50:55 INFO - PROCESS | 1945 | RSS: Main: 307011584 05:50:55 INFO - PROCESS | 1945 | 05:53:57 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49398/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49398/tests/dromaeo/cssquery-prototype.html) 05:53:57 INFO - PROCESS | 1945 | RSS: Main: 308129792 05:53:57 INFO - PROCESS | 1945 | 05:57:00 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49398/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49398/tests/dromaeo/cssquery-yui.html) 05:57:01 INFO - PROCESS | 1945 | RSS: Main: 273657856 05:57:01 INFO - PROCESS | 1945 | 06:00:03 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:49398/tests/dromaeo/cssquery-yui.html (next: http://localhost:49398/tests/dromaeo/cssquery-dojo.html) 06:00:04 INFO - PROCESS | 1945 | RSS: Main: 311218176 06:00:04 INFO - PROCESS | 1945 | 06:00:04 INFO - PROCESS | 1945 | __start_tp_report 06:00:04 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 06:00:04 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 06:00:04 INFO - PROCESS | 1945 | |i|pagename|runs| 06:00:04 INFO - PROCESS | 1945 | |0;dojo.html;3502.2295095079085;3589.515415418921;3593.4430163324696;3602.1534939289263;3673.4673472346512;5880.970595147023;5950.732217050243;5954.166416701665;5996.2504686914135;5998.190244317021;5961.910571341434;5968.104784282349;6012.038073908176;6016.187814645022;6096.939030609693;5317.973410132944;5397.919031214535;5402.189671549269;5408.242846001563;5426.484483974016;5391.595630327722;5443.782248710047;5467.043267428204;5472.699001554913;5472.781088756445;5405.972970135143;5459.535939445152;5463.562914966813;5484.396716361197;5494.36814766301;5151.330327057459;5190.948090519084;5468.207109969052;5483.972580137113;5496.6701997880255;4319.3952846601505;5316.308879845634;5330.786768529254;5382.838514844561;5440.700761458118;5417.4311697272005;5814.156947242626;5873.412658734135;5886.529077673776;5915.438033386821;6282.591631543935;6302.590331628429;6331.081993110973;6335.936640633581;6355.142055822451;6198.256209254873;6313.305536390993;6395.20059992501;6402.199725034371;6409.070684750732;6120.571559990803;6149.385061493859;6157.107219453199;6160.9383906160365;6177.567570270038;6520.673966301666;6522.445592124628;6537.052127441494;6541.574797638138;6542.672866356663;6256.186695729526;6290.811275661737;6343.365663433619;6367.076773867763;6369.426751592379;4826.758662066953;6390.9360906391275;6417.326180751047;6427.1002059710745;6441.291457939623;6262.874742505124;6280.811575652738;6303.338149494237;6309.968450157719;6367.7452901884335;4848.442429120626;6239.407256310643;6240.063990401476;6339.2709838368255;6341.175647165931;5134.2298655201275;5138.229265610188;5140.845774626767;5142.5628821549835;5167.3799144102195;4603.171429142786;4606.815727370935;4609.746463944515;4618.907621847544;4640.257558790645;5927.703614819242;6059.666718330446;6081.604695694854;6088.634681919099;6094.421030002142;6304.464120549805;6320.367963203735;6348;6348.333424990309;6359.17330747;6036.577439579188;6113.57204995655;6142.959235178615;6146.170267013986;6153.230846144232;5582.609217354828;5597.776088956396;5602.719864006784;5603.019471592455;5611.382747897728;6464.191976003;6474.5791523550815;6626.966865165643;6644.634545099969;6650.3682150195655;6410.294867564658;6410.711517981796;6411.647359395278;6424;6435.002574600944;6554.279029306867;6617.636030018395;6642.2361428434415;6663.5002374821115;6667.033280174444;6254.28075771274;6283.3402492737605;6348.682565871595;6365.045243213555;6369.267534233622;6270.090836828543;6382.904256436064;6389.137466442156;6405.391487808651;6420.133282006869;6338.873222535616;6352.237731472253;6358.841028974312;6366.363363663597;6375.4262116409745;6217.689115544295;6238.407351301619;6269.028300613412;6326.810195694136;6361.141245931925;6238.157848690369;6270.686465676789;6287.056941458818;6296.118543403835;6297.1813664223355;6316.7157477914525;6366.172397588284;6377.4579160771855;6379.202599675041;6383.96808015957;6138.171346868295;6186.969065154645;6226.906596401148;6233.189685340877;6233.594816336924 06:00:04 INFO - PROCESS | 1945 | |1;ext.html;512.9076766182087;523.6125267302195;606.9802731411229;611.3519669150703;629.2920464477463;29882.551761723593;30438.84780576097;39975.60024399755;40402.19195616093;40536.79731601345;43362.915927101836;43960;43969.34045989305;43989;43996;8510.234078932926;8887.777805554848;9619.519024048786;9828.508574571279;9850.310478266525;9074.047225041375;11907.583234586791;12147.574834880781;12167.209131406431;12173.452194651218;11739.589114380999;11818.349990750505;11830.8225376619;11833.704157396049;11842.467088981017;11732;11828.7634247315;11835.526578936831;11840.993515551188;11844.644660660195;11781.057515398834;11898.22661526998;11900.464479098462;11904.523819047228;12144.149909506337;42013;42463.7260882174;42465.78767106176;42509.78745106285;42585.57414425847;3328.334333133359;3415.7608967372043;3421;3481.9651803481893;4008.6191811777835;29145.854270728723;29186.832526698905;29231.707682923323;30493.69506304931;30629;2791.8883244670187;2803.8457884816326;2804.340979869729;2868.913932582026;2909.0254764653864;464929;465841;467866;468361;469850;37816.243675126345;38276;38355.04112397157;67385.32614673888;67933.66033169802;29971.400571988437;36197.45703814445;36269;36368.27263454716;36449.817750911076;16196.59508512273;16361;16361.754573681405;16396.426125085563;16420.917895410446;12976;13083.280419577015;13704.794428083587;14040.508582199574;14080.084794488326;6872.759453419107;8302.626381812834;8329.12544182852;8333.66665333392;8393.118722534045;3499.33512632599;3522.841472133709;3523.735719821024;3544.255706301654;3563.572371315407;932.6922115701802;1013.5489707080426;1052.6106533342538;1082.442542090824;1090.8690957085041;30738.31445639924;31636.525452118236;31697.36605267882;31926.680733192836;32181.839090804395;846.6782622603495;909.3316412436853;965.7730433348157;1147.6729132197283;1233.0382301804427;13975.860241397659;16159.353625855068;16227.837721622633;16247.675046499004;16361.509154725378;635970;642706;643835;645406;646538;64665;65708.6714566424;65709.68580628361;65744.6712766433;65905;1401.8037474753542;1500.7823865539435;1541.2139808697868;1542.6066353079893;1542.706885691715;24796;28319.150425487267;31328.21679458032;31403.371932560764;31510.369792604477;51784;52576.21135682891;53187.6703082426;53221.733891330296;53277.73361133169;442133;444447;444526.3321050122;444935;630588;27967.44065118727;28688.42623147567;28884;28890.98881539136;28898.711012890442;44942;46674;46845.2973205409;47598.76200618975;48348.75825620849;36975.44536831896;38457.615423845404;38485.4227186598;38683.80658096691;38764;37856.05359865915;37980.341376104516;38129.80935095418;38502;38799.030024249616;38025.4296185563;38039.80980095192;39236.80381598074;39286.41070383889;39454;33784.831075844464;33842.49236261507;34097;34414;34530.48204276988;25482.617760733232;25551;25584.616230756925;25989;26039.479210416066 06:00:04 INFO - PROCESS | 1945 | |2;jquery.html;397.03124376521293;406.74171900842975;415.9334506478964;418.20958388645465;418.42048762463986;19770.802291977077;19975.40073797785;20658.89670551645;20681.276155334566;20754.896225518853;20062.197512099498;20236.69644955327;21014.57970840582;21027;21090.578188436222;4969.826056088038;5763.366029736736;5921.733521991499;6007.189029481008;6024.397560243984;5872.647641141524;5953.732082056296;6003.099535069731;6006.849828754294;6009.308929473123;5728.942710572904;5935.88128237435;5945.732442040097;5981.9700901495435;5990.820275391723;5877.9412205877825;5907.438793314628;5934.821955341346;5942.108683697458;5948.940510594882;5097.133487307166;5131.974340128276;5214.113600687892;5219.765110570033;5224.843254702365;13051.54319598819;13723.519676811264;13729.519466818714;26337.68311584413;26422.339441513923;2056.0542150610736;2202.1631779923687;2215.9695741480127;2260.5139894922504;2445.5720248956363;9881.16010139139;10220.89779102207;10461.215408844368;10589.576416943313;13958.302084895715;1731.1690388613536;1832.0107142143227;1832.0473353856053;1836.1370156026646;1851.574137948266;67351;103937;110421;121032;128886;9865.358751681119;11857.525698971944;12136.393180340947;12164.14850960442;12324.137310388187;14042.719145617031;14535.927320363331;14559.563213103624;14565.198914059829;14571.41714331415;8004.439689221698;9050.140236677506;9175.908240917639;9202.493862837475;9265.119813617695;9300;9306.395084319498;9326.626934922528;9428;9441.527923603793;4572.154151481986;4736.194846876039;4811.422629284439;4848.200046992278;4888.731119788375;1967.501273382796;2008.5480766827347;2010.839132869367;2017.9103284226646;2022.555037891691;853.3599800149888;950.7290422229631;980.1766516126488;1059.7403636109202;1067.887871773468;20948;21440.89279553592;21519.816410097283;21720.78279217219;22143.335699929026;994.2543092680489;1018.8217062014118;1054.414799786126;1055.3245922609535;1060.7772367802847;10994.560217591366;11396.430178491042;11401.885981140082;11406.315621062762;11424.942875285737;643588;644763;654079;658278;661961;121305.08254257159;130993;134237.3288133553;134478;135242.97135542973;1039.6465201831413;1045.534737042009;1066.3814987307499;1119.6137332620233;1123.2081382625229;18645.72031419557;18914.716279256096;19288.90355548213;19301.517462063563;19609.803901960797;53889.73055134699;54074.72962635162;54254.45745542494;57984;58022.12966805417;77824.22175778169;80567;81375;82945;83578.59799255975;8210.384221183554;8294.42405942869;8386.32909367237;8481.491110533389;8521.190486903733;11733.530658773554;12828.615141545768;12888.42002109926;13157.60527184186;13181.538646147339;11090.279131856403;11255.437228138724;11420;11505.252158609663;11547.422628868691;9857;10742.355458672504;10850.860659630625;11083.612073577386;11197.272177308774;11230.21388502797;11358.60244891425;11372.54509819598;11521.746723147988;11525.423728813694;8193.959030204811;8295.751127466187;8317.251447369767;8569.785755356166;8632.438891472035;8941.731748047569;11467.713307167054;11576.421178941187;11624.825627615422;11830.526778928746 06:00:04 INFO - PROCESS | 1945 | |3;mootools.html;1399.7821894951394;1651.5128037229017;1670.08145519964;1945;1999.9600007999832;7878.9212107878975;8579.399442039052;9072.364934454583;9255.583498742555;9257.953710231439;9062.093790620935;9141.680041198559;9247.121523455278;9257.398269112504;9263.166315031664;2562.7693507584313;2585.8448493090473;2588.4823035392997;2589.197348821862;2589.4691588224464;2516.584763514027;2579.793616510684;2581.1611226351415;2581.6127580862926;2581.8450892946485;2547.9108231211912;2579.742025797424;2596.870156492177;2600.4799040191942;2604.5572252717084;2547.0703193334484;2568.691756989164;2580.9870950645313;2587.4825034993078;2593.6239245309325;2547.0066673997158;2570.7043689975703;2582.690077190749;2585.1856665150417;2586.081940910968;9323.953380233055;9373.718788436357;9395.295352848496;9398.62405503779;9400.670976515788;5642.971785141089;5761.308642962829;5763.049096899007;5823;5829.854253643651;3689.797061161635;3742.2889650966504;3786.545614526247;3806.1816709407613;3807.428885667144;941.1529623338981;1032.4528000159928;1038.9688309350656;1075.0754351257913;1105.171121658756;12249.693757656041;12488.313142777144;12499.312537810416;12616.495340186382;12688.492460301577;5064.392272927273;5070.391553013662;5204.4535323791215;5220.955808838247;5227.634065615371;10876.564937402416;10879;10900.454977251264;10907;10927.3989930553;5868.501177399883;5872.970635146797;5877;5888.057910734262;5890.617109887844;4734.502877197845;4799.66402351832;5090.643654944192;5210.062188805976;5290.947090529123;3645.3620616392554;4197.265478541304;4236.237477254123;4309.224339618836;4327.199468098412;1401.3133564553295;1437.8921580881477;1440.6182361674164;1446.428660679047;1447.5657302809116;903.4804987132425;903.7966457547;957.6456711016968;970.7427531704104;993.3692105512907;6051.515878729691;6145.877082458326;6151.384861513902;6231.127642130105;6264.561480696399;482.8430760003013;489.50559934466446;497.4056003076361;1038.413296487482;1062.198040479433;4042.797860106983;4075.2257071156387;4136.255474014646;4149.004238982622;4150.335946248586;10718.89281107179;11024.062954648945;11137.109031277478;11148.331100134017;11178.99389054989;10852.620158294421;10881.619143330105;10883.619073332395;11028.28316159447;11043.83434248487;8555.614997325136;8616.39685222041;8642.43824151428;8643.308535317034;8671.08953559867;5708.800191993427;6057.42454466825;6114.816555503342;6166.1983942087245;6169.753209871555;10172.593096276361;10176.18590512772;10221.386716797017;10250.282480226313;10276.280660353703;10239.232057595858;10376.16990640762;10564.154867610727;10580.206484513847;10610.363378197333;7897.68409263623;7983.2415920487465;8032.799180020546;8140.592970351578;8154.469959452616;8776.254018408506;8824.3381746368;8849.69026084084;8851.424657397249;8949.910500894908;6532.673366331569;6943.895841562482;7761.417893658111;8024.598770061357;8062.274395304451;7677.270659287359;7753.418493613114;7770.0287464067;7889.605519724106;7927.960360198162;7219;7291.854162916606;7296.306850849161;7299.5255308404785;7312;5021.297018417497;5077.796888124433;5126.923096153486;5224.451432599522;5237.554807841224;5189.558887494605;5257.63196576236;5262.368515778131;5411.621186516906;5473.233747275464 06:00:04 INFO - PROCESS | 1945 | |4;prototype.html;301.8551095474171;336.290427198611;341.485511755365;344.6467370944782;356.11327793793464;6658.500612454073;6668.933310666892;6696.3303669633015;6747;6749.358810912961;6606.405423511883;6653.50098742595;6653.966730166355;6700.664966751655;6705.1953765548205;5023.899522009558;5024.598032157437;5027.84916452507;5030.673006254582;5049.545540901318;5016.49835016499;5025.798968041292;5029.924551131736;5031.547160755531;5035.496450354972;4293.184294983958;4779.4227904791505;4972.5276098770555;4980.153373926423;4996.350474438318;4717.669763116583;5003.274525193845;5008.749562521859;5013.4234563025175;5014.348134742497;3384.526166336715;4143.1713657268365;4144.772037537934;4153.210889930903;4153.709240353177;6335.524835637346;6384.7126879290545;6393.9680301598655;6416.582922110048;6434.195725534309;5679.659220446786;5682.868924153442;5689.687067211302;5702.3157221133315;5706.3722990471015;6086.573939824214;7062.576245425291;7072.752453664149;7073.717051317941;7093.3970612498;4478.2386994211065;4629.490756016836;4642.976785116087;4654.604358629521;4667.369905062807;7098.254683258235;7223.313785190398;7299.124105107368;7399.556026638419;7425.665845036986;4614.353990441341;4624.1445332613575;4640.164770341371;4650.139724151043;4668.6498512611015;4777.044591081798;4787.808487660524;4790.113828941657;4799.448063472745;4807.110684523374;5428.212909128154;5516.972415137979;5617.354004289477;5630.8029218977135;5644.153376993484;7063.328983746535;7077.823054423578;7081.645917704094;7111.92888071123;7115.89326160108;1632.7224371856812;1666.8749843761766;1669.4323929863663;1671.3398207707887;1672.3812189489722;879.1823604048296;1043.4052590023766;1064.11678307005;1066.248294952057;1077.3212875888141;5071.4928507149725;6671;6676.799696009128;6682.966585167043;6698.062271281927;7400.740974065882;7408.888866667005;7430;7445.10658720957;7455.179930207672;6641.634710090895;6647.60114393138;6663.633500157538;6668.233153187349;6670.799876003728;3551.733619978512;3574.392353299945;3854.171353159028;3906.0430194601936;3920.137569734654;6266.060090986297;7437.442191835634;7689.615519224017;7705.807354816175;7738.4969976951315;7511.887321690179;7590.506617069873;7597.202293759186;7640.961795190989;7655.923440765632;7746.651400686983;7766.5728384939375;7793.844123117506;7798.181190974866;7801.29788319054;6363.077353783768;6801.251862295243;7142.285771422816;7446.292602202782;7463.141738699902;7044.225135235222;7198.100237470317;7303.452241082046;7305.3425191732995;7325.890111648436;6089.756409743738;7534.54792712439;7692.92307076922;7711.11322197932;7718.768436946901;3920.294347017564;4645.070985802786;4691.976540117277;4701.365315682339;4708.835190768444;7415.629218539159;7428.182899881117;7443.106827180556;7448.8882666758955;7475;6872.175338959357;7127.216006239206;7186.245444228281;7199.532030418006;7213.314735100157;7134.85730285381;7135.072440582691;7143.035690181759;7203.71185152588;7209.6395180241825;6980.092587963735;7009.7897063088185;7022.12223472066;7029.718811247493;7031.578105313698;7135.85728285421;7154.2130365658695;7245.166805817398;7309.853802923805;7317.780466586011;6775.288594697486;7032.648367581498;7033.085698859116;7051.259617740268;7077.752278670222 06:00:04 INFO - PROCESS | 1945 | |5;yui.html;708.5075872268771;742.3022358982557;775.3138472451878;794.3962588432789;798.476997566594;15380.154091524962;15552.844471555281;15558.45545405911;15558.688826223468;15576.922115389421;14097.436102555885;14244.074135181207;14265;14265.215413152273;14300.642483937881;769.96920123195;792.1523969352795;942.4109931292942;968.8934217236099;972.2562239886493;837.6733074101105;951.2295041016798;952.556876332357;965.946872921989;968.3802366485455;818.8239528501371;1019.4800651667666;1023.5598692562195;1023.6468418395641;1024.9077583017565;977.7408986618552;1006.6979906028199;1010.8129995950699;1010.914072303855;1012.3723291559206;746.7199800074972;748.2629609834308;750.2422553221231;751.6016511249047;752.6537792615349;14937.25313734309;14966.62583435412;14971.775423368657;14989.775153372708;15050.698986020328;12669.936650316782;12681.619551413472;12997.805032924514;13223.537176198883;13281.800772988412;10106.090451859294;10295.742606434824;10328.741781455448;10398.844017339747;10745.355278683304;3478.826058697055;3517.525134106914;3524.559430071241;3566.32239874423;3750.3999360102252;59402.702986484794;77534.22465775326;77594;79339;79648.99999999942;9758.512074396253;9922.355046921926;9959.502024898871;9985.1512621428;10004.299699021145;23135.652965205536;23240.837958102027;23241.76758232396;23281.417964551023;23304.7669523306;7757.224277572311;7827.256410641094;7896.763097107096;8036.557989310527;8157.388195885333;4128.4632997710105;4189.706720529533;4209.705320627588;4341.696081274281;4478.193925093515;3783.319002579507;3825.387937929918;3827.1580252344997;3829.329867273216;3832.0419895026243;687.9518433709592;690.5856486108395;695.1935754524727;701.130598058404;708.0476758759461;573.7953529537497;587.2864469669372;628.7233617208414;643.2120652201016;644.0757512968811;8278.213569710868;8387.370947178864;8408.78978025542;8554.871676924851;8597.226249637437;625.6934102289845;627.3914303125961;631.9589226700342;633.1199632510813;635.8728254349148;3802.296575133609;3813.389857622767;3858.575556688762;3869.941950870739;4056.675465962716;80401.59799200967;80414.79377809337;80443.59778201189;80463.7930431044;82523.5873820627;71947.6402617994;79618.20381796223;79879.20120798834;80833;81557.59221203857;71046.3819851708;71084.64457677679;71417;71663;72291.27708722846;7765.572893490912;7939.801504962191;8064.879026814485;8106.918930810616;8242.421832676575;37608;49110.75444622754;49836.75408114681;50052.24921626251;50332.49667503278;67222;68576;69335.30664693289;69412;70507.64746176236;10274.743131421475;10317.123044541298;11065.280756750786;11124.499397526968;11616.767664646828;9397.483138427453;11877.804522896808;11909.22590031645;12188.719614874353;12373.195742276723;11587.826182607436;11636;11839;11957.76084478323;12134.817977730165;9902.356346837432;11651.41742912834;11865.406729663655;12125.393730313626;12239.143259971717;11398;11616.535338586362;11719;11761.764764704829;12367.319797411228;9728.464934428674;9807.9019209807;9913.45475998827;10091.19270458347;10164.796704066026;9657.517124143624;9693;9697.854532181882;9732;9760.707178784647 06:00:04 INFO - PROCESS | 1945 | __end_tp_report 06:00:04 INFO - PROCESS | 1945 | __start_cc_report 06:00:04 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,1155 06:00:04 INFO - PROCESS | 1945 | __end_cc_report 06:00:04 INFO - PROCESS | 1945 | __startTimestamp1469192404173__endTimestamp 06:00:04 INFO - PROCESS | 1945 | 06:00:04 INFO - PROCESS | 1945 | ------- Summary: start ------- 06:00:04 INFO - PROCESS | 1945 | Number of tests: 6 06:00:04 INFO - PROCESS | 1945 | 06:00:04 INFO - PROCESS | 1945 | [#0] dojo.html Cycles:165 Average:5936.71 Median:6233.19 stddev:655.69 (10.5%) stddev-sans-first:629.27 06:00:04 INFO - PROCESS | 1945 | Values: 3502.2 3589.5 3593.4 3602.2 3673.5 5881.0 5950.7 5954.2 5996.3 5998.2 5961.9 5968.1 6012.0 6016.2 6096.9 5318.0 5397.9 5402.2 5408.2 5426.5 5391.6 5443.8 5467.0 5472.7 5472.8 5406.0 5459.5 5463.6 5484.4 5494.4 5151.3 5190.9 5468.2 5484.0 5496.7 4319.4 5316.3 5330.8 5382.8 5440.7 5417.4 5814.2 5873.4 5886.5 5915.4 6282.6 6302.6 6331.1 6335.9 6355.1 6198.3 6313.3 6395.2 6402.2 6409.1 6120.6 6149.4 6157.1 6160.9 6177.6 6520.7 6522.4 6537.1 6541.6 6542.7 6256.2 6290.8 6343.4 6367.1 6369.4 4826.8 6390.9 6417.3 6427.1 6441.3 6262.9 6280.8 6303.3 6310.0 6367.7 4848.4 6239.4 6240.1 6339.3 6341.2 5134.2 5138.2 5140.8 5142.6 5167.4 4603.2 4606.8 4609.7 4618.9 4640.3 5927.7 6059.7 6081.6 6088.6 6094.4 6304.5 6320.4 6348.0 6348.3 6359.2 6036.6 6113.6 6143.0 6146.2 6153.2 5582.6 5597.8 5602.7 5603.0 5611.4 6464.2 6474.6 6627.0 6644.6 6650.4 6410.3 6410.7 6411.6 6424.0 6435.0 6554.3 6617.6 6642.2 6663.5 6667.0 6254.3 6283.3 6348.7 6365.0 6369.3 6270.1 6382.9 6389.1 6405.4 6420.1 6338.9 6352.2 6358.8 6366.4 6375.4 6217.7 6238.4 6269.0 6326.8 6361.1 6238.2 6270.7 6287.1 6296.1 6297.2 6316.7 6366.2 6377.5 6379.2 6384.0 6138.2 6187.0 6226.9 6233.2 6233.6 06:00:04 INFO - PROCESS | 1945 | 06:00:04 INFO - PROCESS | 1945 | [#1] ext.html Cycles:180 Average:66201.01 Median:3553.91 stddev:143850.72 (4047.7%) stddev-sans-first:144169.71 06:00:04 INFO - PROCESS | 1945 | Values: 512.9 523.6 607.0 611.4 629.3 29882.6 30438.8 39975.6 40402.2 40536.8 43362.9 43960.0 43969.3 43989.0 43996.0 8510.2 8887.8 9619.5 9828.5 9850.3 9074.0 11907.6 12147.6 12167.2 12173.5 11739.6 11818.3 11830.8 11833.7 11842.5 11732.0 11828.8 11835.5 11841.0 11844.6 11781.1 11898.2 11900.5 11904.5 12144.1 42013.0 42463.7 42465.8 42509.8 42585.6 3328.3 3415.8 3421.0 3482.0 4008.6 29145.9 29186.8 29231.7 30493.7 30629.0 2791.9 2803.8 2804.3 2868.9 2909.0 464929.0 465841.0 467866.0 468361.0 469850.0 37816.2 38276.0 38355.0 67385.3 67933.7 29971.4 36197.5 36269.0 36368.3 36449.8 16196.6 16361.0 16361.8 16396.4 16420.9 12976.0 13083.3 13704.8 14040.5 14080.1 6872.8 8302.6 8329.1 8333.7 8393.1 3499.3 3522.8 3523.7 3544.3 3563.6 932.7 1013.5 1052.6 1082.4 1090.9 30738.3 31636.5 31697.4 31926.7 32181.8 846.7 909.3 965.8 1147.7 1233.0 13975.9 16159.4 16227.8 16247.7 16361.5 635970.0 642706.0 643835.0 645406.0 646538.0 64665.0 65708.7 65709.7 65744.7 65905.0 1401.8 1500.8 1541.2 1542.6 1542.7 24796.0 28319.2 31328.2 31403.4 31510.4 51784.0 52576.2 53187.7 53221.7 53277.7 442133.0 444447.0 444526.3 444935.0 630588.0 27967.4 28688.4 28884.0 28891.0 28898.7 44942.0 46674.0 46845.3 47598.8 48348.8 36975.4 38457.6 38485.4 38683.8 38764.0 37856.1 37980.3 38129.8 38502.0 38799.0 38025.4 38039.8 39236.8 39286.4 39454.0 33784.8 33842.5 34097.0 34414.0 34530.5 25482.6 25551.0 25584.6 25989.0 26039.5 06:00:04 INFO - PROCESS | 1945 | 06:00:04 INFO - PROCESS | 1945 | [#2] jquery.html Cycles:180 Average:36427.76 Median:21265.74 stddev:108395.68 (509.7%) stddev-sans-first:108666.00 06:00:04 INFO - PROCESS | 1945 | Values: 397.0 406.7 415.9 418.2 418.4 19770.8 19975.4 20658.9 20681.3 20754.9 20062.2 20236.7 21014.6 21027.0 21090.6 4969.8 5763.4 5921.7 6007.2 6024.4 5872.6 5953.7 6003.1 6006.8 6009.3 5728.9 5935.9 5945.7 5982.0 5990.8 5877.9 5907.4 5934.8 5942.1 5948.9 5097.1 5132.0 5214.1 5219.8 5224.8 13051.5 13723.5 13729.5 26337.7 26422.3 2056.1 2202.2 2216.0 2260.5 2445.6 9881.2 10220.9 10461.2 10589.6 13958.3 1731.2 1832.0 1832.0 1836.1 1851.6 67351.0 103937.0 110421.0 121032.0 128886.0 9865.4 11857.5 12136.4 12164.1 12324.1 14042.7 14535.9 14559.6 14565.2 14571.4 8004.4 9050.1 9175.9 9202.5 9265.1 9300.0 9306.4 9326.6 9428.0 9441.5 4572.2 4736.2 4811.4 4848.2 4888.7 1967.5 2008.5 2010.8 2017.9 2022.6 853.4 950.7 980.2 1059.7 1067.9 20948.0 21440.9 21519.8 21720.8 22143.3 994.3 1018.8 1054.4 1055.3 1060.8 10994.6 11396.4 11401.9 11406.3 11424.9 643588.0 644763.0 654079.0 658278.0 661961.0 121305.1 130993.0 134237.3 134478.0 135243.0 1039.6 1045.5 1066.4 1119.6 1123.2 18645.7 18914.7 19288.9 19301.5 19609.8 53889.7 54074.7 54254.5 57984.0 58022.1 77824.2 80567.0 81375.0 82945.0 83578.6 8210.4 8294.4 8386.3 8481.5 8521.2 11733.5 12828.6 12888.4 13157.6 13181.5 11090.3 11255.4 11420.0 11505.3 11547.4 9857.0 10742.4 10850.9 11083.6 11197.3 11230.2 11358.6 11372.5 11521.7 11525.4 8194.0 8295.8 8317.3 8569.8 8632.4 8941.7 11467.7 11576.4 11624.8 11830.5 06:00:04 INFO - PROCESS | 1945 | 06:00:04 INFO - PROCESS | 1945 | [#3] mootools.html Cycles:180 Average:6022.22 Median:2759.35 stddev:3348.75 (121.4%) stddev-sans-first:3340.13 06:00:04 INFO - PROCESS | 1945 | Values: 1399.8 1651.5 1670.1 1945.0 2000.0 7878.9 8579.4 9072.4 9255.6 9258.0 9062.1 9141.7 9247.1 9257.4 9263.2 2562.8 2585.8 2588.5 2589.2 2589.5 2516.6 2579.8 2581.2 2581.6 2581.8 2547.9 2579.7 2596.9 2600.5 2604.6 2547.1 2568.7 2581.0 2587.5 2593.6 2547.0 2570.7 2582.7 2585.2 2586.1 9324.0 9373.7 9395.3 9398.6 9400.7 5643.0 5761.3 5763.0 5823.0 5829.9 3689.8 3742.3 3786.5 3806.2 3807.4 941.2 1032.5 1039.0 1075.1 1105.2 12249.7 12488.3 12499.3 12616.5 12688.5 5064.4 5070.4 5204.5 5221.0 5227.6 10876.6 10879.0 10900.5 10907.0 10927.4 5868.5 5873.0 5877.0 5888.1 5890.6 4734.5 4799.7 5090.6 5210.1 5290.9 3645.4 4197.3 4236.2 4309.2 4327.2 1401.3 1437.9 1440.6 1446.4 1447.6 903.5 903.8 957.6 970.7 993.4 6051.5 6145.9 6151.4 6231.1 6264.6 482.8 489.5 497.4 1038.4 1062.2 4042.8 4075.2 4136.3 4149.0 4150.3 10718.9 11024.1 11137.1 11148.3 11179.0 10852.6 10881.6 10883.6 11028.3 11043.8 8555.6 8616.4 8642.4 8643.3 8671.1 5708.8 6057.4 6114.8 6166.2 6169.8 10172.6 10176.2 10221.4 10250.3 10276.3 10239.2 10376.2 10564.2 10580.2 10610.4 7897.7 7983.2 8032.8 8140.6 8154.5 8776.3 8824.3 8849.7 8851.4 8949.9 6532.7 6943.9 7761.4 8024.6 8062.3 7677.3 7753.4 7770.0 7889.6 7928.0 7219.0 7291.9 7296.3 7299.5 7312.0 5021.3 5077.8 5126.9 5224.5 5237.6 5189.6 5257.6 5262.4 5411.6 5473.2 06:00:04 INFO - PROCESS | 1945 | 06:00:04 INFO - PROCESS | 1945 | [#4] prototype.html Cycles:180 Average:5812.37 Median:6665.93 stddev:1867.81 (28.0%) stddev-sans-first:1826.69 06:00:04 INFO - PROCESS | 1945 | Values: 301.9 336.3 341.5 344.6 356.1 6658.5 6668.9 6696.3 6747.0 6749.4 6606.4 6653.5 6654.0 6700.7 6705.2 5023.9 5024.6 5027.8 5030.7 5049.5 5016.5 5025.8 5029.9 5031.5 5035.5 4293.2 4779.4 4972.5 4980.2 4996.4 4717.7 5003.3 5008.7 5013.4 5014.3 3384.5 4143.2 4144.8 4153.2 4153.7 6335.5 6384.7 6394.0 6416.6 6434.2 5679.7 5682.9 5689.7 5702.3 5706.4 6086.6 7062.6 7072.8 7073.7 7093.4 4478.2 4629.5 4643.0 4654.6 4667.4 7098.3 7223.3 7299.1 7399.6 7425.7 4614.4 4624.1 4640.2 4650.1 4668.6 4777.0 4787.8 4790.1 4799.4 4807.1 5428.2 5517.0 5617.4 5630.8 5644.2 7063.3 7077.8 7081.6 7111.9 7115.9 1632.7 1666.9 1669.4 1671.3 1672.4 879.2 1043.4 1064.1 1066.2 1077.3 5071.5 6671.0 6676.8 6683.0 6698.1 7400.7 7408.9 7430.0 7445.1 7455.2 6641.6 6647.6 6663.6 6668.2 6670.8 3551.7 3574.4 3854.2 3906.0 3920.1 6266.1 7437.4 7689.6 7705.8 7738.5 7511.9 7590.5 7597.2 7641.0 7655.9 7746.7 7766.6 7793.8 7798.2 7801.3 6363.1 6801.3 7142.3 7446.3 7463.1 7044.2 7198.1 7303.5 7305.3 7325.9 6089.8 7534.5 7692.9 7711.1 7718.8 3920.3 4645.1 4692.0 4701.4 4708.8 7415.6 7428.2 7443.1 7448.9 7475.0 6872.2 7127.2 7186.2 7199.5 7213.3 7134.9 7135.1 7143.0 7203.7 7209.6 6980.1 7009.8 7022.1 7029.7 7031.6 7135.9 7154.2 7245.2 7309.9 7317.8 6775.3 7032.6 7033.1 7051.3 7077.8 06:00:04 INFO - PROCESS | 1945 | 06:00:04 INFO - PROCESS | 1945 | [#5] yui.html Cycles:180 Average:17997.82 Median:29994.99 stddev:24555.21 (81.9%) stddev-sans-first:24589.78 06:00:04 INFO - PROCESS | 1945 | Values: 708.5 742.3 775.3 794.4 798.5 15380.2 15552.8 15558.5 15558.7 15576.9 14097.4 14244.1 14265.0 14265.2 14300.6 770.0 792.2 942.4 968.9 972.3 837.7 951.2 952.6 965.9 968.4 818.8 1019.5 1023.6 1023.6 1024.9 977.7 1006.7 1010.8 1010.9 1012.4 746.7 748.3 750.2 751.6 752.7 14937.3 14966.6 14971.8 14989.8 15050.7 12669.9 12681.6 12997.8 13223.5 13281.8 10106.1 10295.7 10328.7 10398.8 10745.4 3478.8 3517.5 3524.6 3566.3 3750.4 59402.7 77534.2 77594.0 79339.0 79649.0 9758.5 9922.4 9959.5 9985.2 10004.3 23135.7 23240.8 23241.8 23281.4 23304.8 7757.2 7827.3 7896.8 8036.6 8157.4 4128.5 4189.7 4209.7 4341.7 4478.2 3783.3 3825.4 3827.2 3829.3 3832.0 688.0 690.6 695.2 701.1 708.0 573.8 587.3 628.7 643.2 644.1 8278.2 8387.4 8408.8 8554.9 8597.2 625.7 627.4 632.0 633.1 635.9 3802.3 3813.4 3858.6 3869.9 4056.7 80401.6 80414.8 80443.6 80463.8 82523.6 71947.6 79618.2 79879.2 80833.0 81557.6 71046.4 71084.6 71417.0 71663.0 72291.3 7765.6 7939.8 8064.9 8106.9 8242.4 37608.0 49110.8 49836.8 50052.2 50332.5 67222.0 68576.0 69335.3 69412.0 70507.6 10274.7 10317.1 11065.3 11124.5 11616.8 9397.5 11877.8 11909.2 12188.7 12373.2 11587.8 11636.0 11839.0 11957.8 12134.8 9902.4 11651.4 11865.4 12125.4 12239.1 11398.0 11616.5 11719.0 11761.8 12367.3 9728.5 9807.9 9913.5 10091.2 10164.8 9657.5 9693.0 9697.9 9732.0 9760.7 06:00:04 INFO - PROCESS | 1945 | -------- Summary: end -------- 06:00:04 INFO - PROCESS | 1945 | 06:00:04 INFO - TEST-INFO | 1945: exit 0 06:00:04 INFO - TEST-OK | dromaeo_css | took 1113402ms 06:00:04 INFO - TEST-START | kraken 06:00:04 INFO - operating with platform_type : mac_ 06:00:04 INFO - Initialising browser for kraken test... 06:00:04 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/tmpq6P6ug/profile http://localhost:49398/getInfo.html) 06:00:06 INFO - PROCESS | 1948 | __metrics Screen width/height:1600/1200 06:00:06 INFO - PROCESS | 1948 | colorDepth:24 06:00:06 INFO - PROCESS | 1948 | Browser inner width/height: 1024/689 06:00:06 INFO - PROCESS | 1948 | __metrics 06:00:06 INFO - PROCESS | 1948 | 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" 06:00:06 ERROR - PROCESS | 1948 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:00:07 INFO - TEST-INFO | 1948: exit 0 06:00:07 INFO - Browser initialized. 06:00:07 INFO - Running cycle 1/1 for kraken test... 06:00:07 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/tmpq6P6ug/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:00:19 INFO - PROCESS | 1951 | RSS: Main: 160112640 06:00:19 INFO - PROCESS | 1951 | 06:00:22 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49398/tests/kraken/driver.html?testName=audio-beat-detection) 06:00:22 INFO - PROCESS | 1951 | RSS: Main: 223637504 06:00:22 INFO - PROCESS | 1951 | 06:00:25 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49398/tests/kraken/driver.html?testName=audio-dft) 06:00:26 INFO - PROCESS | 1951 | RSS: Main: 239460352 06:00:26 INFO - PROCESS | 1951 | 06:00:29 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49398/tests/kraken/driver.html?testName=audio-fft) 06:00:29 INFO - PROCESS | 1951 | RSS: Main: 326021120 06:00:29 INFO - PROCESS | 1951 | 06:00:31 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49398/tests/kraken/driver.html?testName=audio-oscillator) 06:00:32 INFO - PROCESS | 1951 | RSS: Main: 230162432 06:00:32 INFO - PROCESS | 1951 | 06:00:34 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49398/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:00:35 INFO - PROCESS | 1951 | RSS: Main: 230727680 06:00:35 INFO - PROCESS | 1951 | 06:00:38 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49398/tests/kraken/driver.html?testName=imaging-darkroom) 06:00:38 INFO - PROCESS | 1951 | RSS: Main: 268767232 06:00:38 INFO - PROCESS | 1951 | 06:00:41 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49398/tests/kraken/driver.html?testName=imaging-desaturate) 06:00:42 INFO - PROCESS | 1951 | RSS: Main: 268890112 06:00:42 INFO - PROCESS | 1951 | 06:00:44 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49398/tests/kraken/driver.html?testName=json-parse-financial) 06:00:45 INFO - PROCESS | 1951 | RSS: Main: 267620352 06:00:45 INFO - PROCESS | 1951 | 06:00:46 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49398/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:00:47 INFO - PROCESS | 1951 | RSS: Main: 260505600 06:00:47 INFO - PROCESS | 1951 | 06:00:49 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49398/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:00:49 INFO - PROCESS | 1951 | RSS: Main: 267550720 06:00:49 INFO - PROCESS | 1951 | 06:00:52 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49398/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:00:52 INFO - PROCESS | 1951 | RSS: Main: 270901248 06:00:52 INFO - PROCESS | 1951 | 06:00:55 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49398/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:00:56 INFO - PROCESS | 1951 | RSS: Main: 278196224 06:00:56 INFO - PROCESS | 1951 | 06:00:58 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49398/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:00:59 INFO - PROCESS | 1951 | RSS: Main: 275271680 06:00:59 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:49398/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49398/tests/kraken/driver.html?testName=ai-astar) 06:01:01 INFO - PROCESS | 1951 | RSS: Main: 336596992 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | __start_tp_report 06:01:01 INFO - PROCESS | 1951 | _x_x_mozilla_page_load 06:01:01 INFO - PROCESS | 1951 | _x_x_mozilla_page_load_details 06:01:01 INFO - PROCESS | 1951 | |i|pagename|runs| 06:01:01 INFO - PROCESS | 1951 | |0;ai-astar;113;114;118;113;112;112;113;114;113;132 06:01:01 INFO - PROCESS | 1951 | |1;audio-beat-detection;155;119;144;165;120;146;122;152;157;118 06:01:01 INFO - PROCESS | 1951 | |2;audio-dft;188;186;184;184;187;186;183;185;183;187 06:01:01 INFO - PROCESS | 1951 | |3;audio-fft;71;82;79;195;83;55;55;62;134;57 06:01:01 INFO - PROCESS | 1951 | |4;audio-oscillator;92;92;148;81;79;89;120;79;78;158 06:01:01 INFO - PROCESS | 1951 | |5;imaging-gaussian-blur;87;84;88;87;91;85;89;85;88;83 06:01:01 INFO - PROCESS | 1951 | |6;imaging-darkroom;92;93;93;93;92;93;93;93;94;92 06:01:01 INFO - PROCESS | 1951 | |7;imaging-desaturate;84;85;83;84;83;84;85;85;83;86 06:01:01 INFO - PROCESS | 1951 | |8;json-parse-financial;61;64;64;63;64;64;63;80;59;57 06:01:01 INFO - PROCESS | 1951 | |9;json-stringify-tinderbox;61;62;64;74;73;69;65;66;66;66 06:01:01 INFO - PROCESS | 1951 | |10;stanford-crypto-aes;80;80;80;79;79;79;77;79;81;84 06:01:01 INFO - PROCESS | 1951 | |11;stanford-crypto-ccm;129;135;137;140;122;123;124;125;144;143 06:01:01 INFO - PROCESS | 1951 | |12;stanford-crypto-pbkdf2;175;143;190;149;198;223;218;166;162;157 06:01:01 INFO - PROCESS | 1951 | |13;stanford-crypto-sha256-iterative;70;67;68;67;67;67;69;84;97;94 06:01:01 INFO - PROCESS | 1951 | __end_tp_report 06:01:01 INFO - PROCESS | 1951 | __start_cc_report 06:01:01 INFO - PROCESS | 1951 | _x_x_mozilla_cycle_collect,1904 06:01:01 INFO - PROCESS | 1951 | __end_cc_report 06:01:01 INFO - PROCESS | 1951 | __startTimestamp1469192461612__endTimestamp 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | ------- Summary: start ------- 06:01:01 INFO - PROCESS | 1951 | Number of tests: 14 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#0] ai-astar Cycles:10 Average:115.40 Median:113.50 stddev:6.08 (5.4%) stddev-sans-first:6.38 06:01:01 INFO - PROCESS | 1951 | Values: 113.0 114.0 118.0 113.0 112.0 112.0 113.0 114.0 113.0 132.0 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:149.00 stddev:18.21 (12.2%) stddev-sans-first:18.46 06:01:01 INFO - PROCESS | 1951 | Values: 155.0 119.0 144.0 165.0 120.0 146.0 122.0 152.0 157.0 118.0 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#2] audio-dft Cycles:10 Average:185.30 Median:186.00 stddev:1.77 (0.9%) stddev-sans-first:1.58 06:01:01 INFO - PROCESS | 1951 | Values: 188.0 186.0 184.0 184.0 187.0 186.0 183.0 185.0 183.0 187.0 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#3] audio-fft Cycles:10 Average:87.30 Median:66.50 stddev:44.48 (66.9%) stddev-sans-first:46.79 06:01:01 INFO - PROCESS | 1951 | Values: 71.0 82.0 79.0 195.0 83.0 55.0 55.0 62.0 134.0 57.0 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:80.00 stddev:29.85 (37.3%) stddev-sans-first:31.46 06:01:01 INFO - PROCESS | 1951 | Values: 92.0 92.0 148.0 81.0 79.0 89.0 120.0 79.0 78.0 158.0 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.50 stddev:2.45 (2.8%) stddev-sans-first:2.60 06:01:01 INFO - PROCESS | 1951 | Values: 87.0 84.0 88.0 87.0 91.0 85.0 89.0 85.0 88.0 83.0 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 06:01:01 INFO - PROCESS | 1951 | Values: 92.0 93.0 93.0 93.0 92.0 93.0 93.0 93.0 94.0 92.0 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.50 stddev:1.03 (1.2%) stddev-sans-first:1.09 06:01:01 INFO - PROCESS | 1951 | Values: 84.0 85.0 83.0 84.0 83.0 84.0 85.0 85.0 83.0 86.0 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:64.00 stddev:6.15 (9.6%) stddev-sans-first:6.44 06:01:01 INFO - PROCESS | 1951 | Values: 61.0 64.0 64.0 63.0 64.0 64.0 63.0 80.0 59.0 57.0 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:66.00 stddev:4.27 (6.5%) stddev-sans-first:4.02 06:01:01 INFO - PROCESS | 1951 | Values: 61.0 62.0 64.0 74.0 73.0 69.0 65.0 66.0 66.0 66.0 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:80.00 stddev:1.81 (2.3%) stddev-sans-first:1.92 06:01:01 INFO - PROCESS | 1951 | Values: 80.0 80.0 80.0 79.0 79.0 79.0 77.0 79.0 81.0 84.0 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#11] stanford-crypto-ccm Cycles:10 Average:132.20 Median:136.00 stddev:8.60 (6.3%) stddev-sans-first:9.04 06:01:01 INFO - PROCESS | 1951 | Values: 129.0 135.0 137.0 140.0 122.0 123.0 124.0 125.0 144.0 143.0 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.10 Median:182.50 stddev:28.06 (15.4%) stddev-sans-first:29.74 06:01:01 INFO - PROCESS | 1951 | Values: 175.0 143.0 190.0 149.0 198.0 223.0 218.0 166.0 162.0 157.0 06:01:01 INFO - PROCESS | 1951 | 06:01:01 INFO - PROCESS | 1951 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:69.50 stddev:11.98 (17.2%) stddev-sans-first:12.57 06:01:01 INFO - PROCESS | 1951 | Values: 70.0 67.0 68.0 67.0 67.0 67.0 69.0 84.0 97.0 94.0 06:01:01 INFO - PROCESS | 1951 | -------- Summary: end -------- 06:01:01 INFO - PROCESS | 1951 | 06:01:02 INFO - TEST-INFO | 1951: exit 0 06:01:02 INFO - TEST-OK | kraken | took 57658ms 06:01:02 INFO - SUITE-END | took 1171s 06:01:02 INFO - Completed test suite (00:19:31) 06:01:02 INFO - javascript benchmark 06:01:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3502.2295095079085, 3589.515415418921, 3593.4430163324696, 3602.1534939289263, 3673.4673472346512, 5880.970595147023, 5950.732217050243, 5954.166416701665, 5996.2504686914135, 5998.190244317021, 5961.910571341434, 5968.104784282349, 6012.038073908176, 6016.187814645022, 6096.939030609693, 5317.973410132944, 5397.919031214535, 5402.189671549269, 5408.242846001563, 5426.484483974016, 5391.595630327722, 5443.782248710047, 5467.043267428204, 5472.699001554913, 5472.781088756445, 5405.972970135143, 5459.535939445152, 5463.562914966813, 5484.396716361197, 5494.36814766301, 5151.330327057459, 5190.948090519084, 5468.207109969052, 5483.972580137113, 5496.6701997880255, 4319.3952846601505, 5316.308879845634, 5330.786768529254, 5382.838514844561, 5440.700761458118, 5417.4311697272005, 5814.156947242626, 5873.412658734135, 5886.529077673776, 5915.438033386821, 6282.591631543935, 6302.590331628429, 6331.081993110973, 6335.936640633581, 6355.142055822451, 6198.256209254873, 6313.305536390993, 6395.20059992501, 6402.199725034371, 6409.070684750732, 6120.571559990803, 6149.385061493859, 6157.107219453199, 6160.9383906160365, 6177.567570270038, 6520.673966301666, 6522.445592124628, 6537.052127441494, 6541.574797638138, 6542.672866356663, 6256.186695729526, 6290.811275661737, 6343.365663433619, 6367.076773867763, 6369.426751592379, 4826.758662066953, 6390.9360906391275, 6417.326180751047, 6427.1002059710745, 6441.291457939623, 6262.874742505124, 6280.811575652738, 6303.338149494237, 6309.968450157719, 6367.7452901884335, 4848.442429120626, 6239.407256310643, 6240.063990401476, 6339.2709838368255, 6341.175647165931, 5134.2298655201275, 5138.229265610188, 5140.845774626767, 5142.5628821549835, 5167.3799144102195, 4603.171429142786, 4606.815727370935, 4609.746463944515, 4618.907621847544, 4640.257558790645, 5927.703614819242, 6059.666718330446, 6081.604695694854, 6088.634681919099, 6094.421030002142, 6304.464120549805, 6320.367963203735, 6348.0, 6348.333424990309, 6359.17330747, 6036.577439579188, 6113.57204995655, 6142.959235178615, 6146.170267013986, 6153.230846144232, 5582.609217354828, 5597.776088956396, 5602.719864006784, 5603.019471592455, 5611.382747897728, 6464.191976003, 6474.5791523550815, 6626.966865165643, 6644.634545099969, 6650.3682150195655, 6410.294867564658, 6410.711517981796, 6411.647359395278, 6424.0, 6435.002574600944, 6554.279029306867, 6617.636030018395, 6642.2361428434415, 6663.5002374821115, 6667.033280174444, 6254.28075771274, 6283.3402492737605, 6348.682565871595, 6365.045243213555, 6369.267534233622, 6270.090836828543, 6382.904256436064, 6389.137466442156, 6405.391487808651, 6420.133282006869, 6338.873222535616, 6352.237731472253, 6358.841028974312, 6366.363363663597, 6375.4262116409745, 6217.689115544295, 6238.407351301619, 6269.028300613412, 6326.810195694136, 6361.141245931925, 6238.157848690369, 6270.686465676789, 6287.056941458818, 6296.118543403835, 6297.1813664223355, 6316.7157477914525, 6366.172397588284, 6377.4579160771855, 6379.202599675041, 6383.96808015957, 6138.171346868295, 6186.969065154645, 6226.906596401148, 6233.189685340877, 6233.594816336924], "value": 5897.313201968195, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.9076766182087, 523.6125267302195, 606.9802731411229, 611.3519669150703, 629.2920464477463, 29882.551761723593, 30438.84780576097, 39975.60024399755, 40402.19195616093, 40536.79731601345, 43362.915927101836, 43960.0, 43969.34045989305, 43989.0, 43996.0, 8510.234078932926, 8887.777805554848, 9619.519024048786, 9828.508574571279, 9850.310478266525, 9074.047225041375, 11907.583234586791, 12147.574834880781, 12167.209131406431, 12173.452194651218, 11739.589114380999, 11818.349990750505, 11830.8225376619, 11833.704157396049, 11842.467088981017, 11732.0, 11828.7634247315, 11835.526578936831, 11840.993515551188, 11844.644660660195, 11781.057515398834, 11898.22661526998, 11900.464479098462, 11904.523819047228, 12144.149909506337, 42013.0, 42463.7260882174, 42465.78767106176, 42509.78745106285, 42585.57414425847, 3328.334333133359, 3415.7608967372043, 3421.0, 3481.9651803481893, 4008.6191811777835, 29145.854270728723, 29186.832526698905, 29231.707682923323, 30493.69506304931, 30629.0, 2791.8883244670187, 2803.8457884816326, 2804.340979869729, 2868.913932582026, 2909.0254764653864, 464929.0, 465841.0, 467866.0, 468361.0, 469850.0, 37816.243675126345, 38276.0, 38355.04112397157, 67385.32614673888, 67933.66033169802, 29971.400571988437, 36197.45703814445, 36269.0, 36368.27263454716, 36449.817750911076, 16196.59508512273, 16361.0, 16361.754573681405, 16396.426125085563, 16420.917895410446, 12976.0, 13083.280419577015, 13704.794428083587, 14040.508582199574, 14080.084794488326, 6872.759453419107, 8302.626381812834, 8329.12544182852, 8333.66665333392, 8393.118722534045, 3499.33512632599, 3522.841472133709, 3523.735719821024, 3544.255706301654, 3563.572371315407, 932.6922115701802, 1013.5489707080426, 1052.6106533342538, 1082.442542090824, 1090.8690957085041, 30738.31445639924, 31636.525452118236, 31697.36605267882, 31926.680733192836, 32181.839090804395, 846.6782622603495, 909.3316412436853, 965.7730433348157, 1147.6729132197283, 1233.0382301804427, 13975.860241397659, 16159.353625855068, 16227.837721622633, 16247.675046499004, 16361.509154725378, 635970.0, 642706.0, 643835.0, 645406.0, 646538.0, 64665.0, 65708.6714566424, 65709.68580628361, 65744.6712766433, 65905.0, 1401.8037474753542, 1500.7823865539435, 1541.2139808697868, 1542.6066353079893, 1542.706885691715, 24796.0, 28319.150425487267, 31328.21679458032, 31403.371932560764, 31510.369792604477, 51784.0, 52576.21135682891, 53187.6703082426, 53221.733891330296, 53277.73361133169, 442133.0, 444447.0, 444526.3321050122, 444935.0, 630588.0, 27967.44065118727, 28688.42623147567, 28884.0, 28890.98881539136, 28898.711012890442, 44942.0, 46674.0, 46845.2973205409, 47598.76200618975, 48348.75825620849, 36975.44536831896, 38457.615423845404, 38485.4227186598, 38683.80658096691, 38764.0, 37856.05359865915, 37980.341376104516, 38129.80935095418, 38502.0, 38799.030024249616, 38025.4296185563, 38039.80980095192, 39236.80381598074, 39286.41070383889, 39454.0, 33784.831075844464, 33842.49236261507, 34097.0, 34414.0, 34530.48204276988, 25482.617760733232, 25551.0, 25584.616230756925, 25989.0, 26039.479210416066], "value": 19270.440812378074, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.03124376521293, 406.74171900842975, 415.9334506478964, 418.20958388645465, 418.42048762463986, 19770.802291977077, 19975.40073797785, 20658.89670551645, 20681.276155334566, 20754.896225518853, 20062.197512099498, 20236.69644955327, 21014.57970840582, 21027.0, 21090.578188436222, 4969.826056088038, 5763.366029736736, 5921.733521991499, 6007.189029481008, 6024.397560243984, 5872.647641141524, 5953.732082056296, 6003.099535069731, 6006.849828754294, 6009.308929473123, 5728.942710572904, 5935.88128237435, 5945.732442040097, 5981.9700901495435, 5990.820275391723, 5877.9412205877825, 5907.438793314628, 5934.821955341346, 5942.108683697458, 5948.940510594882, 5097.133487307166, 5131.974340128276, 5214.113600687892, 5219.765110570033, 5224.843254702365, 13051.54319598819, 13723.519676811264, 13729.519466818714, 26337.68311584413, 26422.339441513923, 2056.0542150610736, 2202.1631779923687, 2215.9695741480127, 2260.5139894922504, 2445.5720248956363, 9881.16010139139, 10220.89779102207, 10461.215408844368, 10589.576416943313, 13958.302084895715, 1731.1690388613536, 1832.0107142143227, 1832.0473353856053, 1836.1370156026646, 1851.574137948266, 67351.0, 103937.0, 110421.0, 121032.0, 128886.0, 9865.358751681119, 11857.525698971944, 12136.393180340947, 12164.14850960442, 12324.137310388187, 14042.719145617031, 14535.927320363331, 14559.563213103624, 14565.198914059829, 14571.41714331415, 8004.439689221698, 9050.140236677506, 9175.908240917639, 9202.493862837475, 9265.119813617695, 9300.0, 9306.395084319498, 9326.626934922528, 9428.0, 9441.527923603793, 4572.154151481986, 4736.194846876039, 4811.422629284439, 4848.200046992278, 4888.731119788375, 1967.501273382796, 2008.5480766827347, 2010.839132869367, 2017.9103284226646, 2022.555037891691, 853.3599800149888, 950.7290422229631, 980.1766516126488, 1059.7403636109202, 1067.887871773468, 20948.0, 21440.89279553592, 21519.816410097283, 21720.78279217219, 22143.335699929026, 994.2543092680489, 1018.8217062014118, 1054.414799786126, 1055.3245922609535, 1060.7772367802847, 10994.560217591366, 11396.430178491042, 11401.885981140082, 11406.315621062762, 11424.942875285737, 643588.0, 644763.0, 654079.0, 658278.0, 661961.0, 121305.08254257159, 130993.0, 134237.3288133553, 134478.0, 135242.97135542973, 1039.6465201831413, 1045.534737042009, 1066.3814987307499, 1119.6137332620233, 1123.2081382625229, 18645.72031419557, 18914.716279256096, 19288.90355548213, 19301.517462063563, 19609.803901960797, 53889.73055134699, 54074.72962635162, 54254.45745542494, 57984.0, 58022.12966805417, 77824.22175778169, 80567.0, 81375.0, 82945.0, 83578.59799255975, 8210.384221183554, 8294.42405942869, 8386.32909367237, 8481.491110533389, 8521.190486903733, 11733.530658773554, 12828.615141545768, 12888.42002109926, 13157.60527184186, 13181.538646147339, 11090.279131856403, 11255.437228138724, 11420.0, 11505.252158609663, 11547.422628868691, 9857.0, 10742.355458672504, 10850.860659630625, 11083.612073577386, 11197.272177308774, 11230.21388502797, 11358.60244891425, 11372.54509819598, 11521.746723147988, 11525.423728813694, 8193.959030204811, 8295.751127466187, 8317.251447369767, 8569.785755356166, 8632.438891472035, 8941.731748047569, 11467.713307167054, 11576.421178941187, 11624.825627615422, 11830.526778928746], "value": 9718.850710923125, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1399.7821894951394, 1651.5128037229017, 1670.08145519964, 1945.0, 1999.9600007999832, 7878.9212107878975, 8579.399442039052, 9072.364934454583, 9255.583498742555, 9257.953710231439, 9062.093790620935, 9141.680041198559, 9247.121523455278, 9257.398269112504, 9263.166315031664, 2562.7693507584313, 2585.8448493090473, 2588.4823035392997, 2589.197348821862, 2589.4691588224464, 2516.584763514027, 2579.793616510684, 2581.1611226351415, 2581.6127580862926, 2581.8450892946485, 2547.9108231211912, 2579.742025797424, 2596.870156492177, 2600.4799040191942, 2604.5572252717084, 2547.0703193334484, 2568.691756989164, 2580.9870950645313, 2587.4825034993078, 2593.6239245309325, 2547.0066673997158, 2570.7043689975703, 2582.690077190749, 2585.1856665150417, 2586.081940910968, 9323.953380233055, 9373.718788436357, 9395.295352848496, 9398.62405503779, 9400.670976515788, 5642.971785141089, 5761.308642962829, 5763.049096899007, 5823.0, 5829.854253643651, 3689.797061161635, 3742.2889650966504, 3786.545614526247, 3806.1816709407613, 3807.428885667144, 941.1529623338981, 1032.4528000159928, 1038.9688309350656, 1075.0754351257913, 1105.171121658756, 12249.693757656041, 12488.313142777144, 12499.312537810416, 12616.495340186382, 12688.492460301577, 5064.392272927273, 5070.391553013662, 5204.4535323791215, 5220.955808838247, 5227.634065615371, 10876.564937402416, 10879.0, 10900.454977251264, 10907.0, 10927.3989930553, 5868.501177399883, 5872.970635146797, 5877.0, 5888.057910734262, 5890.617109887844, 4734.502877197845, 4799.66402351832, 5090.643654944192, 5210.062188805976, 5290.947090529123, 3645.3620616392554, 4197.265478541304, 4236.237477254123, 4309.224339618836, 4327.199468098412, 1401.3133564553295, 1437.8921580881477, 1440.6182361674164, 1446.428660679047, 1447.5657302809116, 903.4804987132425, 903.7966457547, 957.6456711016968, 970.7427531704104, 993.3692105512907, 6051.515878729691, 6145.877082458326, 6151.384861513902, 6231.127642130105, 6264.561480696399, 482.8430760003013, 489.50559934466446, 497.4056003076361, 1038.413296487482, 1062.198040479433, 4042.797860106983, 4075.2257071156387, 4136.255474014646, 4149.004238982622, 4150.335946248586, 10718.89281107179, 11024.062954648945, 11137.109031277478, 11148.331100134017, 11178.99389054989, 10852.620158294421, 10881.619143330105, 10883.619073332395, 11028.28316159447, 11043.83434248487, 8555.614997325136, 8616.39685222041, 8642.43824151428, 8643.308535317034, 8671.08953559867, 5708.800191993427, 6057.42454466825, 6114.816555503342, 6166.1983942087245, 6169.753209871555, 10172.593096276361, 10176.18590512772, 10221.386716797017, 10250.282480226313, 10276.280660353703, 10239.232057595858, 10376.16990640762, 10564.154867610727, 10580.206484513847, 10610.363378197333, 7897.68409263623, 7983.2415920487465, 8032.799180020546, 8140.592970351578, 8154.469959452616, 8776.254018408506, 8824.3381746368, 8849.69026084084, 8851.424657397249, 8949.910500894908, 6532.673366331569, 6943.895841562482, 7761.417893658111, 8024.598770061357, 8062.274395304451, 7677.270659287359, 7753.418493613114, 7770.0287464067, 7889.605519724106, 7927.960360198162, 7219.0, 7291.854162916606, 7296.306850849161, 7299.5255308404785, 7312.0, 5021.297018417497, 5077.796888124433, 5126.923096153486, 5224.451432599522, 5237.554807841224, 5189.558887494605, 5257.63196576236, 5262.368515778131, 5411.621186516906, 5473.233747275464], "value": 4823.229715416259, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.8551095474171, 336.290427198611, 341.485511755365, 344.6467370944782, 356.11327793793464, 6658.500612454073, 6668.933310666892, 6696.3303669633015, 6747.0, 6749.358810912961, 6606.405423511883, 6653.50098742595, 6653.966730166355, 6700.664966751655, 6705.1953765548205, 5023.899522009558, 5024.598032157437, 5027.84916452507, 5030.673006254582, 5049.545540901318, 5016.49835016499, 5025.798968041292, 5029.924551131736, 5031.547160755531, 5035.496450354972, 4293.184294983958, 4779.4227904791505, 4972.5276098770555, 4980.153373926423, 4996.350474438318, 4717.669763116583, 5003.274525193845, 5008.749562521859, 5013.4234563025175, 5014.348134742497, 3384.526166336715, 4143.1713657268365, 4144.772037537934, 4153.210889930903, 4153.709240353177, 6335.524835637346, 6384.7126879290545, 6393.9680301598655, 6416.582922110048, 6434.195725534309, 5679.659220446786, 5682.868924153442, 5689.687067211302, 5702.3157221133315, 5706.3722990471015, 6086.573939824214, 7062.576245425291, 7072.752453664149, 7073.717051317941, 7093.3970612498, 4478.2386994211065, 4629.490756016836, 4642.976785116087, 4654.604358629521, 4667.369905062807, 7098.254683258235, 7223.313785190398, 7299.124105107368, 7399.556026638419, 7425.665845036986, 4614.353990441341, 4624.1445332613575, 4640.164770341371, 4650.139724151043, 4668.6498512611015, 4777.044591081798, 4787.808487660524, 4790.113828941657, 4799.448063472745, 4807.110684523374, 5428.212909128154, 5516.972415137979, 5617.354004289477, 5630.8029218977135, 5644.153376993484, 7063.328983746535, 7077.823054423578, 7081.645917704094, 7111.92888071123, 7115.89326160108, 1632.7224371856812, 1666.8749843761766, 1669.4323929863663, 1671.3398207707887, 1672.3812189489722, 879.1823604048296, 1043.4052590023766, 1064.11678307005, 1066.248294952057, 1077.3212875888141, 5071.4928507149725, 6671.0, 6676.799696009128, 6682.966585167043, 6698.062271281927, 7400.740974065882, 7408.888866667005, 7430.0, 7445.10658720957, 7455.179930207672, 6641.634710090895, 6647.60114393138, 6663.633500157538, 6668.233153187349, 6670.799876003728, 3551.733619978512, 3574.392353299945, 3854.171353159028, 3906.0430194601936, 3920.137569734654, 6266.060090986297, 7437.442191835634, 7689.615519224017, 7705.807354816175, 7738.4969976951315, 7511.887321690179, 7590.506617069873, 7597.202293759186, 7640.961795190989, 7655.923440765632, 7746.651400686983, 7766.5728384939375, 7793.844123117506, 7798.181190974866, 7801.29788319054, 6363.077353783768, 6801.251862295243, 7142.285771422816, 7446.292602202782, 7463.141738699902, 7044.225135235222, 7198.100237470317, 7303.452241082046, 7305.3425191732995, 7325.890111648436, 6089.756409743738, 7534.54792712439, 7692.92307076922, 7711.11322197932, 7718.768436946901, 3920.294347017564, 4645.070985802786, 4691.976540117277, 4701.365315682339, 4708.835190768444, 7415.629218539159, 7428.182899881117, 7443.106827180556, 7448.8882666758955, 7475.0, 6872.175338959357, 7127.216006239206, 7186.245444228281, 7199.532030418006, 7213.314735100157, 7134.85730285381, 7135.072440582691, 7143.035690181759, 7203.71185152588, 7209.6395180241825, 6980.092587963735, 7009.7897063088185, 7022.12223472066, 7029.718811247493, 7031.578105313698, 7135.85728285421, 7154.2130365658695, 7245.166805817398, 7309.853802923805, 7317.780466586011, 6775.288594697486, 7032.648367581498, 7033.085698859116, 7051.259617740268, 7077.752278670222], "value": 5184.99573183528, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.5075872268771, 742.3022358982557, 775.3138472451878, 794.3962588432789, 798.476997566594, 15380.154091524962, 15552.844471555281, 15558.45545405911, 15558.688826223468, 15576.922115389421, 14097.436102555885, 14244.074135181207, 14265.0, 14265.215413152273, 14300.642483937881, 769.96920123195, 792.1523969352795, 942.4109931292942, 968.8934217236099, 972.2562239886493, 837.6733074101105, 951.2295041016798, 952.556876332357, 965.946872921989, 968.3802366485455, 818.8239528501371, 1019.4800651667666, 1023.5598692562195, 1023.6468418395641, 1024.9077583017565, 977.7408986618552, 1006.6979906028199, 1010.8129995950699, 1010.914072303855, 1012.3723291559206, 746.7199800074972, 748.2629609834308, 750.2422553221231, 751.6016511249047, 752.6537792615349, 14937.25313734309, 14966.62583435412, 14971.775423368657, 14989.775153372708, 15050.698986020328, 12669.936650316782, 12681.619551413472, 12997.805032924514, 13223.537176198883, 13281.800772988412, 10106.090451859294, 10295.742606434824, 10328.741781455448, 10398.844017339747, 10745.355278683304, 3478.826058697055, 3517.525134106914, 3524.559430071241, 3566.32239874423, 3750.3999360102252, 59402.702986484794, 77534.22465775326, 77594.0, 79339.0, 79648.99999999942, 9758.512074396253, 9922.355046921926, 9959.502024898871, 9985.1512621428, 10004.299699021145, 23135.652965205536, 23240.837958102027, 23241.76758232396, 23281.417964551023, 23304.7669523306, 7757.224277572311, 7827.256410641094, 7896.763097107096, 8036.557989310527, 8157.388195885333, 4128.4632997710105, 4189.706720529533, 4209.705320627588, 4341.696081274281, 4478.193925093515, 3783.319002579507, 3825.387937929918, 3827.1580252344997, 3829.329867273216, 3832.0419895026243, 687.9518433709592, 690.5856486108395, 695.1935754524727, 701.130598058404, 708.0476758759461, 573.7953529537497, 587.2864469669372, 628.7233617208414, 643.2120652201016, 644.0757512968811, 8278.213569710868, 8387.370947178864, 8408.78978025542, 8554.871676924851, 8597.226249637437, 625.6934102289845, 627.3914303125961, 631.9589226700342, 633.1199632510813, 635.8728254349148, 3802.296575133609, 3813.389857622767, 3858.575556688762, 3869.941950870739, 4056.675465962716, 80401.59799200967, 80414.79377809337, 80443.59778201189, 80463.7930431044, 82523.5873820627, 71947.6402617994, 79618.20381796223, 79879.20120798834, 80833.0, 81557.59221203857, 71046.3819851708, 71084.64457677679, 71417.0, 71663.0, 72291.27708722846, 7765.572893490912, 7939.801504962191, 8064.879026814485, 8106.918930810616, 8242.421832676575, 37608.0, 49110.75444622754, 49836.75408114681, 50052.24921626251, 50332.49667503278, 67222.0, 68576.0, 69335.30664693289, 69412.0, 70507.64746176236, 10274.743131421475, 10317.123044541298, 11065.280756750786, 11124.499397526968, 11616.767664646828, 9397.483138427453, 11877.804522896808, 11909.22590031645, 12188.719614874353, 12373.195742276723, 11587.826182607436, 11636.0, 11839.0, 11957.76084478323, 12134.817977730165, 9902.356346837432, 11651.41742912834, 11865.406729663655, 12125.393730313626, 12239.143259971717, 11398.0, 11616.535338586362, 11719.0, 11761.764764704829, 12367.319797411228, 9728.464934428674, 9807.9019209807, 9913.45475998827, 10091.19270458347, 10164.796704066026, 9657.517124143624, 9693.0, 9697.854532181882, 9732.0, 9760.707178784647], "value": 7052.5274770701335, "unit": "score"}], "name": "dromaeo_css", "value": 7613.849970039057, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 118.0, 113.0, 112.0, 112.0, 113.0, 114.0, 113.0, 132.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 119.0, 144.0, 165.0, 120.0, 146.0, 122.0, 152.0, 157.0, 118.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 186.0, 184.0, 184.0, 187.0, 186.0, 183.0, 185.0, 183.0, 187.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 82.0, 79.0, 195.0, 83.0, 55.0, 55.0, 62.0, 134.0, 57.0], "value": 87.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 148.0, 81.0, 79.0, 89.0, 120.0, 79.0, 78.0, 158.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 87.0, 91.0, 85.0, 89.0, 85.0, 88.0, 83.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 93.0, 92.0, 93.0, 93.0, 93.0, 94.0, 92.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 83.0, 84.0, 83.0, 84.0, 85.0, 85.0, 83.0, 86.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 64.0, 63.0, 64.0, 64.0, 63.0, 80.0, 59.0, 57.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 64.0, 74.0, 73.0, 69.0, 65.0, 66.0, 66.0, 66.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 80.0, 79.0, 79.0, 79.0, 77.0, 79.0, 81.0, 84.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 135.0, 137.0, 140.0, 122.0, 123.0, 124.0, 125.0, 144.0, 143.0], "value": 132.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 143.0, 190.0, 149.0, 198.0, 223.0, 218.0, 166.0, 162.0, 157.0], "value": 178.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 68.0, 67.0, 67.0, 67.0, 69.0, 84.0, 97.0, 94.0], "value": 75.0, "unit": "score"}], "name": "kraken", "value": 1488.7, "alertThreshold": 2.0}]} 06:01:02 INFO - Return code: 0 06:01:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:01:02 ERROR - # TBPL SUCCESS # 06:01:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:01:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:01:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:01:02 INFO - 2016-07-22 06:01:02.856 screenresolution[1955:16746] starting screenresolution argv=screenresolution get 06:01:02 INFO - 2016-07-22 06:01:02.859 screenresolution[1955:16746] Display 0: 1600x1200x32@60 06:01:02 INFO - 2016-07-22 06:01:02.865 screenresolution[1956:16749] starting screenresolution argv=screenresolution list 06:01:02 INFO - Available Modes on Display 0 06:01:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:01:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:01:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:01:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:01:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:01:03 INFO - Intel Iris: 06:01:03 INFO - Chipset Model: Intel Iris 06:01:03 INFO - Type: GPU 06:01:03 INFO - Bus: Built-In 06:01:03 INFO - VRAM (Dynamic, Max): 1536 MB 06:01:03 INFO - Vendor: Intel (0x8086) 06:01:03 INFO - Device ID: 0x0a2e 06:01:03 INFO - Revision ID: 0x0009 06:01:03 INFO - Displays: 06:01:03 INFO - Display: 06:01:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:01:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:01:03 INFO - Display Serial Number: 1600x1200 60 06:01:03 INFO - Main Display: Yes 06:01:03 INFO - Mirror: Off 06:01:03 INFO - Online: Yes 06:01:03 INFO - Rotation: Supported 06:01:03 INFO - Return code: 0 06:01:03 INFO - Running post-action listener: _resource_record_post_action 06:01:03 INFO - Running post-run listener: _resource_record_post_run 06:01:04 INFO - Total resource usage - Wall time: 1195s; CPU: 52.0%; Read bytes: 1188352; Write bytes: 492325376; Read time: 15; Write time: 2017 06:01:04 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 189022720; Write bytes: 184782848; Read time: 17768; Write time: 323 06:01:04 INFO - run-tests - Wall time: 1173s; CPU: 52.0%; Read bytes: 1163776; Write bytes: 294814720; Read time: 15; Write time: 1580 06:01:04 INFO - Running post-run listener: _upload_blobber_files 06:01:04 INFO - Blob upload gear active. 06:01:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:01:04 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 06:01:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:01:04 INFO - (blobuploader) - INFO - Open directory for files ... 06:01:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:01:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:01:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:05 INFO - (blobuploader) - INFO - Done attempting. 06:01:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:01:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:01:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:06 INFO - (blobuploader) - INFO - Done attempting. 06:01:06 INFO - (blobuploader) - INFO - Iteration through files over. 06:01:06 INFO - Return code: 0 06:01:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:01:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:01:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d25a9318daf61b39b968838948ae412937640ede1fdab579d736ef7dac63c27716939c2e7e98f291fce1cf400ee01d41bcb2c099d633fb40044a288ac76d8d67", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e8448e45575378e1c4d8bd3e98d5e9de7a9c1ba3640606a2112f4dab1e08c31fb71674f00ae2a454a8891f21b5148557fc0d8006b5c646e294ce2403f60641c"} 06:01:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:01:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:01:06 INFO - Contents: 06:01:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d25a9318daf61b39b968838948ae412937640ede1fdab579d736ef7dac63c27716939c2e7e98f291fce1cf400ee01d41bcb2c099d633fb40044a288ac76d8d67", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e8448e45575378e1c4d8bd3e98d5e9de7a9c1ba3640606a2112f4dab1e08c31fb71674f00ae2a454a8891f21b5148557fc0d8006b5c646e294ce2403f60641c"} 06:01:06 INFO - Running post-run listener: copy_logs_to_upload_dir 06:01:06 INFO - Copying logs to upload dir... 06:01:06 INFO - mkdir: /builds/slave/test/build/upload/logs 06:01:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.655785 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-07-22 06:01:06.901828) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 06:01:06.909446) ========= 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.KwIsgyJZRp/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.YT899LOX7E/Listeners TMPDIR=/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d25a9318daf61b39b968838948ae412937640ede1fdab579d736ef7dac63c27716939c2e7e98f291fce1cf400ee01d41bcb2c099d633fb40044a288ac76d8d67", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e8448e45575378e1c4d8bd3e98d5e9de7a9c1ba3640606a2112f4dab1e08c31fb71674f00ae2a454a8891f21b5148557fc0d8006b5c646e294ce2403f60641c"} build_url:https://queue.taskcluster.net/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011402 build_url: 'https://queue.taskcluster.net/v1/task/QwybpbUCSP6g8foZ6_w_kw/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d25a9318daf61b39b968838948ae412937640ede1fdab579d736ef7dac63c27716939c2e7e98f291fce1cf400ee01d41bcb2c099d633fb40044a288ac76d8d67", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e8448e45575378e1c4d8bd3e98d5e9de7a9c1ba3640606a2112f4dab1e08c31fb71674f00ae2a454a8891f21b5148557fc0d8006b5c646e294ce2403f60641c"}' ========= master_lag: 0.42 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 06:01:07.339414) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 06:01:07.339764) ========= 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.KwIsgyJZRp/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.YT899LOX7E/Listeners TMPDIR=/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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.005141 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 06:01:07.388658) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 06:01:07.388983) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 06:01:07.389299) ========= ========= Total master_lag: 0.59 =========