builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0167 starttime: 1461185160.79 results: success (0) buildid: 20160420103742 builduid: 074e92339f3b4ac0b4d77d67493e1eef revision: 51135b23fb79d814f0ffd9eb18395de80e3a1584 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 13:46:00.791994) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 13:46:00.792473) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 13:46:00.792774) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 13:46:00.815225) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 13:46:00.815606) ========= 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.VcJqOuZS3v/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.GOQtzpp5xu/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-20 13:46:00-- 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% 28.2M=0s 2016-04-20 13:46:00 (28.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111801 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 13:46:00.945142) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 13:46:00.945542) ========= 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.VcJqOuZS3v/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.GOQtzpp5xu/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.030815 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 13:46:00.994680) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 13:46:00.995025) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 51135b23fb79d814f0ffd9eb18395de80e3a1584 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 51135b23fb79d814f0ffd9eb18395de80e3a1584 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VcJqOuZS3v/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.GOQtzpp5xu/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-20 13:46:01,068 truncating revision to first 12 chars 2016-04-20 13:46:01,068 Setting DEBUG logging. 2016-04-20 13:46:01,068 attempt 1/10 2016-04-20 13:46:01,068 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/51135b23fb79?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-20 13:46:02,721 unpacking tar archive at: fx-team-51135b23fb79/testing/mozharness/ program finished with exit code 0 elapsedTime=1.893112 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 13:46:02.907174) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 13:46:02.907875) ========= script_repo_revision: 51135b23fb79d814f0ffd9eb18395de80e3a1584 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 13:46:02.908599) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 13:46:02.909091) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 13:46:02.925504) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 44 secs) (at 2016-04-20 13:46:02.926161) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VcJqOuZS3v/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.GOQtzpp5xu/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 13:46:03 INFO - MultiFileLogger online at 20160420 13:46:03 in /builds/slave/test 13:46:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 13:46:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:46:03 INFO - {'append_to_log': False, 13:46:03 INFO - 'base_work_dir': '/builds/slave/test', 13:46:03 INFO - 'blob_upload_branch': 'Fx-Team', 13:46:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:46:03 INFO - 'branch': 'Fx-Team', 13:46:03 INFO - 'buildbot_json_path': 'buildprops.json', 13:46:03 INFO - 'config_files': ('talos/mac_config.py',), 13:46:03 INFO - 'default_actions': ('clobber', 13:46:03 INFO - 'read-buildbot-config', 13:46:03 INFO - 'download-and-extract', 13:46:03 INFO - 'populate-webroot', 13:46:03 INFO - 'create-virtualenv', 13:46:03 INFO - 'install', 13:46:03 INFO - 'run-tests'), 13:46:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:46:03 INFO - 'download_minidump_stackwalk': True, 13:46:03 INFO - 'download_symbols': 'ondemand', 13:46:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:46:03 INFO - 'tooltool.py': '/tools/tooltool.py', 13:46:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:46:03 INFO - '/tools/misc-python/virtualenv.py')}, 13:46:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:46:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:46:03 INFO - 'installer_path': 'installer.exe', 13:46:03 INFO - 'log_level': 'info', 13:46:03 INFO - 'log_name': 'talos', 13:46:03 INFO - 'log_to_console': True, 13:46:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:46:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:46:03 INFO - 'opt_config_files': (), 13:46:03 INFO - 'pip_index': False, 13:46:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:46:03 INFO - 'cmd': ('bash', 13:46:03 INFO - '-c', 13:46:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:46:03 INFO - 'enabled': True, 13:46:03 INFO - 'halt_on_failure': False, 13:46:03 INFO - 'name': 'check_screen_resolution'},), 13:46:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:46:03 INFO - 'cmd': ('bash', 13:46:03 INFO - '-c', 13:46:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:46:03 INFO - 'enabled': True, 13:46:03 INFO - 'halt_on_failure': False, 13:46:03 INFO - 'name': 'check_screen_resolution'},), 13:46:03 INFO - 'run_cmd_checks_enabled': True, 13:46:03 INFO - 'sps_profile': False, 13:46:03 INFO - 'sps_profile_interval': 0, 13:46:03 INFO - 'suite': 'dromaeojs-e10s', 13:46:03 INFO - 'system_bits': '32', 13:46:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:46:03 INFO - 'title': 't-yosemite-r7-0167', 13:46:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:46:03 INFO - 'use_talos_json': True, 13:46:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:46:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:46:03 INFO - 'work_dir': 'build'} 13:46:03 INFO - ##### 13:46:03 INFO - ##### Running clobber step. 13:46:03 INFO - ##### 13:46:03 INFO - Running pre-action listener: _resource_record_pre_action 13:46:03 INFO - Running main action method: clobber 13:46:03 INFO - rmtree: /builds/slave/test/build 13:46:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:46:04 INFO - Running post-action listener: _resource_record_post_action 13:46:04 INFO - ##### 13:46:04 INFO - ##### Running read-buildbot-config step. 13:46:04 INFO - ##### 13:46:04 INFO - Running pre-action listener: _resource_record_pre_action 13:46:04 INFO - Running main action method: read_buildbot_config 13:46:04 INFO - Using buildbot properties: 13:46:04 INFO - { 13:46:04 INFO - "project": "", 13:46:04 INFO - "product": "firefox", 13:46:04 INFO - "script_repo_revision": "production", 13:46:04 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 13:46:04 INFO - "repository": "", 13:46:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 13:46:04 INFO - "buildid": "20160420103742", 13:46:04 INFO - "pgo_build": "False", 13:46:04 INFO - "basedir": "/builds/slave/test", 13:46:04 INFO - "buildnumber": 611, 13:46:04 INFO - "slavename": "t-yosemite-r7-0167", 13:46:04 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 13:46:04 INFO - "platform": "yosemite_r7", 13:46:04 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 13:46:04 INFO - "branch": "fx-team", 13:46:04 INFO - "repo_path": "integration/fx-team", 13:46:04 INFO - "revision": "51135b23fb79d814f0ffd9eb18395de80e3a1584", 13:46:04 INFO - "stage_platform": "macosx64", 13:46:04 INFO - "builduid": "074e92339f3b4ac0b4d77d67493e1eef", 13:46:04 INFO - "slavebuilddir": "test" 13:46:04 INFO - } 13:46:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 13:46:04 INFO - Running post-action listener: _resource_record_post_action 13:46:04 INFO - ##### 13:46:04 INFO - ##### Running download-and-extract step. 13:46:04 INFO - ##### 13:46:04 INFO - Running pre-action listener: _resource_record_pre_action 13:46:04 INFO - Running main action method: download_and_extract 13:46:04 INFO - mkdir: /builds/slave/test/build/tests 13:46: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')]} 13:46:04 INFO - https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:46:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 13:46:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 13:46:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 13:46:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 13:46:06 INFO - Downloaded 1199 bytes. 13:46:06 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 13:46:06 INFO - Using the following test package requirements: 13:46:06 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 13:46:06 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:06 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 13:46:06 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:06 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 13:46:06 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:06 INFO - u'jsshell-mac.zip'], 13:46:06 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:06 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 13:46:06 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 13:46:06 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:06 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 13:46:06 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:06 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 13:46:06 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:06 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 13:46:06 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:06 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 13:46:06 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:46:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:06 INFO - https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:46:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:46:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:46:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:46:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:46:07 INFO - Downloaded 18096157 bytes. 13:46:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:46:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:46:10 INFO - Return code: 0 13:46:10 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:10 INFO - https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:46:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:46:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:46:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:46:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:46:20 INFO - Downloaded 18096157 bytes. 13:46:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:46:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:46:23 INFO - Return code: 0 13:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:23 INFO - https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:46:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 13:46:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 13:46:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 13:46:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:46:31 INFO - Downloaded 10845952 bytes. 13:46:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:46:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:46:31 INFO - Return code: 0 13:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:31 INFO - https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:46:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:46:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:46:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 13:46:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 13:46:34 INFO - Downloaded 110020515 bytes. 13:46:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:46:34 INFO - mkdir: /builds/slave/test/properties 13:46:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:46:34 INFO - Writing to file /builds/slave/test/properties/build_url 13:46:34 INFO - Contents: 13:46:34 INFO - build_url:https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:46:36 INFO - Running post-action listener: _resource_record_post_action 13:46:36 INFO - Running post-action listener: set_extra_try_arguments 13:46:36 INFO - ##### 13:46:36 INFO - ##### Running populate-webroot step. 13:46:36 INFO - ##### 13:46:36 INFO - Running pre-action listener: _resource_record_pre_action 13:46:36 INFO - Running main action method: populate_webroot 13:46:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:46:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:46:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:46:36 INFO - '--tppagecycles', 13:46:36 INFO - '7'], 13:46:36 INFO - 'tests': ['tsvgm']}}, 13:46:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:46:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:46:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:46:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:46:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:46:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:46:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:36 INFO - 'talos_options': ['--disable-e10s'], 13:46:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:46:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:46:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:36 INFO - 'talos_options': ['--disable-e10s'], 13:46:36 INFO - 'tests': ['damp', 'tps']}, 13:46:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:36 INFO - 'tests': ['damp', 'tps']}, 13:46:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:46:36 INFO - 'tests': ['dromaeo_dom']}, 13:46:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:46:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:46:36 INFO - 'tests': ['a11yr', 13:46:36 INFO - 'ts_paint', 13:46:36 INFO - 'tpaint', 13:46:36 INFO - 'sessionrestore', 13:46:36 INFO - 'sessionrestore_no_auto_restore', 13:46:36 INFO - 'tabpaint']}, 13:46:36 INFO - 'other-e10s': {'tests': ['a11yr', 13:46:36 INFO - 'ts_paint', 13:46:36 INFO - 'tpaint', 13:46:36 INFO - 'sessionrestore', 13:46:36 INFO - 'sessionrestore_no_auto_restore', 13:46:36 INFO - 'tabpaint']}, 13:46:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:46:36 INFO - 'ts_paint', 13:46:36 INFO - 'tpaint', 13:46:36 INFO - 'sessionrestore', 13:46:36 INFO - 'sessionrestore_no_auto_restore', 13:46:36 INFO - 'tabpaint']}, 13:46:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:46:36 INFO - 'ts_paint', 13:46:36 INFO - 'tpaint', 13:46:36 INFO - 'sessionrestore', 13:46:36 INFO - 'sessionrestore_no_auto_restore', 13:46:36 INFO - 'tabpaint']}, 13:46:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:46:36 INFO - 'tests': ['a11yr', 13:46:36 INFO - 'ts_paint', 13:46:36 INFO - 'tpaint', 13:46:36 INFO - 'sessionrestore', 13:46:36 INFO - 'sessionrestore_no_auto_restore', 13:46:36 INFO - 'tabpaint']}, 13:46:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:46:36 INFO - 'tests': ['a11yr', 13:46:36 INFO - 'ts_paint', 13:46:36 INFO - 'tpaint', 13:46:36 INFO - 'sessionrestore', 13:46:36 INFO - 'sessionrestore_no_auto_restore', 13:46:36 INFO - 'tabpaint']}, 13:46:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:46:36 INFO - 'tests': ['tsvgx', 13:46:36 INFO - 'tsvgr_opacity', 13:46:36 INFO - 'tart', 13:46:36 INFO - 'tscrollx', 13:46:36 INFO - 'cart']}, 13:46:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:46:36 INFO - 'tsvgr_opacity', 13:46:36 INFO - 'tart', 13:46:36 INFO - 'tscrollx', 13:46:36 INFO - 'cart']}, 13:46:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:36 INFO - 'talos_options': ['--disable-e10s'], 13:46:36 INFO - 'tests': ['tp5o']}, 13:46:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:36 INFO - 'tests': ['tp5o']}, 13:46:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:36 INFO - 'talos_options': ['--disable-e10s', 13:46:36 INFO - '--xperf_path', 13:46:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:46:36 INFO - 'tests': ['tp5n']}, 13:46:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:36 INFO - 'talos_options': ['--xperf_path', 13:46:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:46:36 INFO - 'tests': ['tp5n']}}, 13:46:36 INFO - 'talos.zip': {'path': '', 13:46:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:46:36 INFO - Running post-action listener: _resource_record_post_action 13:46:36 INFO - ##### 13:46:36 INFO - ##### Running create-virtualenv step. 13:46:36 INFO - ##### 13:46:36 INFO - Running pre-action listener: _resource_record_pre_action 13:46:36 INFO - Running main action method: create_virtualenv 13:46:36 INFO - Creating virtualenv /builds/slave/test/build/venv 13:46:36 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:46:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:46:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:46:36 INFO - Using real prefix '/tools/python27' 13:46:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:46:37 INFO - Installing distribute.............................................................................................................................................................................................done. 13:46:40 INFO - Installing pip.................done. 13:46:40 INFO - Return code: 0 13:46:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8a25510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cde00>, '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 0x106836db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069c8b20>, '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.VcJqOuZS3v/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.GOQtzpp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:46:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:46:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VcJqOuZS3v/Render', 13:46:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:40 INFO - 'HOME': '/Users/cltbld', 13:46:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:40 INFO - 'LOGNAME': 'cltbld', 13:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:40 INFO - 'MOZ_NO_REMOTE': '1', 13:46:40 INFO - 'NO_EM_RESTART': '1', 13:46:40 INFO - 'PAGER': '/bin/cat', 13:46:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:40 INFO - 'PWD': '/builds/slave/test', 13:46:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:40 INFO - 'SHELL': '/bin/bash', 13:46:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GOQtzpp5xu/Listeners', 13:46:40 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:46:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:40 INFO - 'USER': 'cltbld', 13:46:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:40 INFO - 'XPC_FLAGS': '0x0', 13:46:40 INFO - 'XPC_SERVICE_NAME': '0', 13:46:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:40 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:46:40 INFO - Cleaning up... 13:46:40 INFO - Return code: 0 13:46:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106b2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8a25510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cde00>, '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 0x106836db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069c8b20>, '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.VcJqOuZS3v/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.GOQtzpp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:46:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:46:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VcJqOuZS3v/Render', 13:46:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:40 INFO - 'HOME': '/Users/cltbld', 13:46:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:40 INFO - 'LOGNAME': 'cltbld', 13:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:40 INFO - 'MOZ_NO_REMOTE': '1', 13:46:40 INFO - 'NO_EM_RESTART': '1', 13:46:40 INFO - 'PAGER': '/bin/cat', 13:46:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:40 INFO - 'PWD': '/builds/slave/test', 13:46:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:40 INFO - 'SHELL': '/bin/bash', 13:46:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GOQtzpp5xu/Listeners', 13:46:40 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:46:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:40 INFO - 'USER': 'cltbld', 13:46:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:40 INFO - 'XPC_FLAGS': '0x0', 13:46:40 INFO - 'XPC_SERVICE_NAME': '0', 13:46:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:40 INFO - Downloading/unpacking psutil>=0.7.1 13:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:46:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:46:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:46:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:46:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:46:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:46:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:46:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:46:43 INFO - Installing collected packages: psutil 13:46:43 INFO - Running setup.py install for psutil 13:46:43 INFO - building 'psutil._psutil_osx' extension 13:46:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:46:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:46:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:46:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:46:44 INFO - building 'psutil._psutil_posix' extension 13:46:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:46:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:46:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:46:44 INFO - ^ 13:46:44 INFO - 1 warning generated. 13:46:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:46:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:46:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:46:44 INFO - Successfully installed psutil 13:46:44 INFO - Cleaning up... 13:46:44 INFO - Return code: 0 13:46:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106b2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8a25510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cde00>, '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 0x106836db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069c8b20>, '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.VcJqOuZS3v/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.GOQtzpp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:46:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:46:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 13:46:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VcJqOuZS3v/Render', 13:46:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:44 INFO - 'HOME': '/Users/cltbld', 13:46:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:44 INFO - 'LOGNAME': 'cltbld', 13:46:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:44 INFO - 'MOZ_NO_REMOTE': '1', 13:46:44 INFO - 'NO_EM_RESTART': '1', 13:46:44 INFO - 'PAGER': '/bin/cat', 13:46:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:44 INFO - 'PWD': '/builds/slave/test', 13:46:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:44 INFO - 'SHELL': '/bin/bash', 13:46:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GOQtzpp5xu/Listeners', 13:46:44 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:46:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:44 INFO - 'USER': 'cltbld', 13:46:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:44 INFO - 'XPC_FLAGS': '0x0', 13:46:44 INFO - 'XPC_SERVICE_NAME': '0', 13:46:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:46:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:46:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:46:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:46:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:46:47 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:46:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:46:47 INFO - Installing collected packages: mozsystemmonitor 13:46:47 INFO - Running setup.py install for mozsystemmonitor 13:46:47 INFO - Successfully installed mozsystemmonitor 13:46:47 INFO - Cleaning up... 13:46:47 INFO - Return code: 0 13:46:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:46:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46: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', '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 0x106b2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8a25510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cde00>, '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 0x106836db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069c8b20>, '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.VcJqOuZS3v/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.GOQtzpp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:46: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:46: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 blobuploader==1.2.4 13:46:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VcJqOuZS3v/Render', 13:46:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:47 INFO - 'HOME': '/Users/cltbld', 13:46:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:47 INFO - 'LOGNAME': 'cltbld', 13:46:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:47 INFO - 'MOZ_NO_REMOTE': '1', 13:46:47 INFO - 'NO_EM_RESTART': '1', 13:46:47 INFO - 'PAGER': '/bin/cat', 13:46:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:47 INFO - 'PWD': '/builds/slave/test', 13:46:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:47 INFO - 'SHELL': '/bin/bash', 13:46:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GOQtzpp5xu/Listeners', 13:46:47 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:46:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:47 INFO - 'USER': 'cltbld', 13:46:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:47 INFO - 'XPC_FLAGS': '0x0', 13:46:47 INFO - 'XPC_SERVICE_NAME': '0', 13:46:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:47 INFO - Downloading/unpacking blobuploader==1.2.4 13:46: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 13:46: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 13:46: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 13:46: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 13:46: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 13:46: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 13:46:50 INFO - Downloading blobuploader-1.2.4.tar.gz 13:46:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:46:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:46:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:46:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:46:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:46:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:46:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:46:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:46:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:46:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:46:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:46:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:46:51 INFO - Downloading docopt-0.6.1.tar.gz 13:46:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:46:51 INFO - Installing collected packages: blobuploader, requests, docopt 13:46:51 INFO - Running setup.py install for blobuploader 13:46:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:46:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:46:51 INFO - Running setup.py install for requests 13:46:51 INFO - Running setup.py install for docopt 13:46:52 INFO - Successfully installed blobuploader requests docopt 13:46:52 INFO - Cleaning up... 13:46:52 INFO - Return code: 0 13:46:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:52 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 0x106b2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8a25510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cde00>, '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 0x106836db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069c8b20>, '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.VcJqOuZS3v/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.GOQtzpp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:46:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:46:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VcJqOuZS3v/Render', 13:46:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:52 INFO - 'HOME': '/Users/cltbld', 13:46:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:52 INFO - 'LOGNAME': 'cltbld', 13:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:52 INFO - 'MOZ_NO_REMOTE': '1', 13:46:52 INFO - 'NO_EM_RESTART': '1', 13:46:52 INFO - 'PAGER': '/bin/cat', 13:46:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:52 INFO - 'PWD': '/builds/slave/test', 13:46:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:52 INFO - 'SHELL': '/bin/bash', 13:46:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GOQtzpp5xu/Listeners', 13:46:52 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:46:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:52 INFO - 'USER': 'cltbld', 13:46:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:52 INFO - 'XPC_FLAGS': '0x0', 13:46:52 INFO - 'XPC_SERVICE_NAME': '0', 13:46:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:46:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-6ao9UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:46:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-uIyuwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:46:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-DhDcCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:46:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-n3zSqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:46:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-bMbwxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:46:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-9B0BrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:46:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-kqWNbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:46:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-3c8zh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:46:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-sSaIl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:46:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-QmH7v3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:46:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-BFFU27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:46:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-lkrkBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:46:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-2at9IF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:46:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-0TdYY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:46:53 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-c97BDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:46:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:46:54 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-MoGmO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:46:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:46:54 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-68dgX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:46:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:46:54 INFO - Running setup.py install for manifestparser 13:46:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:46:54 INFO - Running setup.py install for mozcrash 13:46:54 INFO - Running setup.py install for mozdebug 13:46:54 INFO - Running setup.py install for mozdevice 13:46:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:46:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:46:54 INFO - Running setup.py install for mozfile 13:46:55 INFO - Running setup.py install for mozhttpd 13:46:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:46:55 INFO - Running setup.py install for mozinfo 13:46:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:46:55 INFO - Running setup.py install for mozInstall 13:46:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:46:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:46:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:46:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:46:55 INFO - Running setup.py install for mozleak 13:46:55 INFO - Running setup.py install for mozlog 13:46:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:46:55 INFO - Running setup.py install for moznetwork 13:46:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:46:55 INFO - Running setup.py install for mozprocess 13:46:56 INFO - Running setup.py install for mozprofile 13:46:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:46:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:46:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:46:56 INFO - Running setup.py install for mozrunner 13:46:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:46:56 INFO - Running setup.py install for mozscreenshot 13:46:56 INFO - Running setup.py install for moztest 13:46:56 INFO - Running setup.py install for mozversion 13:46:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:46:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:46:56 INFO - Cleaning up... 13:46:56 INFO - Return code: 0 13:46:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:56 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 0x106b2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8a25510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cde00>, '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 0x106836db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069c8b20>, '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.VcJqOuZS3v/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.GOQtzpp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:46:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:46:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VcJqOuZS3v/Render', 13:46:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:56 INFO - 'HOME': '/Users/cltbld', 13:46:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:56 INFO - 'LOGNAME': 'cltbld', 13:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:56 INFO - 'MOZ_NO_REMOTE': '1', 13:46:56 INFO - 'NO_EM_RESTART': '1', 13:46:56 INFO - 'PAGER': '/bin/cat', 13:46:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:56 INFO - 'PWD': '/builds/slave/test', 13:46:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:56 INFO - 'SHELL': '/bin/bash', 13:46:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GOQtzpp5xu/Listeners', 13:46:56 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:46:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:56 INFO - 'USER': 'cltbld', 13:46:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:56 INFO - 'XPC_FLAGS': '0x0', 13:46:56 INFO - 'XPC_SERVICE_NAME': '0', 13:46:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:46:57 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-94UXye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:46:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:46:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:46:57 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-01Nqtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:46:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:46:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:46:57 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-ycpSzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:46:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:46:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:46:57 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-BApjP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:46:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:46:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:46:57 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-MDnEan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:46:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:46:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:46:57 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-YIYDMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:46:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:46:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:46:57 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-_rtgLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:46:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:46:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:46:57 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-NLtjSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:46:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:46:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:46:57 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-yGvJq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:46:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-lwpliv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:46:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-YsYxcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:46:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-3K8qj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:46:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Wffd9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:46:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-v_1yzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:46:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-WzH_yI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:46:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-rHlAUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:46:58 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-z8C2iw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:46:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:46:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:46:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:01 INFO - Downloading blessings-1.6.tar.gz 13:47:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:47:01 INFO - Installing collected packages: blessings 13:47:01 INFO - Running setup.py install for blessings 13:47:01 INFO - Successfully installed blessings 13:47:01 INFO - Cleaning up... 13:47:01 INFO - Return code: 0 13:47:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:47:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:47:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:47:01 INFO - Reading from file tmpfile_stdout 13:47:01 INFO - Current package versions: 13:47:01 INFO - blessings == 1.6 13:47:01 INFO - blobuploader == 1.2.4 13:47:01 INFO - docopt == 0.6.1 13:47:01 INFO - manifestparser == 1.1 13:47:01 INFO - mozInstall == 1.12 13:47:01 INFO - mozcrash == 0.17 13:47:01 INFO - mozdebug == 0.1 13:47:01 INFO - mozdevice == 0.48 13:47:01 INFO - mozfile == 1.2 13:47:01 INFO - mozhttpd == 0.7 13:47:01 INFO - mozinfo == 0.9 13:47:01 INFO - mozleak == 0.1 13:47:01 INFO - mozlog == 3.1 13:47:01 INFO - moznetwork == 0.27 13:47:01 INFO - mozprocess == 0.22 13:47:01 INFO - mozprofile == 0.28 13:47:01 INFO - mozrunner == 6.11 13:47:01 INFO - mozscreenshot == 0.1 13:47:01 INFO - mozsystemmonitor == 0.0 13:47:01 INFO - moztest == 0.7 13:47:01 INFO - mozversion == 1.4 13:47:01 INFO - psutil == 3.1.1 13:47:01 INFO - requests == 1.2.3 13:47:01 INFO - wsgiref == 0.1.2 13:47:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:02 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 0x106b2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8a25510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cde00>, '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 0x106836db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069c8b20>, '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.VcJqOuZS3v/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.GOQtzpp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:47:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:47:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:47:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VcJqOuZS3v/Render', 13:47:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:02 INFO - 'HOME': '/Users/cltbld', 13:47:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:02 INFO - 'LOGNAME': 'cltbld', 13:47:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:02 INFO - 'MOZ_NO_REMOTE': '1', 13:47:02 INFO - 'NO_EM_RESTART': '1', 13:47:02 INFO - 'PAGER': '/bin/cat', 13:47:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:02 INFO - 'PWD': '/builds/slave/test', 13:47:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:02 INFO - 'SHELL': '/bin/bash', 13:47:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GOQtzpp5xu/Listeners', 13:47:02 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:47:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:02 INFO - 'USER': 'cltbld', 13:47:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:02 INFO - 'XPC_FLAGS': '0x0', 13:47:02 INFO - 'XPC_SERVICE_NAME': '0', 13:47:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:47:02 INFO - Cleaning up... 13:47:02 INFO - Return code: 0 13:47:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8a25510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cde00>, '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 0x106836db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069c8b20>, '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.VcJqOuZS3v/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.GOQtzpp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:47:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:47:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:47:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VcJqOuZS3v/Render', 13:47:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:02 INFO - 'HOME': '/Users/cltbld', 13:47:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:02 INFO - 'LOGNAME': 'cltbld', 13:47:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:02 INFO - 'MOZ_NO_REMOTE': '1', 13:47:02 INFO - 'NO_EM_RESTART': '1', 13:47:02 INFO - 'PAGER': '/bin/cat', 13:47:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:02 INFO - 'PWD': '/builds/slave/test', 13:47:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:02 INFO - 'SHELL': '/bin/bash', 13:47:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GOQtzpp5xu/Listeners', 13:47:02 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:47:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:02 INFO - 'USER': 'cltbld', 13:47:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:02 INFO - 'XPC_FLAGS': '0x0', 13:47:02 INFO - 'XPC_SERVICE_NAME': '0', 13:47:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:02 INFO - Downloading/unpacking jsonschema 13:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:47:05 INFO - Downloading/unpacking functools32 (from jsonschema) 13:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:05 INFO - Downloading functools32-3.2.3-2.tar.gz 13:47:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:47:05 INFO - warning: no files found matching '*.txt' 13:47:05 INFO - no previously-included directories found matching 'build' 13:47:05 INFO - no previously-included directories found matching 'dist' 13:47:05 INFO - no previously-included directories found matching '.git*' 13:47:05 INFO - Installing collected packages: jsonschema, functools32 13:47:05 INFO - Running setup.py install for functools32 13:47:05 INFO - warning: no files found matching '*.txt' 13:47:05 INFO - no previously-included directories found matching 'build' 13:47:05 INFO - no previously-included directories found matching 'dist' 13:47:05 INFO - no previously-included directories found matching '.git*' 13:47:05 INFO - Successfully installed jsonschema functools32 13:47:05 INFO - Cleaning up... 13:47:06 INFO - Return code: 0 13:47:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:06 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 0x106b2ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8a25510c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cde00>, '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 0x106836db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069c8b20>, '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.VcJqOuZS3v/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.GOQtzpp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:47:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:47:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:47:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VcJqOuZS3v/Render', 13:47:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:06 INFO - 'HOME': '/Users/cltbld', 13:47:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:06 INFO - 'LOGNAME': 'cltbld', 13:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:06 INFO - 'MOZ_NO_REMOTE': '1', 13:47:06 INFO - 'NO_EM_RESTART': '1', 13:47:06 INFO - 'PAGER': '/bin/cat', 13:47:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:06 INFO - 'PWD': '/builds/slave/test', 13:47:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:06 INFO - 'SHELL': '/bin/bash', 13:47:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GOQtzpp5xu/Listeners', 13:47:06 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:47:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:06 INFO - 'USER': 'cltbld', 13:47:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:06 INFO - 'XPC_FLAGS': '0x0', 13:47:06 INFO - 'XPC_SERVICE_NAME': '0', 13:47:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:06 INFO - Downloading/unpacking flake8 13:47: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 13:47: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 13:47: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 13:47: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 13:47: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 13:47: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 13:47:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:47:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:47:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:47:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:47:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:47:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:47:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:47:10 INFO - no previously-included directories found matching 'docs/_build' 13:47:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:47:10 INFO - Running setup.py install for pep8 13:47:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:47:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:47:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:47:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:47:10 INFO - no previously-included directories found matching 'docs/_build' 13:47:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:47:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:47:10 INFO - Cleaning up... 13:47:10 INFO - Return code: 0 13:47:10 INFO - Running post-action listener: _resource_record_post_action 13:47:10 INFO - Running post-action listener: _start_resource_monitoring 13:47:10 INFO - Starting resource monitoring. 13:47:10 INFO - ##### 13:47:10 INFO - ##### Running install step. 13:47:10 INFO - ##### 13:47:10 INFO - Running pre-action listener: _resource_record_pre_action 13:47:10 INFO - Running main action method: install 13:47:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:47:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:47:10 INFO - Reading from file tmpfile_stdout 13:47:10 INFO - Detecting whether we're running mozinstall >=1.0... 13:47:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:47:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:47:11 INFO - Reading from file tmpfile_stdout 13:47:11 INFO - Output received: 13:47:11 INFO - Usage: mozinstall [options] installer 13:47:11 INFO - Options: 13:47:11 INFO - -h, --help show this help message and exit 13:47:11 INFO - -d DEST, --destination=DEST 13:47:11 INFO - Directory to install application into. [default: 13:47:11 INFO - "/builds/slave/test"] 13:47:11 INFO - --app=APP Application being installed. [default: firefox] 13:47:11 INFO - mkdir: /builds/slave/test/build/application 13:47:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:47:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:47:45 INFO - Reading from file tmpfile_stdout 13:47:45 INFO - Output received: 13:47:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:47:45 INFO - Running post-action listener: _resource_record_post_action 13:47:45 INFO - ##### 13:47:45 INFO - ##### Running run-tests step. 13:47:45 INFO - ##### 13:47:45 INFO - Running pre-action listener: _resource_record_pre_action 13:47:45 INFO - Running main action method: run_tests 13:47:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:47:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:47:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:47:45 INFO - 2016-04-20 13:47:45.684 screenresolution[1636:7477] starting screenresolution argv=screenresolution get 13:47:45 INFO - 2016-04-20 13:47:45.687 screenresolution[1636:7477] Display 0: 1600x1200x32@60 13:47:45 INFO - 2016-04-20 13:47:45.693 screenresolution[1637:7479] starting screenresolution argv=screenresolution list 13:47:45 INFO - Available Modes on Display 0 13:47:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:47:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:47:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:47:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:47:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:47:45 INFO - Intel Iris: 13:47:45 INFO - Chipset Model: Intel Iris 13:47:45 INFO - Type: GPU 13:47:45 INFO - Bus: Built-In 13:47:45 INFO - VRAM (Dynamic, Max): 1536 MB 13:47:45 INFO - Vendor: Intel (0x8086) 13:47:45 INFO - Device ID: 0x0a2e 13:47:45 INFO - Revision ID: 0x0009 13:47:45 INFO - Displays: 13:47:45 INFO - Display: 13:47:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:47:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:47:45 INFO - Display Serial Number: 1600x1200 60 13:47:45 INFO - Main Display: Yes 13:47:45 INFO - Mirror: Off 13:47:45 INFO - Online: Yes 13:47:45 INFO - Rotation: Supported 13:47:45 INFO - Return code: 0 13:47:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:47:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:47:45 INFO - Python 2.7.3 13:47:45 INFO - Return code: 0 13:47:45 INFO - grabbing minidump binary from tooltool 13:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:45 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 0x1069cde00>, '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 0x106836db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069c8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:47:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:47:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 13:47:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:47:46 INFO - Return code: 0 13:47:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:47:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:47:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:47:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:47:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:47:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:47:47 INFO - Return code: 0 13:47:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 13:47:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0167 --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 13:47:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VcJqOuZS3v/Render', 13:47:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:47 INFO - 'HOME': '/Users/cltbld', 13:47:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:47 INFO - 'LOGNAME': 'cltbld', 13:47:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:47:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:47 INFO - 'MOZ_NO_REMOTE': '1', 13:47:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:47:47 INFO - 'NO_EM_RESTART': '1', 13:47:47 INFO - 'PAGER': '/bin/cat', 13:47:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:47 INFO - 'PWD': '/builds/slave/test', 13:47:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:47:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:47 INFO - 'SHELL': '/bin/bash', 13:47:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GOQtzpp5xu/Listeners', 13:47:47 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 13:47:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:47 INFO - 'USER': 'cltbld', 13:47:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:47 INFO - 'XPC_FLAGS': '0x0', 13:47:47 INFO - 'XPC_SERVICE_NAME': '0', 13:47:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 13:47:47 INFO - mozversion application_buildid: 20160420103742 13:47:47 INFO - mozversion application_changeset: 51135b23fb79d814f0ffd9eb18395de80e3a1584 13:47:47 INFO - mozversion application_display_name: Nightly 13:47:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:47:47 INFO - mozversion application_name: Firefox 13:47:47 INFO - mozversion application_remotingname: firefox 13:47:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:47:47 INFO - mozversion application_vendor: Mozilla 13:47:47 INFO - mozversion application_version: 48.0a1 13:47:47 INFO - mozversion platform_buildid: 20160420103742 13:47:47 INFO - mozversion platform_changeset: 51135b23fb79d814f0ffd9eb18395de80e3a1584 13:47:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:47:47 INFO - mozversion platform_version: 48.0a1 13:47:47 INFO - using testdate: 1461185267 13:47:47 INFO - actual date: 1461185267 13:47:47 INFO - starting webserver on 'localhost:49271' 13:47:47 INFO - SUITE-START | Running 2 tests 13:47:47 INFO - TEST-START | dromaeo_css 13:47:47 INFO - operating with platform_type : mac_ 13:47:47 INFO - Initialising browser for dromaeo_css test... 13:47:47 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp3OzfRj/profile http://localhost:49271/getInfo.html) 13:47:50 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 13:47:50 INFO - PROCESS | 1655 | colorDepth:24 13:47:50 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 13:47:50 INFO - PROCESS | 1655 | __metrics 13:47:50 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 13:47:51 INFO - TEST-INFO | 1655: exit 0 13:47:51 INFO - Browser initialized. 13:47:51 INFO - Running cycle 1/1 for dromaeo_css test... 13:47:51 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp3OzfRj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:48:03 INFO - PROCESS | 1658 | RSS: Main: 248020992 13:48:03 INFO - PROCESS | 1658 | 13:48:53 INFO - PROCESS | 1658 | 1461185333164 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:51:04 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 13:51:06 INFO - PROCESS | 1658 | RSS: Main: 259854336 13:51:06 INFO - PROCESS | 1658 | 13:54:09 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 13:54:09 INFO - PROCESS | 1658 | RSS: Main: 271908864 13:54:09 INFO - PROCESS | 1658 | 13:57:11 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 13:57:12 INFO - PROCESS | 1658 | RSS: Main: 273760256 13:57:12 INFO - PROCESS | 1658 | 14:00:14 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 14:00:14 INFO - PROCESS | 1658 | RSS: Main: 275554304 14:00:14 INFO - PROCESS | 1658 | 14:03:17 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 14:03:18 INFO - PROCESS | 1658 | RSS: Main: 278360064 14:03:18 INFO - PROCESS | 1658 | 14:06:20 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 14:06:20 INFO - PROCESS | 1658 | RSS: Main: 279285760 14:06:20 INFO - PROCESS | 1658 | 14:06:20 INFO - PROCESS | 1658 | __start_tp_report 14:06:20 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 14:06:20 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 14:06:20 INFO - PROCESS | 1658 | |i|pagename|runs| 14:06:20 INFO - PROCESS | 1658 | |0;dojo.html;3424.7260219182467;3483.756137070405;3507.1582820123176;3507.1758136837852;3550.9112272193206;5749.856253593657;5762.4525670061275;5801.303843538771;5814.592978491502;5833.037548804447;5683.943160568393;5808.854778630536;5831.358550559435;5838.883222335551;5889.587728858982;5231;5271.841844744645;5295.920561191566;5328.8401347959625;5367.812126575571;4059.6752259819145;4343.847965321214;4388.67084968627;4507.346434766957;5389.461053894618;5237.083510385667;5280.128778751501;5282.260483532308;5299.999999999981;5315.681059136464;5293.073712100367;5293.920591191135;5295.179247216687;5301.840944771663;5304.204369344629;4256.297710877701;4796.952030479685;4860.562549370538;5293.708846013467;5356.758945847446;5390.838274851761;5631.521320687746;5683.090705487143;5722.82831515051;5724.5992780505385;6172.22847144107;6219.315875253718;6234.470070044052;6270.6864656766975;6296.275928268216;6111.804454079419;6206.162168107293;6251.3123556408755;6279.591826531261;6289.654069026202;5942.435468630473;5990.64056156632;6011.909821352683;6024.337322894478;6060.757569697163;6281.7271514742;6305.211848518935;6333.081703153017;6339.302676705559;6348.873022539523;5827.921834460638;6051.273847138284;6220.7511699532415;6226.59527130735;6289.993601023814;5931.703414829241;6188;6234.438900498977;6247.5939063960695;6259.217597800275;6139.201903752483;6144.402455361549;6219.471344935731;6226.283977342573;6280.089387038854;5112.463191364853;5244.29202057725;6137.232845894263;6153.077038444269;6261.8747625047245;4961;4984.401871775338;4985.750712464363;4986.177280748636;5043.899122017539;4451.821927122879;4482.663800214997;4493.191225579427;4499.122671079108;4510.278355463176;5738.167965644958;5858.765649373977;5888.263967004124;5921.40785921413;5942.524598032147;6221.906671399933;6231.626102433778;6236.03341482071;6254.249490061222;6326.49388048955;5850.063989761617;5934.406559344117;5941.37615550361;5964.373740757158;6009.218801555857;5327.547158491571;5440.619156659075;5456.127019676833;5505.614606977553;5547.8890422192135;6354.110424540568;6403.711832967528;6419.101325814389;6424.903626445701;6449.6130232186215;6251.531135164972;6256.186695729526;6267.435930766253;6269.749210031548;6276.6547839869245;6228.314885362697;6327.3988971047675;6353.523485738496;6407.2631647359285;6412.775552855627;6139.84650383744;6172.598781079395;6248.218972628421;6258.530610204162;6259.6870156492905;6171.166892469639;6242.438180563771;6246.3753624637175;6254.155688981929;6257.7184026718;5821.417858214145;6034.517238620988;6136.886723215932;6193.4425901669065;6241.407066328691;6107.358727333743;6111.602745821508;6121.0206366981065;6121.234845644294;6124.755009799736;5074.746262686925;5128.384593848762;5482.985647655197;5798.33319168301;5920.200772895604;6034.487068599218;6105.175801266772;6105.419985101408;6123.326434092157;6130.295016073031;6066.393360663898;6091.299500557493;6107.877842443215;6108.602940808833;6112.17485639433 14:06:20 INFO - PROCESS | 1658 | |1;ext.html;498.2526210683974;510.8620672418447;533.9546138578223;581.1341101758379;617.4751461257929;29500.704992950064;36978.445323320135;37428.812855935685;37662.435063474004;37739.622603773954;38200.04499887504;38772;38891.805540972324;39024.41463378045;39378;9317.487538185414;9543.952280238589;9555.856662150038;9572;9585.2811039172;9435.858462123073;9569.90430095697;9571.904280957171;9588.328816982814;9597.520123993772;9536.284778641595;9536.332456728032;9584.37701549397;9621.989691082441;9630.9518452408;9527;9540.33217674756;9580.329376943617;9627.614895404175;9639.566219520138;9550.522473876279;9675.2259819215;9683.564239609164;9689.660861869872;9701.466419346933;37496.87509374723;37925.43111853324;37937;38026;38286.42570361447;3093.489574220251;3190.8564114614896;3249.2851572653976;3332.500124981248;3414.58294807657;28574.142775716762;28584.856605735746;28658.71035803373;28885;29126.27184320388;2251.4146321956246;2294.2199652118193;2668.8799004044863;2715.945681086387;2755.476459472694;400795;401396.9999999971;402061.9896900526;403482;405381;35502.28995420077;62217.688911556055;62613;62633;63030.684846575474;28382.85808570944;34727;35221.29557408838;35363.115922101635;35514.8224258877;15126.848731512764;15189.696206075816;15333.463328783439;15362.231888405759;15388.461403850812;12371.75256494865;12764.93617531925;12879.29163895976;12895.355232238351;12903.612891613267;8058.556779377073;8088.514689118671;8138.145494723087;8201.466904651179;8251.803488494255;3338.382399256145;3364.7644664873715;3379.6282408935;3395.2021275000357;3430.365382404263;918.8024574716468;1001.1990407673832;1035.5857656937135;1062.511244827388;1101.4217535793612;30204;30344.54483182754;30347.737610725082;30625.846870765505;31505.52741708876;1081.167501024207;1085.2999815119204;1111.4498323330004;1119.5409881948362;1164.6971787335185;15540.989835660646;15698.607534811492;15747.291371888063;15980.920095399448;16056.841178187737;633097;637731;645355;645961;649519;60207.39792602018;61287.69356153191;61435.38564614386;61453.38546614477;61537.38462615406;1425.615083927334;1466.3767898643034;1474.6018574984694;1480.696457226266;1483.0360265827082;29128.563071554367;29364.11907642774;29371.559426609045;30155.698443015288;30209.69790302069;53430.73284633552;54556.45443545514;54598;54693.72653136709;54857;578583;582855;583291;584106;584282;26642.600360994988;27042.05352812642;27229;27474.17577472679;27551.862240688668;39787.60212397839;46096.539034609224;46935.53064469311;48048.759756201;48127.75936120297;38715.612843871204;39218;39828.60171398249;39875;40072.39891401573;30961.761529538566;31869.8406507966;32883.35583220877;36523.63476365202;38921.805390974;32194.67805321917;37076.81461592675;37579.248415032096;38337.80831095938;38388.232235355696;29071.83525860381;31576.526352104276;31999.520007199444;32011.03966880997;32141;24133.517329652957;24312.756872431048;24326.63510047315;24341;24676.012959482137 14:06:20 INFO - PROCESS | 1658 | |2;jquery.html;393.33722677288773;393.8090026337225;399.09604745252;400.8957671005541;407.19172442701233;15809.446669366574;18269.995150266746;20267.189312427487;20334.89832550837;20740.896295518538;20071.799282007174;20233.39299821004;20739.17043318269;21579.784202157975;21711.782882171174;5905.202797622331;6011.398860113975;6036.2152920120325;6056.4851987581105;6062.878742425149;5057.468965758598;5085.262636917645;5088.745562721849;5325.973370133144;5746.138079288119;5664.660120392769;5711.628744131639;5738.856528586777;5743.282089738783;5751.856203594902;5452.727363631822;5815.651060936358;5837.09525023622;5843.737031833535;5858.179854820329;5557.083081291582;5651.604387692863;5721.7711291548285;5841.065429531254;5843.5325173985975;13148.079634425594;13296.933515332361;13337.399817008258;22251.666225006637;26111.347216319555;2012.9295474658463;2169.39257008038;2186.8797216153107;2199.890005499735;2334.6498025296173;9445.197158241557;9792.069753373418;9948.204143668489;10056.849147262797;10099.444530550816;1688.1812321024292;1696.2621259751925;1729.4033558422325;1729.7664815250032;1747.685416625007;97496;119977.8002219984;123091;126521.7760845542;128354;11265.943670281596;11682.532698691957;11691.824622630667;11712.716020376116;11893.405329733652;12389.442475088643;13560.93219533896;14188.574342769527;14830;14835.406583736745;8484.872726909101;8537.701180458655;8611.6985905493;8675.132486751401;8716.38985271025;7038.718451261994;8525.95737021311;8567.057623661392;8570.971483421905;8621.180987806149;4546.227141385973;4607.285870690049;4649.814297354153;4687.929681054787;4710.8351207707565;2002.449326435242;2006.4983754061484;2016.5563576013253;2018.7577490700917;2026.5642886779412;879.5821984557284;880.823835232956;935.7941252924346;948.5636607160783;993.9701808945744;14671.00639495169;20208.79791202069;20385.694214586794;20462.283820066226;20548.589028219652;959.6976952260015;965.4545181972163;981.4503877828439;983.5918093991056;1017.4454922067491;10556;10678;10743.677689669323;10769.138468922467;10776.976187262198;528813;598022.0098899564;605525;613200;620893;78313;137182;145372;148725;152636;1004.9899501005043;1024.8975102489692;1029.3823705776474;1055.794120146564;1067.1836045425257;17925.641487169923;17981.910090449463;18204.81795182031;18608.5347866299;19132.80867191366;50638.49361506485;50856.491435085176;51914.70213244699;53001.46998529966;53797.731011344695;64384.35615643783;66741.33258667546;67454;70182.64908675425;71610.64194678994;7942.12636609984;8045.356371490384;8155.021397432346;8197.426180167331;8476.576171191538;10967.616133435291;12533.811992819932;12751.74496510046;12882.227066376048;13033.934830325788;10839;11032.338059716443;11106.944465277622;11262.042726368023;11387.886121138683;9904.653337133166;10996.450177491253;11096.778064438828;11167.050800682033;11334;9090.500022498825;11015.449227538751;11430.085593152695;11490.310581365145;11641.767164656829;6988.301169882971;8173.67305307781;8194.83610327802;8494.065652778067;8565.871511927202;10166.390016599027;10793.892061079288;10890.267909783825;11268.323900565665;11535.250208736405 14:06:20 INFO - PROCESS | 1658 | |3;mootools.html;1599.9360025598976;1624.9918750406248;1874.144596849119;1937.205745644286;1943.018775518363;7058.329458701428;7925.167857374979;8475.449095808768;8749.956250218747;8756.518391488466;7188.712451501934;8603.612837422332;8672.089430609789;8707;8724.345674074453;2434.8417352872093;2434.8539087654754;2446.90212391505;2457.6682147910074;2469.1975108089855;2429.0283886445386;2489.2781093482868;2493.4265119022634;2505.1482495951373;2516.6476693262957;2470.1231062972656;2480.851148931061;2501.6372625969225;2514.585093459586;2515.5094756522485;2069.513664288906;2088.3943656339643;2127.606392817326;2454.472288457981;2491.6137998610243;2424.248482970285;2441.645961335614;2463.261021693485;2464.728879823218;2467.9259622211184;8709.95645021777;8738.21356077956;8780.912190878073;8854.911450885473;8861.025287218401;4739.218029025241;5274.340707411574;5416.21464887593;5462.071447853875;5477.3427188737205;3260.7065364117107;3321.534985102088;3556;3564.1267889367014;3604.098975256186;1017.6183931025865;1031.8091153136693;1037.2169012395668;1041.1306559023224;1049.0034467256137;11351.20541562091;11452.312861228353;11484.425778711031;11509.366984815832;11524.962753352154;4697.647676424282;4739.592012782663;4839.830605928775;4938.555530002245;4963.776630051656;9850.408975461496;9877.604895804086;9894.653687120915;9911.950440247896;9963.80072398548;4896.975515122402;5217.930324283513;5390.946090539122;5411.350637923396;5415.160650099162;4080.449139366207;4405.933910991338;4907.411110666742;4915.262710593368;4990.426100998432;3958.6041395860184;4014.277430062617;4044.919101617951;4092.324766413568;4142.751434913915;1345.159275452842;1347.1311004402105;1360.081944687332;1362.1758835904136;1372.3069849725825;809.2959125560682;848.719922425598;878.4290211362537;895.2211575928853;903.9231665308391;4957;5572.609917305831;5607.495325420732;5735.283089613798;5780.450857168562;435.1145419072173;449.6470270837442;452.20863488894435;462.03895896535124;466.002754106213;3769.2838360711376;3926.980365098156;3956.9208615827524;4004.5394779600138;4035.21313343901;10337.793244135075;10405.06354428105;10621.575136994434;10662.147028237723;10668.786624267472;10101.797964040677;10527.473626318653;10538.947305263424;10572.361284000992;10587;7955.4033447491665;7999.880001799861;8091.0695270043525;8104.554249516334;8162.632681529345;5698.116791897262;5717.799877004285;5746.913796293143;5930.199423077829;6018.007028840192;9400.95299523498;9813.509324533887;9832.950835245778;9891.703248902544;9963.950180249052;10117.747056323651;10225.948870255601;10235.027672371112;10306.690799276033;10429.895701043197;6887.242403335729;7184.9640751798;7537.773866784005;7551.395888329031;7658.73194438192;8129.552874591955;8179.509229446252;8199.467034642728;8271.421000530147;8275.917240827515;6369.267534233622;7240.348368646636;7357.926420735938;7428.888566671612;7470.962645186739;7118.857622847618;7176.138863336433;7229.09636295463;7310.59791711461;7351.963240183979;6091.786787462417;6834.624095674587;6987.1266091738535;7022.508424410242;7042.612656303748;4648.93026604608;4698.365720627671;4741.525847415231;4794.976025119852;4935.580475659609;4800.231962885921;4824.372831532019;4882.6826256293225;4884.120858245407;4940.777665005012 14:06:20 INFO - PROCESS | 1658 | |4;prototype.html;266.6440302196566;319.5159333609581;327.957096433342;330.0840168532324;331.3670888602769;6019.458248757611;6163.784267550636;6206;6241.625502469855;6242.87628226919;5146.948530514694;5386.865328366783;6231.6572588507615;6263.3423490533405;6296.905546416796;4117.773522456264;4726.361941137945;4737.78679959401;4746.365766999314;4750.334953106568;3885.378339465686;4043.8382464701376;4191.392248124021;4360.433143691315;4717.811287548511;3718.8326525306425;4555.316702494636;4610.700304480215;4619.561141691551;4637.9536204638025;3611.7291203159866;3993.4209539616595;4528.728276303433;4601.847734301997;4647.814087436498;3313.4698448248164;3858.5176852893387;3876.3022655921923;3890.5525864525657;3916.8824935251987;5938;5960.254968128984;5961.880762384728;5993.280806303228;5994.700264986777;5161.696514230895;5271.789128434858;5316.441773613792;5328.387235467932;5344.43883392246;5727.799527016576;5759.481646651821;6555.508336874754;6582.3417658234275;6628.6685665717005;4325.805338759764;4334.696571240014;4338.631216346615;4362.2366085935155;4363.836218741419;6246.531510136712;6286.025666021774;6523.478121750249;6888.038143598251;7005;4025.315696331572;4045.939310910339;4089.0595163112653;4355.281378572563;4368.235558777201;4295.076558539929;4454.153710794939;4455.353973673799;4470.284754439274;4487.506374298824;5037.539113656999;5316.415194328621;5333.786648534093;5347.689815995106;5354.196870469461;5108.335916330928;5762.567807414461;6246;6494.778394043815;6608.074869518271;1403.417581703602;1445.4507287230786;1476.5717941796972;1513.9772903406458;1533.8006059212455;978.8042391521724;990.8951519056201;997.2420960070399;1001.7245257554085;1016.4155610523978;5479.698616576047;6158.476529494954;6184.567080304426;6212.4408803207925;6242.531810114168;5463.863403414946;6892.517523773287;6926.445884329342;6953.513254072268;6970.686319115652;6012.625013119793;6200.658963757037;6205.875882482325;6212.689365531705;6222.377762223832;2887.8411687357398;3296.45608474599;3592.568891733009;3620.927581448409;3659.6157403472785;6636.270010298863;6867.313268673092;7139.929420468006;7205;7205.171405288354;7084.858302833914;7091;7110.786676399716;7115.5730656160795;7119.857602847914;5519.69641669712;7180.928190718026;7193.784186474519;7199.748008819771;7254.45591580623;6913.481488888253;6932.376086152271;6936.0636314096955;6954.191910103511;6955.408790252885;6591.736330546724;6840.486963477859;6871.278515755774;6873.759418420332;6881.965590172017;6666.966575194612;6725.529212955046;6886.24251332343;7188.938249888796;7234.457415693949;3674.5774235962135;3875.8837234883;4451.53258907823;4491.416115904912;4506.169755707622;5095.414027386898;5813.79651712188;6579.868402631825;6657.667116644052;6969.895451568332;5548.9722551388595;6480.546361754632;6585.407313341822;6731.461483081243;6731.596104233761;5561.332640083216;5926.392387349552;6660.83347916306;6711.362420570038;6722.226943901318;5283.630145889904;6523.9347606525225;6548.279689234276;6569.277379488348;6584.407403333531;5616.326040875121;6514.7621951829;6713.194416669836;6738.056672065817;6743.1908171019795;6406.615603063831;6418.930572460597;6588.901166482602;6614.735410583523;6641.15195906981 14:06:20 INFO - PROCESS | 1658 | |5;yui.html;657.3360905485458;673.5386260411618;681.1519658025755;761.6991288441066;786.0802860653032;14622.48821291255;15040.924795376022;15155.62110947227;15217.086974781521;15235.390584376639;13443.932780336085;13903.930480347584;14108.929455352734;14159.362828672709;14223.359948802328;708.1997343002425;881.7971866470699;890.3278025091047;897.6768363389175;898.997617656315;863.7106569299292;880.5773228850156;890.2166093837415;897.824924139793;900.5362238447204;932.2681694869528;932.4824722279133;956.1442508954482;956.3449037409362;960.8270511307961;923.7690577355661;926.7127190570911;927.3786563002805;952.1335584617999;956.550421301987;679.7790718016615;693.0609024771412;696.9024336592881;697.3305626598471;699.6291965258421;14912.776308355384;14950.177740224284;14972.775408368883;15070.246487675682;15090.17004064776;12522.749545009048;12605.495780168874;12614.243145411212;12641.557545485955;12858.807117893239;9883.851742223873;9942.254330925209;10016.348937319051;10161.89838101617;10357.741056473573;2905.6658484274367;3393.7624366294367;3465.5148279240925;3526.0655926179584;3612.205314830733;82822;83963;84740.15259847384;84920;84928.87677808042;9333.439357493611;9356.625734970661;9479.620815167455;9504.144626983467;9583.185429238454;18044.736868419346;21184;21711.240106596193;21795.564088718453;21854.344369668936;6317.96841015792;7500.662470188855;7515;7600.239976002355;7669.578173200416;3999.5800440953863;4002.8999275017777;4086.4483294755423;4087.795610219477;4100.2414553307735;3500.5974312953826;3548.4854696068924;3592;3595.8381872815785;3623.5651721793024;650.9869802603921;653.4870126950321;654.5810681164058;657.5955787190843;666.9566478178903;528.8069854503078;538.9461053894579;541.1909195752297;577.7746678795189;582.9854253643608;7607.505512141693;7989.920100799034;8220.342372610177;8339.374546909006;8344.791380215422;518.3598256153647;534.3267482971484;563.7209581257304;578.9797357092566;595.583091835708;3671.1923376857585;3757.229767897574;3773.3585290500682;3889.572147063821;3925.725199236083;89529;90283;91380;92296.53851730698;93464;75282.62358688172;77251.61374193206;77863;78173;78551;62936.68531657312;63753.68123159447;64503;65235.67382163059;66243.66878165578;6112.449879510866;7339.77980660581;7445.739399121005;7599.354054905174;7782.727604533814;35534.28931421409;36094.8195259022;36155.01147436916;44083.55916440795;44202.55797441985;45159.774201128785;56565.43434565766;59845;62532;62694.68652656708;10206.591736330463;11649.009834163851;11771.529138834694;11774.3524106175;11859.466324015268;9005.097224844314;11522.76954460923;11729.178957472895;11960.940195298968;12071.69820754453;9174.587143578421;11175.55297788112;11337.659870203906;11356.432178391213;11366.261193022427;9601.615935362786;11407.201495895208;11462.369569673749;11995.760084798081;12208;10292.639757608447;10918.344899306067;11255.66233013011;11356.716082098013;11711;9489.383190092622;9640.084192001748;9739.366941148803;9900.405975641484;10145.84781228297;9455.959844417246;9798.775153105897;9920.652777152765;9922.485254264377;9972.102510774064 14:06:20 INFO - PROCESS | 1658 | __end_tp_report 14:06:20 INFO - PROCESS | 1658 | __start_cc_report 14:06:20 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,396 14:06:20 INFO - PROCESS | 1658 | __end_cc_report 14:06:20 INFO - PROCESS | 1658 | __startTimestamp1461186380906__endTimestamp 14:06:20 INFO - PROCESS | 1658 | 14:06:20 INFO - PROCESS | 1658 | ------- Summary: start ------- 14:06:20 INFO - PROCESS | 1658 | Number of tests: 6 14:06:20 INFO - PROCESS | 1658 | 14:06:20 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5741.88 Median:6011.91 stddev:670.91 (11.2%) stddev-sans-first:647.87 14:06:20 INFO - PROCESS | 1658 | Values: 3424.7 3483.8 3507.2 3507.2 3550.9 5749.9 5762.5 5801.3 5814.6 5833.0 5683.9 5808.9 5831.4 5838.9 5889.6 5231.0 5271.8 5295.9 5328.8 5367.8 4059.7 4343.8 4388.7 4507.3 5389.5 5237.1 5280.1 5282.3 5300.0 5315.7 5293.1 5293.9 5295.2 5301.8 5304.2 4256.3 4797.0 4860.6 5293.7 5356.8 5390.8 5631.5 5683.1 5722.8 5724.6 6172.2 6219.3 6234.5 6270.7 6296.3 6111.8 6206.2 6251.3 6279.6 6289.7 5942.4 5990.6 6011.9 6024.3 6060.8 6281.7 6305.2 6333.1 6339.3 6348.9 5827.9 6051.3 6220.8 6226.6 6290.0 5931.7 6188.0 6234.4 6247.6 6259.2 6139.2 6144.4 6219.5 6226.3 6280.1 5112.5 5244.3 6137.2 6153.1 6261.9 4961.0 4984.4 4985.8 4986.2 5043.9 4451.8 4482.7 4493.2 4499.1 4510.3 5738.2 5858.8 5888.3 5921.4 5942.5 6221.9 6231.6 6236.0 6254.2 6326.5 5850.1 5934.4 5941.4 5964.4 6009.2 5327.5 5440.6 5456.1 5505.6 5547.9 6354.1 6403.7 6419.1 6424.9 6449.6 6251.5 6256.2 6267.4 6269.7 6276.7 6228.3 6327.4 6353.5 6407.3 6412.8 6139.8 6172.6 6248.2 6258.5 6259.7 6171.2 6242.4 6246.4 6254.2 6257.7 5821.4 6034.5 6136.9 6193.4 6241.4 6107.4 6111.6 6121.0 6121.2 6124.8 5074.7 5128.4 5483.0 5798.3 5920.2 6034.5 6105.2 6105.4 6123.3 6130.3 6066.4 6091.3 6107.9 6108.6 6112.2 14:06:20 INFO - PROCESS | 1658 | 14:06:20 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:66341.87 Median:37620.84 stddev:147947.76 (393.3%) stddev-sans-first:148280.20 14:06:20 INFO - PROCESS | 1658 | Values: 498.3 510.9 534.0 581.1 617.5 29500.7 36978.4 37428.8 37662.4 37739.6 38200.0 38772.0 38891.8 39024.4 39378.0 9317.5 9544.0 9555.9 9572.0 9585.3 9435.9 9569.9 9571.9 9588.3 9597.5 9536.3 9536.3 9584.4 9622.0 9631.0 9527.0 9540.3 9580.3 9627.6 9639.6 9550.5 9675.2 9683.6 9689.7 9701.5 37496.9 37925.4 37937.0 38026.0 38286.4 3093.5 3190.9 3249.3 3332.5 3414.6 28574.1 28584.9 28658.7 28885.0 29126.3 2251.4 2294.2 2668.9 2715.9 2755.5 400795.0 401397.0 402062.0 403482.0 405381.0 35502.3 62217.7 62613.0 62633.0 63030.7 28382.9 34727.0 35221.3 35363.1 35514.8 15126.8 15189.7 15333.5 15362.2 15388.5 12371.8 12764.9 12879.3 12895.4 12903.6 8058.6 8088.5 8138.1 8201.5 8251.8 3338.4 3364.8 3379.6 3395.2 3430.4 918.8 1001.2 1035.6 1062.5 1101.4 30204.0 30344.5 30347.7 30625.8 31505.5 1081.2 1085.3 1111.4 1119.5 1164.7 15541.0 15698.6 15747.3 15980.9 16056.8 633097.0 637731.0 645355.0 645961.0 649519.0 60207.4 61287.7 61435.4 61453.4 61537.4 1425.6 1466.4 1474.6 1480.7 1483.0 29128.6 29364.1 29371.6 30155.7 30209.7 53430.7 54556.5 54598.0 54693.7 54857.0 578583.0 582855.0 583291.0 584106.0 584282.0 26642.6 27042.1 27229.0 27474.2 27551.9 39787.6 46096.5 46935.5 48048.8 48127.8 38715.6 39218.0 39828.6 39875.0 40072.4 30961.8 31869.8 32883.4 36523.6 38921.8 32194.7 37076.8 37579.2 38337.8 38388.2 29071.8 31576.5 31999.5 32011.0 32141.0 24133.5 24312.8 24326.6 24341.0 24676.0 14:06:20 INFO - PROCESS | 1658 | 14:06:20 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:34393.08 Median:12225.78 stddev:99436.83 (813.3%) stddev-sans-first:99683.01 14:06:20 INFO - PROCESS | 1658 | Values: 393.3 393.8 399.1 400.9 407.2 15809.4 18270.0 20267.2 20334.9 20740.9 20071.8 20233.4 20739.2 21579.8 21711.8 5905.2 6011.4 6036.2 6056.5 6062.9 5057.5 5085.3 5088.7 5326.0 5746.1 5664.7 5711.6 5738.9 5743.3 5751.9 5452.7 5815.7 5837.1 5843.7 5858.2 5557.1 5651.6 5721.8 5841.1 5843.5 13148.1 13296.9 13337.4 22251.7 26111.3 2012.9 2169.4 2186.9 2199.9 2334.6 9445.2 9792.1 9948.2 10056.8 10099.4 1688.2 1696.3 1729.4 1729.8 1747.7 97496.0 119977.8 123091.0 126521.8 128354.0 11265.9 11682.5 11691.8 11712.7 11893.4 12389.4 13560.9 14188.6 14830.0 14835.4 8484.9 8537.7 8611.7 8675.1 8716.4 7038.7 8526.0 8567.1 8571.0 8621.2 4546.2 4607.3 4649.8 4687.9 4710.8 2002.4 2006.5 2016.6 2018.8 2026.6 879.6 880.8 935.8 948.6 994.0 14671.0 20208.8 20385.7 20462.3 20548.6 959.7 965.5 981.5 983.6 1017.4 10556.0 10678.0 10743.7 10769.1 10777.0 528813.0 598022.0 605525.0 613200.0 620893.0 78313.0 137182.0 145372.0 148725.0 152636.0 1005.0 1024.9 1029.4 1055.8 1067.2 17925.6 17981.9 18204.8 18608.5 19132.8 50638.5 50856.5 51914.7 53001.5 53797.7 64384.4 66741.3 67454.0 70182.6 71610.6 7942.1 8045.4 8155.0 8197.4 8476.6 10967.6 12533.8 12751.7 12882.2 13033.9 10839.0 11032.3 11106.9 11262.0 11387.9 9904.7 10996.5 11096.8 11167.1 11334.0 9090.5 11015.4 11430.1 11490.3 11641.8 6988.3 8173.7 8194.8 8494.1 8565.9 10166.4 10793.9 10890.3 11268.3 11535.3 14:06:20 INFO - PROCESS | 1658 | 14:06:20 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5631.31 Median:4832.10 stddev:3146.31 (65.1%) stddev-sans-first:3140.55 14:06:20 INFO - PROCESS | 1658 | Values: 1599.9 1625.0 1874.1 1937.2 1943.0 7058.3 7925.2 8475.4 8750.0 8756.5 7188.7 8603.6 8672.1 8707.0 8724.3 2434.8 2434.9 2446.9 2457.7 2469.2 2429.0 2489.3 2493.4 2505.1 2516.6 2470.1 2480.9 2501.6 2514.6 2515.5 2069.5 2088.4 2127.6 2454.5 2491.6 2424.2 2441.6 2463.3 2464.7 2467.9 8710.0 8738.2 8780.9 8854.9 8861.0 4739.2 5274.3 5416.2 5462.1 5477.3 3260.7 3321.5 3556.0 3564.1 3604.1 1017.6 1031.8 1037.2 1041.1 1049.0 11351.2 11452.3 11484.4 11509.4 11525.0 4697.6 4739.6 4839.8 4938.6 4963.8 9850.4 9877.6 9894.7 9912.0 9963.8 4897.0 5217.9 5390.9 5411.4 5415.2 4080.4 4405.9 4907.4 4915.3 4990.4 3958.6 4014.3 4044.9 4092.3 4142.8 1345.2 1347.1 1360.1 1362.2 1372.3 809.3 848.7 878.4 895.2 903.9 4957.0 5572.6 5607.5 5735.3 5780.5 435.1 449.6 452.2 462.0 466.0 3769.3 3927.0 3956.9 4004.5 4035.2 10337.8 10405.1 10621.6 10662.1 10668.8 10101.8 10527.5 10538.9 10572.4 10587.0 7955.4 7999.9 8091.1 8104.6 8162.6 5698.1 5717.8 5746.9 5930.2 6018.0 9401.0 9813.5 9833.0 9891.7 9964.0 10117.7 10225.9 10235.0 10306.7 10429.9 6887.2 7185.0 7537.8 7551.4 7658.7 8129.6 8179.5 8199.5 8271.4 8275.9 6369.3 7240.3 7357.9 7428.9 7471.0 7118.9 7176.1 7229.1 7310.6 7352.0 6091.8 6834.6 6987.1 7022.5 7042.6 4648.9 4698.4 4741.5 4795.0 4935.6 4800.2 4824.4 4882.7 4884.1 4940.8 14:06:20 INFO - PROCESS | 1658 | 14:06:20 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:5303.90 Median:5977.58 stddev:1724.57 (28.9%) stddev-sans-first:1687.45 14:06:20 INFO - PROCESS | 1658 | Values: 266.6 319.5 328.0 330.1 331.4 6019.5 6163.8 6206.0 6241.6 6242.9 5146.9 5386.9 6231.7 6263.3 6296.9 4117.8 4726.4 4737.8 4746.4 4750.3 3885.4 4043.8 4191.4 4360.4 4717.8 3718.8 4555.3 4610.7 4619.6 4638.0 3611.7 3993.4 4528.7 4601.8 4647.8 3313.5 3858.5 3876.3 3890.6 3916.9 5938.0 5960.3 5961.9 5993.3 5994.7 5161.7 5271.8 5316.4 5328.4 5344.4 5727.8 5759.5 6555.5 6582.3 6628.7 4325.8 4334.7 4338.6 4362.2 4363.8 6246.5 6286.0 6523.5 6888.0 7005.0 4025.3 4045.9 4089.1 4355.3 4368.2 4295.1 4454.2 4455.4 4470.3 4487.5 5037.5 5316.4 5333.8 5347.7 5354.2 5108.3 5762.6 6246.0 6494.8 6608.1 1403.4 1445.5 1476.6 1514.0 1533.8 978.8 990.9 997.2 1001.7 1016.4 5479.7 6158.5 6184.6 6212.4 6242.5 5463.9 6892.5 6926.4 6953.5 6970.7 6012.6 6200.7 6205.9 6212.7 6222.4 2887.8 3296.5 3592.6 3620.9 3659.6 6636.3 6867.3 7139.9 7205.0 7205.2 7084.9 7091.0 7110.8 7115.6 7119.9 5519.7 7180.9 7193.8 7199.7 7254.5 6913.5 6932.4 6936.1 6954.2 6955.4 6591.7 6840.5 6871.3 6873.8 6882.0 6667.0 6725.5 6886.2 7188.9 7234.5 3674.6 3875.9 4451.5 4491.4 4506.2 5095.4 5813.8 6579.9 6657.7 6969.9 5549.0 6480.5 6585.4 6731.5 6731.6 5561.3 5926.4 6660.8 6711.4 6722.2 5283.6 6523.9 6548.3 6569.3 6584.4 5616.3 6514.8 6713.2 6738.1 6743.2 6406.6 6418.9 6588.9 6614.7 6641.2 14:06:20 INFO - PROCESS | 1658 | 14:06:20 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:17534.17 Median:62613.34 stddev:24750.87 (39.5%) stddev-sans-first:24787.87 14:06:20 INFO - PROCESS | 1658 | Values: 657.3 673.5 681.2 761.7 786.1 14622.5 15040.9 15155.6 15217.1 15235.4 13443.9 13903.9 14108.9 14159.4 14223.4 708.2 881.8 890.3 897.7 899.0 863.7 880.6 890.2 897.8 900.5 932.3 932.5 956.1 956.3 960.8 923.8 926.7 927.4 952.1 956.6 679.8 693.1 696.9 697.3 699.6 14912.8 14950.2 14972.8 15070.2 15090.2 12522.7 12605.5 12614.2 12641.6 12858.8 9883.9 9942.3 10016.3 10161.9 10357.7 2905.7 3393.8 3465.5 3526.1 3612.2 82822.0 83963.0 84740.2 84920.0 84928.9 9333.4 9356.6 9479.6 9504.1 9583.2 18044.7 21184.0 21711.2 21795.6 21854.3 6318.0 7500.7 7515.0 7600.2 7669.6 3999.6 4002.9 4086.4 4087.8 4100.2 3500.6 3548.5 3592.0 3595.8 3623.6 651.0 653.5 654.6 657.6 667.0 528.8 538.9 541.2 577.8 583.0 7607.5 7989.9 8220.3 8339.4 8344.8 518.4 534.3 563.7 579.0 595.6 3671.2 3757.2 3773.4 3889.6 3925.7 89529.0 90283.0 91380.0 92296.5 93464.0 75282.6 77251.6 77863.0 78173.0 78551.0 62936.7 63753.7 64503.0 65235.7 66243.7 6112.4 7339.8 7445.7 7599.4 7782.7 35534.3 36094.8 36155.0 44083.6 44202.6 45159.8 56565.4 59845.0 62532.0 62694.7 10206.6 11649.0 11771.5 11774.4 11859.5 9005.1 11522.8 11729.2 11960.9 12071.7 9174.6 11175.6 11337.7 11356.4 11366.3 9601.6 11407.2 11462.4 11995.8 12208.0 10292.6 10918.3 11255.7 11356.7 11711.0 9489.4 9640.1 9739.4 9900.4 10145.8 9456.0 9798.8 9920.7 9922.5 9972.1 14:06:20 INFO - PROCESS | 1658 | -------- Summary: end -------- 14:06:20 INFO - PROCESS | 1658 | 14:06:21 INFO - TEST-INFO | 1658: exit 0 14:06:21 INFO - TEST-OK | dromaeo_css | took 1114010ms 14:06:21 INFO - TEST-START | kraken 14:06:21 INFO - operating with platform_type : mac_ 14:06:21 INFO - Initialising browser for kraken test... 14:06:21 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp3vkrhr/profile http://localhost:49271/getInfo.html) 14:06:24 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 14:06:24 INFO - PROCESS | 1666 | colorDepth:24 14:06:24 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 14:06:24 INFO - PROCESS | 1666 | __metrics 14:06:24 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 14:06:25 INFO - TEST-INFO | 1666: exit 0 14:06:25 INFO - Browser initialized. 14:06:25 INFO - Running cycle 1/1 for kraken test... 14:06:25 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp3vkrhr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:06:37 INFO - PROCESS | 1669 | RSS: Main: 247808000 14:06:37 INFO - PROCESS | 1669 | 14:06:39 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 14:06:40 INFO - PROCESS | 1669 | RSS: Main: 260059136 14:06:40 INFO - PROCESS | 1669 | 14:06:43 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 14:06:44 INFO - PROCESS | 1669 | RSS: Main: 269180928 14:06:44 INFO - PROCESS | 1669 | 14:06:47 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 14:06:48 INFO - PROCESS | 1669 | RSS: Main: 272236544 14:06:48 INFO - PROCESS | 1669 | 14:06:50 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 14:06:51 INFO - PROCESS | 1669 | RSS: Main: 273158144 14:06:51 INFO - PROCESS | 1669 | 14:06:53 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:06:53 INFO - PROCESS | 1669 | RSS: Main: 273625088 14:06:53 INFO - PROCESS | 1669 | 14:06:56 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 14:06:57 INFO - PROCESS | 1669 | RSS: Main: 273412096 14:06:57 INFO - PROCESS | 1669 | 14:07:00 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 14:07:00 INFO - PROCESS | 1669 | RSS: Main: 273625088 14:07:00 INFO - PROCESS | 1669 | 14:07:03 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 14:07:04 INFO - PROCESS | 1669 | RSS: Main: 274956288 14:07:04 INFO - PROCESS | 1669 | 14:07:06 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:07:06 INFO - PROCESS | 1669 | RSS: Main: 274964480 14:07:06 INFO - PROCESS | 1669 | 14:07:08 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:07:09 INFO - PROCESS | 1669 | RSS: Main: 274980864 14:07:09 INFO - PROCESS | 1669 | 14:07:11 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:07:12 INFO - PROCESS | 1669 | RSS: Main: 275173376 14:07:12 INFO - PROCESS | 1669 | 14:07:15 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:07:15 INFO - PROCESS | 1669 | RSS: Main: 275140608 14:07:15 INFO - PROCESS | 1669 | 14:07:19 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:07:19 INFO - PROCESS | 1669 | RSS: Main: 275030016 14:07:19 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 14:07:21 INFO - PROCESS | 1669 | RSS: Main: 276713472 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | __start_tp_report 14:07:21 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 14:07:21 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 14:07:21 INFO - PROCESS | 1669 | |i|pagename|runs| 14:07:21 INFO - PROCESS | 1669 | |0;ai-astar;115;108;116;117;122;111;171;171;169;155 14:07:21 INFO - PROCESS | 1669 | |1;audio-beat-detection;157;123;148;168;131;174;174;156;188;127 14:07:21 INFO - PROCESS | 1669 | |2;audio-dft;198;208;214;273;194;280;275;197;205;197 14:07:21 INFO - PROCESS | 1669 | |3;audio-fft;70;78;74;132;58;57;65;65;106;78 14:07:21 INFO - PROCESS | 1669 | |4;audio-oscillator;83;94;124;108;116;118;110;117;116;160 14:07:21 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;92;90;91;88;91;88;93;91;92;88 14:07:21 INFO - PROCESS | 1669 | |6;imaging-darkroom;97;98;97;104;91;91;97;104;93;90 14:07:21 INFO - PROCESS | 1669 | |7;imaging-desaturate;91;95;91;107;147;91;90;90;146;147 14:07:21 INFO - PROCESS | 1669 | |8;json-parse-financial;61;64;69;83;88;62;61;79;63;54 14:07:21 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;66;71;73;67;71;75;84;96;93;68 14:07:21 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;104;77;98;74;86;99;110;107;76;97 14:07:21 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;146;148;149;153;138;154;152;154;176;142 14:07:21 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;180;175;192;209;222;201;173;228;220;171 14:07:21 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;71;69;70;69;67;96;96;70;65;97 14:07:21 INFO - PROCESS | 1669 | __end_tp_report 14:07:21 INFO - PROCESS | 1669 | __start_cc_report 14:07:21 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1047 14:07:21 INFO - PROCESS | 1669 | __end_cc_report 14:07:21 INFO - PROCESS | 1669 | __startTimestamp1461186441751__endTimestamp 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | ------- Summary: start ------- 14:07:21 INFO - PROCESS | 1669 | Number of tests: 14 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:135.50 Median:138.50 stddev:27.29 (19.7%) stddev-sans-first:27.92 14:07:21 INFO - PROCESS | 1669 | Values: 115.0 108.0 116.0 117.0 122.0 111.0 171.0 171.0 169.0 155.0 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:154.60 Median:162.50 stddev:22.15 (13.6%) stddev-sans-first:23.48 14:07:21 INFO - PROCESS | 1669 | Values: 157.0 123.0 148.0 168.0 131.0 174.0 174.0 156.0 188.0 127.0 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:224.10 Median:211.00 stddev:36.35 (17.2%) stddev-sans-first:37.30 14:07:21 INFO - PROCESS | 1669 | Values: 198.0 208.0 214.0 273.0 194.0 280.0 275.0 197.0 205.0 197.0 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:78.30 Median:67.50 stddev:23.47 (34.8%) stddev-sans-first:24.70 14:07:21 INFO - PROCESS | 1669 | Values: 70.0 78.0 74.0 132.0 58.0 57.0 65.0 65.0 106.0 78.0 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:114.60 Median:121.00 stddev:20.16 (16.7%) stddev-sans-first:17.85 14:07:21 INFO - PROCESS | 1669 | Values: 83.0 94.0 124.0 108.0 116.0 118.0 110.0 117.0 116.0 160.0 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:90.40 Median:91.00 stddev:1.84 (2.0%) stddev-sans-first:1.86 14:07:21 INFO - PROCESS | 1669 | Values: 92.0 90.0 91.0 88.0 91.0 88.0 93.0 91.0 92.0 88.0 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:96.20 Median:95.00 stddev:5.05 (5.3%) stddev-sans-first:5.35 14:07:21 INFO - PROCESS | 1669 | Values: 97.0 98.0 97.0 104.0 91.0 91.0 97.0 104.0 93.0 90.0 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:109.50 Median:90.50 stddev:26.14 (28.9%) stddev-sans-first:26.85 14:07:21 INFO - PROCESS | 1669 | Values: 91.0 95.0 91.0 107.0 147.0 91.0 90.0 90.0 146.0 147.0 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:68.40 Median:66.50 stddev:11.14 (16.7%) stddev-sans-first:11.49 14:07:21 INFO - PROCESS | 1669 | Values: 61.0 64.0 69.0 83.0 88.0 62.0 61.0 79.0 63.0 54.0 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:76.40 Median:74.00 stddev:10.83 (14.6%) stddev-sans-first:10.82 14:07:21 INFO - PROCESS | 1669 | Values: 66.0 71.0 73.0 67.0 71.0 75.0 84.0 96.0 93.0 68.0 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:92.80 Median:81.50 stddev:13.49 (16.6%) stddev-sans-first:13.68 14:07:21 INFO - PROCESS | 1669 | Values: 104.0 77.0 98.0 74.0 86.0 99.0 110.0 107.0 76.0 97.0 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:151.20 Median:152.50 stddev:10.20 (6.7%) stddev-sans-first:10.64 14:07:21 INFO - PROCESS | 1669 | Values: 146.0 148.0 149.0 153.0 138.0 154.0 152.0 154.0 176.0 142.0 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.10 Median:205.00 stddev:21.92 (10.7%) stddev-sans-first:22.36 14:07:21 INFO - PROCESS | 1669 | Values: 180.0 175.0 192.0 209.0 222.0 201.0 173.0 228.0 220.0 171.0 14:07:21 INFO - PROCESS | 1669 | 14:07:21 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.00 Median:70.50 stddev:13.45 (19.1%) stddev-sans-first:14.09 14:07:21 INFO - PROCESS | 1669 | Values: 71.0 69.0 70.0 69.0 67.0 96.0 96.0 70.0 65.0 97.0 14:07:21 INFO - PROCESS | 1669 | -------- Summary: end -------- 14:07:21 INFO - PROCESS | 1669 | 14:07:22 INFO - TEST-INFO | 1669: exit 0 14:07:22 INFO - TEST-OK | kraken | took 61072ms 14:07:22 INFO - SUITE-END | took 1175s 14:07:22 INFO - Completed test suite (00:19:35) 14:07:22 INFO - javascript benchmark 14:07:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3424.7260219182467, 3483.756137070405, 3507.1582820123176, 3507.1758136837852, 3550.9112272193206, 5749.856253593657, 5762.4525670061275, 5801.303843538771, 5814.592978491502, 5833.037548804447, 5683.943160568393, 5808.854778630536, 5831.358550559435, 5838.883222335551, 5889.587728858982, 5231.0, 5271.841844744645, 5295.920561191566, 5328.8401347959625, 5367.812126575571, 4059.6752259819145, 4343.847965321214, 4388.67084968627, 4507.346434766957, 5389.461053894618, 5237.083510385667, 5280.128778751501, 5282.260483532308, 5299.999999999981, 5315.681059136464, 5293.073712100367, 5293.920591191135, 5295.179247216687, 5301.840944771663, 5304.204369344629, 4256.297710877701, 4796.952030479685, 4860.562549370538, 5293.708846013467, 5356.758945847446, 5390.838274851761, 5631.521320687746, 5683.090705487143, 5722.82831515051, 5724.5992780505385, 6172.22847144107, 6219.315875253718, 6234.470070044052, 6270.6864656766975, 6296.275928268216, 6111.804454079419, 6206.162168107293, 6251.3123556408755, 6279.591826531261, 6289.654069026202, 5942.435468630473, 5990.64056156632, 6011.909821352683, 6024.337322894478, 6060.757569697163, 6281.7271514742, 6305.211848518935, 6333.081703153017, 6339.302676705559, 6348.873022539523, 5827.921834460638, 6051.273847138284, 6220.7511699532415, 6226.59527130735, 6289.993601023814, 5931.703414829241, 6188.0, 6234.438900498977, 6247.5939063960695, 6259.217597800275, 6139.201903752483, 6144.402455361549, 6219.471344935731, 6226.283977342573, 6280.089387038854, 5112.463191364853, 5244.29202057725, 6137.232845894263, 6153.077038444269, 6261.8747625047245, 4961.0, 4984.401871775338, 4985.750712464363, 4986.177280748636, 5043.899122017539, 4451.821927122879, 4482.663800214997, 4493.191225579427, 4499.122671079108, 4510.278355463176, 5738.167965644958, 5858.765649373977, 5888.263967004124, 5921.40785921413, 5942.524598032147, 6221.906671399933, 6231.626102433778, 6236.03341482071, 6254.249490061222, 6326.49388048955, 5850.063989761617, 5934.406559344117, 5941.37615550361, 5964.373740757158, 6009.218801555857, 5327.547158491571, 5440.619156659075, 5456.127019676833, 5505.614606977553, 5547.8890422192135, 6354.110424540568, 6403.711832967528, 6419.101325814389, 6424.903626445701, 6449.6130232186215, 6251.531135164972, 6256.186695729526, 6267.435930766253, 6269.749210031548, 6276.6547839869245, 6228.314885362697, 6327.3988971047675, 6353.523485738496, 6407.2631647359285, 6412.775552855627, 6139.84650383744, 6172.598781079395, 6248.218972628421, 6258.530610204162, 6259.6870156492905, 6171.166892469639, 6242.438180563771, 6246.3753624637175, 6254.155688981929, 6257.7184026718, 5821.417858214145, 6034.517238620988, 6136.886723215932, 6193.4425901669065, 6241.407066328691, 6107.358727333743, 6111.602745821508, 6121.0206366981065, 6121.234845644294, 6124.755009799736, 5074.746262686925, 5128.384593848762, 5482.985647655197, 5798.33319168301, 5920.200772895604, 6034.487068599218, 6105.175801266772, 6105.419985101408, 6123.326434092157, 6130.295016073031, 6066.393360663898, 6091.299500557493, 6107.877842443215, 6108.602940808833, 6112.17485639433], "value": 5699.10524624037, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.2526210683974, 510.8620672418447, 533.9546138578223, 581.1341101758379, 617.4751461257929, 29500.704992950064, 36978.445323320135, 37428.812855935685, 37662.435063474004, 37739.622603773954, 38200.04499887504, 38772.0, 38891.805540972324, 39024.41463378045, 39378.0, 9317.487538185414, 9543.952280238589, 9555.856662150038, 9572.0, 9585.2811039172, 9435.858462123073, 9569.90430095697, 9571.904280957171, 9588.328816982814, 9597.520123993772, 9536.284778641595, 9536.332456728032, 9584.37701549397, 9621.989691082441, 9630.9518452408, 9527.0, 9540.33217674756, 9580.329376943617, 9627.614895404175, 9639.566219520138, 9550.522473876279, 9675.2259819215, 9683.564239609164, 9689.660861869872, 9701.466419346933, 37496.87509374723, 37925.43111853324, 37937.0, 38026.0, 38286.42570361447, 3093.489574220251, 3190.8564114614896, 3249.2851572653976, 3332.500124981248, 3414.58294807657, 28574.142775716762, 28584.856605735746, 28658.71035803373, 28885.0, 29126.27184320388, 2251.4146321956246, 2294.2199652118193, 2668.8799004044863, 2715.945681086387, 2755.476459472694, 400795.0, 401396.9999999971, 402061.9896900526, 403482.0, 405381.0, 35502.28995420077, 62217.688911556055, 62613.0, 62633.0, 63030.684846575474, 28382.85808570944, 34727.0, 35221.29557408838, 35363.115922101635, 35514.8224258877, 15126.848731512764, 15189.696206075816, 15333.463328783439, 15362.231888405759, 15388.461403850812, 12371.75256494865, 12764.93617531925, 12879.29163895976, 12895.355232238351, 12903.612891613267, 8058.556779377073, 8088.514689118671, 8138.145494723087, 8201.466904651179, 8251.803488494255, 3338.382399256145, 3364.7644664873715, 3379.6282408935, 3395.2021275000357, 3430.365382404263, 918.8024574716468, 1001.1990407673832, 1035.5857656937135, 1062.511244827388, 1101.4217535793612, 30204.0, 30344.54483182754, 30347.737610725082, 30625.846870765505, 31505.52741708876, 1081.167501024207, 1085.2999815119204, 1111.4498323330004, 1119.5409881948362, 1164.6971787335185, 15540.989835660646, 15698.607534811492, 15747.291371888063, 15980.920095399448, 16056.841178187737, 633097.0, 637731.0, 645355.0, 645961.0, 649519.0, 60207.39792602018, 61287.69356153191, 61435.38564614386, 61453.38546614477, 61537.38462615406, 1425.615083927334, 1466.3767898643034, 1474.6018574984694, 1480.696457226266, 1483.0360265827082, 29128.563071554367, 29364.11907642774, 29371.559426609045, 30155.698443015288, 30209.69790302069, 53430.73284633552, 54556.45443545514, 54598.0, 54693.72653136709, 54857.0, 578583.0, 582855.0, 583291.0, 584106.0, 584282.0, 26642.600360994988, 27042.05352812642, 27229.0, 27474.17577472679, 27551.862240688668, 39787.60212397839, 46096.539034609224, 46935.53064469311, 48048.759756201, 48127.75936120297, 38715.612843871204, 39218.0, 39828.60171398249, 39875.0, 40072.39891401573, 30961.761529538566, 31869.8406507966, 32883.35583220877, 36523.63476365202, 38921.805390974, 32194.67805321917, 37076.81461592675, 37579.248415032096, 38337.80831095938, 38388.232235355696, 29071.83525860381, 31576.526352104276, 31999.520007199444, 32011.03966880997, 32141.0, 24133.517329652957, 24312.756872431048, 24326.63510047315, 24341.0, 24676.012959482137], "value": 18381.872658671164, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.33722677288773, 393.8090026337225, 399.09604745252, 400.8957671005541, 407.19172442701233, 15809.446669366574, 18269.995150266746, 20267.189312427487, 20334.89832550837, 20740.896295518538, 20071.799282007174, 20233.39299821004, 20739.17043318269, 21579.784202157975, 21711.782882171174, 5905.202797622331, 6011.398860113975, 6036.2152920120325, 6056.4851987581105, 6062.878742425149, 5057.468965758598, 5085.262636917645, 5088.745562721849, 5325.973370133144, 5746.138079288119, 5664.660120392769, 5711.628744131639, 5738.856528586777, 5743.282089738783, 5751.856203594902, 5452.727363631822, 5815.651060936358, 5837.09525023622, 5843.737031833535, 5858.179854820329, 5557.083081291582, 5651.604387692863, 5721.7711291548285, 5841.065429531254, 5843.5325173985975, 13148.079634425594, 13296.933515332361, 13337.399817008258, 22251.666225006637, 26111.347216319555, 2012.9295474658463, 2169.39257008038, 2186.8797216153107, 2199.890005499735, 2334.6498025296173, 9445.197158241557, 9792.069753373418, 9948.204143668489, 10056.849147262797, 10099.444530550816, 1688.1812321024292, 1696.2621259751925, 1729.4033558422325, 1729.7664815250032, 1747.685416625007, 97496.0, 119977.8002219984, 123091.0, 126521.7760845542, 128354.0, 11265.943670281596, 11682.532698691957, 11691.824622630667, 11712.716020376116, 11893.405329733652, 12389.442475088643, 13560.93219533896, 14188.574342769527, 14830.0, 14835.406583736745, 8484.872726909101, 8537.701180458655, 8611.6985905493, 8675.132486751401, 8716.38985271025, 7038.718451261994, 8525.95737021311, 8567.057623661392, 8570.971483421905, 8621.180987806149, 4546.227141385973, 4607.285870690049, 4649.814297354153, 4687.929681054787, 4710.8351207707565, 2002.449326435242, 2006.4983754061484, 2016.5563576013253, 2018.7577490700917, 2026.5642886779412, 879.5821984557284, 880.823835232956, 935.7941252924346, 948.5636607160783, 993.9701808945744, 14671.00639495169, 20208.79791202069, 20385.694214586794, 20462.283820066226, 20548.589028219652, 959.6976952260015, 965.4545181972163, 981.4503877828439, 983.5918093991056, 1017.4454922067491, 10556.0, 10678.0, 10743.677689669323, 10769.138468922467, 10776.976187262198, 528813.0, 598022.0098899564, 605525.0, 613200.0, 620893.0, 78313.0, 137182.0, 145372.0, 148725.0, 152636.0, 1004.9899501005043, 1024.8975102489692, 1029.3823705776474, 1055.794120146564, 1067.1836045425257, 17925.641487169923, 17981.910090449463, 18204.81795182031, 18608.5347866299, 19132.80867191366, 50638.49361506485, 50856.491435085176, 51914.70213244699, 53001.46998529966, 53797.731011344695, 64384.35615643783, 66741.33258667546, 67454.0, 70182.64908675425, 71610.64194678994, 7942.12636609984, 8045.356371490384, 8155.021397432346, 8197.426180167331, 8476.576171191538, 10967.616133435291, 12533.811992819932, 12751.74496510046, 12882.227066376048, 13033.934830325788, 10839.0, 11032.338059716443, 11106.944465277622, 11262.042726368023, 11387.886121138683, 9904.653337133166, 10996.450177491253, 11096.778064438828, 11167.050800682033, 11334.0, 9090.500022498825, 11015.449227538751, 11430.085593152695, 11490.310581365145, 11641.767164656829, 6988.301169882971, 8173.67305307781, 8194.83610327802, 8494.065652778067, 8565.871511927202, 10166.390016599027, 10793.892061079288, 10890.267909783825, 11268.323900565665, 11535.250208736405], "value": 9366.267136537019, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1599.9360025598976, 1624.9918750406248, 1874.144596849119, 1937.205745644286, 1943.018775518363, 7058.329458701428, 7925.167857374979, 8475.449095808768, 8749.956250218747, 8756.518391488466, 7188.712451501934, 8603.612837422332, 8672.089430609789, 8707.0, 8724.345674074453, 2434.8417352872093, 2434.8539087654754, 2446.90212391505, 2457.6682147910074, 2469.1975108089855, 2429.0283886445386, 2489.2781093482868, 2493.4265119022634, 2505.1482495951373, 2516.6476693262957, 2470.1231062972656, 2480.851148931061, 2501.6372625969225, 2514.585093459586, 2515.5094756522485, 2069.513664288906, 2088.3943656339643, 2127.606392817326, 2454.472288457981, 2491.6137998610243, 2424.248482970285, 2441.645961335614, 2463.261021693485, 2464.728879823218, 2467.9259622211184, 8709.95645021777, 8738.21356077956, 8780.912190878073, 8854.911450885473, 8861.025287218401, 4739.218029025241, 5274.340707411574, 5416.21464887593, 5462.071447853875, 5477.3427188737205, 3260.7065364117107, 3321.534985102088, 3556.0, 3564.1267889367014, 3604.098975256186, 1017.6183931025865, 1031.8091153136693, 1037.2169012395668, 1041.1306559023224, 1049.0034467256137, 11351.20541562091, 11452.312861228353, 11484.425778711031, 11509.366984815832, 11524.962753352154, 4697.647676424282, 4739.592012782663, 4839.830605928775, 4938.555530002245, 4963.776630051656, 9850.408975461496, 9877.604895804086, 9894.653687120915, 9911.950440247896, 9963.80072398548, 4896.975515122402, 5217.930324283513, 5390.946090539122, 5411.350637923396, 5415.160650099162, 4080.449139366207, 4405.933910991338, 4907.411110666742, 4915.262710593368, 4990.426100998432, 3958.6041395860184, 4014.277430062617, 4044.919101617951, 4092.324766413568, 4142.751434913915, 1345.159275452842, 1347.1311004402105, 1360.081944687332, 1362.1758835904136, 1372.3069849725825, 809.2959125560682, 848.719922425598, 878.4290211362537, 895.2211575928853, 903.9231665308391, 4957.0, 5572.609917305831, 5607.495325420732, 5735.283089613798, 5780.450857168562, 435.1145419072173, 449.6470270837442, 452.20863488894435, 462.03895896535124, 466.002754106213, 3769.2838360711376, 3926.980365098156, 3956.9208615827524, 4004.5394779600138, 4035.21313343901, 10337.793244135075, 10405.06354428105, 10621.575136994434, 10662.147028237723, 10668.786624267472, 10101.797964040677, 10527.473626318653, 10538.947305263424, 10572.361284000992, 10587.0, 7955.4033447491665, 7999.880001799861, 8091.0695270043525, 8104.554249516334, 8162.632681529345, 5698.116791897262, 5717.799877004285, 5746.913796293143, 5930.199423077829, 6018.007028840192, 9400.95299523498, 9813.509324533887, 9832.950835245778, 9891.703248902544, 9963.950180249052, 10117.747056323651, 10225.948870255601, 10235.027672371112, 10306.690799276033, 10429.895701043197, 6887.242403335729, 7184.9640751798, 7537.773866784005, 7551.395888329031, 7658.73194438192, 8129.552874591955, 8179.509229446252, 8199.467034642728, 8271.421000530147, 8275.917240827515, 6369.267534233622, 7240.348368646636, 7357.926420735938, 7428.888566671612, 7470.962645186739, 7118.857622847618, 7176.138863336433, 7229.09636295463, 7310.59791711461, 7351.963240183979, 6091.786787462417, 6834.624095674587, 6987.1266091738535, 7022.508424410242, 7042.612656303748, 4648.93026604608, 4698.365720627671, 4741.525847415231, 4794.976025119852, 4935.580475659609, 4800.231962885921, 4824.372831532019, 4882.6826256293225, 4884.120858245407, 4940.777665005012], "value": 4480.500575271283, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.6440302196566, 319.5159333609581, 327.957096433342, 330.0840168532324, 331.3670888602769, 6019.458248757611, 6163.784267550636, 6206.0, 6241.625502469855, 6242.87628226919, 5146.948530514694, 5386.865328366783, 6231.6572588507615, 6263.3423490533405, 6296.905546416796, 4117.773522456264, 4726.361941137945, 4737.78679959401, 4746.365766999314, 4750.334953106568, 3885.378339465686, 4043.8382464701376, 4191.392248124021, 4360.433143691315, 4717.811287548511, 3718.8326525306425, 4555.316702494636, 4610.700304480215, 4619.561141691551, 4637.9536204638025, 3611.7291203159866, 3993.4209539616595, 4528.728276303433, 4601.847734301997, 4647.814087436498, 3313.4698448248164, 3858.5176852893387, 3876.3022655921923, 3890.5525864525657, 3916.8824935251987, 5938.0, 5960.254968128984, 5961.880762384728, 5993.280806303228, 5994.700264986777, 5161.696514230895, 5271.789128434858, 5316.441773613792, 5328.387235467932, 5344.43883392246, 5727.799527016576, 5759.481646651821, 6555.508336874754, 6582.3417658234275, 6628.6685665717005, 4325.805338759764, 4334.696571240014, 4338.631216346615, 4362.2366085935155, 4363.836218741419, 6246.531510136712, 6286.025666021774, 6523.478121750249, 6888.038143598251, 7005.0, 4025.315696331572, 4045.939310910339, 4089.0595163112653, 4355.281378572563, 4368.235558777201, 4295.076558539929, 4454.153710794939, 4455.353973673799, 4470.284754439274, 4487.506374298824, 5037.539113656999, 5316.415194328621, 5333.786648534093, 5347.689815995106, 5354.196870469461, 5108.335916330928, 5762.567807414461, 6246.0, 6494.778394043815, 6608.074869518271, 1403.417581703602, 1445.4507287230786, 1476.5717941796972, 1513.9772903406458, 1533.8006059212455, 978.8042391521724, 990.8951519056201, 997.2420960070399, 1001.7245257554085, 1016.4155610523978, 5479.698616576047, 6158.476529494954, 6184.567080304426, 6212.4408803207925, 6242.531810114168, 5463.863403414946, 6892.517523773287, 6926.445884329342, 6953.513254072268, 6970.686319115652, 6012.625013119793, 6200.658963757037, 6205.875882482325, 6212.689365531705, 6222.377762223832, 2887.8411687357398, 3296.45608474599, 3592.568891733009, 3620.927581448409, 3659.6157403472785, 6636.270010298863, 6867.313268673092, 7139.929420468006, 7205.0, 7205.171405288354, 7084.858302833914, 7091.0, 7110.786676399716, 7115.5730656160795, 7119.857602847914, 5519.69641669712, 7180.928190718026, 7193.784186474519, 7199.748008819771, 7254.45591580623, 6913.481488888253, 6932.376086152271, 6936.0636314096955, 6954.191910103511, 6955.408790252885, 6591.736330546724, 6840.486963477859, 6871.278515755774, 6873.759418420332, 6881.965590172017, 6666.966575194612, 6725.529212955046, 6886.24251332343, 7188.938249888796, 7234.457415693949, 3674.5774235962135, 3875.8837234883, 4451.53258907823, 4491.416115904912, 4506.169755707622, 5095.414027386898, 5813.79651712188, 6579.868402631825, 6657.667116644052, 6969.895451568332, 5548.9722551388595, 6480.546361754632, 6585.407313341822, 6731.461483081243, 6731.596104233761, 5561.332640083216, 5926.392387349552, 6660.83347916306, 6711.362420570038, 6722.226943901318, 5283.630145889904, 6523.9347606525225, 6548.279689234276, 6569.277379488348, 6584.407403333531, 5616.326040875121, 6514.7621951829, 6713.194416669836, 6738.056672065817, 6743.1908171019795, 6406.615603063831, 6418.930572460597, 6588.901166482602, 6614.735410583523, 6641.15195906981], "value": 4738.377984606166, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.3360905485458, 673.5386260411618, 681.1519658025755, 761.6991288441066, 786.0802860653032, 14622.48821291255, 15040.924795376022, 15155.62110947227, 15217.086974781521, 15235.390584376639, 13443.932780336085, 13903.930480347584, 14108.929455352734, 14159.362828672709, 14223.359948802328, 708.1997343002425, 881.7971866470699, 890.3278025091047, 897.6768363389175, 898.997617656315, 863.7106569299292, 880.5773228850156, 890.2166093837415, 897.824924139793, 900.5362238447204, 932.2681694869528, 932.4824722279133, 956.1442508954482, 956.3449037409362, 960.8270511307961, 923.7690577355661, 926.7127190570911, 927.3786563002805, 952.1335584617999, 956.550421301987, 679.7790718016615, 693.0609024771412, 696.9024336592881, 697.3305626598471, 699.6291965258421, 14912.776308355384, 14950.177740224284, 14972.775408368883, 15070.246487675682, 15090.17004064776, 12522.749545009048, 12605.495780168874, 12614.243145411212, 12641.557545485955, 12858.807117893239, 9883.851742223873, 9942.254330925209, 10016.348937319051, 10161.89838101617, 10357.741056473573, 2905.6658484274367, 3393.7624366294367, 3465.5148279240925, 3526.0655926179584, 3612.205314830733, 82822.0, 83963.0, 84740.15259847384, 84920.0, 84928.87677808042, 9333.439357493611, 9356.625734970661, 9479.620815167455, 9504.144626983467, 9583.185429238454, 18044.736868419346, 21184.0, 21711.240106596193, 21795.564088718453, 21854.344369668936, 6317.96841015792, 7500.662470188855, 7515.0, 7600.239976002355, 7669.578173200416, 3999.5800440953863, 4002.8999275017777, 4086.4483294755423, 4087.795610219477, 4100.2414553307735, 3500.5974312953826, 3548.4854696068924, 3592.0, 3595.8381872815785, 3623.5651721793024, 650.9869802603921, 653.4870126950321, 654.5810681164058, 657.5955787190843, 666.9566478178903, 528.8069854503078, 538.9461053894579, 541.1909195752297, 577.7746678795189, 582.9854253643608, 7607.505512141693, 7989.920100799034, 8220.342372610177, 8339.374546909006, 8344.791380215422, 518.3598256153647, 534.3267482971484, 563.7209581257304, 578.9797357092566, 595.583091835708, 3671.1923376857585, 3757.229767897574, 3773.3585290500682, 3889.572147063821, 3925.725199236083, 89529.0, 90283.0, 91380.0, 92296.53851730698, 93464.0, 75282.62358688172, 77251.61374193206, 77863.0, 78173.0, 78551.0, 62936.68531657312, 63753.68123159447, 64503.0, 65235.67382163059, 66243.66878165578, 6112.449879510866, 7339.77980660581, 7445.739399121005, 7599.354054905174, 7782.727604533814, 35534.28931421409, 36094.8195259022, 36155.01147436916, 44083.55916440795, 44202.55797441985, 45159.774201128785, 56565.43434565766, 59845.0, 62532.0, 62694.68652656708, 10206.591736330463, 11649.009834163851, 11771.529138834694, 11774.3524106175, 11859.466324015268, 9005.097224844314, 11522.76954460923, 11729.178957472895, 11960.940195298968, 12071.69820754453, 9174.587143578421, 11175.55297788112, 11337.659870203906, 11356.432178391213, 11366.261193022427, 9601.615935362786, 11407.201495895208, 11462.369569673749, 11995.760084798081, 12208.0, 10292.639757608447, 10918.344899306067, 11255.66233013011, 11356.716082098013, 11711.0, 9489.383190092622, 9640.084192001748, 9739.366941148803, 9900.405975641484, 10145.84781228297, 9455.959844417246, 9798.775153105897, 9920.652777152765, 9922.485254264377, 9972.102510774064], "value": 6728.184867362769, "unit": "score"}], "name": "dromaeo_css", "value": 7207.353398424664, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 108.0, 116.0, 117.0, 122.0, 111.0, 171.0, 171.0, 169.0, 155.0], "value": 135.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 123.0, 148.0, 168.0, 131.0, 174.0, 174.0, 156.0, 188.0, 127.0], "value": 154.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 208.0, 214.0, 273.0, 194.0, 280.0, 275.0, 197.0, 205.0, 197.0], "value": 224.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 74.0, 132.0, 58.0, 57.0, 65.0, 65.0, 106.0, 78.0], "value": 78.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 94.0, 124.0, 108.0, 116.0, 118.0, 110.0, 117.0, 116.0, 160.0], "value": 114.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 91.0, 88.0, 91.0, 88.0, 93.0, 91.0, 92.0, 88.0], "value": 90.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 104.0, 91.0, 91.0, 97.0, 104.0, 93.0, 90.0], "value": 96.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 91.0, 107.0, 147.0, 91.0, 90.0, 90.0, 146.0, 147.0], "value": 109.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 69.0, 83.0, 88.0, 62.0, 61.0, 79.0, 63.0, 54.0], "value": 68.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 73.0, 67.0, 71.0, 75.0, 84.0, 96.0, 93.0, 68.0], "value": 76.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 77.0, 98.0, 74.0, 86.0, 99.0, 110.0, 107.0, 76.0, 97.0], "value": 92.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 149.0, 153.0, 138.0, 154.0, 152.0, 154.0, 176.0, 142.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 175.0, 192.0, 209.0, 222.0, 201.0, 173.0, 228.0, 220.0, 171.0], "value": 197.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 70.0, 69.0, 67.0, 96.0, 96.0, 70.0, 65.0, 97.0], "value": 77.0, "unit": "score"}], "name": "kraken", "value": 1666.1000000000001, "alertThreshold": 2.0}]} 14:07:22 INFO - Return code: 0 14:07:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:07:23 ERROR - # TBPL SUCCESS # 14:07:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:07:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:07:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:07:23 INFO - 2016-04-20 14:07:23.030 screenresolution[1674:10196] starting screenresolution argv=screenresolution get 14:07:23 INFO - 2016-04-20 14:07:23.033 screenresolution[1674:10196] Display 0: 1600x1200x32@60 14:07:23 INFO - 2016-04-20 14:07:23.038 screenresolution[1675:10199] starting screenresolution argv=screenresolution list 14:07:23 INFO - Available Modes on Display 0 14:07:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:07:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:07:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:07:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:07:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:07:23 INFO - Intel Iris: 14:07:23 INFO - Chipset Model: Intel Iris 14:07:23 INFO - Type: GPU 14:07:23 INFO - Bus: Built-In 14:07:23 INFO - VRAM (Dynamic, Max): 1536 MB 14:07:23 INFO - Vendor: Intel (0x8086) 14:07:23 INFO - Device ID: 0x0a2e 14:07:23 INFO - Revision ID: 0x0009 14:07:23 INFO - Displays: 14:07:23 INFO - Display: 14:07:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:07:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:07:23 INFO - Display Serial Number: 1600x1200 60 14:07:23 INFO - Main Display: Yes 14:07:23 INFO - Mirror: Off 14:07:23 INFO - Online: Yes 14:07:23 INFO - Rotation: Supported 14:07:23 INFO - Return code: 0 14:07:23 INFO - Running post-action listener: _resource_record_post_action 14:07:23 INFO - Running post-run listener: _resource_record_post_run 14:07:24 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37361152; Write bytes: 696701952; Read time: 393; Write time: 2028 14:07:24 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 379040256; Write bytes: 367490560; Read time: 28919; Write time: 703 14:07:24 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31092224; Write bytes: 299814400; Read time: 308; Write time: 1273 14:07:24 INFO - Running post-run listener: _upload_blobber_files 14:07:24 INFO - Blob upload gear active. 14:07:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:07:24 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 14:07:24 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:07:24 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:07:24 INFO - (blobuploader) - INFO - Open directory for files ... 14:07:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:07:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:07:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:07:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:07:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:07:25 INFO - (blobuploader) - INFO - Done attempting. 14:07:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:07:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:07:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:07:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:07:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:07:26 INFO - (blobuploader) - INFO - Done attempting. 14:07:26 INFO - (blobuploader) - INFO - Iteration through files over. 14:07:26 INFO - Return code: 0 14:07:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:07:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:07:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/38944cbbc3a4c0fa5cd4aff9a671a8a42f7d92b79be59ffb3fe55c64d97ab2886d1c160afddfdbabf99da2bfe2bd864b618ec8be60d2a6a2fb539ad82904374e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce2aefbb116fe8ded645d743559601f58d3ada32fb2bbcc2584976516e26a8b97c2224b5cb0faf9162ba99d7a57cfcf77673730d2fbc00a2522dcfb5b3db0c7a"} 14:07:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:07:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:07:26 INFO - Contents: 14:07:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/38944cbbc3a4c0fa5cd4aff9a671a8a42f7d92b79be59ffb3fe55c64d97ab2886d1c160afddfdbabf99da2bfe2bd864b618ec8be60d2a6a2fb539ad82904374e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce2aefbb116fe8ded645d743559601f58d3ada32fb2bbcc2584976516e26a8b97c2224b5cb0faf9162ba99d7a57cfcf77673730d2fbc00a2522dcfb5b3db0c7a"} 14:07:26 INFO - Running post-run listener: copy_logs_to_upload_dir 14:07:26 INFO - Copying logs to upload dir... 14:07:26 INFO - mkdir: /builds/slave/test/build/upload/logs 14:07:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.593526 ========= master_lag: 20.86 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 44 secs) (at 2016-04-20 14:07:47.384421) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 9 secs) (at 2016-04-20 14:07:47.388298) ========= 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.VcJqOuZS3v/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.GOQtzpp5xu/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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/Fx-Team/sha512/38944cbbc3a4c0fa5cd4aff9a671a8a42f7d92b79be59ffb3fe55c64d97ab2886d1c160afddfdbabf99da2bfe2bd864b618ec8be60d2a6a2fb539ad82904374e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce2aefbb116fe8ded645d743559601f58d3ada32fb2bbcc2584976516e26a8b97c2224b5cb0faf9162ba99d7a57cfcf77673730d2fbc00a2522dcfb5b3db0c7a"} build_url:https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011424 build_url: 'https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/38944cbbc3a4c0fa5cd4aff9a671a8a42f7d92b79be59ffb3fe55c64d97ab2886d1c160afddfdbabf99da2bfe2bd864b618ec8be60d2a6a2fb539ad82904374e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce2aefbb116fe8ded645d743559601f58d3ada32fb2bbcc2584976516e26a8b97c2224b5cb0faf9162ba99d7a57cfcf77673730d2fbc00a2522dcfb5b3db0c7a"}' ========= master_lag: 9.43 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 9 secs) (at 2016-04-20 14:07:56.827860) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-04-20 14:07:56.828217) ========= 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.VcJqOuZS3v/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.GOQtzpp5xu/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.005144 ========= master_lag: 2.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-04-20 14:07:58.878405) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-20 14:07:58.878793) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-20 14:08:00.188028) ========= ========= Total master_lag: 32.39 =========