builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0215 starttime: 1463626822.81 results: success (0) buildid: 20160518184444 builduid: 067369e73c60416d88ca524a2aa8569c revision: 4902143daafcfca72916ac8cba4ae8150ef271a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 20:00:22.805526) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 20:00:22.805980) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 20:00:22.806291) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 20:00:22.830785) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:00:22.831105) ========= 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.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-18 20:00:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.33M=0.002s 2016-05-18 20:00:22 (7.33 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125378 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:00:22.989876) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:00:22.990214) ========= 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.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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.020664 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:00:23.039213) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-18 20:00:23.039610) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4902143daafcfca72916ac8cba4ae8150ef271a1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4902143daafcfca72916ac8cba4ae8150ef271a1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-18 20:00:23,109 truncating revision to first 12 chars 2016-05-18 20:00:23,109 Setting DEBUG logging. 2016-05-18 20:00:23,109 attempt 1/10 2016-05-18 20:00:23,109 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4902143daafc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 20:00:24,945 unpacking tar archive at: mozilla-aurora-4902143daafc/testing/mozharness/ program finished with exit code 0 elapsedTime=2.098101 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-18 20:00:25.155927) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 20:00:25.156456) ========= script_repo_revision: 4902143daafcfca72916ac8cba4ae8150ef271a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 20:00:25.157105) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 20:00:25.157616) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 20:00:25.173891) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-18 20:00:25.174310) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 20:00:25 INFO - MultiFileLogger online at 20160518 20:00:25 in /builds/slave/test 20:00:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 20:00:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:00:25 INFO - {'append_to_log': False, 20:00:25 INFO - 'base_work_dir': '/builds/slave/test', 20:00:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:00:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:00:25 INFO - 'branch': 'Mozilla-Aurora', 20:00:25 INFO - 'buildbot_json_path': 'buildprops.json', 20:00:25 INFO - 'config_files': ('talos/mac_config.py',), 20:00:25 INFO - 'default_actions': ('clobber', 20:00:25 INFO - 'read-buildbot-config', 20:00:25 INFO - 'download-and-extract', 20:00:25 INFO - 'populate-webroot', 20:00:25 INFO - 'create-virtualenv', 20:00:25 INFO - 'install', 20:00:25 INFO - 'run-tests'), 20:00:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:00:25 INFO - 'download_minidump_stackwalk': True, 20:00:25 INFO - 'download_symbols': 'ondemand', 20:00:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:00:25 INFO - 'tooltool.py': '/tools/tooltool.py', 20:00:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:00:25 INFO - '/tools/misc-python/virtualenv.py')}, 20:00:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:00:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:00:25 INFO - 'installer_path': 'installer.exe', 20:00:25 INFO - 'log_level': 'info', 20:00:25 INFO - 'log_name': 'talos', 20:00:25 INFO - 'log_to_console': True, 20:00:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:00:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:00:25 INFO - 'opt_config_files': (), 20:00:25 INFO - 'pip_index': False, 20:00:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:00:25 INFO - 'cmd': ('bash', 20:00:25 INFO - '-c', 20:00:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:00:25 INFO - 'enabled': True, 20:00:25 INFO - 'halt_on_failure': False, 20:00:25 INFO - 'name': 'check_screen_resolution'},), 20:00:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:00:25 INFO - 'cmd': ('bash', 20:00:25 INFO - '-c', 20:00:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:00:25 INFO - 'enabled': True, 20:00:25 INFO - 'halt_on_failure': False, 20:00:25 INFO - 'name': 'check_screen_resolution'},), 20:00:25 INFO - 'run_cmd_checks_enabled': True, 20:00:25 INFO - 'sps_profile': False, 20:00:25 INFO - 'sps_profile_interval': 0, 20:00:25 INFO - 'suite': 'dromaeojs-e10s', 20:00:25 INFO - 'system_bits': '32', 20:00:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:00:25 INFO - 'title': 't-yosemite-r7-0215', 20:00:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:00:25 INFO - 'use_talos_json': True, 20:00:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:00:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:00:25 INFO - 'work_dir': 'build'} 20:00:25 INFO - ##### 20:00:25 INFO - ##### Running clobber step. 20:00:25 INFO - ##### 20:00:25 INFO - Running pre-action listener: _resource_record_pre_action 20:00:25 INFO - Running main action method: clobber 20:00:25 INFO - rmtree: /builds/slave/test/build 20:00:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:00:26 INFO - Running post-action listener: _resource_record_post_action 20:00:26 INFO - ##### 20:00:26 INFO - ##### Running read-buildbot-config step. 20:00:26 INFO - ##### 20:00:26 INFO - Running pre-action listener: _resource_record_pre_action 20:00:26 INFO - Running main action method: read_buildbot_config 20:00:26 INFO - Using buildbot properties: 20:00:26 INFO - { 20:00:26 INFO - "project": "", 20:00:26 INFO - "product": "firefox", 20:00:26 INFO - "script_repo_revision": "production", 20:00:26 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 20:00:26 INFO - "repository": "", 20:00:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 20:00:26 INFO - "buildid": "20160518184444", 20:00:26 INFO - "pgo_build": "False", 20:00:26 INFO - "basedir": "/builds/slave/test", 20:00:26 INFO - "buildnumber": 13, 20:00:26 INFO - "slavename": "t-yosemite-r7-0215", 20:00:26 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 20:00:26 INFO - "platform": "yosemite_r7", 20:00:26 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 20:00:26 INFO - "branch": "mozilla-aurora", 20:00:26 INFO - "repo_path": "releases/mozilla-aurora", 20:00:26 INFO - "revision": "4902143daafcfca72916ac8cba4ae8150ef271a1", 20:00:26 INFO - "stage_platform": "macosx64", 20:00:26 INFO - "builduid": "067369e73c60416d88ca524a2aa8569c", 20:00:26 INFO - "slavebuilddir": "test" 20:00:26 INFO - } 20:00:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 20:00:26 INFO - Running post-action listener: _resource_record_post_action 20:00:26 INFO - ##### 20:00:26 INFO - ##### Running download-and-extract step. 20:00:26 INFO - ##### 20:00:26 INFO - Running pre-action listener: _resource_record_pre_action 20:00:26 INFO - Running main action method: download_and_extract 20:00:26 INFO - mkdir: /builds/slave/test/build/tests 20:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:26 INFO - https://queue.taskcluster.net/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:00:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 20:00:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 20:00:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 20:00:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 20:00:28 INFO - Downloaded 1199 bytes. 20:00:28 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 20:00:28 INFO - Using the following test package requirements: 20:00:28 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 20:00:28 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:00:28 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 20:00:28 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:00:28 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 20:00:28 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:00:28 INFO - u'jsshell-mac.zip'], 20:00:28 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:00:28 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 20:00:28 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 20:00:28 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:00:28 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 20:00:28 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:00:28 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 20:00:28 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:00:28 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 20:00:28 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:00:28 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 20:00:28 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 20:00: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')]} 20:00:28 INFO - https://queue.taskcluster.net/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:00:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:00:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:00:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:00:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:00:30 INFO - Downloaded 18102405 bytes. 20:00:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:00:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:00:33 INFO - Return code: 0 20:00:33 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 20:00:33 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')]} 20:00:33 INFO - https://queue.taskcluster.net/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:00:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:00:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:00:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:00:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:00:34 INFO - Downloaded 18102405 bytes. 20:00:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:00:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:00:37 INFO - Return code: 0 20:00: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')]} 20:00:37 INFO - https://queue.taskcluster.net/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:00:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 20:00:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 20:00:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 20:00:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 20:00:38 INFO - Downloaded 10840145 bytes. 20:00:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:00:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 20:00:39 INFO - Return code: 0 20:00:39 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')]} 20:00:39 INFO - https://queue.taskcluster.net/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 20:00:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:00:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:00:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 20:00:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 20:00:42 INFO - Downloaded 114096659 bytes. 20:00:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:00:42 INFO - mkdir: /builds/slave/test/properties 20:00:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:00:42 INFO - Writing to file /builds/slave/test/properties/build_url 20:00:42 INFO - Contents: 20:00:42 INFO - build_url:https://queue.taskcluster.net/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:00:43 INFO - Running post-action listener: _resource_record_post_action 20:00:43 INFO - Running post-action listener: set_extra_try_arguments 20:00:43 INFO - ##### 20:00:43 INFO - ##### Running populate-webroot step. 20:00:43 INFO - ##### 20:00:43 INFO - Running pre-action listener: _resource_record_pre_action 20:00:43 INFO - Running main action method: populate_webroot 20:00:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:00:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:00:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:00:43 INFO - '--tppagecycles', 20:00:43 INFO - '7'], 20:00:43 INFO - 'tests': ['tsvgm']}}, 20:00:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:00:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:00:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:00:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:00:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:00:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:00:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:43 INFO - 'talos_options': ['--disable-e10s'], 20:00:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:00:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:00:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:43 INFO - 'talos_options': ['--disable-e10s'], 20:00:43 INFO - 'tests': ['damp', 'tps']}, 20:00:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:43 INFO - 'tests': ['damp', 'tps']}, 20:00:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:00:43 INFO - 'tests': ['dromaeo_dom']}, 20:00:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:00:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:00:43 INFO - 'tests': ['a11yr', 20:00:43 INFO - 'ts_paint', 20:00:43 INFO - 'tpaint', 20:00:43 INFO - 'sessionrestore', 20:00:43 INFO - 'sessionrestore_no_auto_restore', 20:00:43 INFO - 'tabpaint']}, 20:00:43 INFO - 'other-e10s': {'tests': ['a11yr', 20:00:43 INFO - 'ts_paint', 20:00:43 INFO - 'tpaint', 20:00:43 INFO - 'sessionrestore', 20:00:43 INFO - 'sessionrestore_no_auto_restore', 20:00:43 INFO - 'tabpaint']}, 20:00:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:00:43 INFO - 'ts_paint', 20:00:43 INFO - 'tpaint', 20:00:43 INFO - 'sessionrestore', 20:00:43 INFO - 'sessionrestore_no_auto_restore', 20:00:43 INFO - 'tabpaint']}, 20:00:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:00:43 INFO - 'ts_paint', 20:00:43 INFO - 'tpaint', 20:00:43 INFO - 'sessionrestore', 20:00:43 INFO - 'sessionrestore_no_auto_restore', 20:00:43 INFO - 'tabpaint']}, 20:00:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:00:43 INFO - 'tests': ['a11yr', 20:00:43 INFO - 'ts_paint', 20:00:43 INFO - 'tpaint', 20:00:43 INFO - 'sessionrestore', 20:00:43 INFO - 'sessionrestore_no_auto_restore', 20:00:43 INFO - 'tabpaint']}, 20:00:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:00:43 INFO - 'tests': ['a11yr', 20:00:43 INFO - 'ts_paint', 20:00:43 INFO - 'tpaint', 20:00:43 INFO - 'sessionrestore', 20:00:43 INFO - 'sessionrestore_no_auto_restore', 20:00:43 INFO - 'tabpaint']}, 20:00:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:00:43 INFO - 'tests': ['tsvgx', 20:00:43 INFO - 'tsvgr_opacity', 20:00:43 INFO - 'tart', 20:00:43 INFO - 'tscrollx', 20:00:43 INFO - 'cart']}, 20:00:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:00:43 INFO - 'tsvgr_opacity', 20:00:43 INFO - 'tart', 20:00:43 INFO - 'tscrollx', 20:00:43 INFO - 'cart']}, 20:00:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:43 INFO - 'talos_options': ['--disable-e10s'], 20:00:43 INFO - 'tests': ['tp5o']}, 20:00:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:43 INFO - 'tests': ['tp5o']}, 20:00:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:43 INFO - 'talos_options': ['--disable-e10s', 20:00:43 INFO - '--xperf_path', 20:00:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:00:43 INFO - 'tests': ['tp5n']}, 20:00:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:43 INFO - 'talos_options': ['--xperf_path', 20:00:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:00:43 INFO - 'tests': ['tp5n']}}, 20:00:43 INFO - 'talos.zip': {'path': '', 20:00:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:00:43 INFO - Running post-action listener: _resource_record_post_action 20:00:43 INFO - ##### 20:00:43 INFO - ##### Running create-virtualenv step. 20:00:43 INFO - ##### 20:00:43 INFO - Running pre-action listener: _resource_record_pre_action 20:00:43 INFO - Running main action method: create_virtualenv 20:00:43 INFO - Creating virtualenv /builds/slave/test/build/venv 20:00:43 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 20:00:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:00:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:00:43 INFO - Using real prefix '/tools/python27' 20:00:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:00:44 INFO - Installing distribute.............................................................................................................................................................................................done. 20:00:47 INFO - Installing pip.................done. 20:00:47 INFO - Return code: 0 20:00:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:00:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:00:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1036caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96114cb000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103569e00>, '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 0x1033d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103564b20>, '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.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 20:00:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:00:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:00:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3OqCo9ahP/Render', 20:00:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:00:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:00:47 INFO - 'HOME': '/Users/cltbld', 20:00:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:00:47 INFO - 'LOGNAME': 'cltbld', 20:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:00:47 INFO - 'MOZ_NO_REMOTE': '1', 20:00:47 INFO - 'NO_EM_RESTART': '1', 20:00:47 INFO - 'PAGER': '/bin/cat', 20:00:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:00:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:00:47 INFO - 'PWD': '/builds/slave/test', 20:00:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:00:47 INFO - 'SHELL': '/bin/bash', 20:00:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x2Gm0qLyDG/Listeners', 20:00:47 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 20:00:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:00:47 INFO - 'USER': 'cltbld', 20:00:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:00:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:00:47 INFO - 'XPC_FLAGS': '0x0', 20:00:47 INFO - 'XPC_SERVICE_NAME': '0', 20:00:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:00:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:00:47 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 20:00:47 INFO - Cleaning up... 20:00:47 INFO - Return code: 0 20:00:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 20:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:00:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:00:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1036caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96114cb000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103569e00>, '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 0x1033d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103564b20>, '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.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 20:00:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 20:00:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 20:00:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3OqCo9ahP/Render', 20:00:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:00:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:00:47 INFO - 'HOME': '/Users/cltbld', 20:00:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:00:47 INFO - 'LOGNAME': 'cltbld', 20:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:00:47 INFO - 'MOZ_NO_REMOTE': '1', 20:00:47 INFO - 'NO_EM_RESTART': '1', 20:00:47 INFO - 'PAGER': '/bin/cat', 20:00:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:00:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:00:47 INFO - 'PWD': '/builds/slave/test', 20:00:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:00:47 INFO - 'SHELL': '/bin/bash', 20:00:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x2Gm0qLyDG/Listeners', 20:00:47 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 20:00:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:00:47 INFO - 'USER': 'cltbld', 20:00:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:00:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:00:47 INFO - 'XPC_FLAGS': '0x0', 20:00:47 INFO - 'XPC_SERVICE_NAME': '0', 20:00:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:00:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:00:47 INFO - Downloading/unpacking psutil>=0.7.1 20:00: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 20:00: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 20:00: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 20:00: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 20:00: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 20:00: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 20:00:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:00:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:00:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:00:50 INFO - Installing collected packages: psutil 20:00:50 INFO - Running setup.py install for psutil 20:00:50 INFO - building 'psutil._psutil_osx' extension 20:00:50 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 20:00:50 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 20:00:50 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 20:00:50 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 20:00:50 INFO - building 'psutil._psutil_posix' extension 20:00:50 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 20:00:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:00:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:00:51 INFO - ^ 20:00:51 INFO - 1 warning generated. 20:00:51 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 20:00:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:00:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:00:51 INFO - Successfully installed psutil 20:00:51 INFO - Cleaning up... 20:00:51 INFO - Return code: 0 20:00:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 20:00:51 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')]} 20:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:51 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')]} 20:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:51 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 0x1036caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96114cb000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103569e00>, '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 0x1033d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103564b20>, '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.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 20:00:51 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 20:00:51 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 20:00:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3OqCo9ahP/Render', 20:00:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:00:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:00:51 INFO - 'HOME': '/Users/cltbld', 20:00:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:00:51 INFO - 'LOGNAME': 'cltbld', 20:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:00:51 INFO - 'MOZ_NO_REMOTE': '1', 20:00:51 INFO - 'NO_EM_RESTART': '1', 20:00:51 INFO - 'PAGER': '/bin/cat', 20:00:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:00:51 INFO - 'PWD': '/builds/slave/test', 20:00:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:00:51 INFO - 'SHELL': '/bin/bash', 20:00:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x2Gm0qLyDG/Listeners', 20:00:51 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 20:00:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:00:51 INFO - 'USER': 'cltbld', 20:00:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:00:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:00:51 INFO - 'XPC_FLAGS': '0x0', 20:00:51 INFO - 'XPC_SERVICE_NAME': '0', 20:00:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:00:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:00:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:00:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:00:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:00:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:00:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:00:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:00:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 20:00:55 INFO - Installing collected packages: mozsystemmonitor 20:00:55 INFO - Running setup.py install for mozsystemmonitor 20:00:55 INFO - Successfully installed mozsystemmonitor 20:00:55 INFO - Cleaning up... 20:00:55 INFO - Return code: 0 20:00:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1036caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96114cb000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103569e00>, '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 0x1033d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103564b20>, '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.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 20:00:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:00:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:00:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3OqCo9ahP/Render', 20:00:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:00:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:00:55 INFO - 'HOME': '/Users/cltbld', 20:00:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:00:55 INFO - 'LOGNAME': 'cltbld', 20:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:00:55 INFO - 'MOZ_NO_REMOTE': '1', 20:00:55 INFO - 'NO_EM_RESTART': '1', 20:00:55 INFO - 'PAGER': '/bin/cat', 20:00:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:00:55 INFO - 'PWD': '/builds/slave/test', 20:00:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:00:55 INFO - 'SHELL': '/bin/bash', 20:00:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x2Gm0qLyDG/Listeners', 20:00:55 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 20:00:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:00:55 INFO - 'USER': 'cltbld', 20:00:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:00:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:00:55 INFO - 'XPC_FLAGS': '0x0', 20:00:55 INFO - 'XPC_SERVICE_NAME': '0', 20:00:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:00:55 INFO - Downloading/unpacking blobuploader==1.2.4 20:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:00:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:00:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:00:57 INFO - Downloading blobuploader-1.2.4.tar.gz 20:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:00:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:00:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:00:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:00:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:00:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:00:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:00:59 INFO - Downloading docopt-0.6.1.tar.gz 20:00:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:00:59 INFO - Installing collected packages: blobuploader, requests, docopt 20:00:59 INFO - Running setup.py install for blobuploader 20:00:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:00:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:00:59 INFO - Running setup.py install for requests 20:00:59 INFO - Running setup.py install for docopt 20:00:59 INFO - Successfully installed blobuploader requests docopt 20:00:59 INFO - Cleaning up... 20:01:00 INFO - Return code: 0 20:01:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:01:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1036caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96114cb000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103569e00>, '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 0x1033d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103564b20>, '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.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 20:01:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:01:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:01:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3OqCo9ahP/Render', 20:01:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:01:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:01:00 INFO - 'HOME': '/Users/cltbld', 20:01:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:01:00 INFO - 'LOGNAME': 'cltbld', 20:01:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:00 INFO - 'MOZ_NO_REMOTE': '1', 20:01:00 INFO - 'NO_EM_RESTART': '1', 20:01:00 INFO - 'PAGER': '/bin/cat', 20:01:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:01:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:00 INFO - 'PWD': '/builds/slave/test', 20:01:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:01:00 INFO - 'SHELL': '/bin/bash', 20:01:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x2Gm0qLyDG/Listeners', 20:01:00 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 20:01:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:01:00 INFO - 'USER': 'cltbld', 20:01:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:01:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:01:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:00 INFO - 'XPC_FLAGS': '0x0', 20:01:00 INFO - 'XPC_SERVICE_NAME': '0', 20:01:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:01:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:01:00 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-i22Ih8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:01:00 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-0XctDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:01:00 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-0el6vC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:01:00 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-BzYIN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:01:00 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-HfMzyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:01:00 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-XjmObN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:01:00 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-tmolzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:01:01 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-ZGAZNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:01:01 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-P35MwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:01:01 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-DcwJWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:01:01 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-6ffa_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:01:01 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-3apDTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:01:01 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-iYPnJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:01:01 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-idJYYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:01:01 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-FBa1vI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:01:01 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-hC0DOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:01:01 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-jFooPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:01:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:01:02 INFO - Running setup.py install for manifestparser 20:01:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:01:02 INFO - Running setup.py install for mozcrash 20:01:02 INFO - Running setup.py install for mozdebug 20:01:02 INFO - Running setup.py install for mozdevice 20:01:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:01:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:01:02 INFO - Running setup.py install for mozfile 20:01:02 INFO - Running setup.py install for mozhttpd 20:01:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:01:02 INFO - Running setup.py install for mozinfo 20:01:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:01:03 INFO - Running setup.py install for mozInstall 20:01:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:01:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:01:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:01:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:01:03 INFO - Running setup.py install for mozleak 20:01:03 INFO - Running setup.py install for mozlog 20:01:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:01:03 INFO - Running setup.py install for moznetwork 20:01:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:01:03 INFO - Running setup.py install for mozprocess 20:01:03 INFO - Running setup.py install for mozprofile 20:01:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:01:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:01:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:01:04 INFO - Running setup.py install for mozrunner 20:01:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:01:04 INFO - Running setup.py install for mozscreenshot 20:01:04 INFO - Running setup.py install for moztest 20:01:04 INFO - Running setup.py install for mozversion 20:01:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:01:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:01:04 INFO - Cleaning up... 20:01:04 INFO - Return code: 0 20:01:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1036caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96114cb000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103569e00>, '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 0x1033d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103564b20>, '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.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 20:01:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:01:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3OqCo9ahP/Render', 20:01:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:01:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:01:04 INFO - 'HOME': '/Users/cltbld', 20:01:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:01:04 INFO - 'LOGNAME': 'cltbld', 20:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:04 INFO - 'MOZ_NO_REMOTE': '1', 20:01:04 INFO - 'NO_EM_RESTART': '1', 20:01:04 INFO - 'PAGER': '/bin/cat', 20:01:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:04 INFO - 'PWD': '/builds/slave/test', 20:01:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:01:04 INFO - 'SHELL': '/bin/bash', 20:01:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x2Gm0qLyDG/Listeners', 20:01:04 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 20:01:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:01:04 INFO - 'USER': 'cltbld', 20:01:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:01:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:04 INFO - 'XPC_FLAGS': '0x0', 20:01:04 INFO - 'XPC_SERVICE_NAME': '0', 20:01:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:01:04 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-J00uY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:01:05 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-Ykz3gJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:01:05 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-_eqhVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:01:05 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-raPrh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:01:05 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-RGgwhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:01:05 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-mplH3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:01:05 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-5xcRlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:01:05 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-KBPeTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:01:05 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-x3fyQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:01:05 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-gHFUKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:01:05 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)) 20:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:01:05 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-jzM9W0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:01:06 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-k_F6rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:01:06 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-awApnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:01:06 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-YHpJEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:01:06 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-EGfX4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:01:06 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-D2yfFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:01:06 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-68u4Dt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:01:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:01: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 20:01: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 20:01: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 20:01: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 20:01: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 20:01: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 20:01:09 INFO - Downloading blessings-1.6.tar.gz 20:01:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:01:09 INFO - Installing collected packages: blessings 20:01:09 INFO - Running setup.py install for blessings 20:01:09 INFO - Successfully installed blessings 20:01:09 INFO - Cleaning up... 20:01:09 INFO - Return code: 0 20:01:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:01:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:01:09 INFO - Reading from file tmpfile_stdout 20:01:09 INFO - Current package versions: 20:01:09 INFO - blessings == 1.6 20:01:09 INFO - blobuploader == 1.2.4 20:01:09 INFO - docopt == 0.6.1 20:01:09 INFO - manifestparser == 1.1 20:01:09 INFO - mozInstall == 1.12 20:01:09 INFO - mozcrash == 0.17 20:01:09 INFO - mozdebug == 0.1 20:01:09 INFO - mozdevice == 0.48 20:01:09 INFO - mozfile == 1.2 20:01:09 INFO - mozhttpd == 0.7 20:01:09 INFO - mozinfo == 0.9 20:01:09 INFO - mozleak == 0.1 20:01:09 INFO - mozlog == 3.1 20:01:09 INFO - moznetwork == 0.27 20:01:09 INFO - mozprocess == 0.22 20:01:09 INFO - mozprofile == 0.28 20:01:09 INFO - mozrunner == 6.11 20:01:09 INFO - mozscreenshot == 0.1 20:01:09 INFO - mozsystemmonitor == 0.0 20:01:09 INFO - moztest == 0.7 20:01:09 INFO - mozversion == 1.4 20:01:09 INFO - psutil == 3.1.1 20:01:09 INFO - requests == 1.2.3 20:01:09 INFO - wsgiref == 0.1.2 20:01:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1036caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96114cb000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103569e00>, '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 0x1033d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103564b20>, '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.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 20:01:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:01:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3OqCo9ahP/Render', 20:01:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:01:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:01:09 INFO - 'HOME': '/Users/cltbld', 20:01:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:01:09 INFO - 'LOGNAME': 'cltbld', 20:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:09 INFO - 'MOZ_NO_REMOTE': '1', 20:01:09 INFO - 'NO_EM_RESTART': '1', 20:01:09 INFO - 'PAGER': '/bin/cat', 20:01:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:09 INFO - 'PWD': '/builds/slave/test', 20:01:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:01:09 INFO - 'SHELL': '/bin/bash', 20:01:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x2Gm0qLyDG/Listeners', 20:01:09 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 20:01:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:01:09 INFO - 'USER': 'cltbld', 20:01:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:01:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:09 INFO - 'XPC_FLAGS': '0x0', 20:01:09 INFO - 'XPC_SERVICE_NAME': '0', 20:01:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:09 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)) 20:01:09 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)) 20:01:09 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)) 20:01:09 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)) 20:01:09 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)) 20:01:09 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)) 20:01:09 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)) 20:01:09 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)) 20:01:09 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)) 20:01:09 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)) 20:01:09 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)) 20:01:09 INFO - Cleaning up... 20:01:09 INFO - Return code: 0 20:01:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1036caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96114cb000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103569e00>, '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 0x1033d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103564b20>, '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.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 20:01:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:01:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3OqCo9ahP/Render', 20:01:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:01:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:01:09 INFO - 'HOME': '/Users/cltbld', 20:01:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:01:09 INFO - 'LOGNAME': 'cltbld', 20:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:09 INFO - 'MOZ_NO_REMOTE': '1', 20:01:10 INFO - 'NO_EM_RESTART': '1', 20:01:10 INFO - 'PAGER': '/bin/cat', 20:01:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:10 INFO - 'PWD': '/builds/slave/test', 20:01:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:01:10 INFO - 'SHELL': '/bin/bash', 20:01:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x2Gm0qLyDG/Listeners', 20:01:10 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 20:01:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:01:10 INFO - 'USER': 'cltbld', 20:01:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:01:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:10 INFO - 'XPC_FLAGS': '0x0', 20:01:10 INFO - 'XPC_SERVICE_NAME': '0', 20:01:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:01:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:10 INFO - Downloading/unpacking jsonschema 20:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:01:12 INFO - Downloading/unpacking functools32 (from jsonschema) 20:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:13 INFO - Downloading functools32-3.2.3-2.tar.gz 20:01:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:01:13 INFO - warning: no files found matching '*.txt' 20:01:13 INFO - no previously-included directories found matching 'build' 20:01:13 INFO - no previously-included directories found matching 'dist' 20:01:13 INFO - no previously-included directories found matching '.git*' 20:01:13 INFO - Installing collected packages: jsonschema, functools32 20:01:13 INFO - Running setup.py install for functools32 20:01:13 INFO - warning: no files found matching '*.txt' 20:01:13 INFO - no previously-included directories found matching 'build' 20:01:13 INFO - no previously-included directories found matching 'dist' 20:01:13 INFO - no previously-included directories found matching '.git*' 20:01:13 INFO - Successfully installed jsonschema functools32 20:01:13 INFO - Cleaning up... 20:01:13 INFO - Return code: 0 20:01:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:01:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1036caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96114cb000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103569e00>, '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 0x1033d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103564b20>, '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.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 20:01:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 20:01:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 20:01:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3OqCo9ahP/Render', 20:01:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:01:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:01:13 INFO - 'HOME': '/Users/cltbld', 20:01:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:01:13 INFO - 'LOGNAME': 'cltbld', 20:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:13 INFO - 'MOZ_NO_REMOTE': '1', 20:01:13 INFO - 'NO_EM_RESTART': '1', 20:01:13 INFO - 'PAGER': '/bin/cat', 20:01:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:13 INFO - 'PWD': '/builds/slave/test', 20:01:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:01:13 INFO - 'SHELL': '/bin/bash', 20:01:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x2Gm0qLyDG/Listeners', 20:01:13 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 20:01:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:01:13 INFO - 'USER': 'cltbld', 20:01:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:01:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:13 INFO - 'XPC_FLAGS': '0x0', 20:01:13 INFO - 'XPC_SERVICE_NAME': '0', 20:01:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:13 INFO - Downloading/unpacking flake8 20:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:01:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:01:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:01:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:01:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:01:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:01:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:01:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:01:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:01:17 INFO - no previously-included directories found matching 'docs/_build' 20:01:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:01:17 INFO - Running setup.py install for pep8 20:01:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:01:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:01:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:01:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:01:17 INFO - no previously-included directories found matching 'docs/_build' 20:01:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:01:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:01:17 INFO - Cleaning up... 20:01:17 INFO - Return code: 0 20:01:17 INFO - Running post-action listener: _resource_record_post_action 20:01:17 INFO - Running post-action listener: _start_resource_monitoring 20:01:17 INFO - Starting resource monitoring. 20:01:17 INFO - ##### 20:01:17 INFO - ##### Running install step. 20:01:17 INFO - ##### 20:01:17 INFO - Running pre-action listener: _resource_record_pre_action 20:01:17 INFO - Running main action method: install 20:01:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:01:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:01:18 INFO - Reading from file tmpfile_stdout 20:01:18 INFO - Detecting whether we're running mozinstall >=1.0... 20:01:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:01:18 INFO - Reading from file tmpfile_stdout 20:01:18 INFO - Output received: 20:01:18 INFO - Usage: mozinstall [options] installer 20:01:18 INFO - Options: 20:01:18 INFO - -h, --help show this help message and exit 20:01:18 INFO - -d DEST, --destination=DEST 20:01:18 INFO - Directory to install application into. [default: 20:01:18 INFO - "/builds/slave/test"] 20:01:18 INFO - --app=APP Application being installed. [default: firefox] 20:01:18 INFO - mkdir: /builds/slave/test/build/application 20:01:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 20:01:49 INFO - Reading from file tmpfile_stdout 20:01:49 INFO - Output received: 20:01:49 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 20:01:49 INFO - Running post-action listener: _resource_record_post_action 20:01:49 INFO - ##### 20:01:49 INFO - ##### Running run-tests step. 20:01:49 INFO - ##### 20:01:49 INFO - Running pre-action listener: _resource_record_pre_action 20:01:49 INFO - Running main action method: run_tests 20:01:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:01:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:01:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:01:49 INFO - 2016-05-18 20:01:49.969 screenresolution[2228:29325] starting screenresolution argv=screenresolution get 20:01:49 INFO - 2016-05-18 20:01:49.971 screenresolution[2228:29325] Display 0: 1600x1200x32@60 20:01:49 INFO - 2016-05-18 20:01:49.977 screenresolution[2229:29327] starting screenresolution argv=screenresolution list 20:01:49 INFO - Available Modes on Display 0 20:01:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:01:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:01:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:01:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:01:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:01:50 INFO - Intel Iris: 20:01:50 INFO - Chipset Model: Intel Iris 20:01:50 INFO - Type: GPU 20:01:50 INFO - Bus: Built-In 20:01:50 INFO - VRAM (Dynamic, Max): 1536 MB 20:01:50 INFO - Vendor: Intel (0x8086) 20:01:50 INFO - Device ID: 0x0a2e 20:01:50 INFO - Revision ID: 0x0009 20:01:50 INFO - Displays: 20:01:50 INFO - Display: 20:01:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:01:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:01:50 INFO - Display Serial Number: 1600x1200 60 20:01:50 INFO - Main Display: Yes 20:01:50 INFO - Mirror: Off 20:01:50 INFO - Online: Yes 20:01:50 INFO - Rotation: Supported 20:01:50 INFO - Return code: 0 20:01:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:01:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:01:50 INFO - Python 2.7.3 20:01:50 INFO - Return code: 0 20:01:50 INFO - grabbing minidump binary from tooltool 20:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:50 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103569e00>, '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 0x1033d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103564b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:01:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:01:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 20:01:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:01:50 INFO - Return code: 0 20:01:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:01:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:01:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:01:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:01:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:01:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:01:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:01:51 INFO - Return code: 0 20:01:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0215', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0215 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3OqCo9ahP/Render', 20:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:01:51 INFO - 'HOME': '/Users/cltbld', 20:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:01:51 INFO - 'LOGNAME': 'cltbld', 20:01:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:01:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:51 INFO - 'MOZ_NO_REMOTE': '1', 20:01:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:01:51 INFO - 'NO_EM_RESTART': '1', 20:01:51 INFO - 'PAGER': '/bin/cat', 20:01:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:51 INFO - 'PWD': '/builds/slave/test', 20:01:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:01:51 INFO - 'SHELL': '/bin/bash', 20:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x2Gm0qLyDG/Listeners', 20:01:51 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 20:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:01:51 INFO - 'USER': 'cltbld', 20:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:51 INFO - 'XPC_FLAGS': '0x0', 20:01:51 INFO - 'XPC_SERVICE_NAME': '0', 20:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:01:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0215', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:01:51 INFO - mozversion application_buildid: 20160518184444 20:01:51 INFO - mozversion application_changeset: 4902143daafcfca72916ac8cba4ae8150ef271a1 20:01:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:01:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:01:51 INFO - mozversion application_name: Firefox 20:01:51 INFO - mozversion application_remotingname: firefox-dev 20:01:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:01:51 INFO - mozversion application_vendor: Mozilla 20:01:51 INFO - mozversion application_version: 48.0a2 20:01:51 INFO - mozversion platform_buildid: 20160518184444 20:01:51 INFO - mozversion platform_changeset: 4902143daafcfca72916ac8cba4ae8150ef271a1 20:01:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:01:51 INFO - mozversion platform_version: 48.0a2 20:01:51 INFO - using testdate: 1463626911 20:01:51 INFO - actual date: 1463626911 20:01:51 INFO - starting webserver on 'localhost:53136' 20:01:51 INFO - SUITE-START | Running 2 tests 20:01:51 INFO - TEST-START | dromaeo_css 20:01:51 INFO - operating with platform_type : mac_ 20:01:51 INFO - Initialising browser for dromaeo_css test... 20:01:51 INFO - TEST-INFO | started process 2247 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpUCGixz/profile http://localhost:53136/getInfo.html) 20:01:54 INFO - PROCESS | 2247 | __metrics Screen width/height:1600/1200 20:01:54 INFO - PROCESS | 2247 | colorDepth:24 20:01:54 INFO - PROCESS | 2247 | Browser inner width/height: 1024/689 20:01:54 INFO - PROCESS | 2247 | __metrics 20:01:54 ERROR - PROCESS | 2247 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 20:01:55 INFO - TEST-INFO | 2247: exit 0 20:01:55 INFO - Browser initialized. 20:01:55 INFO - Running cycle 1/1 for dromaeo_css test... 20:01:55 INFO - TEST-INFO | started process 2250 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpUCGixz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:02:07 INFO - PROCESS | 2250 | RSS: Main: 221052928 20:02:07 INFO - PROCESS | 2250 | 20:02:57 INFO - PROCESS | 2250 | 1463626977097 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] 20:05:08 INFO - PROCESS | 2250 | Cycle 1(1): loaded http://localhost:53136/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53136/tests/dromaeo/cssquery-ext.html) 20:05:09 INFO - PROCESS | 2250 | RSS: Main: 231387136 20:05:09 INFO - PROCESS | 2250 | 20:08:11 INFO - PROCESS | 2250 | Cycle 1(1): loaded http://localhost:53136/tests/dromaeo/cssquery-ext.html (next: http://localhost:53136/tests/dromaeo/cssquery-jquery.html) 20:08:12 INFO - PROCESS | 2250 | RSS: Main: 243175424 20:08:12 INFO - PROCESS | 2250 | 20:11:13 INFO - PROCESS | 2250 | Cycle 1(1): loaded http://localhost:53136/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53136/tests/dromaeo/cssquery-mootools.html) 20:11:14 INFO - PROCESS | 2250 | RSS: Main: 245252096 20:11:14 INFO - PROCESS | 2250 | 20:14:15 INFO - PROCESS | 2250 | Cycle 1(1): loaded http://localhost:53136/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53136/tests/dromaeo/cssquery-prototype.html) 20:14:16 INFO - PROCESS | 2250 | RSS: Main: 246988800 20:14:16 INFO - PROCESS | 2250 | 20:17:19 INFO - PROCESS | 2250 | Cycle 1(1): loaded http://localhost:53136/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53136/tests/dromaeo/cssquery-yui.html) 20:17:19 INFO - PROCESS | 2250 | RSS: Main: 249872384 20:17:19 INFO - PROCESS | 2250 | 20:20:21 INFO - PROCESS | 2250 | Cycle 1(1): loaded http://localhost:53136/tests/dromaeo/cssquery-yui.html (next: http://localhost:53136/tests/dromaeo/cssquery-dojo.html) 20:20:22 INFO - PROCESS | 2250 | RSS: Main: 251109376 20:20:22 INFO - PROCESS | 2250 | 20:20:22 INFO - PROCESS | 2250 | __start_tp_report 20:20:22 INFO - PROCESS | 2250 | _x_x_mozilla_page_load 20:20:22 INFO - PROCESS | 2250 | _x_x_mozilla_page_load_details 20:20:22 INFO - PROCESS | 2250 | |i|pagename|runs| 20:20:22 INFO - PROCESS | 2250 | |0;dojo.html;3396.4905264210365;3424.469207272872;3471.600765911919;3477.0611934777594;3481.825908704566;5147.536721695046;5666.37669856316;5705.2583164188645;5737.913931291028;5740.167675687023;4843.854684359466;5738.913916291259;5742.339630942442;5757.856053598662;5772.595918285721;5162.741862906859;5173.741312934357;5250.343707036621;5264.368275806909;5266.947330526703;5143.4599367066485;5277;5285.709285989269;5291.920621190685;5296.841094767163;5241.161414173714;5259.184826351902;5268.973655131738;5271.710055946922;5307.097793375136;4005.97997010016;4456.309272062836;5285.471452854684;5295.682259064469;5314.707691076989;5108.795648174068;5216.426193118754;5247.921281180786;5302.0721373759825;5304;5483.177523371486;5552.639078459928;5562.249096371978;5658.547316214693;5667.489925906688;6203;6248.656323902183;6274.403931626488;6276.560640755149;6297.716602752887;5499.587530935196;6283.685815709242;6308.558400911938;6346.492280617539;6349.904751428732;5422.8373148805595;5674.404187560329;5684.573656975744;5791.565632577532;5837.445442682938;5166.974165129151;6254.530910181755;6269.498440124779;6303.684815759194;6324.494040476751;6086.391360863879;6125.050617154348;6156.753729850756;6162.969185154136;6169.629822210682;5277.4986376294955;6154.569180157346;6155;6227.937720622826;6292.528060395489;4944.505549445027;6237.844053898598;6265.216847894013;6271.7804876830005;6271.937280627227;6065.727042283108;6107.511399088063;6273.686315684197;6282.811515654537;6310.936890631035;3751.9812400938367;4908.4846091160625;4920.926186107211;4929.876753081202;5086.516780905808;4432.711873728197;4440.3561483585345;4453.910921781546;4466.955330446719;4492.415985921874;4893.584045356113;5059.2916991621205;5743;5816.5928385013485;5817.650940943557;6141.547378150805;6165.753369865155;6270.341614130451;6274.874502509924;6286.05709143623;5783.913241301384;5787.652740835563;5810.157527158538;5856.4143585641095;5870.090136028923;5324;5410.431904649955;5412.377576578744;5421.295231619864;5462.781488740406;6304.621722696561;6305.621662700252;6340.7463701451425;6349.111124442582;6359.17330747;6225.782097626561;6262.718177682106;6273.498120150468;6300.149479820166;6303.936960630335;6197.473214776614;6210.223722034746;6287.874242515032;6313.715882785194;6351.1425957496995;6198.0702894566175;6206.813795586139;6218;6235.095911092957;6236.719347629277;6284.622922624657;6294.181756371642;6307.558470906993;6316.431521163117;6328.24061112651;5769.798057067983;6056.454919057306;6111.895622426936;6135.692037377249;6149.631022138686;6126.768630067982;6128.959784318273;6136.570440069152;6144.477719393902;6175.536834737323;5083.440821509705;5822.243108395882;5877.324107727666;6031.336552979081;6043.879122417439;6086.6348019119;6113.841195312599;6130.6628135452975;6168.506519478341;6183.443490085734;5959.880802383841;5965.6718880461995;5983.790567330123;5995.610285331614;6005.969970150121 20:20:22 INFO - PROCESS | 2250 | |1;ext.html;512.5156726893085;520.3677531798864;540.1142126911864;544.8964696707625;629.0721186250285;29695.406091878176;39694;39697.206055878865;39834;39967.80016099919;40259;40344.193116137736;40928.59071409285;40937.59062409375;41134.58865411345;9452.102050305228;9640.855387169198;9653.806923861519;9707.95146024269;9714.514274286294;9611.086946740048;9707.271954603397;9754.853677194847;9780.119789218968;9842.06500382466;8441.366897482714;9721.270904682176;9788.804223915517;9832.967538408471;9871.950640246825;8318.500889946592;8345.582720863962;9807.019298070207;9828.066333698287;9837.655682051165;9744.269179811472;9748.073932976356;9841.261905357056;9850.50747462631;9857.90142098577;38652.226955460734;38981.02547436309;39029;39072.60927390718;39205.80397098025;2460.434100156956;3109.5957525521762;3179.8887038953526;3217.469117595594;3254.8209848458328;25881;26459.206223813315;26526.73473265281;26680.73319266802;26983;2719.7552220300267;2730.945381092387;2773.320536468578;2780.4856101621267;2967.109867039879;364376;389969.10030899895;390540.0472997645;412436;413065;28845.85577072143;36613.450798238046;37042.8147859259;65007.349926501076;65404.34595653982;35355.823220884246;35535.28929421397;35732.106697332776;35771.64228357683;35813.82093089518;15817;16027.919860400623;16049;16053.357865685475;16064.839351606568;11424.086073114131;13235;13411.798823017663;13495.190288582717;13519.66200844967;8353.289970352467;8394.580270986426;8432.409731318872;8452.197041281068;8473.70342038038;2980.225141463235;3410.6589341065696;3421.5380923575494;3455.0498612881156;3486;1036.6268143468346;1042.285895454358;1066.3015724700333;1119.630521927762;1162.9883701163048;30574.388512230074;30574.69425305763;31087.84456077705;31201.8439907799;31220.843895780377;1060.3425875956957;1138.5445821671399;1169.0822704177351;1181.8699943006263;1218.9817152742717;11814.173007889365;12121.27272363661;14900;15616.531504054896;15985.520434386986;597408;600335;608287;608336;609783;61311.69344153251;62005.37994619996;63104.73790524255;63288;63311.6834415825;1469.1992863889207;1480.3486465955161;1481.037325738257;1487.6950225203807;1488.4120772294882;26169.73830261673;30037;30432.391352173276;30475.695243047285;30805.845970770002;54900.72549637226;55388.169177463176;55723.442765571825;56112.719436402556;56152;544134.8379774386;544990;557799;557924;558961;27261.72738272592;27671.861640691666;27719.86140069287;27908.86045569759;27998.72001279961;46376;46883;47760.28359574539;47922.5207747918;48437.5156248433;38015.61984380121;38640.42039369468;38980.2203955925;39075.60924390834;40100.3984940232;31659.683403165673;37931;38498.0375490615;38510.037249069;39131;32377.838110809294;38280.42579361256;38537.80731096327;38831.41752873766;39264;30353.69646303509;31368.475720591763;31631.20921976969;31635;31699.04902852918;24586.631200532363;24731.258062258163;25932.351691207117;26366.604500932117;27109.72890271151 20:20:22 INFO - PROCESS | 2250 | |2;jquery.html;357.545916685809;402.4446264155465;404.9392591111331;413.35929309570156;414.09520198366573;15772.290246938886;15911.965722228048;16683.83316166838;17182.398616048442;17200.56998575033;20157.59684806303;20223.49441263969;20549.075291611876;20584.588308233826;20622.072006759696;5692.601517893749;5861.6189947653265;5974.342822289566;5983.730732117044;5986.132010858423;5643.633163844357;5854.970725146369;5929.822105336847;5934.88130237395;5942.2275104236605;5772.5670574706855;5850.795222167225;5875.177475153482;5886.735096920627;5886.823395298148;5592.328920529521;5767.106098554689;5786.161006654053;5833.795817146401;5846.239988801472;5094.261332106861;5096.745162741885;5127.717975511346;5147.996140752554;5155.510226528474;24920.750792492207;25170.748292517022;25317.49365012708;25388;25791.35521611956;2187.3765976696723;2327.150590034642;2357.446000189954;2358.433975845802;2395.5089206712582;9053.818923621557;9760.17038551724;9859.654912078044;14804.259787010606;15400.460983865623;1720.1055451165323;1744.6772347115825;1759.1643969114696;1787.705028670268;1817.0188098426834;104241.47879260556;107759;108858.91141088567;113172.43413782961;114594.42702786517;11737.471813768401;11964.162508624313;11969.940150299193;12040;12153.69615759613;14875.776863347059;14925.328360223815;14997.925010374878;15007.099574025593;15028.624284392978;7275.818104547323;7348.77953661391;8990.460572365679;9028.277737780962;9067;9040.457572545669;9048.547572621343;9051.411658242194;9174.220191283683;9268.629254829732;4608.953910460852;4612.561806628365;4649.418822647169;4674.182018146811;4700.271457924027;2027.70598263251;2033.8372930165847;2035.735354403918;2046.1303945822965;2058.5162486815584;891.3893982621925;968.5399435268332;969.2439896880445;1000.1990445859843;1028.2647906746713;16217.432389866533;18461.7230741539;19486.02569871529;20112.59774804525;20301.695474567892;978.0854900667898;979.7648564344648;986.4475893499663;997.3068717241448;1021.7752094539189;10587.682369528773;10700.357978521313;10769.62306319275;10779.191560632984;10784.245102842726;536020;540062;542195;581621;623720.288391349;72849.6357518209;73121.63439182876;75178;127627.72372276345;128310.35844820902;457.3848174205688;475.07834800486944;475.09494413143017;478.73908719747124;482.79963815016373;8834.204921557091;10199.031092046243;10787.892121078688;11225.158113141384;11236.381998990135;49342.75328623334;53282.73358633182;53513.46486535085;54673.72663136659;55097;58632.70683646554;72211.63894180495;77637.61181194057;79081.8137727923;79484.20515795;7871.803204919694;8038.196180382149;8091.757247282591;8203.958980205061;8207;9837.60649574009;10801.459927003461;10941.398223097805;12055.095867810125;12702.428390722254;9952.452615106238;10820.729481762704;10886.782264354826;10911.781764364827;10956.397398143179;10539.525721342405;10613.628523001658;10705.143588513056;10867.239293249713;10871.891281087404;10387.324823886423;10866.565337386415;10936.289141205796;11185.72035699114;11272.3800190987;6672.573643334232;7646.923530764621;7780.455368124177;8195.83608327842;8279.751607451786;10542.841857371992;10769.461526923465;10872.347659140476;10936.56253749841;10939.343639381344 20:20:22 INFO - PROCESS | 2250 | |3;mootools.html;1524.893257471977;1528.4192007037325;1722.4057700093463;1828.5977085041295;1896.9810301896978;7796.376289896809;8486.44838085524;8592.226699597035;8812.986506551757;8820.514871682055;8410.621522031508;8426.325893928486;8523.360747943898;8536.103709110546;8540;2420.140849998247;2431.3921519620094;2432.8661923594195;2437.061731233479;2467.864267465289;2394.174009966559;2399.6760437341;2430.0887167312258;2437.9390515237174;2444.511097780442;2416.915407960722;2424.5878200705833;2433.1362366359863;2444.584420648494;2448.8530688158767;2384.451576137481;2401.711794584652;2412.191915708231;2414.070582825616;2421.3583400398907;2417.0090262992267;2433.7809597136165;2439.1706819681394;2441.963370549443;2455.472073504205;8603.655853765842;8613.267872230868;8654.177853103945;8656.134386561294;8697.826043479095;5242.528172464458;5346.97326513365;5359.919601205985;5398.1902714593125;5421.593380496479;3500.4574290984683;3562.893113206582;3572.392693242155;3601.225736466672;3648.927021459556;1045.8588090607748;1061.5912873543664;1063.3247887591358;1064.6912395405323;1067.1836045425257;11806.46870890812;11886.989605883424;11907.94046029773;11910.523579056828;11934.045276377869;4665.766711664403;4687.695299805502;4775.283707443842;4793.952060479351;4821.5178482151505;10068.899311006797;10072.345297555636;10080.546375413123;10088.243381746399;10105.747356316004;5333.466653334635;5342.54588359986;5370.194470829407;5385.461453854583;5405.864853378697;4327.891802704958;4354.477462704495;4539.954600454019;4764.952350476451;4963.379577552806;3338.2989572189626;3563.501109844624;4111.732737372061;4137.234611596864;4171.228322760299;1368.8494265630773;1370.2121280263889;1370.712150448397;1373.3133433283358;1389.3400634698437;844.3697881100328;908.9681861134961;943.3302355327656;955.2119501411363;958.9856152157723;5400.62195646301;5824.592278540462;5908.527317814564;5946.43508866657;6062.757489700362;909.8862642169729;971.7182017214928;1006.7231511334295;1030.850526673628;1039.5166247694858;3765.7740535567955;4029.697772667003;4060.959390406058;4084.5302790178916;4107.774072426046;10132.189424845994;10214.285000049926;10515.737106572396;10520.947395262974;10526.421046842497;9063.229625481908;9652.179564736936;10262.179025677928;10375.066244037922;10578.841317380246;6936.930630693629;8040.557769322743;8114.675412983414;8374.748757537405;8423.199796019368;5746.913796292975;5800.274965629296;5811.506021988009;5893.233879595625;6060.878782424239;9381.906180938104;9419.152276295166;9499.762505937408;9577.808443831223;9636.855447168438;9638.469884156439;9778.804423911624;9787.608495660379;9853.507324633883;9899.65351212704;6603.735850565923;6746.4940129489505;6923;6995.685194166376;7043.084399028094;7678.385729141543;7728;7740.806479838049;7753.573553454614;7795.22047795216;6810.114685090906;6916.135483064617;6924.58452492852;6932.237453879969;7023.578585284899;5790.884182316246;6839.89740153908;6889.173299204128;6966.4775141865575;6985.406240469617;6514.804555863332;6625.436837868642;6655.600663959985;6703.095082163845;6743.831404214934;4428.136513379989;4496.955030449653;4530.116627257785;4533.274676051816;4571.291449825282;4344.674149438868;4647.233206520887;4767.4517430495935;4897.8285759998225;4928.556429921325 20:20:22 INFO - PROCESS | 2250 | |4;prototype.html;327.62159705540097;328.14190890820504;333.79304831004765;336.0322271857052;338.42974587819543;6789.558678685894;6801.965990170049;6828.82927926802;6837.726490940357;6857.485688573358;6780.830479238021;6801.897971530431;6803.659817009155;6804.4556435485165;6825.94879788955;4736.834210802631;4842.249451335048;4947.8268260610885;4963.60291176705;4963.627727920402;4895.265710143472;4951.158303088466;4951.628627852908;4966.900661986758;4981.480648402254;4916.70499770015;4920.360353154085;4929;4942.703437793743;4951.257311403282;4921.311016457699;4934.654574179809;4945.282933974571;4946.554810067111;4980.427250866161;3323.80057196569;4283.614474697292;4285.100128972919;4291.420658211133;4300.505441874193;5143.948560514385;6490.837729056764;6604.339566043405;6608.107905432801;6610;4833.806647734086;4863.343448634424;5912.054071348563;5964.582479226456;5967.313758917737;6370.458511026568;6811.739828131762;7067.681954312017;7291.34377905991;7312.378447831888;3987.7208595398333;4103.774292413916;4537.342085397599;4822.517748225185;4829.468758436569;5946.316173640043;6075.817725468243;7334.743283985088;7377.446691498159;7674.884876726854;4755.785989630475;4845.709257444564;4863.3434486344595;4916.852494425173;4918.975405123023;4593.402857628487;4965.205567109245;4989.476105008995;5005.824796132118;5009.223570346602;5904.143899134601;5905.5570832186895;5918.644881307136;5951.553633477506;5959.851003724942;7326.377257933029;7361.190269070398;7370.852582948418;7404.407647388196;7437.851242975218;1443.5452832357773;1715.4939292908623;1744.8516876065423;1746.9126543672764;1752.9035903025201;882.5543100734217;1043.2592859069994;1118.5403049021106;1131.2477202660302;1142.3031950510183;6797.708435397358;6851.143607049119;6862.862742745217;6880.793576192722;6900.2409734929115;7289.489735718449;7598;7630.847383052308;7642.961785191039;7685.308322251024;6697.631630260383;6917.89623155653;6920.411765000032;6935.6878940447805;6956.478264130211;4018;4053.3717273822604;4066.5526792053442;4085.7344272622186;4086.244044851712;7959.522428654299;7971.800704982305;7988.640511177011;7998.360131189491;8014.238647328494;7580.507267027626;7838.647260873275;7925.405594580429;7945.245201705829;7950;7904.20957904205;7904.407169462198;7962.601869906482;7965.163657816078;8005.239502247277;7648.73229436967;7649.923500765144;7679.462437629312;7684.3084122429245;7695.961520192363;6842.178938527409;6866.489372338077;7507.211742766931;7532.661030253542;7564.092308922965;7575.962120189364;7906.52560846351;7946.523208607502;7956.5226086435;7972.84054318922;4679.415073115861;4691.882702932454;4736.5973892219545;4745.667803253739;4746.477887432311;5755.798547050833;7375.594342311224;7573.35626471756;7582.772516824504;7599.924000759922;7309.451791115581;7335.303146201102;7337.229590892879;7347.70609175627;7360.300771426706;7029.61337126463;7360.374368178765;7375.373093286916;7389;7407.333339999426;7119.82200444993;7146.606936618328;7183.389411900047;7201.459890508338;7201.927980720126;7354.742584009534;7370.77887663371;7372.778816635509;7373.373263272468;7420.740274090381;6916.3429474199875;7200.71197152129;7229.710811567478;7237.457190710613;7237.927620723725 20:20:22 INFO - PROCESS | 2250 | |5;yui.html;694.656145208122;694.7082225465307;723.7394537966328;734.5225603357817;748.3339827553478;11740.589079382224;13883.889288856892;15027.999999999985;15041.7966562675;16675.83324166758;15158.924205378959;15192.164430956293;15299.235038248098;15308.081515109101;15325.463608773694;904.6878826804758;913.2100732866056;924.935254532183;926.3978414030901;930.4463844012802;832.2135581875131;915.071202729227;929.7815013471828;933.0156684697641;934.1359242700509;921.4977837078775;934.4580143516744;959.7600599850037;964.7009427077593;978.8727465429484;912.5665308978248;920.0477505781485;926.8841394825647;926.9165775080241;967.4775621164563;612.8631388773805;682.6757290287123;683.1017212365723;685.7120009595982;686.9278725733776;14348.28258587077;14567.490137845125;14764.114153150846;14786.926065369711;14834.258287085602;12657.050721195856;12692.365381730877;12756.936215318863;12819.935900320532;12840;10000.89999100007;10030.64892728751;10107.646232381905;10186.337888037328;10290.302100153465;3422.758093448795;3451.5858097028517;3467.9769468007;3481.634428385034;3498.142954976022;84571;84788;85010;85125.14874851296;85640.57179714124;9286.814263714688;9489.572969216402;9509.80537773356;9814.263930205263;9824.852627210597;22129.114835406726;22132.446688832584;22177;22193.334199974026;22204.444888877908;6047.820674968427;6659.267480577133;7346.5592064476305;7564.773056808304;7667.846643067108;3994.560598334239;4054.128362402039;4118.094019315746;4126.257273690764;4131.814068366931;3438.209211881281;3524.0308915048568;3530.029241958482;3530.8764193253114;3544.043108360728;623.2738859229038;632.9715162817685;640.1326202994875;648.8313038609901;651.7393042782925;550.5155463192365;554.7780887644893;576.63095618804;605.3552966091099;609.8414412252757;8000.319972802261;8038.057254540119;8203.179682031749;8324.626436638007;8494.362922780692;502.5351549816405;533.6184627991005;539.5333036923033;544.8365490352878;614.0604874541958;3817.8854634361014;3823.3118038752737;3827.770333779982;3863.9806800966376;3916.432117343026;92994.53502732443;94655.10689786165;95117;95744;96201.5569766454;65778.67110664512;67330;67473.66263168653;68051;68106.65946670334;65635.67182164059;67198;67257.32742672609;67489;70679;7502.324790768857;7578.166401695919;7668.5782281975025;7739.419543534144;7876.645550950107;49666;50596.49403505918;51119.2332115026;51298;51848.74075629598;65810;68923.65538172478;69224.6538767303;70394.94407583993;72079.63960180165;9532.237421006164;10030.899691002996;10044.799104018024;10091.596336146471;10225.539850706587;10393.272470926962;10420.06219440254;10482.161427085968;10799.920007999137;10964.671059868217;10188.847167292348;10199.541020654236;10303.433311167659;10315.17478601695;10472.5172610186;8003.119656837627;9238.122378374044;10049.246306527186;10118.392896426238;10301.793964120825;9053.818923621622;9900.801983960424;9956.103950644478;9981.850272245776;10142.087212150942;8722.781930451789;8762.518061506678;8817.206451419146;8990.550472476481;9055.320850936074;7165.856682866209;7372.262773722585;8726.65093396257;8935.28517718568;8937.06160853127 20:20:22 INFO - PROCESS | 2250 | __end_tp_report 20:20:22 INFO - PROCESS | 2250 | __start_cc_report 20:20:22 INFO - PROCESS | 2250 | _x_x_mozilla_cycle_collect,417 20:20:22 INFO - PROCESS | 2250 | __end_cc_report 20:20:22 INFO - PROCESS | 2250 | __startTimestamp1463628022221__endTimestamp 20:20:22 INFO - PROCESS | 2250 | 20:20:22 INFO - PROCESS | 2250 | ------- Summary: start ------- 20:20:22 INFO - PROCESS | 2250 | Number of tests: 6 20:20:22 INFO - PROCESS | 2250 | 20:20:22 INFO - PROCESS | 2250 | [#0] dojo.html Cycles:165 Average:5702.29 Median:5877.32 stddev:675.13 (11.5%) stddev-sans-first:652.52 20:20:22 INFO - PROCESS | 2250 | Values: 3396.5 3424.5 3471.6 3477.1 3481.8 5147.5 5666.4 5705.3 5737.9 5740.2 4843.9 5738.9 5742.3 5757.9 5772.6 5162.7 5173.7 5250.3 5264.4 5266.9 5143.5 5277.0 5285.7 5291.9 5296.8 5241.2 5259.2 5269.0 5271.7 5307.1 4006.0 4456.3 5285.5 5295.7 5314.7 5108.8 5216.4 5247.9 5302.1 5304.0 5483.2 5552.6 5562.2 5658.5 5667.5 6203.0 6248.7 6274.4 6276.6 6297.7 5499.6 6283.7 6308.6 6346.5 6349.9 5422.8 5674.4 5684.6 5791.6 5837.4 5167.0 6254.5 6269.5 6303.7 6324.5 6086.4 6125.1 6156.8 6163.0 6169.6 5277.5 6154.6 6155.0 6227.9 6292.5 4944.5 6237.8 6265.2 6271.8 6271.9 6065.7 6107.5 6273.7 6282.8 6310.9 3752.0 4908.5 4920.9 4929.9 5086.5 4432.7 4440.4 4453.9 4467.0 4492.4 4893.6 5059.3 5743.0 5816.6 5817.7 6141.5 6165.8 6270.3 6274.9 6286.1 5783.9 5787.7 5810.2 5856.4 5870.1 5324.0 5410.4 5412.4 5421.3 5462.8 6304.6 6305.6 6340.7 6349.1 6359.2 6225.8 6262.7 6273.5 6300.1 6303.9 6197.5 6210.2 6287.9 6313.7 6351.1 6198.1 6206.8 6218.0 6235.1 6236.7 6284.6 6294.2 6307.6 6316.4 6328.2 5769.8 6056.5 6111.9 6135.7 6149.6 6126.8 6129.0 6136.6 6144.5 6175.5 5083.4 5822.2 5877.3 6031.3 6043.9 6086.6 6113.8 6130.7 6168.5 6183.4 5959.9 5965.7 5983.8 5995.6 6006.0 20:20:22 INFO - PROCESS | 2250 | 20:20:22 INFO - PROCESS | 2250 | [#1] ext.html Cycles:180 Average:64317.42 Median:38504.04 stddev:140446.76 (364.8%) stddev-sans-first:140759.05 20:20:22 INFO - PROCESS | 2250 | Values: 512.5 520.4 540.1 544.9 629.1 29695.4 39694.0 39697.2 39834.0 39967.8 40259.0 40344.2 40928.6 40937.6 41134.6 9452.1 9640.9 9653.8 9708.0 9714.5 9611.1 9707.3 9754.9 9780.1 9842.1 8441.4 9721.3 9788.8 9833.0 9872.0 8318.5 8345.6 9807.0 9828.1 9837.7 9744.3 9748.1 9841.3 9850.5 9857.9 38652.2 38981.0 39029.0 39072.6 39205.8 2460.4 3109.6 3179.9 3217.5 3254.8 25881.0 26459.2 26526.7 26680.7 26983.0 2719.8 2730.9 2773.3 2780.5 2967.1 364376.0 389969.1 390540.0 412436.0 413065.0 28845.9 36613.5 37042.8 65007.3 65404.3 35355.8 35535.3 35732.1 35771.6 35813.8 15817.0 16027.9 16049.0 16053.4 16064.8 11424.1 13235.0 13411.8 13495.2 13519.7 8353.3 8394.6 8432.4 8452.2 8473.7 2980.2 3410.7 3421.5 3455.0 3486.0 1036.6 1042.3 1066.3 1119.6 1163.0 30574.4 30574.7 31087.8 31201.8 31220.8 1060.3 1138.5 1169.1 1181.9 1219.0 11814.2 12121.3 14900.0 15616.5 15985.5 597408.0 600335.0 608287.0 608336.0 609783.0 61311.7 62005.4 63104.7 63288.0 63311.7 1469.2 1480.3 1481.0 1487.7 1488.4 26169.7 30037.0 30432.4 30475.7 30805.8 54900.7 55388.2 55723.4 56112.7 56152.0 544134.8 544990.0 557799.0 557924.0 558961.0 27261.7 27671.9 27719.9 27908.9 27998.7 46376.0 46883.0 47760.3 47922.5 48437.5 38015.6 38640.4 38980.2 39075.6 40100.4 31659.7 37931.0 38498.0 38510.0 39131.0 32377.8 38280.4 38537.8 38831.4 39264.0 30353.7 31368.5 31631.2 31635.0 31699.0 24586.6 24731.3 25932.4 26366.6 27109.7 20:20:22 INFO - PROCESS | 2250 | 20:20:22 INFO - PROCESS | 2250 | [#2] jquery.html Cycles:180 Average:32427.17 Median:403.69 stddev:93928.98 (23267.5%) stddev-sans-first:94161.61 20:20:22 INFO - PROCESS | 2250 | Values: 357.5 402.4 404.9 413.4 414.1 15772.3 15912.0 16683.8 17182.4 17200.6 20157.6 20223.5 20549.1 20584.6 20622.1 5692.6 5861.6 5974.3 5983.7 5986.1 5643.6 5855.0 5929.8 5934.9 5942.2 5772.6 5850.8 5875.2 5886.7 5886.8 5592.3 5767.1 5786.2 5833.8 5846.2 5094.3 5096.7 5127.7 5148.0 5155.5 24920.8 25170.7 25317.5 25388.0 25791.4 2187.4 2327.2 2357.4 2358.4 2395.5 9053.8 9760.2 9859.7 14804.3 15400.5 1720.1 1744.7 1759.2 1787.7 1817.0 104241.5 107759.0 108858.9 113172.4 114594.4 11737.5 11964.2 11969.9 12040.0 12153.7 14875.8 14925.3 14997.9 15007.1 15028.6 7275.8 7348.8 8990.5 9028.3 9067.0 9040.5 9048.5 9051.4 9174.2 9268.6 4609.0 4612.6 4649.4 4674.2 4700.3 2027.7 2033.8 2035.7 2046.1 2058.5 891.4 968.5 969.2 1000.2 1028.3 16217.4 18461.7 19486.0 20112.6 20301.7 978.1 979.8 986.4 997.3 1021.8 10587.7 10700.4 10769.6 10779.2 10784.2 536020.0 540062.0 542195.0 581621.0 623720.3 72849.6 73121.6 75178.0 127627.7 128310.4 457.4 475.1 475.1 478.7 482.8 8834.2 10199.0 10787.9 11225.2 11236.4 49342.8 53282.7 53513.5 54673.7 55097.0 58632.7 72211.6 77637.6 79081.8 79484.2 7871.8 8038.2 8091.8 8204.0 8207.0 9837.6 10801.5 10941.4 12055.1 12702.4 9952.5 10820.7 10886.8 10911.8 10956.4 10539.5 10613.6 10705.1 10867.2 10871.9 10387.3 10866.6 10936.3 11185.7 11272.4 6672.6 7646.9 7780.5 8195.8 8279.8 10542.8 10769.5 10872.3 10936.6 10939.3 20:20:22 INFO - PROCESS | 2250 | 20:20:22 INFO - PROCESS | 2250 | [#3] mootools.html Cycles:180 Average:5575.87 Median:4784.62 stddev:3075.02 (64.3%) stddev-sans-first:3068.57 20:20:22 INFO - PROCESS | 2250 | Values: 1524.9 1528.4 1722.4 1828.6 1897.0 7796.4 8486.4 8592.2 8813.0 8820.5 8410.6 8426.3 8523.4 8536.1 8540.0 2420.1 2431.4 2432.9 2437.1 2467.9 2394.2 2399.7 2430.1 2437.9 2444.5 2416.9 2424.6 2433.1 2444.6 2448.9 2384.5 2401.7 2412.2 2414.1 2421.4 2417.0 2433.8 2439.2 2442.0 2455.5 8603.7 8613.3 8654.2 8656.1 8697.8 5242.5 5347.0 5359.9 5398.2 5421.6 3500.5 3562.9 3572.4 3601.2 3648.9 1045.9 1061.6 1063.3 1064.7 1067.2 11806.5 11887.0 11907.9 11910.5 11934.0 4665.8 4687.7 4775.3 4794.0 4821.5 10068.9 10072.3 10080.5 10088.2 10105.7 5333.5 5342.5 5370.2 5385.5 5405.9 4327.9 4354.5 4540.0 4765.0 4963.4 3338.3 3563.5 4111.7 4137.2 4171.2 1368.8 1370.2 1370.7 1373.3 1389.3 844.4 909.0 943.3 955.2 959.0 5400.6 5824.6 5908.5 5946.4 6062.8 909.9 971.7 1006.7 1030.9 1039.5 3765.8 4029.7 4061.0 4084.5 4107.8 10132.2 10214.3 10515.7 10520.9 10526.4 9063.2 9652.2 10262.2 10375.1 10578.8 6936.9 8040.6 8114.7 8374.7 8423.2 5746.9 5800.3 5811.5 5893.2 6060.9 9381.9 9419.2 9499.8 9577.8 9636.9 9638.5 9778.8 9787.6 9853.5 9899.7 6603.7 6746.5 6923.0 6995.7 7043.1 7678.4 7728.0 7740.8 7753.6 7795.2 6810.1 6916.1 6924.6 6932.2 7023.6 5790.9 6839.9 6889.2 6966.5 6985.4 6514.8 6625.4 6655.6 6703.1 6743.8 4428.1 4497.0 4530.1 4533.3 4571.3 4344.7 4647.2 4767.5 4897.8 4928.6 20:20:22 INFO - PROCESS | 2250 | 20:20:22 INFO - PROCESS | 2250 | [#4] prototype.html Cycles:180 Average:5942.58 Median:6808.10 stddev:1932.31 (28.4%) stddev-sans-first:1891.21 20:20:22 INFO - PROCESS | 2250 | Values: 327.6 328.1 333.8 336.0 338.4 6789.6 6802.0 6828.8 6837.7 6857.5 6780.8 6801.9 6803.7 6804.5 6825.9 4736.8 4842.2 4947.8 4963.6 4963.6 4895.3 4951.2 4951.6 4966.9 4981.5 4916.7 4920.4 4929.0 4942.7 4951.3 4921.3 4934.7 4945.3 4946.6 4980.4 3323.8 4283.6 4285.1 4291.4 4300.5 5143.9 6490.8 6604.3 6608.1 6610.0 4833.8 4863.3 5912.1 5964.6 5967.3 6370.5 6811.7 7067.7 7291.3 7312.4 3987.7 4103.8 4537.3 4822.5 4829.5 5946.3 6075.8 7334.7 7377.4 7674.9 4755.8 4845.7 4863.3 4916.9 4919.0 4593.4 4965.2 4989.5 5005.8 5009.2 5904.1 5905.6 5918.6 5951.6 5959.9 7326.4 7361.2 7370.9 7404.4 7437.9 1443.5 1715.5 1744.9 1746.9 1752.9 882.6 1043.3 1118.5 1131.2 1142.3 6797.7 6851.1 6862.9 6880.8 6900.2 7289.5 7598.0 7630.8 7643.0 7685.3 6697.6 6917.9 6920.4 6935.7 6956.5 4018.0 4053.4 4066.6 4085.7 4086.2 7959.5 7971.8 7988.6 7998.4 8014.2 7580.5 7838.6 7925.4 7945.2 7950.0 7904.2 7904.4 7962.6 7965.2 8005.2 7648.7 7649.9 7679.5 7684.3 7696.0 6842.2 6866.5 7507.2 7532.7 7564.1 7576.0 7906.5 7946.5 7956.5 7972.8 4679.4 4691.9 4736.6 4745.7 4746.5 5755.8 7375.6 7573.4 7582.8 7599.9 7309.5 7335.3 7337.2 7347.7 7360.3 7029.6 7360.4 7375.4 7389.0 7407.3 7119.8 7146.6 7183.4 7201.5 7201.9 7354.7 7370.8 7372.8 7373.4 7420.7 6916.3 7200.7 7229.7 7237.5 7237.9 20:20:22 INFO - PROCESS | 2250 | 20:20:22 INFO - PROCESS | 2250 | [#5] yui.html Cycles:180 Average:17951.17 Median:65707.17 stddev:25651.68 (39.0%) stddev-sans-first:25690.92 20:20:22 INFO - PROCESS | 2250 | Values: 694.7 694.7 723.7 734.5 748.3 11740.6 13883.9 15028.0 15041.8 16675.8 15158.9 15192.2 15299.2 15308.1 15325.5 904.7 913.2 924.9 926.4 930.4 832.2 915.1 929.8 933.0 934.1 921.5 934.5 959.8 964.7 978.9 912.6 920.0 926.9 926.9 967.5 612.9 682.7 683.1 685.7 686.9 14348.3 14567.5 14764.1 14786.9 14834.3 12657.1 12692.4 12756.9 12819.9 12840.0 10000.9 10030.6 10107.6 10186.3 10290.3 3422.8 3451.6 3468.0 3481.6 3498.1 84571.0 84788.0 85010.0 85125.1 85640.6 9286.8 9489.6 9509.8 9814.3 9824.9 22129.1 22132.4 22177.0 22193.3 22204.4 6047.8 6659.3 7346.6 7564.8 7667.8 3994.6 4054.1 4118.1 4126.3 4131.8 3438.2 3524.0 3530.0 3530.9 3544.0 623.3 633.0 640.1 648.8 651.7 550.5 554.8 576.6 605.4 609.8 8000.3 8038.1 8203.2 8324.6 8494.4 502.5 533.6 539.5 544.8 614.1 3817.9 3823.3 3827.8 3864.0 3916.4 92994.5 94655.1 95117.0 95744.0 96201.6 65778.7 67330.0 67473.7 68051.0 68106.7 65635.7 67198.0 67257.3 67489.0 70679.0 7502.3 7578.2 7668.6 7739.4 7876.6 49666.0 50596.5 51119.2 51298.0 51848.7 65810.0 68923.7 69224.7 70394.9 72079.6 9532.2 10030.9 10044.8 10091.6 10225.5 10393.3 10420.1 10482.2 10799.9 10964.7 10188.8 10199.5 10303.4 10315.2 10472.5 8003.1 9238.1 10049.2 10118.4 10301.8 9053.8 9900.8 9956.1 9981.9 10142.1 8722.8 8762.5 8817.2 8990.6 9055.3 7165.9 7372.3 8726.7 8935.3 8937.1 20:20:22 INFO - PROCESS | 2250 | -------- Summary: end -------- 20:20:22 INFO - PROCESS | 2250 | 20:20:22 INFO - TEST-INFO | 2250: exit 0 20:20:22 INFO - TEST-OK | dromaeo_css | took 1111149ms 20:20:22 INFO - TEST-START | kraken 20:20:22 INFO - operating with platform_type : mac_ 20:20:22 INFO - Initialising browser for kraken test... 20:20:22 INFO - TEST-INFO | started process 2256 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpdIJi3s/profile http://localhost:53136/getInfo.html) 20:20:25 INFO - PROCESS | 2256 | __metrics Screen width/height:1600/1200 20:20:25 INFO - PROCESS | 2256 | colorDepth:24 20:20:25 INFO - PROCESS | 2256 | Browser inner width/height: 1024/689 20:20:25 INFO - PROCESS | 2256 | __metrics 20:20:25 ERROR - PROCESS | 2256 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 20:20:26 INFO - TEST-INFO | 2256: exit 0 20:20:26 INFO - Browser initialized. 20:20:26 INFO - Running cycle 1/1 for kraken test... 20:20:26 INFO - TEST-INFO | started process 2259 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpdIJi3s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:20:38 INFO - PROCESS | 2259 | RSS: Main: 220995584 20:20:38 INFO - PROCESS | 2259 | 20:20:40 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53136/tests/kraken/driver.html?testName=audio-beat-detection) 20:20:41 INFO - PROCESS | 2259 | RSS: Main: 232783872 20:20:41 INFO - PROCESS | 2259 | 20:20:44 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53136/tests/kraken/driver.html?testName=audio-dft) 20:20:44 INFO - PROCESS | 2259 | RSS: Main: 241569792 20:20:44 INFO - PROCESS | 2259 | 20:20:48 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53136/tests/kraken/driver.html?testName=audio-fft) 20:20:48 INFO - PROCESS | 2259 | RSS: Main: 245723136 20:20:48 INFO - PROCESS | 2259 | 20:20:50 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53136/tests/kraken/driver.html?testName=audio-oscillator) 20:20:51 INFO - PROCESS | 2259 | RSS: Main: 247050240 20:20:51 INFO - PROCESS | 2259 | 20:20:53 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53136/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:20:54 INFO - PROCESS | 2259 | RSS: Main: 247635968 20:20:54 INFO - PROCESS | 2259 | 20:20:56 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53136/tests/kraken/driver.html?testName=imaging-darkroom) 20:20:57 INFO - PROCESS | 2259 | RSS: Main: 248168448 20:20:57 INFO - PROCESS | 2259 | 20:21:00 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53136/tests/kraken/driver.html?testName=imaging-desaturate) 20:21:00 INFO - PROCESS | 2259 | RSS: Main: 247820288 20:21:00 INFO - PROCESS | 2259 | 20:21:03 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53136/tests/kraken/driver.html?testName=json-parse-financial) 20:21:04 INFO - PROCESS | 2259 | RSS: Main: 248172544 20:21:04 INFO - PROCESS | 2259 | 20:21:06 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53136/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:21:06 INFO - PROCESS | 2259 | RSS: Main: 247115776 20:21:06 INFO - PROCESS | 2259 | 20:21:08 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53136/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:21:09 INFO - PROCESS | 2259 | RSS: Main: 247242752 20:21:09 INFO - PROCESS | 2259 | 20:21:11 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53136/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:21:12 INFO - PROCESS | 2259 | RSS: Main: 247468032 20:21:12 INFO - PROCESS | 2259 | 20:21:15 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53136/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:21:15 INFO - PROCESS | 2259 | RSS: Main: 247611392 20:21:15 INFO - PROCESS | 2259 | 20:21:18 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53136/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:21:19 INFO - PROCESS | 2259 | RSS: Main: 247492608 20:21:19 INFO - PROCESS | 2259 | 20:21:20 INFO - PROCESS | 2259 | Cycle 1(1): loaded http://localhost:53136/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53136/tests/kraken/driver.html?testName=ai-astar) 20:21:21 INFO - PROCESS | 2259 | RSS: Main: 249266176 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | __start_tp_report 20:21:21 INFO - PROCESS | 2259 | _x_x_mozilla_page_load 20:21:21 INFO - PROCESS | 2259 | _x_x_mozilla_page_load_details 20:21:21 INFO - PROCESS | 2259 | |i|pagename|runs| 20:21:21 INFO - PROCESS | 2259 | |0;ai-astar;115;118;108;117;119;119;131;151;114;115 20:21:21 INFO - PROCESS | 2259 | |1;audio-beat-detection;157;121;147;162;121;140;122;167;147;154 20:21:21 INFO - PROCESS | 2259 | |2;audio-dft;193;200;199;201;196;200;199;200;201;196 20:21:21 INFO - PROCESS | 2259 | |3;audio-fft;66;79;73;164;84;82;56;62;125;55 20:21:21 INFO - PROCESS | 2259 | |4;audio-oscillator;79;83;126;78;79;83;122;82;82;119 20:21:21 INFO - PROCESS | 2259 | |5;imaging-gaussian-blur;89;85;88;96;134;85;88;86;87;86 20:21:21 INFO - PROCESS | 2259 | |6;imaging-darkroom;94;94;95;94;95;94;94;96;94;95 20:21:21 INFO - PROCESS | 2259 | |7;imaging-desaturate;90;102;92;104;149;124;90;90;90;100 20:21:21 INFO - PROCESS | 2259 | |8;json-parse-financial;75;80;65;64;68;67;66;79;62;62 20:21:21 INFO - PROCESS | 2259 | |9;json-stringify-tinderbox;65;70;86;109;58;99;114;106;102;76 20:21:21 INFO - PROCESS | 2259 | |10;stanford-crypto-aes;83;84;81;84;81;85;81;85;85;85 20:21:21 INFO - PROCESS | 2259 | |11;stanford-crypto-ccm;131;134;161;134;127;127;128;130;151;169 20:21:21 INFO - PROCESS | 2259 | |12;stanford-crypto-pbkdf2;168;173;172;172;172;170;168;166;168;168 20:21:21 INFO - PROCESS | 2259 | |13;stanford-crypto-sha256-iterative;66;71;68;68;71;68;68;67;70;66 20:21:21 INFO - PROCESS | 2259 | __end_tp_report 20:21:21 INFO - PROCESS | 2259 | __start_cc_report 20:21:21 INFO - PROCESS | 2259 | _x_x_mozilla_cycle_collect,1106 20:21:21 INFO - PROCESS | 2259 | __end_cc_report 20:21:21 INFO - PROCESS | 2259 | __startTimestamp1463628081078__endTimestamp 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | ------- Summary: start ------- 20:21:21 INFO - PROCESS | 2259 | Number of tests: 14 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#0] ai-astar Cycles:10 Average:120.70 Median:118.50 stddev:12.12 (10.2%) stddev-sans-first:12.68 20:21:21 INFO - PROCESS | 2259 | Values: 115.0 118.0 108.0 117.0 119.0 119.0 131.0 151.0 114.0 115.0 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#1] audio-beat-detection Cycles:10 Average:143.80 Median:150.50 stddev:17.31 (11.5%) stddev-sans-first:17.69 20:21:21 INFO - PROCESS | 2259 | Values: 157.0 121.0 147.0 162.0 121.0 140.0 122.0 167.0 147.0 154.0 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#2] audio-dft Cycles:10 Average:198.50 Median:200.00 stddev:2.64 (1.3%) stddev-sans-first:1.90 20:21:21 INFO - PROCESS | 2259 | Values: 193.0 200.0 199.0 201.0 196.0 200.0 199.0 200.0 201.0 196.0 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#3] audio-fft Cycles:10 Average:84.60 Median:69.50 stddev:34.42 (49.5%) stddev-sans-first:35.84 20:21:21 INFO - PROCESS | 2259 | Values: 66.0 79.0 73.0 164.0 84.0 82.0 56.0 62.0 125.0 55.0 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:80.50 stddev:20.18 (25.1%) stddev-sans-first:20.73 20:21:21 INFO - PROCESS | 2259 | Values: 79.0 83.0 126.0 78.0 79.0 83.0 122.0 82.0 82.0 119.0 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:87.50 stddev:14.96 (17.1%) stddev-sans-first:15.82 20:21:21 INFO - PROCESS | 2259 | Values: 89.0 85.0 88.0 96.0 134.0 85.0 88.0 86.0 87.0 86.0 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 20:21:21 INFO - PROCESS | 2259 | Values: 94.0 94.0 95.0 94.0 95.0 94.0 94.0 96.0 94.0 95.0 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#7] imaging-desaturate Cycles:10 Average:103.10 Median:90.00 stddev:19.34 (21.5%) stddev-sans-first:19.92 20:21:21 INFO - PROCESS | 2259 | Values: 90.0 102.0 92.0 104.0 149.0 124.0 90.0 90.0 90.0 100.0 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#8] json-parse-financial Cycles:10 Average:68.80 Median:67.50 stddev:6.75 (10.0%) stddev-sans-first:6.77 20:21:21 INFO - PROCESS | 2259 | Values: 75.0 80.0 65.0 64.0 68.0 67.0 66.0 79.0 62.0 62.0 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#9] json-stringify-tinderbox Cycles:10 Average:88.50 Median:67.50 stddev:20.16 (29.9%) stddev-sans-first:19.50 20:21:21 INFO - PROCESS | 2259 | Values: 65.0 70.0 86.0 109.0 58.0 99.0 114.0 106.0 102.0 76.0 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:84.50 stddev:1.78 (2.1%) stddev-sans-first:1.88 20:21:21 INFO - PROCESS | 2259 | Values: 83.0 84.0 81.0 84.0 81.0 85.0 81.0 85.0 85.0 85.0 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#11] stanford-crypto-ccm Cycles:10 Average:139.20 Median:134.00 stddev:15.39 (11.5%) stddev-sans-first:16.03 20:21:21 INFO - PROCESS | 2259 | Values: 131.0 134.0 161.0 134.0 127.0 127.0 128.0 130.0 151.0 169.0 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:171.00 stddev:2.41 (1.4%) stddev-sans-first:2.47 20:21:21 INFO - PROCESS | 2259 | Values: 168.0 173.0 172.0 172.0 172.0 170.0 168.0 166.0 168.0 168.0 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - PROCESS | 2259 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.00 stddev:1.83 (2.7%) stddev-sans-first:1.74 20:21:21 INFO - PROCESS | 2259 | Values: 66.0 71.0 68.0 68.0 71.0 68.0 68.0 67.0 70.0 66.0 20:21:21 INFO - PROCESS | 2259 | -------- Summary: end -------- 20:21:21 INFO - PROCESS | 2259 | 20:21:21 INFO - TEST-INFO | 2259: exit 0 20:21:21 INFO - TEST-OK | kraken | took 59081ms 20:21:21 INFO - SUITE-END | took 1170s 20:21:22 INFO - Completed test suite (00:19:30) 20:21:22 INFO - javascript benchmark 20:21:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3396.4905264210365, 3424.469207272872, 3471.600765911919, 3477.0611934777594, 3481.825908704566, 5147.536721695046, 5666.37669856316, 5705.2583164188645, 5737.913931291028, 5740.167675687023, 4843.854684359466, 5738.913916291259, 5742.339630942442, 5757.856053598662, 5772.595918285721, 5162.741862906859, 5173.741312934357, 5250.343707036621, 5264.368275806909, 5266.947330526703, 5143.4599367066485, 5277.0, 5285.709285989269, 5291.920621190685, 5296.841094767163, 5241.161414173714, 5259.184826351902, 5268.973655131738, 5271.710055946922, 5307.097793375136, 4005.97997010016, 4456.309272062836, 5285.471452854684, 5295.682259064469, 5314.707691076989, 5108.795648174068, 5216.426193118754, 5247.921281180786, 5302.0721373759825, 5304.0, 5483.177523371486, 5552.639078459928, 5562.249096371978, 5658.547316214693, 5667.489925906688, 6203.0, 6248.656323902183, 6274.403931626488, 6276.560640755149, 6297.716602752887, 5499.587530935196, 6283.685815709242, 6308.558400911938, 6346.492280617539, 6349.904751428732, 5422.8373148805595, 5674.404187560329, 5684.573656975744, 5791.565632577532, 5837.445442682938, 5166.974165129151, 6254.530910181755, 6269.498440124779, 6303.684815759194, 6324.494040476751, 6086.391360863879, 6125.050617154348, 6156.753729850756, 6162.969185154136, 6169.629822210682, 5277.4986376294955, 6154.569180157346, 6155.0, 6227.937720622826, 6292.528060395489, 4944.505549445027, 6237.844053898598, 6265.216847894013, 6271.7804876830005, 6271.937280627227, 6065.727042283108, 6107.511399088063, 6273.686315684197, 6282.811515654537, 6310.936890631035, 3751.9812400938367, 4908.4846091160625, 4920.926186107211, 4929.876753081202, 5086.516780905808, 4432.711873728197, 4440.3561483585345, 4453.910921781546, 4466.955330446719, 4492.415985921874, 4893.584045356113, 5059.2916991621205, 5743.0, 5816.5928385013485, 5817.650940943557, 6141.547378150805, 6165.753369865155, 6270.341614130451, 6274.874502509924, 6286.05709143623, 5783.913241301384, 5787.652740835563, 5810.157527158538, 5856.4143585641095, 5870.090136028923, 5324.0, 5410.431904649955, 5412.377576578744, 5421.295231619864, 5462.781488740406, 6304.621722696561, 6305.621662700252, 6340.7463701451425, 6349.111124442582, 6359.17330747, 6225.782097626561, 6262.718177682106, 6273.498120150468, 6300.149479820166, 6303.936960630335, 6197.473214776614, 6210.223722034746, 6287.874242515032, 6313.715882785194, 6351.1425957496995, 6198.0702894566175, 6206.813795586139, 6218.0, 6235.095911092957, 6236.719347629277, 6284.622922624657, 6294.181756371642, 6307.558470906993, 6316.431521163117, 6328.24061112651, 5769.798057067983, 6056.454919057306, 6111.895622426936, 6135.692037377249, 6149.631022138686, 6126.768630067982, 6128.959784318273, 6136.570440069152, 6144.477719393902, 6175.536834737323, 5083.440821509705, 5822.243108395882, 5877.324107727666, 6031.336552979081, 6043.879122417439, 6086.6348019119, 6113.841195312599, 6130.6628135452975, 6168.506519478341, 6183.443490085734, 5959.880802383841, 5965.6718880461995, 5983.790567330123, 5995.610285331614, 6005.969970150121], "value": 5661.620314332596, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.5156726893085, 520.3677531798864, 540.1142126911864, 544.8964696707625, 629.0721186250285, 29695.406091878176, 39694.0, 39697.206055878865, 39834.0, 39967.80016099919, 40259.0, 40344.193116137736, 40928.59071409285, 40937.59062409375, 41134.58865411345, 9452.102050305228, 9640.855387169198, 9653.806923861519, 9707.95146024269, 9714.514274286294, 9611.086946740048, 9707.271954603397, 9754.853677194847, 9780.119789218968, 9842.06500382466, 8441.366897482714, 9721.270904682176, 9788.804223915517, 9832.967538408471, 9871.950640246825, 8318.500889946592, 8345.582720863962, 9807.019298070207, 9828.066333698287, 9837.655682051165, 9744.269179811472, 9748.073932976356, 9841.261905357056, 9850.50747462631, 9857.90142098577, 38652.226955460734, 38981.02547436309, 39029.0, 39072.60927390718, 39205.80397098025, 2460.434100156956, 3109.5957525521762, 3179.8887038953526, 3217.469117595594, 3254.8209848458328, 25881.0, 26459.206223813315, 26526.73473265281, 26680.73319266802, 26983.0, 2719.7552220300267, 2730.945381092387, 2773.320536468578, 2780.4856101621267, 2967.109867039879, 364376.0, 389969.10030899895, 390540.0472997645, 412436.0, 413065.0, 28845.85577072143, 36613.450798238046, 37042.8147859259, 65007.349926501076, 65404.34595653982, 35355.823220884246, 35535.28929421397, 35732.106697332776, 35771.64228357683, 35813.82093089518, 15817.0, 16027.919860400623, 16049.0, 16053.357865685475, 16064.839351606568, 11424.086073114131, 13235.0, 13411.798823017663, 13495.190288582717, 13519.66200844967, 8353.289970352467, 8394.580270986426, 8432.409731318872, 8452.197041281068, 8473.70342038038, 2980.225141463235, 3410.6589341065696, 3421.5380923575494, 3455.0498612881156, 3486.0, 1036.6268143468346, 1042.285895454358, 1066.3015724700333, 1119.630521927762, 1162.9883701163048, 30574.388512230074, 30574.69425305763, 31087.84456077705, 31201.8439907799, 31220.843895780377, 1060.3425875956957, 1138.5445821671399, 1169.0822704177351, 1181.8699943006263, 1218.9817152742717, 11814.173007889365, 12121.27272363661, 14900.0, 15616.531504054896, 15985.520434386986, 597408.0, 600335.0, 608287.0, 608336.0, 609783.0, 61311.69344153251, 62005.37994619996, 63104.73790524255, 63288.0, 63311.6834415825, 1469.1992863889207, 1480.3486465955161, 1481.037325738257, 1487.6950225203807, 1488.4120772294882, 26169.73830261673, 30037.0, 30432.391352173276, 30475.695243047285, 30805.845970770002, 54900.72549637226, 55388.169177463176, 55723.442765571825, 56112.719436402556, 56152.0, 544134.8379774386, 544990.0, 557799.0, 557924.0, 558961.0, 27261.72738272592, 27671.861640691666, 27719.86140069287, 27908.86045569759, 27998.72001279961, 46376.0, 46883.0, 47760.28359574539, 47922.5207747918, 48437.5156248433, 38015.61984380121, 38640.42039369468, 38980.2203955925, 39075.60924390834, 40100.3984940232, 31659.683403165673, 37931.0, 38498.0375490615, 38510.037249069, 39131.0, 32377.838110809294, 38280.42579361256, 38537.80731096327, 38831.41752873766, 39264.0, 30353.69646303509, 31368.475720591763, 31631.20921976969, 31635.0, 31699.04902852918, 24586.631200532363, 24731.258062258163, 25932.351691207117, 26366.604500932117, 27109.72890271151], "value": 18444.441456025947, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.545916685809, 402.4446264155465, 404.9392591111331, 413.35929309570156, 414.09520198366573, 15772.290246938886, 15911.965722228048, 16683.83316166838, 17182.398616048442, 17200.56998575033, 20157.59684806303, 20223.49441263969, 20549.075291611876, 20584.588308233826, 20622.072006759696, 5692.601517893749, 5861.6189947653265, 5974.342822289566, 5983.730732117044, 5986.132010858423, 5643.633163844357, 5854.970725146369, 5929.822105336847, 5934.88130237395, 5942.2275104236605, 5772.5670574706855, 5850.795222167225, 5875.177475153482, 5886.735096920627, 5886.823395298148, 5592.328920529521, 5767.106098554689, 5786.161006654053, 5833.795817146401, 5846.239988801472, 5094.261332106861, 5096.745162741885, 5127.717975511346, 5147.996140752554, 5155.510226528474, 24920.750792492207, 25170.748292517022, 25317.49365012708, 25388.0, 25791.35521611956, 2187.3765976696723, 2327.150590034642, 2357.446000189954, 2358.433975845802, 2395.5089206712582, 9053.818923621557, 9760.17038551724, 9859.654912078044, 14804.259787010606, 15400.460983865623, 1720.1055451165323, 1744.6772347115825, 1759.1643969114696, 1787.705028670268, 1817.0188098426834, 104241.47879260556, 107759.0, 108858.91141088567, 113172.43413782961, 114594.42702786517, 11737.471813768401, 11964.162508624313, 11969.940150299193, 12040.0, 12153.69615759613, 14875.776863347059, 14925.328360223815, 14997.925010374878, 15007.099574025593, 15028.624284392978, 7275.818104547323, 7348.77953661391, 8990.460572365679, 9028.277737780962, 9067.0, 9040.457572545669, 9048.547572621343, 9051.411658242194, 9174.220191283683, 9268.629254829732, 4608.953910460852, 4612.561806628365, 4649.418822647169, 4674.182018146811, 4700.271457924027, 2027.70598263251, 2033.8372930165847, 2035.735354403918, 2046.1303945822965, 2058.5162486815584, 891.3893982621925, 968.5399435268332, 969.2439896880445, 1000.1990445859843, 1028.2647906746713, 16217.432389866533, 18461.7230741539, 19486.02569871529, 20112.59774804525, 20301.695474567892, 978.0854900667898, 979.7648564344648, 986.4475893499663, 997.3068717241448, 1021.7752094539189, 10587.682369528773, 10700.357978521313, 10769.62306319275, 10779.191560632984, 10784.245102842726, 536020.0, 540062.0, 542195.0, 581621.0, 623720.288391349, 72849.6357518209, 73121.63439182876, 75178.0, 127627.72372276345, 128310.35844820902, 457.3848174205688, 475.07834800486944, 475.09494413143017, 478.73908719747124, 482.79963815016373, 8834.204921557091, 10199.031092046243, 10787.892121078688, 11225.158113141384, 11236.381998990135, 49342.75328623334, 53282.73358633182, 53513.46486535085, 54673.72663136659, 55097.0, 58632.70683646554, 72211.63894180495, 77637.61181194057, 79081.8137727923, 79484.20515795, 7871.803204919694, 8038.196180382149, 8091.757247282591, 8203.958980205061, 8207.0, 9837.60649574009, 10801.459927003461, 10941.398223097805, 12055.095867810125, 12702.428390722254, 9952.452615106238, 10820.729481762704, 10886.782264354826, 10911.781764364827, 10956.397398143179, 10539.525721342405, 10613.628523001658, 10705.143588513056, 10867.239293249713, 10871.891281087404, 10387.324823886423, 10866.565337386415, 10936.289141205796, 11185.72035699114, 11272.3800190987, 6672.573643334232, 7646.923530764621, 7780.455368124177, 8195.83608327842, 8279.751607451786, 10542.841857371992, 10769.461526923465, 10872.347659140476, 10936.56253749841, 10939.343639381344], "value": 9048.77866482277, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1524.893257471977, 1528.4192007037325, 1722.4057700093463, 1828.5977085041295, 1896.9810301896978, 7796.376289896809, 8486.44838085524, 8592.226699597035, 8812.986506551757, 8820.514871682055, 8410.621522031508, 8426.325893928486, 8523.360747943898, 8536.103709110546, 8540.0, 2420.140849998247, 2431.3921519620094, 2432.8661923594195, 2437.061731233479, 2467.864267465289, 2394.174009966559, 2399.6760437341, 2430.0887167312258, 2437.9390515237174, 2444.511097780442, 2416.915407960722, 2424.5878200705833, 2433.1362366359863, 2444.584420648494, 2448.8530688158767, 2384.451576137481, 2401.711794584652, 2412.191915708231, 2414.070582825616, 2421.3583400398907, 2417.0090262992267, 2433.7809597136165, 2439.1706819681394, 2441.963370549443, 2455.472073504205, 8603.655853765842, 8613.267872230868, 8654.177853103945, 8656.134386561294, 8697.826043479095, 5242.528172464458, 5346.97326513365, 5359.919601205985, 5398.1902714593125, 5421.593380496479, 3500.4574290984683, 3562.893113206582, 3572.392693242155, 3601.225736466672, 3648.927021459556, 1045.8588090607748, 1061.5912873543664, 1063.3247887591358, 1064.6912395405323, 1067.1836045425257, 11806.46870890812, 11886.989605883424, 11907.94046029773, 11910.523579056828, 11934.045276377869, 4665.766711664403, 4687.695299805502, 4775.283707443842, 4793.952060479351, 4821.5178482151505, 10068.899311006797, 10072.345297555636, 10080.546375413123, 10088.243381746399, 10105.747356316004, 5333.466653334635, 5342.54588359986, 5370.194470829407, 5385.461453854583, 5405.864853378697, 4327.891802704958, 4354.477462704495, 4539.954600454019, 4764.952350476451, 4963.379577552806, 3338.2989572189626, 3563.501109844624, 4111.732737372061, 4137.234611596864, 4171.228322760299, 1368.8494265630773, 1370.2121280263889, 1370.712150448397, 1373.3133433283358, 1389.3400634698437, 844.3697881100328, 908.9681861134961, 943.3302355327656, 955.2119501411363, 958.9856152157723, 5400.62195646301, 5824.592278540462, 5908.527317814564, 5946.43508866657, 6062.757489700362, 909.8862642169729, 971.7182017214928, 1006.7231511334295, 1030.850526673628, 1039.5166247694858, 3765.7740535567955, 4029.697772667003, 4060.959390406058, 4084.5302790178916, 4107.774072426046, 10132.189424845994, 10214.285000049926, 10515.737106572396, 10520.947395262974, 10526.421046842497, 9063.229625481908, 9652.179564736936, 10262.179025677928, 10375.066244037922, 10578.841317380246, 6936.930630693629, 8040.557769322743, 8114.675412983414, 8374.748757537405, 8423.199796019368, 5746.913796292975, 5800.274965629296, 5811.506021988009, 5893.233879595625, 6060.878782424239, 9381.906180938104, 9419.152276295166, 9499.762505937408, 9577.808443831223, 9636.855447168438, 9638.469884156439, 9778.804423911624, 9787.608495660379, 9853.507324633883, 9899.65351212704, 6603.735850565923, 6746.4940129489505, 6923.0, 6995.685194166376, 7043.084399028094, 7678.385729141543, 7728.0, 7740.806479838049, 7753.573553454614, 7795.22047795216, 6810.114685090906, 6916.135483064617, 6924.58452492852, 6932.237453879969, 7023.578585284899, 5790.884182316246, 6839.89740153908, 6889.173299204128, 6966.4775141865575, 6985.406240469617, 6514.804555863332, 6625.436837868642, 6655.600663959985, 6703.095082163845, 6743.831404214934, 4428.136513379989, 4496.955030449653, 4530.116627257785, 4533.274676051816, 4571.291449825282, 4344.674149438868, 4647.233206520887, 4767.4517430495935, 4897.8285759998225, 4928.556429921325], "value": 4541.268790808641, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.62159705540097, 328.14190890820504, 333.79304831004765, 336.0322271857052, 338.42974587819543, 6789.558678685894, 6801.965990170049, 6828.82927926802, 6837.726490940357, 6857.485688573358, 6780.830479238021, 6801.897971530431, 6803.659817009155, 6804.4556435485165, 6825.94879788955, 4736.834210802631, 4842.249451335048, 4947.8268260610885, 4963.60291176705, 4963.627727920402, 4895.265710143472, 4951.158303088466, 4951.628627852908, 4966.900661986758, 4981.480648402254, 4916.70499770015, 4920.360353154085, 4929.0, 4942.703437793743, 4951.257311403282, 4921.311016457699, 4934.654574179809, 4945.282933974571, 4946.554810067111, 4980.427250866161, 3323.80057196569, 4283.614474697292, 4285.100128972919, 4291.420658211133, 4300.505441874193, 5143.948560514385, 6490.837729056764, 6604.339566043405, 6608.107905432801, 6610.0, 4833.806647734086, 4863.343448634424, 5912.054071348563, 5964.582479226456, 5967.313758917737, 6370.458511026568, 6811.739828131762, 7067.681954312017, 7291.34377905991, 7312.378447831888, 3987.7208595398333, 4103.774292413916, 4537.342085397599, 4822.517748225185, 4829.468758436569, 5946.316173640043, 6075.817725468243, 7334.743283985088, 7377.446691498159, 7674.884876726854, 4755.785989630475, 4845.709257444564, 4863.3434486344595, 4916.852494425173, 4918.975405123023, 4593.402857628487, 4965.205567109245, 4989.476105008995, 5005.824796132118, 5009.223570346602, 5904.143899134601, 5905.5570832186895, 5918.644881307136, 5951.553633477506, 5959.851003724942, 7326.377257933029, 7361.190269070398, 7370.852582948418, 7404.407647388196, 7437.851242975218, 1443.5452832357773, 1715.4939292908623, 1744.8516876065423, 1746.9126543672764, 1752.9035903025201, 882.5543100734217, 1043.2592859069994, 1118.5403049021106, 1131.2477202660302, 1142.3031950510183, 6797.708435397358, 6851.143607049119, 6862.862742745217, 6880.793576192722, 6900.2409734929115, 7289.489735718449, 7598.0, 7630.847383052308, 7642.961785191039, 7685.308322251024, 6697.631630260383, 6917.89623155653, 6920.411765000032, 6935.6878940447805, 6956.478264130211, 4018.0, 4053.3717273822604, 4066.5526792053442, 4085.7344272622186, 4086.244044851712, 7959.522428654299, 7971.800704982305, 7988.640511177011, 7998.360131189491, 8014.238647328494, 7580.507267027626, 7838.647260873275, 7925.405594580429, 7945.245201705829, 7950.0, 7904.20957904205, 7904.407169462198, 7962.601869906482, 7965.163657816078, 8005.239502247277, 7648.73229436967, 7649.923500765144, 7679.462437629312, 7684.3084122429245, 7695.961520192363, 6842.178938527409, 6866.489372338077, 7507.211742766931, 7532.661030253542, 7564.092308922965, 7575.962120189364, 7906.52560846351, 7946.523208607502, 7956.5226086435, 7972.84054318922, 4679.415073115861, 4691.882702932454, 4736.5973892219545, 4745.667803253739, 4746.477887432311, 5755.798547050833, 7375.594342311224, 7573.35626471756, 7582.772516824504, 7599.924000759922, 7309.451791115581, 7335.303146201102, 7337.229590892879, 7347.70609175627, 7360.300771426706, 7029.61337126463, 7360.374368178765, 7375.373093286916, 7389.0, 7407.333339999426, 7119.82200444993, 7146.606936618328, 7183.389411900047, 7201.459890508338, 7201.927980720126, 7354.742584009534, 7370.77887663371, 7372.778816635509, 7373.373263272468, 7420.740274090381, 6916.3429474199875, 7200.71197152129, 7229.710811567478, 7237.457190710613, 7237.927620723725], "value": 5293.399663200886, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.656145208122, 694.7082225465307, 723.7394537966328, 734.5225603357817, 748.3339827553478, 11740.589079382224, 13883.889288856892, 15027.999999999985, 15041.7966562675, 16675.83324166758, 15158.924205378959, 15192.164430956293, 15299.235038248098, 15308.081515109101, 15325.463608773694, 904.6878826804758, 913.2100732866056, 924.935254532183, 926.3978414030901, 930.4463844012802, 832.2135581875131, 915.071202729227, 929.7815013471828, 933.0156684697641, 934.1359242700509, 921.4977837078775, 934.4580143516744, 959.7600599850037, 964.7009427077593, 978.8727465429484, 912.5665308978248, 920.0477505781485, 926.8841394825647, 926.9165775080241, 967.4775621164563, 612.8631388773805, 682.6757290287123, 683.1017212365723, 685.7120009595982, 686.9278725733776, 14348.28258587077, 14567.490137845125, 14764.114153150846, 14786.926065369711, 14834.258287085602, 12657.050721195856, 12692.365381730877, 12756.936215318863, 12819.935900320532, 12840.0, 10000.89999100007, 10030.64892728751, 10107.646232381905, 10186.337888037328, 10290.302100153465, 3422.758093448795, 3451.5858097028517, 3467.9769468007, 3481.634428385034, 3498.142954976022, 84571.0, 84788.0, 85010.0, 85125.14874851296, 85640.57179714124, 9286.814263714688, 9489.572969216402, 9509.80537773356, 9814.263930205263, 9824.852627210597, 22129.114835406726, 22132.446688832584, 22177.0, 22193.334199974026, 22204.444888877908, 6047.820674968427, 6659.267480577133, 7346.5592064476305, 7564.773056808304, 7667.846643067108, 3994.560598334239, 4054.128362402039, 4118.094019315746, 4126.257273690764, 4131.814068366931, 3438.209211881281, 3524.0308915048568, 3530.029241958482, 3530.8764193253114, 3544.043108360728, 623.2738859229038, 632.9715162817685, 640.1326202994875, 648.8313038609901, 651.7393042782925, 550.5155463192365, 554.7780887644893, 576.63095618804, 605.3552966091099, 609.8414412252757, 8000.319972802261, 8038.057254540119, 8203.179682031749, 8324.626436638007, 8494.362922780692, 502.5351549816405, 533.6184627991005, 539.5333036923033, 544.8365490352878, 614.0604874541958, 3817.8854634361014, 3823.3118038752737, 3827.770333779982, 3863.9806800966376, 3916.432117343026, 92994.53502732443, 94655.10689786165, 95117.0, 95744.0, 96201.5569766454, 65778.67110664512, 67330.0, 67473.66263168653, 68051.0, 68106.65946670334, 65635.67182164059, 67198.0, 67257.32742672609, 67489.0, 70679.0, 7502.324790768857, 7578.166401695919, 7668.5782281975025, 7739.419543534144, 7876.645550950107, 49666.0, 50596.49403505918, 51119.2332115026, 51298.0, 51848.74075629598, 65810.0, 68923.65538172478, 69224.6538767303, 70394.94407583993, 72079.63960180165, 9532.237421006164, 10030.899691002996, 10044.799104018024, 10091.596336146471, 10225.539850706587, 10393.272470926962, 10420.06219440254, 10482.161427085968, 10799.920007999137, 10964.671059868217, 10188.847167292348, 10199.541020654236, 10303.433311167659, 10315.17478601695, 10472.5172610186, 8003.119656837627, 9238.122378374044, 10049.246306527186, 10118.392896426238, 10301.793964120825, 9053.818923621622, 9900.801983960424, 9956.103950644478, 9981.850272245776, 10142.087212150942, 8722.781930451789, 8762.518061506678, 8817.206451419146, 8990.550472476481, 9055.320850936074, 7165.856682866209, 7372.262773722585, 8726.65093396257, 8935.28517718568, 8937.06160853127], "value": 6695.144207728496, "unit": "score"}], "name": "dromaeo_css", "value": 7306.067749595534, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 118.0, 108.0, 117.0, 119.0, 119.0, 131.0, 151.0, 114.0, 115.0], "value": 120.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 121.0, 147.0, 162.0, 121.0, 140.0, 122.0, 167.0, 147.0, 154.0], "value": 143.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 200.0, 199.0, 201.0, 196.0, 200.0, 199.0, 200.0, 201.0, 196.0], "value": 198.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 79.0, 73.0, 164.0, 84.0, 82.0, 56.0, 62.0, 125.0, 55.0], "value": 84.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 83.0, 126.0, 78.0, 79.0, 83.0, 122.0, 82.0, 82.0, 119.0], "value": 93.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 96.0, 134.0, 85.0, 88.0, 86.0, 87.0, 86.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 96.0, 94.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 102.0, 92.0, 104.0, 149.0, 124.0, 90.0, 90.0, 90.0, 100.0], "value": 103.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 80.0, 65.0, 64.0, 68.0, 67.0, 66.0, 79.0, 62.0, 62.0], "value": 68.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 86.0, 109.0, 58.0, 99.0, 114.0, 106.0, 102.0, 76.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 81.0, 84.0, 81.0, 85.0, 81.0, 85.0, 85.0, 85.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 134.0, 161.0, 134.0, 127.0, 127.0, 128.0, 130.0, 151.0, 169.0], "value": 139.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 173.0, 172.0, 172.0, 172.0, 170.0, 168.0, 166.0, 168.0, 168.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 68.0, 68.0, 71.0, 68.0, 68.0, 67.0, 70.0, 66.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1548.8, "alertThreshold": 2.0}]} 20:21:22 INFO - Return code: 0 20:21:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:21:22 ERROR - # TBPL SUCCESS # 20:21:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:21:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:21:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:21:22 INFO - 2016-05-18 20:21:22.419 screenresolution[2264:31946] starting screenresolution argv=screenresolution get 20:21:22 INFO - 2016-05-18 20:21:22.422 screenresolution[2264:31946] Display 0: 1600x1200x32@60 20:21:22 INFO - 2016-05-18 20:21:22.428 screenresolution[2265:31948] starting screenresolution argv=screenresolution list 20:21:22 INFO - Available Modes on Display 0 20:21:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:21:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:21:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:21:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:21:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:21:22 INFO - Intel Iris: 20:21:22 INFO - Chipset Model: Intel Iris 20:21:22 INFO - Type: GPU 20:21:22 INFO - Bus: Built-In 20:21:22 INFO - VRAM (Dynamic, Max): 1536 MB 20:21:22 INFO - Vendor: Intel (0x8086) 20:21:22 INFO - Device ID: 0x0a2e 20:21:22 INFO - Revision ID: 0x0009 20:21:22 INFO - Displays: 20:21:22 INFO - Display: 20:21:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:21:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:21:22 INFO - Display Serial Number: 1600x1200 60 20:21:22 INFO - Main Display: Yes 20:21:22 INFO - Mirror: Off 20:21:22 INFO - Online: Yes 20:21:22 INFO - Rotation: Supported 20:21:22 INFO - Return code: 0 20:21:22 INFO - Running post-action listener: _resource_record_post_action 20:21:22 INFO - Running post-run listener: _resource_record_post_run 20:21:23 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 704512; Write bytes: 657181696; Read time: 8; Write time: 1891 20:21:23 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354331136; Write bytes: 357365760; Read time: 27899; Write time: 775 20:21:23 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 704512; Write bytes: 298968064; Read time: 8; Write time: 1113 20:21:23 INFO - Running post-run listener: _upload_blobber_files 20:21:23 INFO - Blob upload gear active. 20:21:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:21:23 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 20:21:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:21:24 INFO - (blobuploader) - INFO - Open directory for files ... 20:21:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:21:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:21:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:21:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:21:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:21:24 INFO - (blobuploader) - INFO - Done attempting. 20:21:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:21:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:21:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:21:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:21:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:21:26 INFO - (blobuploader) - INFO - Done attempting. 20:21:26 INFO - (blobuploader) - INFO - Iteration through files over. 20:21:26 INFO - Return code: 0 20:21:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:21:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:21:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d5d9863940a7dfe4e235168f27bf4e568de33812e6e5fb55ead9f38c66918e3984d2711cb101b7b679b3c739ccfb3810a4848178a982dcb6469151317f04c076", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb0b62da2560fbe55bec85050737a85e0c0822a937bbb2e5fa43288d0870aa5b08ed566eb4ff5d47e364c6374218d5f820ab8d66cc74049eee3b39efa550cfe6"} 20:21:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:21:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:21:26 INFO - Contents: 20:21:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d5d9863940a7dfe4e235168f27bf4e568de33812e6e5fb55ead9f38c66918e3984d2711cb101b7b679b3c739ccfb3810a4848178a982dcb6469151317f04c076", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb0b62da2560fbe55bec85050737a85e0c0822a937bbb2e5fa43288d0870aa5b08ed566eb4ff5d47e364c6374218d5f820ab8d66cc74049eee3b39efa550cfe6"} 20:21:26 INFO - Running post-run listener: copy_logs_to_upload_dir 20:21:26 INFO - Copying logs to upload dir... 20:21:26 INFO - mkdir: /builds/slave/test/build/upload/logs 20:21:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.289019 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-18 20:21:26.499454) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 20:21:26.502550) ========= 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.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d5d9863940a7dfe4e235168f27bf4e568de33812e6e5fb55ead9f38c66918e3984d2711cb101b7b679b3c739ccfb3810a4848178a982dcb6469151317f04c076", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb0b62da2560fbe55bec85050737a85e0c0822a937bbb2e5fa43288d0870aa5b08ed566eb4ff5d47e364c6374218d5f820ab8d66cc74049eee3b39efa550cfe6"} build_url:https://queue.taskcluster.net/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011623 build_url: 'https://queue.taskcluster.net/v1/task/O1HovTY0Svmnl1jIiRy9hg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d5d9863940a7dfe4e235168f27bf4e568de33812e6e5fb55ead9f38c66918e3984d2711cb101b7b679b3c739ccfb3810a4848178a982dcb6469151317f04c076", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb0b62da2560fbe55bec85050737a85e0c0822a937bbb2e5fa43288d0870aa5b08ed566eb4ff5d47e364c6374218d5f820ab8d66cc74049eee3b39efa550cfe6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 20:21:26.530785) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:21:26.531099) ========= 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.f3OqCo9ahP/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.x2Gm0qLyDG/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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.005369 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:21:26.580067) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 20:21:26.580401) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 20:21:26.580744) ========= ========= Total master_lag: 0.18 =========