builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0167 starttime: 1462900268.48 results: success (0) buildid: 20160510044311 builduid: 5be4e77192d14761bc6040da40f2aee3 revision: 2f9351bae69d056e4615d21dda6bf42fec5d16b7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 10:11:08.481323) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 10:11:08.481778) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 10:11:08.482079) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 10:11:08.500298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 10:11:08.500697) ========= 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.mfVWXV13Gl/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.AtbnJrX1CA/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-05-10 10:11:08-- 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% 39.5M=0s 2016-05-10 10:11:08 (39.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128902 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 10:11:08.646900) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 10:11:08.647399) ========= 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.mfVWXV13Gl/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.AtbnJrX1CA/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.019750 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 10:11:08.696195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 10:11:08.696559) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2f9351bae69d056e4615d21dda6bf42fec5d16b7 --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 2f9351bae69d056e4615d21dda6bf42fec5d16b7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mfVWXV13Gl/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.AtbnJrX1CA/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-05-10 10:11:08,761 truncating revision to first 12 chars 2016-05-10 10:11:08,762 Setting DEBUG logging. 2016-05-10 10:11:08,762 attempt 1/10 2016-05-10 10:11:08,762 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2f9351bae69d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 10:11:10,412 unpacking tar archive at: fx-team-2f9351bae69d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.900807 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 10:11:10.614887) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 10:11:10.615311) ========= script_repo_revision: 2f9351bae69d056e4615d21dda6bf42fec5d16b7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 10:11:10.615792) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 10:11:10.616131) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 10:11:10.630161) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-10 10:11:10.630582) ========= /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.mfVWXV13Gl/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.AtbnJrX1CA/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 10:11:10 INFO - MultiFileLogger online at 20160510 10:11:10 in /builds/slave/test 10:11:10 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 10:11:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:11:10 INFO - {'append_to_log': False, 10:11:10 INFO - 'base_work_dir': '/builds/slave/test', 10:11:10 INFO - 'blob_upload_branch': 'Fx-Team', 10:11:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:11:10 INFO - 'branch': 'Fx-Team', 10:11:10 INFO - 'buildbot_json_path': 'buildprops.json', 10:11:10 INFO - 'config_files': ('talos/mac_config.py',), 10:11:10 INFO - 'default_actions': ('clobber', 10:11:10 INFO - 'read-buildbot-config', 10:11:10 INFO - 'download-and-extract', 10:11:10 INFO - 'populate-webroot', 10:11:10 INFO - 'create-virtualenv', 10:11:10 INFO - 'install', 10:11:10 INFO - 'run-tests'), 10:11:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:11:10 INFO - 'download_minidump_stackwalk': True, 10:11:10 INFO - 'download_symbols': 'ondemand', 10:11:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:11:10 INFO - 'tooltool.py': '/tools/tooltool.py', 10:11:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:11:10 INFO - '/tools/misc-python/virtualenv.py')}, 10:11:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:11:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:11:10 INFO - 'installer_path': 'installer.exe', 10:11:10 INFO - 'log_level': 'info', 10:11:10 INFO - 'log_name': 'talos', 10:11:10 INFO - 'log_to_console': True, 10:11:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:11:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:11:10 INFO - 'opt_config_files': (), 10:11:10 INFO - 'pip_index': False, 10:11:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:11:10 INFO - 'cmd': ('bash', 10:11:10 INFO - '-c', 10:11:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:11:10 INFO - 'enabled': True, 10:11:10 INFO - 'halt_on_failure': False, 10:11:10 INFO - 'name': 'check_screen_resolution'},), 10:11:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:11:10 INFO - 'cmd': ('bash', 10:11:10 INFO - '-c', 10:11:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:11:10 INFO - 'enabled': True, 10:11:10 INFO - 'halt_on_failure': False, 10:11:10 INFO - 'name': 'check_screen_resolution'},), 10:11:10 INFO - 'run_cmd_checks_enabled': True, 10:11:10 INFO - 'sps_profile': False, 10:11:10 INFO - 'sps_profile_interval': 0, 10:11:10 INFO - 'suite': 'dromaeojs-e10s', 10:11:10 INFO - 'system_bits': '32', 10:11:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:11:10 INFO - 'title': 't-yosemite-r7-0167', 10:11:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:11:10 INFO - 'use_talos_json': True, 10:11:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:11:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:11:10 INFO - 'work_dir': 'build'} 10:11:10 INFO - ##### 10:11:10 INFO - ##### Running clobber step. 10:11:10 INFO - ##### 10:11:10 INFO - Running pre-action listener: _resource_record_pre_action 10:11:10 INFO - Running main action method: clobber 10:11:10 INFO - rmtree: /builds/slave/test/build 10:11:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:11:12 INFO - Running post-action listener: _resource_record_post_action 10:11:12 INFO - ##### 10:11:12 INFO - ##### Finished clobber step (success) 10:11:12 INFO - ##### 10:11:12 INFO - ##### 10:11:12 INFO - ##### Running read-buildbot-config step. 10:11:12 INFO - ##### 10:11:12 INFO - Running pre-action listener: _resource_record_pre_action 10:11:12 INFO - Running main action method: read_buildbot_config 10:11:12 INFO - Using buildbot properties: 10:11:12 INFO - { 10:11:12 INFO - "project": "", 10:11:12 INFO - "product": "firefox", 10:11:12 INFO - "script_repo_revision": "production", 10:11:12 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 10:11:12 INFO - "repository": "", 10:11:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 10:11:12 INFO - "buildid": "20160510044311", 10:11:12 INFO - "pgo_build": "False", 10:11:12 INFO - "basedir": "/builds/slave/test", 10:11:12 INFO - "buildnumber": 3, 10:11:12 INFO - "slavename": "t-yosemite-r7-0167", 10:11:12 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:11:12 INFO - "platform": "yosemite_r7", 10:11:12 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 10:11:12 INFO - "branch": "fx-team", 10:11:12 INFO - "repo_path": "integration/fx-team", 10:11:12 INFO - "revision": "2f9351bae69d056e4615d21dda6bf42fec5d16b7", 10:11:12 INFO - "stage_platform": "macosx64", 10:11:12 INFO - "builduid": "5be4e77192d14761bc6040da40f2aee3", 10:11:12 INFO - "slavebuilddir": "test" 10:11:12 INFO - } 10:11:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 10:11:12 INFO - Running post-action listener: _resource_record_post_action 10:11:12 INFO - ##### 10:11:12 INFO - ##### Finished read-buildbot-config step (success) 10:11:12 INFO - ##### 10:11:12 INFO - ##### 10:11:12 INFO - ##### Running download-and-extract step. 10:11:12 INFO - ##### 10:11:12 INFO - Running pre-action listener: _resource_record_pre_action 10:11:12 INFO - Running main action method: download_and_extract 10:11:12 INFO - mkdir: /builds/slave/test/build/tests 10:11:12 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')]} 10:11:12 INFO - https://queue.taskcluster.net/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:11:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:11:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:11:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:11:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 10:11:12 INFO - Downloaded 1199 bytes. 10:11:12 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:11:12 INFO - Using the following test package requirements: 10:11:12 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:11:12 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:11:12 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 10:11:12 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:11:12 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 10:11:12 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:11:12 INFO - u'jsshell-mac.zip'], 10:11:12 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:11:12 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 10:11:12 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:11:12 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:11:12 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 10:11:12 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:11:12 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 10:11:12 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:11:12 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 10:11:12 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:11:12 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 10:11:12 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:11:12 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')]} 10:11:12 INFO - https://queue.taskcluster.net/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:11:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:11:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:11:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:11:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:11:14 INFO - Downloaded 18149785 bytes. 10:11:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:11:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:11:17 INFO - Return code: 0 10:11:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:11:17 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')]} 10:11:17 INFO - https://queue.taskcluster.net/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:11:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:11:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:11:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:11:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:11:18 INFO - Downloaded 18149785 bytes. 10:11:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:11:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:11:21 INFO - Return code: 0 10:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:11:21 INFO - https://queue.taskcluster.net/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:11:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:11:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:11:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:11:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:11:22 INFO - Downloaded 10840152 bytes. 10:11:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:11:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:11:23 INFO - Return code: 0 10:11: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')]} 10:11:23 INFO - https://queue.taskcluster.net/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:11:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:11:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:11:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 10:11:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 10:11:25 INFO - Downloaded 109782101 bytes. 10:11:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:11:25 INFO - mkdir: /builds/slave/test/properties 10:11:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:11:25 INFO - Writing to file /builds/slave/test/properties/build_url 10:11:25 INFO - Contents: 10:11:25 INFO - build_url:https://queue.taskcluster.net/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:11:27 INFO - Running post-action listener: _resource_record_post_action 10:11:27 INFO - Running post-action listener: set_extra_try_arguments 10:11:27 INFO - ##### 10:11:27 INFO - ##### Finished download-and-extract step (success) 10:11:27 INFO - ##### 10:11:27 INFO - ##### 10:11:27 INFO - ##### Running populate-webroot step. 10:11:27 INFO - ##### 10:11:27 INFO - Running pre-action listener: _resource_record_pre_action 10:11:27 INFO - Running main action method: populate_webroot 10:11:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:11:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:11:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:11:27 INFO - '--tppagecycles', 10:11:27 INFO - '7'], 10:11:27 INFO - 'tests': ['tsvgm']}}, 10:11:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:11:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:11:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:11:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:11:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:11:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:11:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:27 INFO - 'talos_options': ['--disable-e10s'], 10:11:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:11:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:11:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:27 INFO - 'talos_options': ['--disable-e10s'], 10:11:27 INFO - 'tests': ['damp', 'tps']}, 10:11:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:27 INFO - 'tests': ['damp', 'tps']}, 10:11:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:11:27 INFO - 'tests': ['dromaeo_dom']}, 10:11:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:11:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:11:27 INFO - 'tests': ['a11yr', 10:11:27 INFO - 'ts_paint', 10:11:27 INFO - 'tpaint', 10:11:27 INFO - 'sessionrestore', 10:11:27 INFO - 'sessionrestore_no_auto_restore', 10:11:27 INFO - 'tabpaint']}, 10:11:27 INFO - 'other-e10s': {'tests': ['a11yr', 10:11:27 INFO - 'ts_paint', 10:11:27 INFO - 'tpaint', 10:11:27 INFO - 'sessionrestore', 10:11:27 INFO - 'sessionrestore_no_auto_restore', 10:11:27 INFO - 'tabpaint']}, 10:11:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:11:27 INFO - 'ts_paint', 10:11:27 INFO - 'tpaint', 10:11:27 INFO - 'sessionrestore', 10:11:27 INFO - 'sessionrestore_no_auto_restore', 10:11:27 INFO - 'tabpaint']}, 10:11:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:11:27 INFO - 'ts_paint', 10:11:27 INFO - 'tpaint', 10:11:27 INFO - 'sessionrestore', 10:11:27 INFO - 'sessionrestore_no_auto_restore', 10:11:27 INFO - 'tabpaint']}, 10:11:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:11:27 INFO - 'tests': ['a11yr', 10:11:27 INFO - 'ts_paint', 10:11:27 INFO - 'tpaint', 10:11:27 INFO - 'sessionrestore', 10:11:27 INFO - 'sessionrestore_no_auto_restore', 10:11:27 INFO - 'tabpaint']}, 10:11:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:11:27 INFO - 'tests': ['a11yr', 10:11:27 INFO - 'ts_paint', 10:11:27 INFO - 'tpaint', 10:11:27 INFO - 'sessionrestore', 10:11:27 INFO - 'sessionrestore_no_auto_restore', 10:11:27 INFO - 'tabpaint']}, 10:11:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:11:27 INFO - 'tests': ['tsvgx', 10:11:27 INFO - 'tsvgr_opacity', 10:11:27 INFO - 'tart', 10:11:27 INFO - 'tscrollx', 10:11:27 INFO - 'cart']}, 10:11:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:11:27 INFO - 'tsvgr_opacity', 10:11:27 INFO - 'tart', 10:11:27 INFO - 'tscrollx', 10:11:27 INFO - 'cart']}, 10:11:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:27 INFO - 'talos_options': ['--disable-e10s'], 10:11:27 INFO - 'tests': ['tp5o']}, 10:11:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:27 INFO - 'tests': ['tp5o']}, 10:11:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:27 INFO - 'talos_options': ['--disable-e10s', 10:11:27 INFO - '--xperf_path', 10:11:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:11:27 INFO - 'tests': ['tp5n']}, 10:11:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:27 INFO - 'talos_options': ['--xperf_path', 10:11:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:11:27 INFO - 'tests': ['tp5n']}}, 10:11:27 INFO - 'talos.zip': {'path': '', 10:11:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:11:27 INFO - Running post-action listener: _resource_record_post_action 10:11:27 INFO - ##### 10:11:27 INFO - ##### Finished populate-webroot step (success) 10:11:27 INFO - ##### 10:11:27 INFO - ##### 10:11:27 INFO - ##### Running create-virtualenv step. 10:11:27 INFO - ##### 10:11:27 INFO - Running pre-action listener: _resource_record_pre_action 10:11:27 INFO - Running main action method: create_virtualenv 10:11:27 INFO - Creating virtualenv /builds/slave/test/build/venv 10:11:27 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 10:11:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:11:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:11:27 INFO - Using real prefix '/tools/python27' 10:11:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:11:28 INFO - Installing distribute.............................................................................................................................................................................................done. 10:11:31 INFO - Installing pip.................done. 10:11:31 INFO - Return code: 0 10:11:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:11: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')]} 10:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11: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')]} 10:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd984e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.mfVWXV13Gl/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.AtbnJrX1CA/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 10:11:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:11:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mfVWXV13Gl/Render', 10:11:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:31 INFO - 'HOME': '/Users/cltbld', 10:11:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:31 INFO - 'LOGNAME': 'cltbld', 10:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:31 INFO - 'MOZ_NO_REMOTE': '1', 10:11:31 INFO - 'NO_EM_RESTART': '1', 10:11:31 INFO - 'PAGER': '/bin/cat', 10:11:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:31 INFO - 'PWD': '/builds/slave/test', 10:11:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:31 INFO - 'SHELL': '/bin/bash', 10:11:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AtbnJrX1CA/Listeners', 10:11:31 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:11:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:31 INFO - 'USER': 'cltbld', 10:11:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:31 INFO - 'XPC_FLAGS': '0x0', 10:11:31 INFO - 'XPC_SERVICE_NAME': '0', 10:11:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:31 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 10:11:31 INFO - Cleaning up... 10:11:31 INFO - Return code: 0 10:11:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 10:11: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')]} 10:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11: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')]} 10:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd984e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.mfVWXV13Gl/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.AtbnJrX1CA/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 10:11:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 10:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 10:11:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mfVWXV13Gl/Render', 10:11:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:31 INFO - 'HOME': '/Users/cltbld', 10:11:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:31 INFO - 'LOGNAME': 'cltbld', 10:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:31 INFO - 'MOZ_NO_REMOTE': '1', 10:11:31 INFO - 'NO_EM_RESTART': '1', 10:11:31 INFO - 'PAGER': '/bin/cat', 10:11:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:31 INFO - 'PWD': '/builds/slave/test', 10:11:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:31 INFO - 'SHELL': '/bin/bash', 10:11:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AtbnJrX1CA/Listeners', 10:11:31 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:11:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:31 INFO - 'USER': 'cltbld', 10:11:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:31 INFO - 'XPC_FLAGS': '0x0', 10:11:31 INFO - 'XPC_SERVICE_NAME': '0', 10:11:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:31 INFO - Downloading/unpacking psutil>=0.7.1 10:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:11:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:11:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:11:34 INFO - Installing collected packages: psutil 10:11:34 INFO - Running setup.py install for psutil 10:11:34 INFO - building 'psutil._psutil_osx' extension 10:11:34 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 10:11:34 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 10:11:34 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 10:11:34 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 10:11:34 INFO - building 'psutil._psutil_posix' extension 10:11:34 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 10:11:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:11:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:11:34 INFO - ^ 10:11:34 INFO - 1 warning generated. 10:11:34 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 10:11:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:11:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:11:34 INFO - Successfully installed psutil 10:11:34 INFO - Cleaning up... 10:11:35 INFO - Return code: 0 10:11:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 10:11:35 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')]} 10:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:35 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')]} 10:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:35 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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd984e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.mfVWXV13Gl/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.AtbnJrX1CA/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 10:11:35 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 10:11:35 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 10:11:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mfVWXV13Gl/Render', 10:11:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:35 INFO - 'HOME': '/Users/cltbld', 10:11:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:35 INFO - 'LOGNAME': 'cltbld', 10:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:35 INFO - 'MOZ_NO_REMOTE': '1', 10:11:35 INFO - 'NO_EM_RESTART': '1', 10:11:35 INFO - 'PAGER': '/bin/cat', 10:11:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:35 INFO - 'PWD': '/builds/slave/test', 10:11:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:35 INFO - 'SHELL': '/bin/bash', 10:11:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AtbnJrX1CA/Listeners', 10:11:35 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:11:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:35 INFO - 'USER': 'cltbld', 10:11:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:35 INFO - 'XPC_FLAGS': '0x0', 10:11:35 INFO - 'XPC_SERVICE_NAME': '0', 10:11:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:11:37 INFO - Installing collected packages: mozsystemmonitor 10:11:37 INFO - Running setup.py install for mozsystemmonitor 10:11:37 INFO - Successfully installed mozsystemmonitor 10:11:37 INFO - Cleaning up... 10:11:37 INFO - Return code: 0 10:11:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd984e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.mfVWXV13Gl/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.AtbnJrX1CA/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 10:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:11:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mfVWXV13Gl/Render', 10:11:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:37 INFO - 'HOME': '/Users/cltbld', 10:11:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:37 INFO - 'LOGNAME': 'cltbld', 10:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:37 INFO - 'MOZ_NO_REMOTE': '1', 10:11:37 INFO - 'NO_EM_RESTART': '1', 10:11:37 INFO - 'PAGER': '/bin/cat', 10:11:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:37 INFO - 'PWD': '/builds/slave/test', 10:11:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:37 INFO - 'SHELL': '/bin/bash', 10:11:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AtbnJrX1CA/Listeners', 10:11:37 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:11:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:37 INFO - 'USER': 'cltbld', 10:11:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:37 INFO - 'XPC_FLAGS': '0x0', 10:11:37 INFO - 'XPC_SERVICE_NAME': '0', 10:11:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:38 INFO - Downloading/unpacking blobuploader==1.2.4 10:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:40 INFO - Downloading blobuploader-1.2.4.tar.gz 10:11:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:11:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:11: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 10:11: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 10:11: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 10:11: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 10:11: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 10:11: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 10:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:11:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:41 INFO - Downloading docopt-0.6.1.tar.gz 10:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:11:41 INFO - Installing collected packages: blobuploader, requests, docopt 10:11:41 INFO - Running setup.py install for blobuploader 10:11:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:11:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:11:42 INFO - Running setup.py install for requests 10:11:42 INFO - Running setup.py install for docopt 10:11:42 INFO - Successfully installed blobuploader requests docopt 10:11:42 INFO - Cleaning up... 10:11:42 INFO - Return code: 0 10:11:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:11:42 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')]} 10:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:42 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')]} 10:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:42 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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd984e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.mfVWXV13Gl/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.AtbnJrX1CA/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 10:11:42 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 10:11:42 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 10:11:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mfVWXV13Gl/Render', 10:11:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:42 INFO - 'HOME': '/Users/cltbld', 10:11:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:42 INFO - 'LOGNAME': 'cltbld', 10:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:42 INFO - 'MOZ_NO_REMOTE': '1', 10:11:42 INFO - 'NO_EM_RESTART': '1', 10:11:42 INFO - 'PAGER': '/bin/cat', 10:11:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:42 INFO - 'PWD': '/builds/slave/test', 10:11:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:42 INFO - 'SHELL': '/bin/bash', 10:11:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AtbnJrX1CA/Listeners', 10:11:42 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:11:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:42 INFO - 'USER': 'cltbld', 10:11:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:42 INFO - 'XPC_FLAGS': '0x0', 10:11:42 INFO - 'XPC_SERVICE_NAME': '0', 10:11:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:11:43 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-CHryvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:11:43 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-mpbCn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:11:43 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-kiaNcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:11:43 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-UjJspJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:11:43 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-WN7wkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:11:43 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-mHYE8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:11:43 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-K0UDkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:11:43 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-_OFP8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:11:43 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-fhfkDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:11:43 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Du1twr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:11:44 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-8kclNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:11:44 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-a0L16I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:11:44 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-hG4c9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:11:44 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-_0oVao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:44 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-DNUEwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:11:44 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-wf6LDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:11:44 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-pZD4T6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:11:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:11:44 INFO - Running setup.py install for manifestparser 10:11:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:11:44 INFO - Running setup.py install for mozcrash 10:11:45 INFO - Running setup.py install for mozdebug 10:11:45 INFO - Running setup.py install for mozdevice 10:11:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:11:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:11:45 INFO - Running setup.py install for mozfile 10:11:45 INFO - Running setup.py install for mozhttpd 10:11:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:11:45 INFO - Running setup.py install for mozinfo 10:11:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:11:45 INFO - Running setup.py install for mozInstall 10:11:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:11:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:11:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:11:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:11:45 INFO - Running setup.py install for mozleak 10:11:46 INFO - Running setup.py install for mozlog 10:11:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:11:46 INFO - Running setup.py install for moznetwork 10:11:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:11:46 INFO - Running setup.py install for mozprocess 10:11:46 INFO - Running setup.py install for mozprofile 10:11:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:11:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:11:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:11:46 INFO - Running setup.py install for mozrunner 10:11:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:11:46 INFO - Running setup.py install for mozscreenshot 10:11:47 INFO - Running setup.py install for moztest 10:11:47 INFO - Running setup.py install for mozversion 10:11:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:11:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:11:47 INFO - Cleaning up... 10:11:47 INFO - Return code: 0 10:11:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:11: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')]} 10:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11: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')]} 10:11:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:47 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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd984e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.mfVWXV13Gl/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.AtbnJrX1CA/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 10:11:47 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 10:11:47 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 10:11:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mfVWXV13Gl/Render', 10:11:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:47 INFO - 'HOME': '/Users/cltbld', 10:11:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:47 INFO - 'LOGNAME': 'cltbld', 10:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:47 INFO - 'MOZ_NO_REMOTE': '1', 10:11:47 INFO - 'NO_EM_RESTART': '1', 10:11:47 INFO - 'PAGER': '/bin/cat', 10:11:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:47 INFO - 'PWD': '/builds/slave/test', 10:11:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:47 INFO - 'SHELL': '/bin/bash', 10:11:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AtbnJrX1CA/Listeners', 10:11:47 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:11:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:47 INFO - 'USER': 'cltbld', 10:11:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:47 INFO - 'XPC_FLAGS': '0x0', 10:11:47 INFO - 'XPC_SERVICE_NAME': '0', 10:11:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:11:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-jfI0BO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:11:47 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)) 10:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:11:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-iUJpq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:11:47 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)) 10:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:11:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-gS76Re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:11:47 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)) 10:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:11:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-xq4yrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:11:48 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)) 10:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:11:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-l5hzHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:11:48 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)) 10:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:11:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-aBkRnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:11:48 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)) 10:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:11:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-nXk8Nh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:11:48 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)) 10:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:11:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-vw4FKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:11:48 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)) 10:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:11:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-zErv2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:11:48 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)) 10:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:11:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-8wUxRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:11:48 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)) 10:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:11:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-be5PG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:11:48 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)) 10:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:11:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-BmMaoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:11:48 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)) 10:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:11:48 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-JZNV29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:11:48 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)) 10:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:11:49 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-EYRAhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:11:49 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)) 10:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:49 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-GOWPWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:49 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)) 10:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:11:49 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-FdyPT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:11:49 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)) 10:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:11:49 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-3lcWcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:11:49 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)) 10:11:49 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)) 10:11:49 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)) 10:11:49 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)) 10:11:49 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)) 10:11:49 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)) 10:11:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:11:49 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 10:11:49 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 10:11:49 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 10:11:49 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 10:11:49 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 10:11:49 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 10:11:51 INFO - Downloading blessings-1.6.tar.gz 10:11:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:11:51 INFO - Installing collected packages: blessings 10:11:51 INFO - Running setup.py install for blessings 10:11:52 INFO - Successfully installed blessings 10:11:52 INFO - Cleaning up... 10:11:52 INFO - Return code: 0 10:11:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:11:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:11:52 INFO - Reading from file tmpfile_stdout 10:11:52 INFO - Current package versions: 10:11:52 INFO - blessings == 1.6 10:11:52 INFO - blobuploader == 1.2.4 10:11:52 INFO - docopt == 0.6.1 10:11:52 INFO - manifestparser == 1.1 10:11:52 INFO - mozInstall == 1.12 10:11:52 INFO - mozcrash == 0.17 10:11:52 INFO - mozdebug == 0.1 10:11:52 INFO - mozdevice == 0.48 10:11:52 INFO - mozfile == 1.2 10:11:52 INFO - mozhttpd == 0.7 10:11:52 INFO - mozinfo == 0.9 10:11:52 INFO - mozleak == 0.1 10:11:52 INFO - mozlog == 3.1 10:11:52 INFO - moznetwork == 0.27 10:11:52 INFO - mozprocess == 0.22 10:11:52 INFO - mozprofile == 0.28 10:11:52 INFO - mozrunner == 6.11 10:11:52 INFO - mozscreenshot == 0.1 10:11:52 INFO - mozsystemmonitor == 0.0 10:11:52 INFO - moztest == 0.7 10:11:52 INFO - mozversion == 1.4 10:11:52 INFO - psutil == 3.1.1 10:11:52 INFO - requests == 1.2.3 10:11:52 INFO - wsgiref == 0.1.2 10:11:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:11: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')]} 10:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11: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')]} 10:11:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:52 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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd984e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.mfVWXV13Gl/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.AtbnJrX1CA/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 10:11:52 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 10:11:52 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 10:11:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mfVWXV13Gl/Render', 10:11:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:52 INFO - 'HOME': '/Users/cltbld', 10:11:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:52 INFO - 'LOGNAME': 'cltbld', 10:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:52 INFO - 'MOZ_NO_REMOTE': '1', 10:11:52 INFO - 'NO_EM_RESTART': '1', 10:11:52 INFO - 'PAGER': '/bin/cat', 10:11:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:52 INFO - 'PWD': '/builds/slave/test', 10:11:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:52 INFO - 'SHELL': '/bin/bash', 10:11:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AtbnJrX1CA/Listeners', 10:11:52 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:11:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:52 INFO - 'USER': 'cltbld', 10:11:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:52 INFO - 'XPC_FLAGS': '0x0', 10:11:52 INFO - 'XPC_SERVICE_NAME': '0', 10:11:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:52 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)) 10:11:52 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)) 10:11:52 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)) 10:11:52 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)) 10:11:52 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)) 10:11:52 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)) 10:11:52 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)) 10:11:52 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)) 10:11:52 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)) 10:11:52 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)) 10:11:52 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)) 10:11:52 INFO - Cleaning up... 10:11:52 INFO - Return code: 0 10:11:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:11: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')]} 10:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11: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')]} 10:11:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd984e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.mfVWXV13Gl/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.AtbnJrX1CA/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 10:11:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:11:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mfVWXV13Gl/Render', 10:11:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:52 INFO - 'HOME': '/Users/cltbld', 10:11:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:52 INFO - 'LOGNAME': 'cltbld', 10:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:52 INFO - 'MOZ_NO_REMOTE': '1', 10:11:52 INFO - 'NO_EM_RESTART': '1', 10:11:52 INFO - 'PAGER': '/bin/cat', 10:11:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:52 INFO - 'PWD': '/builds/slave/test', 10:11:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:52 INFO - 'SHELL': '/bin/bash', 10:11:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AtbnJrX1CA/Listeners', 10:11:52 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:11:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:52 INFO - 'USER': 'cltbld', 10:11:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:52 INFO - 'XPC_FLAGS': '0x0', 10:11:52 INFO - 'XPC_SERVICE_NAME': '0', 10:11:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:53 INFO - Downloading/unpacking jsonschema 10:11:53 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 10:11:53 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 10:11:53 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 10:11:53 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 10:11:53 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 10:11:53 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 10:11:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:11:55 INFO - Downloading/unpacking functools32 (from jsonschema) 10:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:55 INFO - Downloading functools32-3.2.3-2.tar.gz 10:11:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:11:55 INFO - warning: no files found matching '*.txt' 10:11:55 INFO - no previously-included directories found matching 'build' 10:11:55 INFO - no previously-included directories found matching 'dist' 10:11:55 INFO - no previously-included directories found matching '.git*' 10:11:55 INFO - Installing collected packages: jsonschema, functools32 10:11:55 INFO - Running setup.py install for functools32 10:11:56 INFO - warning: no files found matching '*.txt' 10:11:56 INFO - no previously-included directories found matching 'build' 10:11:56 INFO - no previously-included directories found matching 'dist' 10:11:56 INFO - no previously-included directories found matching '.git*' 10:11:56 INFO - Successfully installed jsonschema functools32 10:11:56 INFO - Cleaning up... 10:11:56 INFO - Return code: 0 10:11:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:11: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')]} 10:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11: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')]} 10:11:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:56 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 0x1030d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8bd984e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, '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.mfVWXV13Gl/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.AtbnJrX1CA/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 10:11:56 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 10:11:56 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 10:11:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mfVWXV13Gl/Render', 10:11:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:56 INFO - 'HOME': '/Users/cltbld', 10:11:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:56 INFO - 'LOGNAME': 'cltbld', 10:11:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:56 INFO - 'MOZ_NO_REMOTE': '1', 10:11:56 INFO - 'NO_EM_RESTART': '1', 10:11:56 INFO - 'PAGER': '/bin/cat', 10:11:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:56 INFO - 'PWD': '/builds/slave/test', 10:11:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:56 INFO - 'SHELL': '/bin/bash', 10:11:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AtbnJrX1CA/Listeners', 10:11:56 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:11:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:56 INFO - 'USER': 'cltbld', 10:11:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:56 INFO - 'XPC_FLAGS': '0x0', 10:11:56 INFO - 'XPC_SERVICE_NAME': '0', 10:11:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:56 INFO - Downloading/unpacking flake8 10:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:11:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:11: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 10:11: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 10:11: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 10:11: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 10:11: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 10:11: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 10:11:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:11:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:12:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:12:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:12:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:12:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:12:00 INFO - no previously-included directories found matching 'docs/_build' 10:12:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:12:00 INFO - Running setup.py install for pep8 10:12:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:12:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:12:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:12:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:12:00 INFO - no previously-included directories found matching 'docs/_build' 10:12:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:12:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:12:00 INFO - Cleaning up... 10:12:00 INFO - Return code: 0 10:12:00 INFO - Running post-action listener: _resource_record_post_action 10:12:00 INFO - Running post-action listener: _start_resource_monitoring 10:12:00 INFO - Starting resource monitoring. 10:12:00 INFO - ##### 10:12:00 INFO - ##### Finished create-virtualenv step (success) 10:12:00 INFO - ##### 10:12:00 INFO - ##### 10:12:00 INFO - ##### Running install step. 10:12:00 INFO - ##### 10:12:00 INFO - Running pre-action listener: _resource_record_pre_action 10:12:00 INFO - Running main action method: install 10:12:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:12:00 INFO - Reading from file tmpfile_stdout 10:12:00 INFO - Detecting whether we're running mozinstall >=1.0... 10:12:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:12:00 INFO - Reading from file tmpfile_stdout 10:12:00 INFO - Output received: 10:12:00 INFO - Usage: mozinstall [options] installer 10:12:00 INFO - Options: 10:12:00 INFO - -h, --help show this help message and exit 10:12:00 INFO - -d DEST, --destination=DEST 10:12:00 INFO - Directory to install application into. [default: 10:12:00 INFO - "/builds/slave/test"] 10:12:00 INFO - --app=APP Application being installed. [default: firefox] 10:12:00 INFO - mkdir: /builds/slave/test/build/application 10:12:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:12:34 INFO - Reading from file tmpfile_stdout 10:12:34 INFO - Output received: 10:12:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:12:34 INFO - Running post-action listener: _resource_record_post_action 10:12:34 INFO - ##### 10:12:34 INFO - ##### Finished install step (success) 10:12:34 INFO - ##### 10:12:34 INFO - ##### 10:12:34 INFO - ##### Running run-tests step. 10:12:34 INFO - ##### 10:12:34 INFO - Running pre-action listener: _resource_record_pre_action 10:12:34 INFO - Running main action method: run_tests 10:12:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:12:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:12:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:12:34 INFO - 2016-05-10 10:12:34.070 screenresolution[2182:30073] starting screenresolution argv=screenresolution get 10:12:34 INFO - 2016-05-10 10:12:34.072 screenresolution[2182:30073] Display 0: 1600x1200x32@60 10:12:34 INFO - 2016-05-10 10:12:34.078 screenresolution[2183:30078] starting screenresolution argv=screenresolution list 10:12:34 INFO - Available Modes on Display 0 10:12:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:12:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:12:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:12:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:12:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:12:34 INFO - Intel Iris: 10:12:34 INFO - Chipset Model: Intel Iris 10:12:34 INFO - Type: GPU 10:12:34 INFO - Bus: Built-In 10:12:34 INFO - VRAM (Dynamic, Max): 1536 MB 10:12:34 INFO - Vendor: Intel (0x8086) 10:12:34 INFO - Device ID: 0x0a2e 10:12:34 INFO - Revision ID: 0x0009 10:12:34 INFO - Displays: 10:12:34 INFO - Display: 10:12:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:12:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:12:34 INFO - Display Serial Number: 1600x1200 60 10:12:34 INFO - Main Display: Yes 10:12:34 INFO - Mirror: Off 10:12:34 INFO - Online: Yes 10:12:34 INFO - Rotation: Supported 10:12:34 INFO - Return code: 0 10:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:12:34 INFO - Python 2.7.3 10:12:34 INFO - Return code: 0 10:12:34 INFO - grabbing minidump binary from tooltool 10:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:12:34 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 0x102f7bd30>, '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 0x102d9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f72b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:12:34 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 10:12:34 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 10:12:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:12:34 INFO - Return code: 0 10:12:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:12:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:12:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:12:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:12:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:12:35 INFO - Return code: 0 10:12:35 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/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.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 10:12:35 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/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.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 10:12:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mfVWXV13Gl/Render', 10:12:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:35 INFO - 'HOME': '/Users/cltbld', 10:12:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:35 INFO - 'LOGNAME': 'cltbld', 10:12:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:12:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:35 INFO - 'MOZ_NO_REMOTE': '1', 10:12:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:12:35 INFO - 'NO_EM_RESTART': '1', 10:12:35 INFO - 'PAGER': '/bin/cat', 10:12:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:35 INFO - 'PWD': '/builds/slave/test', 10:12:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:12:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:35 INFO - 'SHELL': '/bin/bash', 10:12:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AtbnJrX1CA/Listeners', 10:12:35 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:12:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:35 INFO - 'USER': 'cltbld', 10:12:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:35 INFO - 'XPC_FLAGS': '0x0', 10:12:35 INFO - 'XPC_SERVICE_NAME': '0', 10:12:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:35 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/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.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 10:12:35 INFO - mozversion application_buildid: 20160510044311 10:12:35 INFO - mozversion application_changeset: 2f9351bae69d056e4615d21dda6bf42fec5d16b7 10:12:35 INFO - mozversion application_display_name: Nightly 10:12:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:12:35 INFO - mozversion application_name: Firefox 10:12:35 INFO - mozversion application_remotingname: firefox 10:12:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:12:35 INFO - mozversion application_vendor: Mozilla 10:12:35 INFO - mozversion application_version: 49.0a1 10:12:35 INFO - mozversion platform_buildid: 20160510044311 10:12:35 INFO - mozversion platform_changeset: 2f9351bae69d056e4615d21dda6bf42fec5d16b7 10:12:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:12:35 INFO - mozversion platform_version: 49.0a1 10:12:35 INFO - using testdate: 1462900355 10:12:35 INFO - actual date: 1462900355 10:12:35 INFO - starting webserver on 'localhost:53409' 10:12:35 INFO - SUITE-START | Running 2 tests 10:12:35 INFO - TEST-START | dromaeo_css 10:12:35 INFO - operating with platform_type : mac_ 10:12:35 INFO - Initialising browser for dromaeo_css test... 10:12:35 INFO - TEST-INFO | started process 2201 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpc52dVS/profile http://localhost:53409/getInfo.html) 10:12:38 INFO - PROCESS | 2201 | __metrics Screen width/height:1600/1200 10:12:38 INFO - PROCESS | 2201 | colorDepth:24 10:12:38 INFO - PROCESS | 2201 | Browser inner width/height: 1024/689 10:12:38 INFO - PROCESS | 2201 | __metrics 10:12:38 ERROR - PROCESS | 2201 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 10:12:39 INFO - TEST-INFO | 2201: exit 0 10:12:39 INFO - Browser initialized. 10:12:39 INFO - Running cycle 1/1 for dromaeo_css test... 10:12:39 INFO - TEST-INFO | started process 2204 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpc52dVS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:12:51 INFO - PROCESS | 2204 | RSS: Main: 249982976 10:12:51 INFO - PROCESS | 2204 | 10:13:41 INFO - PROCESS | 2204 | 1462900421120 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] 10:15:51 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:53409/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53409/tests/dromaeo/cssquery-ext.html) 10:15:52 INFO - PROCESS | 2204 | RSS: Main: 262090752 10:15:52 INFO - PROCESS | 2204 | 10:18:54 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:53409/tests/dromaeo/cssquery-ext.html (next: http://localhost:53409/tests/dromaeo/cssquery-jquery.html) 10:18:55 INFO - PROCESS | 2204 | RSS: Main: 273715200 10:18:55 INFO - PROCESS | 2204 | 10:21:57 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:53409/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53409/tests/dromaeo/cssquery-mootools.html) 10:21:57 INFO - PROCESS | 2204 | RSS: Main: 275738624 10:21:57 INFO - PROCESS | 2204 | 10:24:59 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:53409/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53409/tests/dromaeo/cssquery-prototype.html) 10:25:00 INFO - PROCESS | 2204 | RSS: Main: 277340160 10:25:00 INFO - PROCESS | 2204 | 10:28:03 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:53409/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53409/tests/dromaeo/cssquery-yui.html) 10:28:03 INFO - PROCESS | 2204 | RSS: Main: 280199168 10:28:03 INFO - PROCESS | 2204 | 10:31:06 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:53409/tests/dromaeo/cssquery-yui.html (next: http://localhost:53409/tests/dromaeo/cssquery-dojo.html) 10:31:06 INFO - PROCESS | 2204 | RSS: Main: 281534464 10:31:06 INFO - PROCESS | 2204 | 10:31:06 INFO - PROCESS | 2204 | __start_tp_report 10:31:06 INFO - PROCESS | 2204 | _x_x_mozilla_page_load 10:31:06 INFO - PROCESS | 2204 | _x_x_mozilla_page_load_details 10:31:06 INFO - PROCESS | 2204 | |i|pagename|runs| 10:31:06 INFO - PROCESS | 2204 | |0;dojo.html;2902.579126026726;3293.423650861099;3437.1922598189435;3500.6499350064996;3502.282032183403;5650.689212093338;5680.772769089232;5714.799982000631;5726.3414707308675;5782.421757824226;5572.804951826687;5674.148877668342;5717.456841600041;5736.4550367715;5810.883782324351;5114.795408183668;5165.457626949173;5193.402758682762;5244.318238628973;5264.289320941682;630.7437352483304;5211.635185537014;5234.979179060085;5241.3186285782795;5263.947360526384;5125.974370128126;5140.126178549655;5208.453112423198;5208.713520756357;5240.711760853152;5130.30740849988;5200.791968321262;5210.036143313499;5266.92099618506;5270.2358158067245;5118.948810511884;5129.846104616868;5158.458361871987;5172.27588137661;5192.688438693653;5485.4788795064405;5605.7197140142825;5621.887562248773;5623.297087864017;5643.068893632546;5998.880022399571;6117.675163990739;6255.655938923357;6255.937440625627;6256.15541901842;6152.354002829683;6241.126242326078;6261.749530018794;6275.937240627581;6278.905816412757;5800.970995144998;5805;5806.361300256926;5827.679477628729;5869;6176.536759743038;6181.258249010058;6194.5354098442795;6280.497560195265;6303.36966303366;6146.862830376305;6159.630422174684;6165.290991536031;6171.938280617136;6189.6286222826775;6208.72060757267;6263.214983729707;6265.749370025239;6270.7491700331475;6302.149209856702;5751.705866180076;6112.321110680185;6139.154842350747;6186.19579454668;6238.033104868752;6055.1220073090035;6146.938530614637;6181.505479561624;6188.1027251048345;6225.128482012522;4535.365048893158;4869.9269510957365;4957.628177886673;4967.279744437109;4968.950310496849;4307.353896915425;4344.630706389929;4381.014271788822;4405.427294451701;4413.558644135561;4539.500654927955;5702.486776190163;5786;5843.853903652443;5849.736761845727;4751.833685821049;6198.783042593487;6251.593646412968;6251.749930002749;6266.373362663697;5636.887262254732;5718.656880587178;5783.4794868461195;5803.767849286066;5804.796832110856;5411.594130440233;5421.213923981;5429.5656347492895;5464.781408743605;5467.398586155519;6365.936340636627;6372.426481616677;6411.1024456576115;6412.519061070345;6468.094466774655;5037.471065538212;6209.348018457997;6226.470749986302;6228.190335256388;6235.251769787655;6337.334579869047;6364.395382438661;6376.840578985562;6390.680465976589;6402.711877965596;6189.319174890668;6207.162033125651;6225.315215326401;6239.594426362272;6245.125682404558;6134.35589263121;6180.505559555315;6188.938110618837;6219.40915613016;6308.621482711052;6118.5411094169;6155.630662160284;6156.661383623944;6157.538184636081;6161.815145545641;5623.465770751772;6117.1130186123655;6151.907721384094;6159.84600384994;6174.289956655042;5716;5755.683437410982;5802.65184088956;5823.650580965155;5911.674857882857;6141;6145.938540614537;6146.016637338004;6153;6153.707721378561;6006.309274433322;6017.729202185825;6022.819315420545;6029.939700602938;6042.607230530001 10:31:06 INFO - PROCESS | 2204 | |1;ext.html;474.4401606104797;494.45115921327323;509.18276166752383;513.7970501651847;573.9426057394264;31820;40931.79534102329;41026.794866025666;41057.178856422855;41139.99999999993;41608.79195604018;41979.58020419803;42037.36943945843;42042.159156816844;42201.78899105508;9567.330286879922;9716.757081072994;9744.366616169962;9761.267904907125;9778.755531111708;7601.923980760204;9625.470599117045;9697.660581879636;9739.074787895139;9969.451680157625;9676.225901927864;9688.66089686861;9719.416834989888;9747.171490423323;9751.41491510508;9655.082767137146;9670.468124253162;9696.854547181832;9698.51507424626;9745.512724363824;7815.609219539057;8581.485110893367;9532.475713835805;9646.855297170549;9649.807003859954;32342.676573234203;39194.41208381877;39340.60659393398;39475.605243947764;39541.40687889655;3121.7658675599423;3179.5071763876635;3184.2357834119875;3606.3688854450365;4052.351623740217;27391.04131355393;29534.704652953624;29710.702892971225;29718.161595296762;29804.254893627614;2703.026910312286;2718.619393284942;2845.6585209774753;2856.0432255194537;2859.0850927703145;506306;508617;512286;514125;516267.418662908;32818.83590582032;33366.3326733464;64581;65448;65735.67132164404;36974.8151259242;37295.25409491795;37296.627033729856;37445.438318425244;37511.624883751356;15100.093994360373;15342.693146137015;15470.767938480933;15484.845151548565;15522;12675.17611355259;12771;12994.675133121747;13071.215727056217;13118.147320424141;8100.392470564731;8267.710630127916;8291.295239904555;8298.170182981654;8344.541050242295;3426.845791939319;3440.449528075546;3443.9483407748903;3468.7051600613727;3506;991.6430085169334;1015.8069966706303;1019.3578045831227;1141.390639198726;1152.7002979225463;29740.107796766133;29932.850335748182;29955.550666740015;29988.100356989326;30089.849550752107;1100.5322737836389;1111.522045520434;1170.1925671286785;1190.5118901250446;1273.1024627637494;13724.65688357799;15070.397184112731;15114.470993515175;15379.923100384427;15659.608509787118;664382;690872;695625;698490;699567;59179;60277.39722602806;60363.39636603666;60597.78804423887;60623;1371.376023909139;1421.6374824419913;1428.7783944727316;1489.7765335199806;1492.5671555249073;28519.42961140807;28600.57099143556;28711.189483489365;29047.419051618428;29659;51585.484145158065;51642.741786290826;51820.22269665883;52262.693432664484;52322;578074;579725;588326;589090;590553;26914.461710766067;27163.049293274642;27189.320266993484;27360.315992100357;27496.450070998868;45312.77343613261;45408.54591454043;45805.08389832118;45812;46080.539194608966;38157.427638586;38210.853674389815;38512.80743596376;38874.41688374733;38966.6103338963;37181.62818371782;37511.81244093762;37569.87290381293;38191;38369;32196.678033220305;37316.813415932746;37465.625343747306;38144.237115258096;38270;32546.02361929146;33378.666213338525;33384;33474.832625836716;33599.664003359656;23397.649035264825;23462.64806027945;24826.875865620557;24865.378365540284;25400 10:31:06 INFO - PROCESS | 2204 | |2;jquery.html;397.00748129675793;401.5783427401229;403.10912882529607;405.1188664654377;406.18559787625804;19623.215071397128;20015.89912554809;20794;21373;21444.463888402777;19118.374938130277;19799.703004454946;20953.95230238486;20973.895130524324;21019.789802102012;4999.550040496355;5373.408925018245;5379.300690910176;5421.1326187809955;5429.837104886859;4575.977120114411;5295.947040529603;5297.17893726473;5895.4399332063385;5965.433283838029;5456.61803673743;5540.307461567304;5888.793892213774;5916.822495325147;6028.879422411571;5968.97015514924;6056.303525094604;6084.300305464862;6118.3269840317525;6126.571140020201;5554.389017208104;5814.47669709724;5823.330317013555;5932.961731696936;5945.732442040118;13370.933145334307;13507.122037067558;13693.178409295475;13706.177629342272;13855.376508057161;2152.9461763455884;2223.3441134865093;2277.4192580891768;2312.2369618026178;2347.788699017097;9943.204543636492;10056.798864022752;10144.847827282596;10281.588736450532;10293.742656433575;1736.5224563245083;1753.2636292757072;1763.2770564068671;1782.3138091834608;1787.8480329171905;122522;128055;129703;129829.35085324512;133146;9520.476373799507;11866.703332416759;11871.0503159747;11988;12115.273083615011;15334.386624535116;15335.079895206323;15371.538853834403;15376.692466150615;15447.227638618024;8651.264642505457;8844.867326990101;8854.734357969142;8871.689490867788;8972.506512141896;8679.739607811776;8785.472871627722;8794.956025219833;8921.241694456045;8959.686410975715;4612.654050946193;4696.342512048316;4698.083873644607;4711.717296962193;4770.069836381872;1911.3405874973112;1999.3302243748508;2004.9398518044482;2036.7046778217075;2039.6838490034067;922.7877588154762;977.0815433492494;1002.333448256901;1012.1649639047815;1020.3010937507682;17633.294668213126;19544.31594894172;19957.301494447624;20031.29890453827;20333.389998300074;938.7559234598917;942.2273735536794;961.4471678784727;972.0571046085286;989.4459102902398;9071.866016747907;10285.485725713685;10612.734681633021;10848.837267440995;10953.780924381468;618821;627324;627691;646584;647083;117031;119548;143052;144451;146002;861.7371701631063;930.6975233049286;1041.9531121099417;1048.8688913885765;1070.3203465799118;17965.55086122805;18210;18294.359697410528;18478.907605461885;19531.51171220731;56344.15483767829;56433.43566564282;57617.71191144017;57952.71023644855;60206;66653.66673166603;81248.3750325002;82440.58779706064;82855;83143;7050.894236586353;7983;8062.234087761653;8176.591170441573;8457;11434.256773309708;12383.814242786546;12534.874651253736;12883.453985521797;12974.221546707227;8901.55492225399;10327.690169294932;10441.477926103817;11063.502142403771;11143.777124457627;9744.220462363135;10594.841077383691;10928.945355273172;11090.611828585961;11344.092472602337;5891.823245302648;7062;11112.499937502993;11346.546138154381;11544.538218471167;8110.270075693216;8207.548584827653;8260.876086858822;8366.62350194255;8542.103079176846;9561.187299079656;10868.456577170951;11125.332480051224;11174.720631984266;11362.091032717197 10:31:06 INFO - PROCESS | 2204 | |3;mootools.html;1597.5926138834595;1644.4491095483013;1900.7529021227238;1998.6602277612794;2018.4045706516577;8014.118446970843;8713;8758.474491530513;9257.722268331952;9292.628294868206;8851.424657397265;8970.686025989091;8981.191692747652;9064.320175986812;9109.362344635878;2434.306222726519;2461.950760984779;2462.273629279358;2466.087547607379;2467.0501856785177;2392.5932591459405;2424.2363655448567;2446.5473887330813;2450.6324051392257;2459.8032157427365;2413.263954493879;2441.645961335605;2444.1567659157563;2462.8768561571937;2463.1132792194794;2384.499255156419;2412.2160297903074;2415.6376543518436;2429.7448767879473;2437.0617312334703;2414.022320960014;2418.117387153694;2427.7693619106335;2434.245383930987;2438.1344622659053;8959.5968181432;9061.320400969953;9066.54667266634;9070.86393704095;9088.636454541811;5358.571314294847;5511.366192887829;5560.527355174815;5573.832785016456;5578.135389014709;3611.1694310308776;3634.4366623173187;3649.562052553684;3700.9444858327147;3706.146926536732;1008.7679833638231;1046.539522610049;1050.8318669012922;1052.7789164275512;1065.579096256982;11268.605598804003;12241;12269.815952760715;12284.508619655204;12293.139480236387;4901.117798796179;4938.802447902043;5033.119204139261;5039.470855560114;5050.520200580939;10097.24270679702;10164.186865050779;10200.642977495752;10213.131883789812;10218.795624087625;5428.864278393072;5443.42844001382;5529.751161197756;5536.7785288588;5542.61201715876;4034.35450327952;4462.44219472566;4980.8754781130765;5001.249812528077;5053.84838454847;4054.3107671695884;4081.1544394758894;4116.650084742771;4149.066460046526;4149.97925010373;1353.0735379039743;1371.8422381426064;1375.4223226245;1391.1305434103685;1391.6520869782555;857.2713193785232;879.6261588824724;940.9012053734397;941.8587211918267;943.9244860411151;4535.614472769851;5628.705397758538;5846.532277417797;6054.757809687651;6074.088886667036;444.2691772035049;462.43582828948627;472.6077355794682;473.62346934187406;478.0129033545717;3209.7111259986714;3405.9829700851337;3695.815209239527;3975.521418758586;4050.4936882889638;10608.681739547827;10848.294860834021;10950.178736594788;10962.397068161328;10965.342079475256;9070.954645226864;9091.499967501719;10279;10959.671209863558;11169;8312.667493300321;8331.750047498585;8342.958285208413;8409.822624832528;8477.872831907527;6085.847853803513;6125.326214116354;6140.385961403824;6150.7847225346895;6224.502039836892;10290.897091028994;10356.637517686844;10358.741031474274;10376.221783366429;10463.372197667859;9746.561404736662;10213.285070045025;10395.480225988822;10507.47462626881;10585.364878107339;6130.570860039755;6966.825829354307;7552.66013029404;7707.229277072248;7838.176991416048;8503.659853606034;8514.787130321543;8516.276116530164;8591.87112193305;8605.225529702357;6293.905591416224;7542.396608271215;7566.1298950621385;7667.731629392944;7736.187700291388;7387.8153046174275;7467.813304667427;7483.775486735407;7543.057117860268;7557.962210188914;5708.628939118944;7072.68172932209;7113.110861142357;7124.501284910007;7180.281971802778;4867.537583929521;4886.877828054327;5029.421616514148;5074.5686616637995;5200.089984252665;4063.9796801015805;5022.924656130088;5188.22176673502;5195.6622819516615;5331.413544510023 10:31:06 INFO - PROCESS | 2204 | |4;prototype.html;258.70895242851793;318.3267389471267;320.3129287677931;323.1452807324625;325.6712612540834;4802.735849528274;4934.4818794026605;5023.372078490189;5185.4296027437;5426.810061647838;4489.553193509537;5318.25544423781;5330.840074797762;5373.140297552382;5425.457454254572;3851.171998020418;4034.616711412411;4123.587641235882;4126.741343890112;4152.792360381984;3007.5639032340405;3277.000514842972;3583.4266517357228;4121.216968775938;4235.894102647428;3748.700103991674;3902.70729695274;3937.350337194374;4108.219438306727;4109.589041095896;3552.609212986569;3758.9812050939845;3972.642462178389;4086.3870419437026;4115.806416139316;3004.2789730464533;3543.3877585698447;3543.537241617077;3576;3583.605803361628;4485.372047913294;4741.620670346364;5224.660397074178;5224.73876306187;5233.084210263189;3581.2658405026905;3821.6942644588366;4703.2239680452685;4704.976475117603;4758.238681810928;4877.561019508225;5427.213054107172;5581.190727344512;5700.914486282709;5705.800296989627;3508.6315936826522;3931.5085614298214;3937.1731936293413;3945.782981935992;3981.7411868228473;5199.688018718851;5287.656302340336;5731;5735.426457354273;5905.6751878646655;3744.9139749472347;3896.5129358830145;4006.6193711597352;4019.9749063988497;4028.3081105935576;4060.309747342959;4095.9385609215888;4098.856540021085;4113.691473139526;4117.341225403981;4517.88705282364;4706.388169538007;4744.312074749142;4756.167670657621;4759.762011899391;4539.046800172001;4666.556677115668;5769.740361683735;5773.653580785166;5803.854903627359;1169.3159501691605;1348.858369871169;1356.8507464178933;1357.6470117769254;1387.9791803122962;669.494531628627;748.5658318175446;750.89487471754;840.6637345062024;841.4320333774677;4501.437320334958;5193.3767947845745;5298.2052692096495;5309.4779979510195;5371.301730774974;4623.69945953512;4868.561829435368;5450.618456707993;5711;5940.495057920039;4624.3294722265755;5048.545630893237;5106.493584392613;5142.1772516397195;5204.973975130176;2499.6750422444966;2587.469568738404;3014.5130267529375;3093.8453077346044;3150.991682661572;5064;5138.507823705575;5733.111367738007;5936.762529498858;6205.100260462298;5786.623869448556;5986.640801551921;6037.758489660452;6066.484348830399;6096.603720758137;5584.97207513968;6144.969275153774;6178.6601736904;6206.410391012847;6218;5172.9741351293005;5552.778388754467;5601.963636727219;5648.971755141198;5942.168096466412;5222.7127507987425;5519.530839878656;5542.418046105101;5689.630174038674;5937.2578427696535;5269.736513174403;5458.317710286215;5585.804496842591;5814.476697097281;6173.9008737027825;3549.343371476285;3852.094757731875;3913.3151698453225;3962.623550762673;4005.2189822984237;5150.922736159036;5894.882102358015;5917.455494137562;5936.970315148396;5959.642421454727;4871.464138944642;5106.106431374569;5302.700838294642;5803.288030031066;5809.419058094326;4722.740249286185;5366.463353664602;5747.396523365153;5782.739776709974;5799.797007104731;5165.6754460492175;5561.888762224652;5575.358833734173;5628.549716022791;5630.789380283259;4819.349387832598;5646.632968857011;5701.806988086685;5799.5070419014855;5814.767409303749;5237.973810131078;5599.244102046172;5641.6050876439895;5648.85877853057;5696.718238396327 10:31:06 INFO - PROCESS | 2204 | |5;yui.html;641.9696387298383;683.6889215406989;761.3338328962158;776.0260872604883;784.4822417204646;12782.977361811056;14114.858851411484;14452.710945781078;14903.478378256763;15014.624634384147;13777.655558611015;13798.172109673427;13886.236257005861;13961;13969.58091257264;918.8024574716451;937.0161330602874;944.0889541592362;944.7874228298647;949.6011675096441;729.438332483987;910.026271889079;943.4763706143087;945.8250223708601;951.2485136741966;946.5361972633394;948.611069461521;995;996.8853581838073;997.7904640025604;811.9715809946653;954.8138113067954;991.3209451525692;991.5934466868586;1000.2248257600418;685.0512040823495;719.9496035277533;732.2311572848488;732.8754111801006;744.9106107267163;14504.057236279608;14598.854011459856;14612.415503379852;14687.706245875022;14690.632734181625;12178.634640960785;12180.390980451031;12241.69395765113;12254.509819607205;12407.503699851995;8714.346424018224;10039.799204015952;10171.643992460302;10387.584496620126;10473.319234249826;3104.689531046877;3394.2702319001532;3491.4239150540384;3505.4741788731894;3632.673059424638;81580.59209703973;83863.32273354499;84203.3159336816;86049.56975215147;86158;8719.433236839584;8983.685571004984;9565.473898935625;9724.367916085432;10034.69895903124;22327.776722232895;22349.21777737804;22423.99092040862;22445.887770561043;22457.55084898293;6982.47631427645;7396.519226250277;7416.629168541659;7634.847303053908;7682.462227644123;3945.447637330776;4010.799460026987;4017.4978127734034;4027.597240275949;4064.8170832312617;3432.8970130895614;3558.857645694195;3565.625609311037;3566.1262990566956;3568.928621427557;647.2588885725818;651.794684674326;655.3675702946584;660.9173853268342;661.0909998751716;537.9004884096455;556.7272036701987;575.1516513143081;582.1384351160307;587.8412828536357;6137.355577664371;7629.923700763033;7787.766367008999;7990.68037278514;8005.159458256916;504.37457552635203;520.1988937036996;565.1438071321951;566.0010082204911;595.8033848829875;3571.964130402206;3713.4801127842125;3733.4586484959527;3764.7176461765475;3774.3206222879594;58874.411255887746;63194.684026579576;80604.19395806085;80613.19386806175;81806.59096704479;47754.76122619434;64472;66287;67038.99999999903;68094.63810723758;59615.701921490116;60272;61767.45581360502;61937.690311548155;61953.69023154856;7316.670749816378;7362.852742945218;7447.590382529013;7467.850642987219;7520.323170914644;38912.80543597264;39735.2052958945;39829.80085099556;39832.2033559333;40966.1806763869;55083;56551;56916.71541642265;57773;59480.70259648674;10953.561857525929;11287.20989530725;11488.080953523866;11606.419679015846;11702.531898724295;12230.571929982409;12234.571789987307;12443.93778031104;12489.31308778026;12491.500339986298;11516.481758320728;11605.883941160819;11978.58074967372;11981.700457488632;11997.280163190237;9567.569459374485;11581.305121692389;11666.475008624462;11692.883071169179;11950.282983021047;11226.270292430965;11268.830967535658;11327.716807079889;11461.312321260752;11639.243449176116;9507.286953478655;9646.42121472714;9919.900800991898;9935.403875767477;10161.84757228657;8500.957495212484;9479.620815167316;9689.612415503301;9777.804443911224;9779.902200977898 10:31:06 INFO - PROCESS | 2204 | __end_tp_report 10:31:06 INFO - PROCESS | 2204 | __start_cc_report 10:31:06 INFO - PROCESS | 2204 | _x_x_mozilla_cycle_collect,409 10:31:06 INFO - PROCESS | 2204 | __end_cc_report 10:31:06 INFO - PROCESS | 2204 | __startTimestamp1462901466744__endTimestamp 10:31:06 INFO - PROCESS | 2204 | 10:31:06 INFO - PROCESS | 2204 | ------- Summary: start ------- 10:31:06 INFO - PROCESS | 2204 | Number of tests: 6 10:31:06 INFO - PROCESS | 2204 | 10:31:06 INFO - PROCESS | 2204 | [#0] dojo.html Cycles:165 Average:5691.44 Median:5911.67 stddev:772.72 (13.1%) stddev-sans-first:743.47 10:31:06 INFO - PROCESS | 2204 | Values: 2902.6 3293.4 3437.2 3500.6 3502.3 5650.7 5680.8 5714.8 5726.3 5782.4 5572.8 5674.1 5717.5 5736.5 5810.9 5114.8 5165.5 5193.4 5244.3 5264.3 630.7 5211.6 5235.0 5241.3 5263.9 5126.0 5140.1 5208.5 5208.7 5240.7 5130.3 5200.8 5210.0 5266.9 5270.2 5118.9 5129.8 5158.5 5172.3 5192.7 5485.5 5605.7 5621.9 5623.3 5643.1 5998.9 6117.7 6255.7 6255.9 6256.2 6152.4 6241.1 6261.7 6275.9 6278.9 5801.0 5805.0 5806.4 5827.7 5869.0 6176.5 6181.3 6194.5 6280.5 6303.4 6146.9 6159.6 6165.3 6171.9 6189.6 6208.7 6263.2 6265.7 6270.7 6302.1 5751.7 6112.3 6139.2 6186.2 6238.0 6055.1 6146.9 6181.5 6188.1 6225.1 4535.4 4869.9 4957.6 4967.3 4969.0 4307.4 4344.6 4381.0 4405.4 4413.6 4539.5 5702.5 5786.0 5843.9 5849.7 4751.8 6198.8 6251.6 6251.7 6266.4 5636.9 5718.7 5783.5 5803.8 5804.8 5411.6 5421.2 5429.6 5464.8 5467.4 6365.9 6372.4 6411.1 6412.5 6468.1 5037.5 6209.3 6226.5 6228.2 6235.3 6337.3 6364.4 6376.8 6390.7 6402.7 6189.3 6207.2 6225.3 6239.6 6245.1 6134.4 6180.5 6188.9 6219.4 6308.6 6118.5 6155.6 6156.7 6157.5 6161.8 5623.5 6117.1 6151.9 6159.8 6174.3 5716.0 5755.7 5802.7 5823.7 5911.7 6141.0 6145.9 6146.0 6153.0 6153.7 6006.3 6017.7 6022.8 6029.9 6042.6 10:31:06 INFO - PROCESS | 2204 | 10:31:06 INFO - PROCESS | 2204 | [#1] ext.html Cycles:180 Average:70889.12 Median:38200.93 stddev:160933.18 (421.3%) stddev-sans-first:161297.80 10:31:06 INFO - PROCESS | 2204 | Values: 474.4 494.5 509.2 513.8 573.9 31820.0 40931.8 41026.8 41057.2 41140.0 41608.8 41979.6 42037.4 42042.2 42201.8 9567.3 9716.8 9744.4 9761.3 9778.8 7601.9 9625.5 9697.7 9739.1 9969.5 9676.2 9688.7 9719.4 9747.2 9751.4 9655.1 9670.5 9696.9 9698.5 9745.5 7815.6 8581.5 9532.5 9646.9 9649.8 32342.7 39194.4 39340.6 39475.6 39541.4 3121.8 3179.5 3184.2 3606.4 4052.4 27391.0 29534.7 29710.7 29718.2 29804.3 2703.0 2718.6 2845.7 2856.0 2859.1 506306.0 508617.0 512286.0 514125.0 516267.4 32818.8 33366.3 64581.0 65448.0 65735.7 36974.8 37295.3 37296.6 37445.4 37511.6 15100.1 15342.7 15470.8 15484.8 15522.0 12675.2 12771.0 12994.7 13071.2 13118.1 8100.4 8267.7 8291.3 8298.2 8344.5 3426.8 3440.4 3443.9 3468.7 3506.0 991.6 1015.8 1019.4 1141.4 1152.7 29740.1 29932.9 29955.6 29988.1 30089.8 1100.5 1111.5 1170.2 1190.5 1273.1 13724.7 15070.4 15114.5 15379.9 15659.6 664382.0 690872.0 695625.0 698490.0 699567.0 59179.0 60277.4 60363.4 60597.8 60623.0 1371.4 1421.6 1428.8 1489.8 1492.6 28519.4 28600.6 28711.2 29047.4 29659.0 51585.5 51642.7 51820.2 52262.7 52322.0 578074.0 579725.0 588326.0 589090.0 590553.0 26914.5 27163.0 27189.3 27360.3 27496.5 45312.8 45408.5 45805.1 45812.0 46080.5 38157.4 38210.9 38512.8 38874.4 38966.6 37181.6 37511.8 37569.9 38191.0 38369.0 32196.7 37316.8 37465.6 38144.2 38270.0 32546.0 33378.7 33384.0 33474.8 33599.7 23397.6 23462.6 24826.9 24865.4 25400.0 10:31:06 INFO - PROCESS | 2204 | 10:31:06 INFO - PROCESS | 2204 | [#2] jquery.html Cycles:180 Average:36201.62 Median:2294.83 stddev:105970.80 (4617.8%) stddev-sans-first:106233.98 10:31:06 INFO - PROCESS | 2204 | Values: 397.0 401.6 403.1 405.1 406.2 19623.2 20015.9 20794.0 21373.0 21444.5 19118.4 19799.7 20954.0 20973.9 21019.8 4999.6 5373.4 5379.3 5421.1 5429.8 4576.0 5295.9 5297.2 5895.4 5965.4 5456.6 5540.3 5888.8 5916.8 6028.9 5969.0 6056.3 6084.3 6118.3 6126.6 5554.4 5814.5 5823.3 5933.0 5945.7 13370.9 13507.1 13693.2 13706.2 13855.4 2152.9 2223.3 2277.4 2312.2 2347.8 9943.2 10056.8 10144.8 10281.6 10293.7 1736.5 1753.3 1763.3 1782.3 1787.8 122522.0 128055.0 129703.0 129829.4 133146.0 9520.5 11866.7 11871.1 11988.0 12115.3 15334.4 15335.1 15371.5 15376.7 15447.2 8651.3 8844.9 8854.7 8871.7 8972.5 8679.7 8785.5 8795.0 8921.2 8959.7 4612.7 4696.3 4698.1 4711.7 4770.1 1911.3 1999.3 2004.9 2036.7 2039.7 922.8 977.1 1002.3 1012.2 1020.3 17633.3 19544.3 19957.3 20031.3 20333.4 938.8 942.2 961.4 972.1 989.4 9071.9 10285.5 10612.7 10848.8 10953.8 618821.0 627324.0 627691.0 646584.0 647083.0 117031.0 119548.0 143052.0 144451.0 146002.0 861.7 930.7 1042.0 1048.9 1070.3 17965.6 18210.0 18294.4 18478.9 19531.5 56344.2 56433.4 57617.7 57952.7 60206.0 66653.7 81248.4 82440.6 82855.0 83143.0 7050.9 7983.0 8062.2 8176.6 8457.0 11434.3 12383.8 12534.9 12883.5 12974.2 8901.6 10327.7 10441.5 11063.5 11143.8 9744.2 10594.8 10928.9 11090.6 11344.1 5891.8 7062.0 11112.5 11346.5 11544.5 8110.3 8207.5 8260.9 8366.6 8542.1 9561.2 10868.5 11125.3 11174.7 11362.1 10:31:06 INFO - PROCESS | 2204 | 10:31:06 INFO - PROCESS | 2204 | [#3] mootools.html Cycles:180 Average:5786.11 Median:2672.78 stddev:3265.43 (122.2%) stddev-sans-first:3259.42 10:31:06 INFO - PROCESS | 2204 | Values: 1597.6 1644.4 1900.8 1998.7 2018.4 8014.1 8713.0 8758.5 9257.7 9292.6 8851.4 8970.7 8981.2 9064.3 9109.4 2434.3 2462.0 2462.3 2466.1 2467.1 2392.6 2424.2 2446.5 2450.6 2459.8 2413.3 2441.6 2444.2 2462.9 2463.1 2384.5 2412.2 2415.6 2429.7 2437.1 2414.0 2418.1 2427.8 2434.2 2438.1 8959.6 9061.3 9066.5 9070.9 9088.6 5358.6 5511.4 5560.5 5573.8 5578.1 3611.2 3634.4 3649.6 3700.9 3706.1 1008.8 1046.5 1050.8 1052.8 1065.6 11268.6 12241.0 12269.8 12284.5 12293.1 4901.1 4938.8 5033.1 5039.5 5050.5 10097.2 10164.2 10200.6 10213.1 10218.8 5428.9 5443.4 5529.8 5536.8 5542.6 4034.4 4462.4 4980.9 5001.2 5053.8 4054.3 4081.2 4116.7 4149.1 4150.0 1353.1 1371.8 1375.4 1391.1 1391.7 857.3 879.6 940.9 941.9 943.9 4535.6 5628.7 5846.5 6054.8 6074.1 444.3 462.4 472.6 473.6 478.0 3209.7 3406.0 3695.8 3975.5 4050.5 10608.7 10848.3 10950.2 10962.4 10965.3 9071.0 9091.5 10279.0 10959.7 11169.0 8312.7 8331.8 8343.0 8409.8 8477.9 6085.8 6125.3 6140.4 6150.8 6224.5 10290.9 10356.6 10358.7 10376.2 10463.4 9746.6 10213.3 10395.5 10507.5 10585.4 6130.6 6966.8 7552.7 7707.2 7838.2 8503.7 8514.8 8516.3 8591.9 8605.2 6293.9 7542.4 7566.1 7667.7 7736.2 7387.8 7467.8 7483.8 7543.1 7558.0 5708.6 7072.7 7113.1 7124.5 7180.3 4867.5 4886.9 5029.4 5074.6 5200.1 4064.0 5022.9 5188.2 5195.7 5331.4 10:31:06 INFO - PROCESS | 2204 | 10:31:06 INFO - PROCESS | 2204 | [#4] prototype.html Cycles:180 Average:4552.35 Median:5140.34 stddev:1423.75 (27.7%) stddev-sans-first:1390.79 10:31:06 INFO - PROCESS | 2204 | Values: 258.7 318.3 320.3 323.1 325.7 4802.7 4934.5 5023.4 5185.4 5426.8 4489.6 5318.3 5330.8 5373.1 5425.5 3851.2 4034.6 4123.6 4126.7 4152.8 3007.6 3277.0 3583.4 4121.2 4235.9 3748.7 3902.7 3937.4 4108.2 4109.6 3552.6 3759.0 3972.6 4086.4 4115.8 3004.3 3543.4 3543.5 3576.0 3583.6 4485.4 4741.6 5224.7 5224.7 5233.1 3581.3 3821.7 4703.2 4705.0 4758.2 4877.6 5427.2 5581.2 5700.9 5705.8 3508.6 3931.5 3937.2 3945.8 3981.7 5199.7 5287.7 5731.0 5735.4 5905.7 3744.9 3896.5 4006.6 4020.0 4028.3 4060.3 4095.9 4098.9 4113.7 4117.3 4517.9 4706.4 4744.3 4756.2 4759.8 4539.0 4666.6 5769.7 5773.7 5803.9 1169.3 1348.9 1356.9 1357.6 1388.0 669.5 748.6 750.9 840.7 841.4 4501.4 5193.4 5298.2 5309.5 5371.3 4623.7 4868.6 5450.6 5711.0 5940.5 4624.3 5048.5 5106.5 5142.2 5205.0 2499.7 2587.5 3014.5 3093.8 3151.0 5064.0 5138.5 5733.1 5936.8 6205.1 5786.6 5986.6 6037.8 6066.5 6096.6 5585.0 6145.0 6178.7 6206.4 6218.0 5173.0 5552.8 5602.0 5649.0 5942.2 5222.7 5519.5 5542.4 5689.6 5937.3 5269.7 5458.3 5585.8 5814.5 6173.9 3549.3 3852.1 3913.3 3962.6 4005.2 5150.9 5894.9 5917.5 5937.0 5959.6 4871.5 5106.1 5302.7 5803.3 5809.4 4722.7 5366.5 5747.4 5782.7 5799.8 5165.7 5561.9 5575.4 5628.5 5630.8 4819.3 5646.6 5701.8 5799.5 5814.8 5238.0 5599.2 5641.6 5648.9 5696.7 10:31:06 INFO - PROCESS | 2204 | 10:31:06 INFO - PROCESS | 2204 | [#5] yui.html Cycles:180 Average:16572.28 Median:28745.93 stddev:22294.60 (77.6%) stddev-sans-first:22325.06 10:31:06 INFO - PROCESS | 2204 | Values: 642.0 683.7 761.3 776.0 784.5 12783.0 14114.9 14452.7 14903.5 15014.6 13777.7 13798.2 13886.2 13961.0 13969.6 918.8 937.0 944.1 944.8 949.6 729.4 910.0 943.5 945.8 951.2 946.5 948.6 995.0 996.9 997.8 812.0 954.8 991.3 991.6 1000.2 685.1 719.9 732.2 732.9 744.9 14504.1 14598.9 14612.4 14687.7 14690.6 12178.6 12180.4 12241.7 12254.5 12407.5 8714.3 10039.8 10171.6 10387.6 10473.3 3104.7 3394.3 3491.4 3505.5 3632.7 81580.6 83863.3 84203.3 86049.6 86158.0 8719.4 8983.7 9565.5 9724.4 10034.7 22327.8 22349.2 22424.0 22445.9 22457.6 6982.5 7396.5 7416.6 7634.8 7682.5 3945.4 4010.8 4017.5 4027.6 4064.8 3432.9 3558.9 3565.6 3566.1 3568.9 647.3 651.8 655.4 660.9 661.1 537.9 556.7 575.2 582.1 587.8 6137.4 7629.9 7787.8 7990.7 8005.2 504.4 520.2 565.1 566.0 595.8 3572.0 3713.5 3733.5 3764.7 3774.3 58874.4 63194.7 80604.2 80613.2 81806.6 47754.8 64472.0 66287.0 67039.0 68094.6 59615.7 60272.0 61767.5 61937.7 61953.7 7316.7 7362.9 7447.6 7467.9 7520.3 38912.8 39735.2 39829.8 39832.2 40966.2 55083.0 56551.0 56916.7 57773.0 59480.7 10953.6 11287.2 11488.1 11606.4 11702.5 12230.6 12234.6 12443.9 12489.3 12491.5 11516.5 11605.9 11978.6 11981.7 11997.3 9567.6 11581.3 11666.5 11692.9 11950.3 11226.3 11268.8 11327.7 11461.3 11639.2 9507.3 9646.4 9919.9 9935.4 10161.8 8501.0 9479.6 9689.6 9777.8 9779.9 10:31:06 INFO - PROCESS | 2204 | -------- Summary: end -------- 10:31:06 INFO - PROCESS | 2204 | 10:31:07 INFO - TEST-INFO | 2204: exit 0 10:31:07 INFO - TEST-OK | dromaeo_css | took 1111538ms 10:31:07 INFO - TEST-START | kraken 10:31:07 INFO - operating with platform_type : mac_ 10:31:07 INFO - Initialising browser for kraken test... 10:31:07 INFO - TEST-INFO | started process 2210 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpS4sclX/profile http://localhost:53409/getInfo.html) 10:31:10 INFO - PROCESS | 2210 | __metrics Screen width/height:1600/1200 10:31:10 INFO - PROCESS | 2210 | colorDepth:24 10:31:10 INFO - PROCESS | 2210 | Browser inner width/height: 1024/689 10:31:10 INFO - PROCESS | 2210 | __metrics 10:31:10 ERROR - PROCESS | 2210 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 10:31:10 INFO - TEST-INFO | 2210: exit 0 10:31:10 INFO - Browser initialized. 10:31:10 INFO - Running cycle 1/1 for kraken test... 10:31:10 INFO - TEST-INFO | started process 2213 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpS4sclX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:31:22 INFO - PROCESS | 2213 | RSS: Main: 249679872 10:31:22 INFO - PROCESS | 2213 | 10:31:25 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53409/tests/kraken/driver.html?testName=audio-beat-detection) 10:31:26 INFO - PROCESS | 2213 | RSS: Main: 261566464 10:31:26 INFO - PROCESS | 2213 | 10:31:29 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53409/tests/kraken/driver.html?testName=audio-dft) 10:31:29 INFO - PROCESS | 2213 | RSS: Main: 266326016 10:31:29 INFO - PROCESS | 2213 | 10:31:33 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53409/tests/kraken/driver.html?testName=audio-fft) 10:31:34 INFO - PROCESS | 2213 | RSS: Main: 266731520 10:31:34 INFO - PROCESS | 2213 | 10:31:36 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53409/tests/kraken/driver.html?testName=audio-oscillator) 10:31:37 INFO - PROCESS | 2213 | RSS: Main: 267313152 10:31:37 INFO - PROCESS | 2213 | 10:31:39 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53409/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:31:39 INFO - PROCESS | 2213 | RSS: Main: 268361728 10:31:39 INFO - PROCESS | 2213 | 10:31:42 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53409/tests/kraken/driver.html?testName=imaging-darkroom) 10:31:43 INFO - PROCESS | 2213 | RSS: Main: 267685888 10:31:43 INFO - PROCESS | 2213 | 10:31:46 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53409/tests/kraken/driver.html?testName=imaging-desaturate) 10:31:47 INFO - PROCESS | 2213 | RSS: Main: 268345344 10:31:47 INFO - PROCESS | 2213 | 10:31:50 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53409/tests/kraken/driver.html?testName=json-parse-financial) 10:31:50 INFO - PROCESS | 2213 | RSS: Main: 268079104 10:31:50 INFO - PROCESS | 2213 | 10:31:52 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53409/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:31:53 INFO - PROCESS | 2213 | RSS: Main: 268001280 10:31:53 INFO - PROCESS | 2213 | 10:31:55 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53409/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:31:55 INFO - PROCESS | 2213 | RSS: Main: 268304384 10:31:55 INFO - PROCESS | 2213 | 10:31:57 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53409/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:31:58 INFO - PROCESS | 2213 | RSS: Main: 268902400 10:31:58 INFO - PROCESS | 2213 | 10:32:01 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53409/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:32:02 INFO - PROCESS | 2213 | RSS: Main: 268435456 10:32:02 INFO - PROCESS | 2213 | 10:32:05 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53409/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:32:06 INFO - PROCESS | 2213 | RSS: Main: 268910592 10:32:06 INFO - PROCESS | 2213 | 10:32:07 INFO - PROCESS | 2213 | Cycle 1(1): loaded http://localhost:53409/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53409/tests/kraken/driver.html?testName=ai-astar) 10:32:08 INFO - PROCESS | 2213 | RSS: Main: 270270464 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | __start_tp_report 10:32:08 INFO - PROCESS | 2213 | _x_x_mozilla_page_load 10:32:08 INFO - PROCESS | 2213 | _x_x_mozilla_page_load_details 10:32:08 INFO - PROCESS | 2213 | |i|pagename|runs| 10:32:08 INFO - PROCESS | 2213 | |0;ai-astar;112;116;109;116;114;116;108;117;94;171 10:32:08 INFO - PROCESS | 2213 | |1;audio-beat-detection;153;143;136;213;151;164;140;124;173;119 10:32:08 INFO - PROCESS | 2213 | |2;audio-dft;194;200;208;249;270;271;212;194;282;222 10:32:08 INFO - PROCESS | 2213 | |3;audio-fft;71;80;110;204;58;56;60;66;132;60 10:32:08 INFO - PROCESS | 2213 | |4;audio-oscillator;83;114;198;114;118;92;122;81;84;124 10:32:08 INFO - PROCESS | 2213 | |5;imaging-gaussian-blur;88;85;88;99;113;85;89;86;88;86 10:32:08 INFO - PROCESS | 2213 | |6;imaging-darkroom;94;103;96;95;94;94;96;152;152;94 10:32:08 INFO - PROCESS | 2213 | |7;imaging-desaturate;91;92;90;90;89;90;93;89;91;89 10:32:08 INFO - PROCESS | 2213 | |8;json-parse-financial;60;63;61;61;61;62;62;123;61;59 10:32:08 INFO - PROCESS | 2213 | |9;json-stringify-tinderbox;66;68;68;74;81;74;72;65;71;68 10:32:08 INFO - PROCESS | 2213 | |10;stanford-crypto-aes;89;86;92;84;86;85;87;86;89;86 10:32:08 INFO - PROCESS | 2213 | |11;stanford-crypto-ccm;144;145;144;149;153;138;150;145;254;156 10:32:08 INFO - PROCESS | 2213 | |12;stanford-crypto-pbkdf2;234;236;179;176;168;168;193;149;220;188 10:32:08 INFO - PROCESS | 2213 | |13;stanford-crypto-sha256-iterative;68;70;68;79;85;84;74;66;66;90 10:32:08 INFO - PROCESS | 2213 | __end_tp_report 10:32:08 INFO - PROCESS | 2213 | __start_cc_report 10:32:08 INFO - PROCESS | 2213 | _x_x_mozilla_cycle_collect,1065 10:32:08 INFO - PROCESS | 2213 | __end_cc_report 10:32:08 INFO - PROCESS | 2213 | __startTimestamp1462901528196__endTimestamp 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | ------- Summary: start ------- 10:32:08 INFO - PROCESS | 2213 | Number of tests: 14 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#0] ai-astar Cycles:10 Average:117.30 Median:116.00 stddev:20.07 (17.3%) stddev-sans-first:21.20 10:32:08 INFO - PROCESS | 2213 | Values: 112.0 116.0 109.0 116.0 114.0 116.0 108.0 117.0 94.0 171.0 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#1] audio-beat-detection Cycles:10 Average:151.60 Median:152.00 stddev:27.20 (17.9%) stddev-sans-first:28.85 10:32:08 INFO - PROCESS | 2213 | Values: 153.0 143.0 136.0 213.0 151.0 164.0 140.0 124.0 173.0 119.0 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#2] audio-dft Cycles:10 Average:230.20 Median:235.50 stddev:34.50 (14.6%) stddev-sans-first:34.01 10:32:08 INFO - PROCESS | 2213 | Values: 194.0 200.0 208.0 249.0 270.0 271.0 212.0 194.0 282.0 222.0 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#3] audio-fft Cycles:10 Average:89.70 Median:60.00 stddev:47.35 (78.9%) stddev-sans-first:49.73 10:32:08 INFO - PROCESS | 2213 | Values: 71.0 80.0 110.0 204.0 58.0 56.0 60.0 66.0 132.0 60.0 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#4] audio-oscillator Cycles:10 Average:113.00 Median:139.50 stddev:34.42 (24.7%) stddev-sans-first:34.75 10:32:08 INFO - PROCESS | 2213 | Values: 83.0 114.0 198.0 114.0 118.0 92.0 122.0 81.0 84.0 124.0 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:88.00 stddev:8.82 (10.0%) stddev-sans-first:9.30 10:32:08 INFO - PROCESS | 2213 | Values: 88.0 85.0 88.0 99.0 113.0 85.0 89.0 86.0 88.0 86.0 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#6] imaging-darkroom Cycles:10 Average:107.00 Median:94.00 stddev:23.87 (25.4%) stddev-sans-first:24.85 10:32:08 INFO - PROCESS | 2213 | Values: 94.0 103.0 96.0 95.0 94.0 94.0 96.0 152.0 152.0 94.0 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#7] imaging-desaturate Cycles:10 Average:90.40 Median:90.50 stddev:1.35 (1.5%) stddev-sans-first:1.41 10:32:08 INFO - PROCESS | 2213 | Values: 91.0 92.0 90.0 90.0 89.0 90.0 93.0 89.0 91.0 89.0 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#8] json-parse-financial Cycles:10 Average:67.30 Median:61.00 stddev:19.60 (32.1%) stddev-sans-first:20.61 10:32:08 INFO - PROCESS | 2213 | Values: 60.0 63.0 61.0 61.0 61.0 62.0 62.0 123.0 61.0 59.0 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.50 stddev:4.79 (6.7%) stddev-sans-first:4.76 10:32:08 INFO - PROCESS | 2213 | Values: 66.0 68.0 68.0 74.0 81.0 74.0 72.0 65.0 71.0 68.0 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:86.50 stddev:2.36 (2.7%) stddev-sans-first:2.39 10:32:08 INFO - PROCESS | 2213 | Values: 89.0 86.0 92.0 84.0 86.0 85.0 87.0 86.0 89.0 86.0 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#11] stanford-crypto-ccm Cycles:10 Average:157.80 Median:149.50 stddev:34.19 (22.9%) stddev-sans-first:35.90 10:32:08 INFO - PROCESS | 2213 | Values: 144.0 145.0 144.0 149.0 153.0 138.0 150.0 145.0 254.0 156.0 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.10 Median:190.50 stddev:29.66 (15.6%) stddev-sans-first:27.10 10:32:08 INFO - PROCESS | 2213 | Values: 234.0 236.0 179.0 176.0 168.0 168.0 193.0 149.0 220.0 188.0 10:32:08 INFO - PROCESS | 2213 | 10:32:08 INFO - PROCESS | 2213 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:76.50 stddev:8.87 (11.6%) stddev-sans-first:9.04 10:32:08 INFO - PROCESS | 2213 | Values: 68.0 70.0 68.0 79.0 85.0 84.0 74.0 66.0 66.0 90.0 10:32:08 INFO - PROCESS | 2213 | -------- Summary: end -------- 10:32:08 INFO - PROCESS | 2213 | 10:32:09 INFO - TEST-INFO | 2213: exit 0 10:32:09 INFO - TEST-OK | kraken | took 61841ms 10:32:09 INFO - SUITE-END | took 1173s 10:32:09 INFO - Completed test suite (00:19:33) 10:32:09 INFO - javascript benchmark 10:32:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2902.579126026726, 3293.423650861099, 3437.1922598189435, 3500.6499350064996, 3502.282032183403, 5650.689212093338, 5680.772769089232, 5714.799982000631, 5726.3414707308675, 5782.421757824226, 5572.804951826687, 5674.148877668342, 5717.456841600041, 5736.4550367715, 5810.883782324351, 5114.795408183668, 5165.457626949173, 5193.402758682762, 5244.318238628973, 5264.289320941682, 630.7437352483304, 5211.635185537014, 5234.979179060085, 5241.3186285782795, 5263.947360526384, 5125.974370128126, 5140.126178549655, 5208.453112423198, 5208.713520756357, 5240.711760853152, 5130.30740849988, 5200.791968321262, 5210.036143313499, 5266.92099618506, 5270.2358158067245, 5118.948810511884, 5129.846104616868, 5158.458361871987, 5172.27588137661, 5192.688438693653, 5485.4788795064405, 5605.7197140142825, 5621.887562248773, 5623.297087864017, 5643.068893632546, 5998.880022399571, 6117.675163990739, 6255.655938923357, 6255.937440625627, 6256.15541901842, 6152.354002829683, 6241.126242326078, 6261.749530018794, 6275.937240627581, 6278.905816412757, 5800.970995144998, 5805.0, 5806.361300256926, 5827.679477628729, 5869.0, 6176.536759743038, 6181.258249010058, 6194.5354098442795, 6280.497560195265, 6303.36966303366, 6146.862830376305, 6159.630422174684, 6165.290991536031, 6171.938280617136, 6189.6286222826775, 6208.72060757267, 6263.214983729707, 6265.749370025239, 6270.7491700331475, 6302.149209856702, 5751.705866180076, 6112.321110680185, 6139.154842350747, 6186.19579454668, 6238.033104868752, 6055.1220073090035, 6146.938530614637, 6181.505479561624, 6188.1027251048345, 6225.128482012522, 4535.365048893158, 4869.9269510957365, 4957.628177886673, 4967.279744437109, 4968.950310496849, 4307.353896915425, 4344.630706389929, 4381.014271788822, 4405.427294451701, 4413.558644135561, 4539.500654927955, 5702.486776190163, 5786.0, 5843.853903652443, 5849.736761845727, 4751.833685821049, 6198.783042593487, 6251.593646412968, 6251.749930002749, 6266.373362663697, 5636.887262254732, 5718.656880587178, 5783.4794868461195, 5803.767849286066, 5804.796832110856, 5411.594130440233, 5421.213923981, 5429.5656347492895, 5464.781408743605, 5467.398586155519, 6365.936340636627, 6372.426481616677, 6411.1024456576115, 6412.519061070345, 6468.094466774655, 5037.471065538212, 6209.348018457997, 6226.470749986302, 6228.190335256388, 6235.251769787655, 6337.334579869047, 6364.395382438661, 6376.840578985562, 6390.680465976589, 6402.711877965596, 6189.319174890668, 6207.162033125651, 6225.315215326401, 6239.594426362272, 6245.125682404558, 6134.35589263121, 6180.505559555315, 6188.938110618837, 6219.40915613016, 6308.621482711052, 6118.5411094169, 6155.630662160284, 6156.661383623944, 6157.538184636081, 6161.815145545641, 5623.465770751772, 6117.1130186123655, 6151.907721384094, 6159.84600384994, 6174.289956655042, 5716.0, 5755.683437410982, 5802.65184088956, 5823.650580965155, 5911.674857882857, 6141.0, 6145.938540614537, 6146.016637338004, 6153.0, 6153.707721378561, 6006.309274433322, 6017.729202185825, 6022.819315420545, 6029.939700602938, 6042.607230530001], "value": 5643.668003985405, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.4401606104797, 494.45115921327323, 509.18276166752383, 513.7970501651847, 573.9426057394264, 31820.0, 40931.79534102329, 41026.794866025666, 41057.178856422855, 41139.99999999993, 41608.79195604018, 41979.58020419803, 42037.36943945843, 42042.159156816844, 42201.78899105508, 9567.330286879922, 9716.757081072994, 9744.366616169962, 9761.267904907125, 9778.755531111708, 7601.923980760204, 9625.470599117045, 9697.660581879636, 9739.074787895139, 9969.451680157625, 9676.225901927864, 9688.66089686861, 9719.416834989888, 9747.171490423323, 9751.41491510508, 9655.082767137146, 9670.468124253162, 9696.854547181832, 9698.51507424626, 9745.512724363824, 7815.609219539057, 8581.485110893367, 9532.475713835805, 9646.855297170549, 9649.807003859954, 32342.676573234203, 39194.41208381877, 39340.60659393398, 39475.605243947764, 39541.40687889655, 3121.7658675599423, 3179.5071763876635, 3184.2357834119875, 3606.3688854450365, 4052.351623740217, 27391.04131355393, 29534.704652953624, 29710.702892971225, 29718.161595296762, 29804.254893627614, 2703.026910312286, 2718.619393284942, 2845.6585209774753, 2856.0432255194537, 2859.0850927703145, 506306.0, 508617.0, 512286.0, 514125.0, 516267.418662908, 32818.83590582032, 33366.3326733464, 64581.0, 65448.0, 65735.67132164404, 36974.8151259242, 37295.25409491795, 37296.627033729856, 37445.438318425244, 37511.624883751356, 15100.093994360373, 15342.693146137015, 15470.767938480933, 15484.845151548565, 15522.0, 12675.17611355259, 12771.0, 12994.675133121747, 13071.215727056217, 13118.147320424141, 8100.392470564731, 8267.710630127916, 8291.295239904555, 8298.170182981654, 8344.541050242295, 3426.845791939319, 3440.449528075546, 3443.9483407748903, 3468.7051600613727, 3506.0, 991.6430085169334, 1015.8069966706303, 1019.3578045831227, 1141.390639198726, 1152.7002979225463, 29740.107796766133, 29932.850335748182, 29955.550666740015, 29988.100356989326, 30089.849550752107, 1100.5322737836389, 1111.522045520434, 1170.1925671286785, 1190.5118901250446, 1273.1024627637494, 13724.65688357799, 15070.397184112731, 15114.470993515175, 15379.923100384427, 15659.608509787118, 664382.0, 690872.0, 695625.0, 698490.0, 699567.0, 59179.0, 60277.39722602806, 60363.39636603666, 60597.78804423887, 60623.0, 1371.376023909139, 1421.6374824419913, 1428.7783944727316, 1489.7765335199806, 1492.5671555249073, 28519.42961140807, 28600.57099143556, 28711.189483489365, 29047.419051618428, 29659.0, 51585.484145158065, 51642.741786290826, 51820.22269665883, 52262.693432664484, 52322.0, 578074.0, 579725.0, 588326.0, 589090.0, 590553.0, 26914.461710766067, 27163.049293274642, 27189.320266993484, 27360.315992100357, 27496.450070998868, 45312.77343613261, 45408.54591454043, 45805.08389832118, 45812.0, 46080.539194608966, 38157.427638586, 38210.853674389815, 38512.80743596376, 38874.41688374733, 38966.6103338963, 37181.62818371782, 37511.81244093762, 37569.87290381293, 38191.0, 38369.0, 32196.678033220305, 37316.813415932746, 37465.625343747306, 38144.237115258096, 38270.0, 32546.02361929146, 33378.666213338525, 33384.0, 33474.832625836716, 33599.664003359656, 23397.649035264825, 23462.64806027945, 24826.875865620557, 24865.378365540284, 25400.0], "value": 18712.22734037857, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.00748129675793, 401.5783427401229, 403.10912882529607, 405.1188664654377, 406.18559787625804, 19623.215071397128, 20015.89912554809, 20794.0, 21373.0, 21444.463888402777, 19118.374938130277, 19799.703004454946, 20953.95230238486, 20973.895130524324, 21019.789802102012, 4999.550040496355, 5373.408925018245, 5379.300690910176, 5421.1326187809955, 5429.837104886859, 4575.977120114411, 5295.947040529603, 5297.17893726473, 5895.4399332063385, 5965.433283838029, 5456.61803673743, 5540.307461567304, 5888.793892213774, 5916.822495325147, 6028.879422411571, 5968.97015514924, 6056.303525094604, 6084.300305464862, 6118.3269840317525, 6126.571140020201, 5554.389017208104, 5814.47669709724, 5823.330317013555, 5932.961731696936, 5945.732442040118, 13370.933145334307, 13507.122037067558, 13693.178409295475, 13706.177629342272, 13855.376508057161, 2152.9461763455884, 2223.3441134865093, 2277.4192580891768, 2312.2369618026178, 2347.788699017097, 9943.204543636492, 10056.798864022752, 10144.847827282596, 10281.588736450532, 10293.742656433575, 1736.5224563245083, 1753.2636292757072, 1763.2770564068671, 1782.3138091834608, 1787.8480329171905, 122522.0, 128055.0, 129703.0, 129829.35085324512, 133146.0, 9520.476373799507, 11866.703332416759, 11871.0503159747, 11988.0, 12115.273083615011, 15334.386624535116, 15335.079895206323, 15371.538853834403, 15376.692466150615, 15447.227638618024, 8651.264642505457, 8844.867326990101, 8854.734357969142, 8871.689490867788, 8972.506512141896, 8679.739607811776, 8785.472871627722, 8794.956025219833, 8921.241694456045, 8959.686410975715, 4612.654050946193, 4696.342512048316, 4698.083873644607, 4711.717296962193, 4770.069836381872, 1911.3405874973112, 1999.3302243748508, 2004.9398518044482, 2036.7046778217075, 2039.6838490034067, 922.7877588154762, 977.0815433492494, 1002.333448256901, 1012.1649639047815, 1020.3010937507682, 17633.294668213126, 19544.31594894172, 19957.301494447624, 20031.29890453827, 20333.389998300074, 938.7559234598917, 942.2273735536794, 961.4471678784727, 972.0571046085286, 989.4459102902398, 9071.866016747907, 10285.485725713685, 10612.734681633021, 10848.837267440995, 10953.780924381468, 618821.0, 627324.0, 627691.0, 646584.0, 647083.0, 117031.0, 119548.0, 143052.0, 144451.0, 146002.0, 861.7371701631063, 930.6975233049286, 1041.9531121099417, 1048.8688913885765, 1070.3203465799118, 17965.55086122805, 18210.0, 18294.359697410528, 18478.907605461885, 19531.51171220731, 56344.15483767829, 56433.43566564282, 57617.71191144017, 57952.71023644855, 60206.0, 66653.66673166603, 81248.3750325002, 82440.58779706064, 82855.0, 83143.0, 7050.894236586353, 7983.0, 8062.234087761653, 8176.591170441573, 8457.0, 11434.256773309708, 12383.814242786546, 12534.874651253736, 12883.453985521797, 12974.221546707227, 8901.55492225399, 10327.690169294932, 10441.477926103817, 11063.502142403771, 11143.777124457627, 9744.220462363135, 10594.841077383691, 10928.945355273172, 11090.611828585961, 11344.092472602337, 5891.823245302648, 7062.0, 11112.499937502993, 11346.546138154381, 11544.538218471167, 8110.270075693216, 8207.548584827653, 8260.876086858822, 8366.62350194255, 8542.103079176846, 9561.187299079656, 10868.456577170951, 11125.332480051224, 11174.720631984266, 11362.091032717197], "value": 9418.753604464751, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1597.5926138834595, 1644.4491095483013, 1900.7529021227238, 1998.6602277612794, 2018.4045706516577, 8014.118446970843, 8713.0, 8758.474491530513, 9257.722268331952, 9292.628294868206, 8851.424657397265, 8970.686025989091, 8981.191692747652, 9064.320175986812, 9109.362344635878, 2434.306222726519, 2461.950760984779, 2462.273629279358, 2466.087547607379, 2467.0501856785177, 2392.5932591459405, 2424.2363655448567, 2446.5473887330813, 2450.6324051392257, 2459.8032157427365, 2413.263954493879, 2441.645961335605, 2444.1567659157563, 2462.8768561571937, 2463.1132792194794, 2384.499255156419, 2412.2160297903074, 2415.6376543518436, 2429.7448767879473, 2437.0617312334703, 2414.022320960014, 2418.117387153694, 2427.7693619106335, 2434.245383930987, 2438.1344622659053, 8959.5968181432, 9061.320400969953, 9066.54667266634, 9070.86393704095, 9088.636454541811, 5358.571314294847, 5511.366192887829, 5560.527355174815, 5573.832785016456, 5578.135389014709, 3611.1694310308776, 3634.4366623173187, 3649.562052553684, 3700.9444858327147, 3706.146926536732, 1008.7679833638231, 1046.539522610049, 1050.8318669012922, 1052.7789164275512, 1065.579096256982, 11268.605598804003, 12241.0, 12269.815952760715, 12284.508619655204, 12293.139480236387, 4901.117798796179, 4938.802447902043, 5033.119204139261, 5039.470855560114, 5050.520200580939, 10097.24270679702, 10164.186865050779, 10200.642977495752, 10213.131883789812, 10218.795624087625, 5428.864278393072, 5443.42844001382, 5529.751161197756, 5536.7785288588, 5542.61201715876, 4034.35450327952, 4462.44219472566, 4980.8754781130765, 5001.249812528077, 5053.84838454847, 4054.3107671695884, 4081.1544394758894, 4116.650084742771, 4149.066460046526, 4149.97925010373, 1353.0735379039743, 1371.8422381426064, 1375.4223226245, 1391.1305434103685, 1391.6520869782555, 857.2713193785232, 879.6261588824724, 940.9012053734397, 941.8587211918267, 943.9244860411151, 4535.614472769851, 5628.705397758538, 5846.532277417797, 6054.757809687651, 6074.088886667036, 444.2691772035049, 462.43582828948627, 472.6077355794682, 473.62346934187406, 478.0129033545717, 3209.7111259986714, 3405.9829700851337, 3695.815209239527, 3975.521418758586, 4050.4936882889638, 10608.681739547827, 10848.294860834021, 10950.178736594788, 10962.397068161328, 10965.342079475256, 9070.954645226864, 9091.499967501719, 10279.0, 10959.671209863558, 11169.0, 8312.667493300321, 8331.750047498585, 8342.958285208413, 8409.822624832528, 8477.872831907527, 6085.847853803513, 6125.326214116354, 6140.385961403824, 6150.7847225346895, 6224.502039836892, 10290.897091028994, 10356.637517686844, 10358.741031474274, 10376.221783366429, 10463.372197667859, 9746.561404736662, 10213.285070045025, 10395.480225988822, 10507.47462626881, 10585.364878107339, 6130.570860039755, 6966.825829354307, 7552.66013029404, 7707.229277072248, 7838.176991416048, 8503.659853606034, 8514.787130321543, 8516.276116530164, 8591.87112193305, 8605.225529702357, 6293.905591416224, 7542.396608271215, 7566.1298950621385, 7667.731629392944, 7736.187700291388, 7387.8153046174275, 7467.813304667427, 7483.775486735407, 7543.057117860268, 7557.962210188914, 5708.628939118944, 7072.68172932209, 7113.110861142357, 7124.501284910007, 7180.281971802778, 4867.537583929521, 4886.877828054327, 5029.421616514148, 5074.5686616637995, 5200.089984252665, 4063.9796801015805, 5022.924656130088, 5188.22176673502, 5195.6622819516615, 5331.413544510023], "value": 4588.041749321728, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [258.70895242851793, 318.3267389471267, 320.3129287677931, 323.1452807324625, 325.6712612540834, 4802.735849528274, 4934.4818794026605, 5023.372078490189, 5185.4296027437, 5426.810061647838, 4489.553193509537, 5318.25544423781, 5330.840074797762, 5373.140297552382, 5425.457454254572, 3851.171998020418, 4034.616711412411, 4123.587641235882, 4126.741343890112, 4152.792360381984, 3007.5639032340405, 3277.000514842972, 3583.4266517357228, 4121.216968775938, 4235.894102647428, 3748.700103991674, 3902.70729695274, 3937.350337194374, 4108.219438306727, 4109.589041095896, 3552.609212986569, 3758.9812050939845, 3972.642462178389, 4086.3870419437026, 4115.806416139316, 3004.2789730464533, 3543.3877585698447, 3543.537241617077, 3576.0, 3583.605803361628, 4485.372047913294, 4741.620670346364, 5224.660397074178, 5224.73876306187, 5233.084210263189, 3581.2658405026905, 3821.6942644588366, 4703.2239680452685, 4704.976475117603, 4758.238681810928, 4877.561019508225, 5427.213054107172, 5581.190727344512, 5700.914486282709, 5705.800296989627, 3508.6315936826522, 3931.5085614298214, 3937.1731936293413, 3945.782981935992, 3981.7411868228473, 5199.688018718851, 5287.656302340336, 5731.0, 5735.426457354273, 5905.6751878646655, 3744.9139749472347, 3896.5129358830145, 4006.6193711597352, 4019.9749063988497, 4028.3081105935576, 4060.309747342959, 4095.9385609215888, 4098.856540021085, 4113.691473139526, 4117.341225403981, 4517.88705282364, 4706.388169538007, 4744.312074749142, 4756.167670657621, 4759.762011899391, 4539.046800172001, 4666.556677115668, 5769.740361683735, 5773.653580785166, 5803.854903627359, 1169.3159501691605, 1348.858369871169, 1356.8507464178933, 1357.6470117769254, 1387.9791803122962, 669.494531628627, 748.5658318175446, 750.89487471754, 840.6637345062024, 841.4320333774677, 4501.437320334958, 5193.3767947845745, 5298.2052692096495, 5309.4779979510195, 5371.301730774974, 4623.69945953512, 4868.561829435368, 5450.618456707993, 5711.0, 5940.495057920039, 4624.3294722265755, 5048.545630893237, 5106.493584392613, 5142.1772516397195, 5204.973975130176, 2499.6750422444966, 2587.469568738404, 3014.5130267529375, 3093.8453077346044, 3150.991682661572, 5064.0, 5138.507823705575, 5733.111367738007, 5936.762529498858, 6205.100260462298, 5786.623869448556, 5986.640801551921, 6037.758489660452, 6066.484348830399, 6096.603720758137, 5584.97207513968, 6144.969275153774, 6178.6601736904, 6206.410391012847, 6218.0, 5172.9741351293005, 5552.778388754467, 5601.963636727219, 5648.971755141198, 5942.168096466412, 5222.7127507987425, 5519.530839878656, 5542.418046105101, 5689.630174038674, 5937.2578427696535, 5269.736513174403, 5458.317710286215, 5585.804496842591, 5814.476697097281, 6173.9008737027825, 3549.343371476285, 3852.094757731875, 3913.3151698453225, 3962.623550762673, 4005.2189822984237, 5150.922736159036, 5894.882102358015, 5917.455494137562, 5936.970315148396, 5959.642421454727, 4871.464138944642, 5106.106431374569, 5302.700838294642, 5803.288030031066, 5809.419058094326, 4722.740249286185, 5366.463353664602, 5747.396523365153, 5782.739776709974, 5799.797007104731, 5165.6754460492175, 5561.888762224652, 5575.358833734173, 5628.549716022791, 5630.789380283259, 4819.349387832598, 5646.632968857011, 5701.806988086685, 5799.5070419014855, 5814.767409303749, 5237.973810131078, 5599.244102046172, 5641.6050876439895, 5648.85877853057, 5696.718238396327], "value": 4090.7366446744136, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.9696387298383, 683.6889215406989, 761.3338328962158, 776.0260872604883, 784.4822417204646, 12782.977361811056, 14114.858851411484, 14452.710945781078, 14903.478378256763, 15014.624634384147, 13777.655558611015, 13798.172109673427, 13886.236257005861, 13961.0, 13969.58091257264, 918.8024574716451, 937.0161330602874, 944.0889541592362, 944.7874228298647, 949.6011675096441, 729.438332483987, 910.026271889079, 943.4763706143087, 945.8250223708601, 951.2485136741966, 946.5361972633394, 948.611069461521, 995.0, 996.8853581838073, 997.7904640025604, 811.9715809946653, 954.8138113067954, 991.3209451525692, 991.5934466868586, 1000.2248257600418, 685.0512040823495, 719.9496035277533, 732.2311572848488, 732.8754111801006, 744.9106107267163, 14504.057236279608, 14598.854011459856, 14612.415503379852, 14687.706245875022, 14690.632734181625, 12178.634640960785, 12180.390980451031, 12241.69395765113, 12254.509819607205, 12407.503699851995, 8714.346424018224, 10039.799204015952, 10171.643992460302, 10387.584496620126, 10473.319234249826, 3104.689531046877, 3394.2702319001532, 3491.4239150540384, 3505.4741788731894, 3632.673059424638, 81580.59209703973, 83863.32273354499, 84203.3159336816, 86049.56975215147, 86158.0, 8719.433236839584, 8983.685571004984, 9565.473898935625, 9724.367916085432, 10034.69895903124, 22327.776722232895, 22349.21777737804, 22423.99092040862, 22445.887770561043, 22457.55084898293, 6982.47631427645, 7396.519226250277, 7416.629168541659, 7634.847303053908, 7682.462227644123, 3945.447637330776, 4010.799460026987, 4017.4978127734034, 4027.597240275949, 4064.8170832312617, 3432.8970130895614, 3558.857645694195, 3565.625609311037, 3566.1262990566956, 3568.928621427557, 647.2588885725818, 651.794684674326, 655.3675702946584, 660.9173853268342, 661.0909998751716, 537.9004884096455, 556.7272036701987, 575.1516513143081, 582.1384351160307, 587.8412828536357, 6137.355577664371, 7629.923700763033, 7787.766367008999, 7990.68037278514, 8005.159458256916, 504.37457552635203, 520.1988937036996, 565.1438071321951, 566.0010082204911, 595.8033848829875, 3571.964130402206, 3713.4801127842125, 3733.4586484959527, 3764.7176461765475, 3774.3206222879594, 58874.411255887746, 63194.684026579576, 80604.19395806085, 80613.19386806175, 81806.59096704479, 47754.76122619434, 64472.0, 66287.0, 67038.99999999903, 68094.63810723758, 59615.701921490116, 60272.0, 61767.45581360502, 61937.690311548155, 61953.69023154856, 7316.670749816378, 7362.852742945218, 7447.590382529013, 7467.850642987219, 7520.323170914644, 38912.80543597264, 39735.2052958945, 39829.80085099556, 39832.2033559333, 40966.1806763869, 55083.0, 56551.0, 56916.71541642265, 57773.0, 59480.70259648674, 10953.561857525929, 11287.20989530725, 11488.080953523866, 11606.419679015846, 11702.531898724295, 12230.571929982409, 12234.571789987307, 12443.93778031104, 12489.31308778026, 12491.500339986298, 11516.481758320728, 11605.883941160819, 11978.58074967372, 11981.700457488632, 11997.280163190237, 9567.569459374485, 11581.305121692389, 11666.475008624462, 11692.883071169179, 11950.282983021047, 11226.270292430965, 11268.830967535658, 11327.716807079889, 11461.312321260752, 11639.243449176116, 9507.286953478655, 9646.42121472714, 9919.900800991898, 9935.403875767477, 10161.84757228657, 8500.957495212484, 9479.620815167316, 9689.612415503301, 9777.804443911224, 9779.902200977898], "value": 6685.534474295225, "unit": "score"}], "name": "dromaeo_css", "value": 7069.382377698187, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 109.0, 116.0, 114.0, 116.0, 108.0, 117.0, 94.0, 171.0], "value": 117.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 143.0, 136.0, 213.0, 151.0, 164.0, 140.0, 124.0, 173.0, 119.0], "value": 151.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 200.0, 208.0, 249.0, 270.0, 271.0, 212.0, 194.0, 282.0, 222.0], "value": 230.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 80.0, 110.0, 204.0, 58.0, 56.0, 60.0, 66.0, 132.0, 60.0], "value": 89.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 114.0, 198.0, 114.0, 118.0, 92.0, 122.0, 81.0, 84.0, 124.0], "value": 113.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 99.0, 113.0, 85.0, 89.0, 86.0, 88.0, 86.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 103.0, 96.0, 95.0, 94.0, 94.0, 96.0, 152.0, 152.0, 94.0], "value": 107.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 90.0, 90.0, 89.0, 90.0, 93.0, 89.0, 91.0, 89.0], "value": 90.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 61.0, 61.0, 61.0, 62.0, 62.0, 123.0, 61.0, 59.0], "value": 67.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 74.0, 81.0, 74.0, 72.0, 65.0, 71.0, 68.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 92.0, 84.0, 86.0, 85.0, 87.0, 86.0, 89.0, 86.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 145.0, 144.0, 149.0, 153.0, 138.0, 150.0, 145.0, 254.0, 156.0], "value": 157.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [234.0, 236.0, 179.0, 176.0, 168.0, 168.0, 193.0, 149.0, 220.0, 188.0], "value": 191.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 68.0, 79.0, 85.0, 84.0, 74.0, 66.0, 66.0, 90.0], "value": 75.0, "unit": "score"}], "name": "kraken", "value": 1638.8, "alertThreshold": 2.0}]} 10:32:09 INFO - Return code: 0 10:32:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:32:09 ERROR - # TBPL SUCCESS # 10:32:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:32:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:32:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:32:09 INFO - 2016-05-10 10:32:09.502 screenresolution[2219:32700] starting screenresolution argv=screenresolution get 10:32:09 INFO - 2016-05-10 10:32:09.505 screenresolution[2219:32700] Display 0: 1600x1200x32@60 10:32:09 INFO - 2016-05-10 10:32:09.511 screenresolution[2220:32702] starting screenresolution argv=screenresolution list 10:32:09 INFO - Available Modes on Display 0 10:32:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:32:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:32:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:32:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:32:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:32:09 INFO - Intel Iris: 10:32:09 INFO - Chipset Model: Intel Iris 10:32:09 INFO - Type: GPU 10:32:09 INFO - Bus: Built-In 10:32:09 INFO - VRAM (Dynamic, Max): 1536 MB 10:32:09 INFO - Vendor: Intel (0x8086) 10:32:09 INFO - Device ID: 0x0a2e 10:32:09 INFO - Revision ID: 0x0009 10:32:09 INFO - Displays: 10:32:09 INFO - Display: 10:32:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:32:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:32:09 INFO - Display Serial Number: 1600x1200 60 10:32:09 INFO - Main Display: Yes 10:32:09 INFO - Mirror: Off 10:32:09 INFO - Online: Yes 10:32:09 INFO - Rotation: Supported 10:32:09 INFO - Return code: 0 10:32:09 INFO - Running post-action listener: _resource_record_post_action 10:32:09 INFO - ##### 10:32:09 INFO - ##### Finished run-tests step (success) 10:32:09 INFO - ##### 10:32:09 INFO - Running post-run listener: _resource_record_post_run 10:32:10 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 774144; Write bytes: 693187584; Read time: 8; Write time: 1885 10:32:10 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 383779328; Write bytes: 377649152; Read time: 28387; Write time: 743 10:32:10 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 749568; Write bytes: 295099392; Read time: 8; Write time: 1094 10:32:10 INFO - Running post-run listener: _upload_blobber_files 10:32:10 INFO - Blob upload gear active. 10:32:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:32:10 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 10:32:10 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'] 10:32:10 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 10:32:11 INFO - (blobuploader) - INFO - Open directory for files ... 10:32:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:32:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:32:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:32:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:32:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:32:11 INFO - (blobuploader) - INFO - Done attempting. 10:32:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:32:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:32:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:32:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:32:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:32:12 INFO - (blobuploader) - INFO - Done attempting. 10:32:12 INFO - (blobuploader) - INFO - Iteration through files over. 10:32:12 INFO - Return code: 0 10:32:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:32:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:32:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a0a649b1600837058991419a753106735743723c0a56e65821a74f8249e196a84561cf1b371953fc6a2910c274a79c1c93114e9825eb7439d8ff41f74231538", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d1f6f34f839a6674cabe3aa091017546e69224bd30201b76eebef3ca43bce8218cc7b9227e06902a5065facc5d57450f81e8f2989280a14c8168275a77de75b4"} 10:32:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:32:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:32:12 INFO - Contents: 10:32:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a0a649b1600837058991419a753106735743723c0a56e65821a74f8249e196a84561cf1b371953fc6a2910c274a79c1c93114e9825eb7439d8ff41f74231538", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d1f6f34f839a6674cabe3aa091017546e69224bd30201b76eebef3ca43bce8218cc7b9227e06902a5065facc5d57450f81e8f2989280a14c8168275a77de75b4"} 10:32:12 INFO - Running post-run listener: copy_logs_to_upload_dir 10:32:12 INFO - Copying logs to upload dir... 10:32:12 INFO - mkdir: /builds/slave/test/build/upload/logs 10:32:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.300507 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-10 10:32:12.967914) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 10:32:12.971534) ========= 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.mfVWXV13Gl/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.AtbnJrX1CA/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/6a0a649b1600837058991419a753106735743723c0a56e65821a74f8249e196a84561cf1b371953fc6a2910c274a79c1c93114e9825eb7439d8ff41f74231538", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d1f6f34f839a6674cabe3aa091017546e69224bd30201b76eebef3ca43bce8218cc7b9227e06902a5065facc5d57450f81e8f2989280a14c8168275a77de75b4"} build_url:https://queue.taskcluster.net/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/Us0DzWqgQmCejMEth3arhg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a0a649b1600837058991419a753106735743723c0a56e65821a74f8249e196a84561cf1b371953fc6a2910c274a79c1c93114e9825eb7439d8ff41f74231538", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d1f6f34f839a6674cabe3aa091017546e69224bd30201b76eebef3ca43bce8218cc7b9227e06902a5065facc5d57450f81e8f2989280a14c8168275a77de75b4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 10:32:12.999710) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 10:32:13.000057) ========= 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.mfVWXV13Gl/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.AtbnJrX1CA/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.005145 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 10:32:13.049048) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 10:32:13.049386) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 10:32:13.049745) ========= ========= Total master_lag: 0.16 =========