builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0074 starttime: 1462889271.25 results: success (0) buildid: 20160510043413 builduid: 5276ca6f34594f639ecde72088652449 revision: 88e5c19cd98ed6e353d9a762fb460976a7d4b244 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 07:07:51.254083) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 07:07:51.261380) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 07:07:51.261695) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 07:07:51.282954) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 07:07:51.283347) ========= 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.qP56LBHJTA/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.0uF8YDmu9m/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:07:51-- 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% 8.22M=0.001s 2016-05-10 07:07:51 (8.22 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.587544 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 07:07:51.888473) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 07:07:51.888767) ========= 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.qP56LBHJTA/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.0uF8YDmu9m/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.020990 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 07:07:51.937156) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 07:07:51.937519) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 88e5c19cd98ed6e353d9a762fb460976a7d4b244 --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 88e5c19cd98ed6e353d9a762fb460976a7d4b244 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qP56LBHJTA/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.0uF8YDmu9m/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:07:52,013 truncating revision to first 12 chars 2016-05-10 07:07:52,013 Setting DEBUG logging. 2016-05-10 07:07:52,013 attempt 1/10 2016-05-10 07:07:52,013 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/88e5c19cd98e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 07:07:53,239 unpacking tar archive at: fx-team-88e5c19cd98e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.465596 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 07:07:53.420805) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 07:07:53.421418) ========= script_repo_revision: 88e5c19cd98ed6e353d9a762fb460976a7d4b244 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 07:07:53.421951) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 07:07:53.422324) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 07:07:53.436073) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2016-05-10 07:07:53.436479) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.qP56LBHJTA/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.0uF8YDmu9m/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:07:53 INFO - MultiFileLogger online at 20160510 07:07:53 in /builds/slave/test 07:07:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 07:07:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:07:53 INFO - {'append_to_log': False, 07:07:53 INFO - 'base_work_dir': '/builds/slave/test', 07:07:53 INFO - 'blob_upload_branch': 'Fx-Team', 07:07:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:07:53 INFO - 'branch': 'Fx-Team', 07:07:53 INFO - 'buildbot_json_path': 'buildprops.json', 07:07:53 INFO - 'config_files': ('talos/mac_config.py',), 07:07:53 INFO - 'default_actions': ('clobber', 07:07:53 INFO - 'read-buildbot-config', 07:07:53 INFO - 'download-and-extract', 07:07:53 INFO - 'populate-webroot', 07:07:53 INFO - 'create-virtualenv', 07:07:53 INFO - 'install', 07:07:53 INFO - 'run-tests'), 07:07:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:07:53 INFO - 'download_minidump_stackwalk': True, 07:07:53 INFO - 'download_symbols': 'ondemand', 07:07:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:07:53 INFO - 'tooltool.py': '/tools/tooltool.py', 07:07:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:07:53 INFO - '/tools/misc-python/virtualenv.py')}, 07:07:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:07:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:07:53 INFO - 'installer_path': 'installer.exe', 07:07:53 INFO - 'log_level': 'info', 07:07:53 INFO - 'log_name': 'talos', 07:07:53 INFO - 'log_to_console': True, 07:07:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:07:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:07:53 INFO - 'opt_config_files': (), 07:07:53 INFO - 'pip_index': False, 07:07:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:07:53 INFO - 'cmd': ('bash', 07:07:53 INFO - '-c', 07:07:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:07:53 INFO - 'enabled': True, 07:07:53 INFO - 'halt_on_failure': False, 07:07:53 INFO - 'name': 'check_screen_resolution'},), 07:07:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:07:53 INFO - 'cmd': ('bash', 07:07:53 INFO - '-c', 07:07:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:07:53 INFO - 'enabled': True, 07:07:53 INFO - 'halt_on_failure': False, 07:07:53 INFO - 'name': 'check_screen_resolution'},), 07:07:53 INFO - 'run_cmd_checks_enabled': True, 07:07:53 INFO - 'sps_profile': False, 07:07:53 INFO - 'sps_profile_interval': 0, 07:07:53 INFO - 'suite': 'dromaeojs', 07:07:53 INFO - 'system_bits': '32', 07:07:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:07:53 INFO - 'title': 't-yosemite-r7-0074', 07:07:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:07:53 INFO - 'use_talos_json': True, 07:07:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:07:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:07:53 INFO - 'work_dir': 'build'} 07:07:53 INFO - ##### 07:07:53 INFO - ##### Running clobber step. 07:07:53 INFO - ##### 07:07:53 INFO - Running pre-action listener: _resource_record_pre_action 07:07:53 INFO - Running main action method: clobber 07:07:53 INFO - rmtree: /builds/slave/test/build 07:07:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:07:55 INFO - Running post-action listener: _resource_record_post_action 07:07:55 INFO - ##### 07:07:55 INFO - ##### Finished clobber step (success) 07:07:55 INFO - ##### 07:07:55 INFO - ##### 07:07:55 INFO - ##### Running read-buildbot-config step. 07:07:55 INFO - ##### 07:07:55 INFO - Running pre-action listener: _resource_record_pre_action 07:07:55 INFO - Running main action method: read_buildbot_config 07:07:55 INFO - Using buildbot properties: 07:07:55 INFO - { 07:07:55 INFO - "project": "", 07:07:55 INFO - "product": "firefox", 07:07:55 INFO - "script_repo_revision": "production", 07:07:55 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:07:55 INFO - "repository": "", 07:07:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:07:55 INFO - "buildid": "20160510043413", 07:07:55 INFO - "pgo_build": "False", 07:07:55 INFO - "basedir": "/builds/slave/test", 07:07:55 INFO - "buildnumber": 5, 07:07:55 INFO - "slavename": "t-yosemite-r7-0074", 07:07:55 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:07:55 INFO - "platform": "yosemite_r7", 07:07:55 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:07:55 INFO - "branch": "fx-team", 07:07:55 INFO - "repo_path": "integration/fx-team", 07:07:55 INFO - "revision": "88e5c19cd98ed6e353d9a762fb460976a7d4b244", 07:07:55 INFO - "stage_platform": "macosx64", 07:07:55 INFO - "builduid": "5276ca6f34594f639ecde72088652449", 07:07:55 INFO - "slavebuilddir": "test" 07:07:55 INFO - } 07:07:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:07:55 INFO - Running post-action listener: _resource_record_post_action 07:07:55 INFO - ##### 07:07:55 INFO - ##### Finished read-buildbot-config step (success) 07:07:55 INFO - ##### 07:07:55 INFO - ##### 07:07:55 INFO - ##### Running download-and-extract step. 07:07:55 INFO - ##### 07:07:55 INFO - Running pre-action listener: _resource_record_pre_action 07:07:55 INFO - Running main action method: download_and_extract 07:07:55 INFO - mkdir: /builds/slave/test/build/tests 07:07:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:07:55 INFO - https://queue.taskcluster.net/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:07:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:07:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:07:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/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 07:07:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/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 07:07:58 INFO - Downloaded 1199 bytes. 07:07:58 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:07:58 INFO - Using the following test package requirements: 07:07:58 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:07:58 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:58 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:07:58 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:58 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:07:58 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:58 INFO - u'jsshell-mac.zip'], 07:07:58 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:58 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:07:58 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:07:58 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:58 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:07:58 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:58 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:07:58 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:58 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:07:58 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:58 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:07:58 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:07:58 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')]} 07:07:58 INFO - https://queue.taskcluster.net/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:07:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:07:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:07:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/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 07:07:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/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 07:07:59 INFO - Downloaded 18149661 bytes. 07:07:59 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'] 07:07:59 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 07:08:02 INFO - Return code: 0 07:08:02 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 07:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:08:02 INFO - https://queue.taskcluster.net/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:08:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:08:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:08:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/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 07:08:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/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 07:08:03 INFO - Downloaded 18149661 bytes. 07:08:03 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'] 07:08:03 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 07:08:07 INFO - Return code: 0 07:08:07 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')]} 07:08:07 INFO - https://queue.taskcluster.net/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:08:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:08:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:08:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/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 07:08:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/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 07:08:08 INFO - Downloaded 10840152 bytes. 07:08:08 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'] 07:08:08 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 07:08:08 INFO - Return code: 0 07:08:08 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')]} 07:08:08 INFO - https://queue.taskcluster.net/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:08:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:08:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:08:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:08:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Be02VjSBQ3aIZS50URsbdQ/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 07:08:12 INFO - Downloaded 109785424 bytes. 07:08:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:08:12 INFO - mkdir: /builds/slave/test/properties 07:08:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:08:12 INFO - Writing to file /builds/slave/test/properties/build_url 07:08:12 INFO - Contents: 07:08:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:08:13 INFO - Running post-action listener: _resource_record_post_action 07:08:13 INFO - Running post-action listener: set_extra_try_arguments 07:08:13 INFO - ##### 07:08:13 INFO - ##### Finished download-and-extract step (success) 07:08:13 INFO - ##### 07:08:13 INFO - ##### 07:08:13 INFO - ##### Running populate-webroot step. 07:08:13 INFO - ##### 07:08:13 INFO - Running pre-action listener: _resource_record_pre_action 07:08:13 INFO - Running main action method: populate_webroot 07:08:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:08:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:08:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:08:13 INFO - '--tppagecycles', 07:08:13 INFO - '7'], 07:08:13 INFO - 'tests': ['tsvgm']}}, 07:08:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:08:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:08:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:08:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:08:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:08:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:08:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:08:13 INFO - 'talos_options': ['--disable-e10s'], 07:08:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:08:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:08:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:08:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:08:13 INFO - 'talos_options': ['--disable-e10s'], 07:08:13 INFO - 'tests': ['damp', 'tps']}, 07:08:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:08:13 INFO - 'tests': ['damp', 'tps']}, 07:08:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:08:13 INFO - 'tests': ['dromaeo_dom']}, 07:08:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:08:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:08:13 INFO - 'tests': ['a11yr', 07:08:13 INFO - 'ts_paint', 07:08:13 INFO - 'tpaint', 07:08:13 INFO - 'sessionrestore', 07:08:13 INFO - 'sessionrestore_no_auto_restore', 07:08:13 INFO - 'tabpaint']}, 07:08:13 INFO - 'other-e10s': {'tests': ['a11yr', 07:08:13 INFO - 'ts_paint', 07:08:13 INFO - 'tpaint', 07:08:13 INFO - 'sessionrestore', 07:08:13 INFO - 'sessionrestore_no_auto_restore', 07:08:13 INFO - 'tabpaint']}, 07:08:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:08:13 INFO - 'ts_paint', 07:08:13 INFO - 'tpaint', 07:08:13 INFO - 'sessionrestore', 07:08:13 INFO - 'sessionrestore_no_auto_restore', 07:08:13 INFO - 'tabpaint']}, 07:08:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:08:13 INFO - 'ts_paint', 07:08:13 INFO - 'tpaint', 07:08:13 INFO - 'sessionrestore', 07:08:13 INFO - 'sessionrestore_no_auto_restore', 07:08:13 INFO - 'tabpaint']}, 07:08:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:08:13 INFO - 'tests': ['a11yr', 07:08:13 INFO - 'ts_paint', 07:08:13 INFO - 'tpaint', 07:08:13 INFO - 'sessionrestore', 07:08:13 INFO - 'sessionrestore_no_auto_restore', 07:08:13 INFO - 'tabpaint']}, 07:08:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:08:13 INFO - 'tests': ['a11yr', 07:08:13 INFO - 'ts_paint', 07:08:13 INFO - 'tpaint', 07:08:13 INFO - 'sessionrestore', 07:08:13 INFO - 'sessionrestore_no_auto_restore', 07:08:13 INFO - 'tabpaint']}, 07:08:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:08:13 INFO - 'tests': ['tsvgx', 07:08:13 INFO - 'tsvgr_opacity', 07:08:13 INFO - 'tart', 07:08:13 INFO - 'tscrollx', 07:08:13 INFO - 'cart']}, 07:08:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:08:13 INFO - 'tsvgr_opacity', 07:08:13 INFO - 'tart', 07:08:13 INFO - 'tscrollx', 07:08:13 INFO - 'cart']}, 07:08:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:08:13 INFO - 'talos_options': ['--disable-e10s'], 07:08:13 INFO - 'tests': ['tp5o']}, 07:08:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:08:13 INFO - 'tests': ['tp5o']}, 07:08:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:08:13 INFO - 'talos_options': ['--disable-e10s', 07:08:13 INFO - '--xperf_path', 07:08:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:08:13 INFO - 'tests': ['tp5n']}, 07:08:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:08:13 INFO - 'talos_options': ['--xperf_path', 07:08:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:08:13 INFO - 'tests': ['tp5n']}}, 07:08:13 INFO - 'talos.zip': {'path': '', 07:08:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:08:13 INFO - Running post-action listener: _resource_record_post_action 07:08:13 INFO - ##### 07:08:13 INFO - ##### Finished populate-webroot step (success) 07:08:13 INFO - ##### 07:08:13 INFO - ##### 07:08:13 INFO - ##### Running create-virtualenv step. 07:08:13 INFO - ##### 07:08:13 INFO - Running pre-action listener: _resource_record_pre_action 07:08:13 INFO - Running main action method: create_virtualenv 07:08:13 INFO - Creating virtualenv /builds/slave/test/build/venv 07:08:13 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 07:08:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:08:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:08:13 INFO - Using real prefix '/tools/python27' 07:08:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:08:14 INFO - Installing distribute.............................................................................................................................................................................................done. 07:08:17 INFO - Installing pip.................done. 07:08:17 INFO - Return code: 0 07:08:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:08: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')]} 07:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08: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')]} 07:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:17 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 0x10d105a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d10b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe40ac655b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa9d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa0b20>, '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.qP56LBHJTA/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.0uF8YDmu9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:08:17 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 07:08:17 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 07:08:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qP56LBHJTA/Render', 07:08:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:17 INFO - 'HOME': '/Users/cltbld', 07:08:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:17 INFO - 'LOGNAME': 'cltbld', 07:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:17 INFO - 'MOZ_NO_REMOTE': '1', 07:08:17 INFO - 'NO_EM_RESTART': '1', 07:08:17 INFO - 'PAGER': '/bin/cat', 07:08:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:17 INFO - 'PWD': '/builds/slave/test', 07:08:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:17 INFO - 'SHELL': '/bin/bash', 07:08:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0uF8YDmu9m/Listeners', 07:08:17 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:08:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:17 INFO - 'USER': 'cltbld', 07:08:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:17 INFO - 'XPC_FLAGS': '0x0', 07:08:17 INFO - 'XPC_SERVICE_NAME': '0', 07:08:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:17 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 07:08:17 INFO - Cleaning up... 07:08:17 INFO - Return code: 0 07:08:17 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:08: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')]} 07:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08: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')]} 07:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:17 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 0x10d105a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d10b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe40ac655b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa9d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa0b20>, '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.qP56LBHJTA/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.0uF8YDmu9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:08:17 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 07:08:17 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 07:08:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qP56LBHJTA/Render', 07:08:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:17 INFO - 'HOME': '/Users/cltbld', 07:08:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:17 INFO - 'LOGNAME': 'cltbld', 07:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:17 INFO - 'MOZ_NO_REMOTE': '1', 07:08:17 INFO - 'NO_EM_RESTART': '1', 07:08:17 INFO - 'PAGER': '/bin/cat', 07:08:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:17 INFO - 'PWD': '/builds/slave/test', 07:08:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:17 INFO - 'SHELL': '/bin/bash', 07:08:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0uF8YDmu9m/Listeners', 07:08:17 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:08:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:17 INFO - 'USER': 'cltbld', 07:08:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:17 INFO - 'XPC_FLAGS': '0x0', 07:08:17 INFO - 'XPC_SERVICE_NAME': '0', 07:08:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:17 INFO - Downloading/unpacking psutil>=0.7.1 07:08:17 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 07:08:17 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 07:08:17 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 07:08:17 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 07:08:17 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 07:08:17 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 07:08:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:08:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:08:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:08:20 INFO - Installing collected packages: psutil 07:08:20 INFO - Running setup.py install for psutil 07:08:20 INFO - building 'psutil._psutil_osx' extension 07:08:20 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 07:08:21 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 07:08:21 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 07:08:21 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 07:08:21 INFO - building 'psutil._psutil_posix' extension 07:08:21 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 07:08:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:08:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:08:21 INFO - ^ 07:08:21 INFO - 1 warning generated. 07:08:21 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 07:08:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:08:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:08:21 INFO - Successfully installed psutil 07:08:21 INFO - Cleaning up... 07:08:21 INFO - Return code: 0 07:08:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:08: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')]} 07:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08: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')]} 07:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:21 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 0x10d105a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d10b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe40ac655b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa9d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa0b20>, '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.qP56LBHJTA/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.0uF8YDmu9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:08:21 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 07:08:21 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 07:08:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qP56LBHJTA/Render', 07:08:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:21 INFO - 'HOME': '/Users/cltbld', 07:08:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:21 INFO - 'LOGNAME': 'cltbld', 07:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:21 INFO - 'MOZ_NO_REMOTE': '1', 07:08:21 INFO - 'NO_EM_RESTART': '1', 07:08:21 INFO - 'PAGER': '/bin/cat', 07:08:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:21 INFO - 'PWD': '/builds/slave/test', 07:08:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:21 INFO - 'SHELL': '/bin/bash', 07:08:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0uF8YDmu9m/Listeners', 07:08:21 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:08:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:21 INFO - 'USER': 'cltbld', 07:08:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:21 INFO - 'XPC_FLAGS': '0x0', 07:08:21 INFO - 'XPC_SERVICE_NAME': '0', 07:08:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:22 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:08:22 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 07:08:22 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 07:08:22 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 07:08:22 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 07:08:22 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 07:08:22 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 07:08:24 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:08:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:08:24 INFO - Installing collected packages: mozsystemmonitor 07:08:24 INFO - Running setup.py install for mozsystemmonitor 07:08:24 INFO - Successfully installed mozsystemmonitor 07:08:24 INFO - Cleaning up... 07:08:24 INFO - Return code: 0 07:08:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:08:24 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')]} 07:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:24 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')]} 07:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:24 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 0x10d105a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d10b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe40ac655b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa9d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa0b20>, '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.qP56LBHJTA/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.0uF8YDmu9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:08:24 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 07:08:24 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 07:08:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qP56LBHJTA/Render', 07:08:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:24 INFO - 'HOME': '/Users/cltbld', 07:08:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:24 INFO - 'LOGNAME': 'cltbld', 07:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:24 INFO - 'MOZ_NO_REMOTE': '1', 07:08:24 INFO - 'NO_EM_RESTART': '1', 07:08:24 INFO - 'PAGER': '/bin/cat', 07:08:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:24 INFO - 'PWD': '/builds/slave/test', 07:08:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:24 INFO - 'SHELL': '/bin/bash', 07:08:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0uF8YDmu9m/Listeners', 07:08:24 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:08:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:24 INFO - 'USER': 'cltbld', 07:08:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:24 INFO - 'XPC_FLAGS': '0x0', 07:08:24 INFO - 'XPC_SERVICE_NAME': '0', 07:08:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:25 INFO - Downloading/unpacking blobuploader==1.2.4 07:08:25 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 07:08:25 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 07:08:25 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 07:08:25 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 07:08:25 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 07:08:25 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 07:08:27 INFO - Downloading blobuploader-1.2.4.tar.gz 07:08:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:08:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:08:27 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 07:08:27 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 07:08:27 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 07:08:27 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 07:08:27 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 07:08:27 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 07:08:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:08:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:28 INFO - Downloading docopt-0.6.1.tar.gz 07:08:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:08:28 INFO - Installing collected packages: blobuploader, requests, docopt 07:08:28 INFO - Running setup.py install for blobuploader 07:08:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:08:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:08:28 INFO - Running setup.py install for requests 07:08:29 INFO - Running setup.py install for docopt 07:08:29 INFO - Successfully installed blobuploader requests docopt 07:08:29 INFO - Cleaning up... 07:08:29 INFO - Return code: 0 07:08:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:29 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 0x10d105a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d10b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe40ac655b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa9d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa0b20>, '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.qP56LBHJTA/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.0uF8YDmu9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:08:29 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 07:08:29 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 07:08:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qP56LBHJTA/Render', 07:08:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:29 INFO - 'HOME': '/Users/cltbld', 07:08:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:29 INFO - 'LOGNAME': 'cltbld', 07:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:29 INFO - 'MOZ_NO_REMOTE': '1', 07:08:29 INFO - 'NO_EM_RESTART': '1', 07:08:29 INFO - 'PAGER': '/bin/cat', 07:08:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:29 INFO - 'PWD': '/builds/slave/test', 07:08:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:29 INFO - 'SHELL': '/bin/bash', 07:08:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0uF8YDmu9m/Listeners', 07:08:29 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:08:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:29 INFO - 'USER': 'cltbld', 07:08:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:29 INFO - 'XPC_FLAGS': '0x0', 07:08:29 INFO - 'XPC_SERVICE_NAME': '0', 07:08:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:08:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-zKafiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:08:30 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-7AxJR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:08:30 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Q1AdvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:08:30 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-9yiV28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:08:30 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-BRAgku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:08:30 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-qHtKGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:08:30 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Xn8vgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:08:30 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-maGjT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:08:30 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-30xIi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:08:30 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-fNt0eX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:08:30 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-vd6Fig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:08:31 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-tGsOfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:08:31 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-f9rj8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:08:31 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-RCzT4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:08:31 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-UaDqon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:08:31 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-UuW_pt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:08:31 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-8VLZWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:08:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:08:31 INFO - Running setup.py install for manifestparser 07:08:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:08:31 INFO - Running setup.py install for mozcrash 07:08:31 INFO - Running setup.py install for mozdebug 07:08:32 INFO - Running setup.py install for mozdevice 07:08:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:08:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:08:32 INFO - Running setup.py install for mozfile 07:08:32 INFO - Running setup.py install for mozhttpd 07:08:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:08:32 INFO - Running setup.py install for mozinfo 07:08:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:08:32 INFO - Running setup.py install for mozInstall 07:08:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:08:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:08:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:08:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:08:32 INFO - Running setup.py install for mozleak 07:08:32 INFO - Running setup.py install for mozlog 07:08:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:08:33 INFO - Running setup.py install for moznetwork 07:08:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:08:33 INFO - Running setup.py install for mozprocess 07:08:33 INFO - Running setup.py install for mozprofile 07:08:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:08:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:08:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:08:33 INFO - Running setup.py install for mozrunner 07:08:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:08:33 INFO - Running setup.py install for mozscreenshot 07:08:33 INFO - Running setup.py install for moztest 07:08:34 INFO - Running setup.py install for mozversion 07:08:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:08:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:08:34 INFO - Cleaning up... 07:08:34 INFO - Return code: 0 07:08:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:08: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')]} 07:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08: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')]} 07:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:34 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 0x10d105a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d10b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe40ac655b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa9d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa0b20>, '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.qP56LBHJTA/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.0uF8YDmu9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:08:34 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 07:08:34 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 07:08:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qP56LBHJTA/Render', 07:08:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:34 INFO - 'HOME': '/Users/cltbld', 07:08:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:34 INFO - 'LOGNAME': 'cltbld', 07:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:34 INFO - 'MOZ_NO_REMOTE': '1', 07:08:34 INFO - 'NO_EM_RESTART': '1', 07:08:34 INFO - 'PAGER': '/bin/cat', 07:08:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:34 INFO - 'PWD': '/builds/slave/test', 07:08:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:34 INFO - 'SHELL': '/bin/bash', 07:08:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0uF8YDmu9m/Listeners', 07:08:34 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:08:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:34 INFO - 'USER': 'cltbld', 07:08:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:34 INFO - 'XPC_FLAGS': '0x0', 07:08:34 INFO - 'XPC_SERVICE_NAME': '0', 07:08:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:08:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-_cULZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:08:34 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)) 07:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:08:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-xJYYlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:08:34 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)) 07:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:08:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-P5HFQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:08:34 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)) 07:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:08:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-eSPV9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:08:34 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)) 07:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:08:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-fbGd4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:08:35 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)) 07:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:08:35 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-K8C0fa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:08:35 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)) 07:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:08:35 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-GUZURp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:08:35 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)) 07:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:08:35 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-jXavZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:08:35 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)) 07:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:08:35 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-wpiLzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:08:35 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)) 07:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:08:35 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ITPJR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:08:35 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)) 07:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:08:35 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-aXxxoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:08:35 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)) 07:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:08:35 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-hsZyR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:08:35 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)) 07:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:08:35 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-K1XrZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:08:35 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)) 07:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:08:35 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-vSrP6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:08:36 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)) 07:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:08:36 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-YhWPK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:08:36 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)) 07:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:08:36 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-hjCjVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:08:36 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)) 07:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:08:36 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-XcGSJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:08:36 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)) 07:08:36 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)) 07:08:36 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)) 07:08:36 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)) 07:08:36 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)) 07:08:36 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)) 07:08:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:08:36 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 07:08:36 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 07:08:36 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 07:08:36 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 07:08:36 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 07:08:36 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 07:08:38 INFO - Downloading blessings-1.6.tar.gz 07:08:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:08:38 INFO - Installing collected packages: blessings 07:08:38 INFO - Running setup.py install for blessings 07:08:39 INFO - Successfully installed blessings 07:08:39 INFO - Cleaning up... 07:08:39 INFO - Return code: 0 07:08:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:08:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:08:39 INFO - Reading from file tmpfile_stdout 07:08:39 INFO - Current package versions: 07:08:39 INFO - blessings == 1.6 07:08:39 INFO - blobuploader == 1.2.4 07:08:39 INFO - docopt == 0.6.1 07:08:39 INFO - manifestparser == 1.1 07:08:39 INFO - mozInstall == 1.12 07:08:39 INFO - mozcrash == 0.17 07:08:39 INFO - mozdebug == 0.1 07:08:39 INFO - mozdevice == 0.48 07:08:39 INFO - mozfile == 1.2 07:08:39 INFO - mozhttpd == 0.7 07:08:39 INFO - mozinfo == 0.9 07:08:39 INFO - mozleak == 0.1 07:08:39 INFO - mozlog == 3.1 07:08:39 INFO - moznetwork == 0.27 07:08:39 INFO - mozprocess == 0.22 07:08:39 INFO - mozprofile == 0.28 07:08:39 INFO - mozrunner == 6.11 07:08:39 INFO - mozscreenshot == 0.1 07:08:39 INFO - mozsystemmonitor == 0.0 07:08:39 INFO - moztest == 0.7 07:08:39 INFO - mozversion == 1.4 07:08:39 INFO - psutil == 3.1.1 07:08:39 INFO - requests == 1.2.3 07:08:39 INFO - wsgiref == 0.1.2 07:08:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:39 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 0x10d105a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d10b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe40ac655b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa9d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa0b20>, '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.qP56LBHJTA/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.0uF8YDmu9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:08:39 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 07:08:39 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 07:08:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qP56LBHJTA/Render', 07:08:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:39 INFO - 'HOME': '/Users/cltbld', 07:08:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:39 INFO - 'LOGNAME': 'cltbld', 07:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:39 INFO - 'MOZ_NO_REMOTE': '1', 07:08:39 INFO - 'NO_EM_RESTART': '1', 07:08:39 INFO - 'PAGER': '/bin/cat', 07:08:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:39 INFO - 'PWD': '/builds/slave/test', 07:08:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:39 INFO - 'SHELL': '/bin/bash', 07:08:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0uF8YDmu9m/Listeners', 07:08:39 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:08:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:39 INFO - 'USER': 'cltbld', 07:08:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:39 INFO - 'XPC_FLAGS': '0x0', 07:08:39 INFO - 'XPC_SERVICE_NAME': '0', 07:08:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:39 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)) 07:08:39 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)) 07:08:39 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)) 07:08:39 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)) 07:08:39 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)) 07:08:39 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)) 07:08:39 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)) 07:08:39 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)) 07:08:39 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)) 07:08:39 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)) 07:08:39 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)) 07:08:39 INFO - Cleaning up... 07:08:39 INFO - Return code: 0 07:08:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:39 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 0x10d105a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d10b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe40ac655b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa9d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa0b20>, '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.qP56LBHJTA/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.0uF8YDmu9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:08:39 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 07:08:39 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 07:08:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qP56LBHJTA/Render', 07:08:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:39 INFO - 'HOME': '/Users/cltbld', 07:08:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:39 INFO - 'LOGNAME': 'cltbld', 07:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:39 INFO - 'MOZ_NO_REMOTE': '1', 07:08:39 INFO - 'NO_EM_RESTART': '1', 07:08:39 INFO - 'PAGER': '/bin/cat', 07:08:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:39 INFO - 'PWD': '/builds/slave/test', 07:08:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:39 INFO - 'SHELL': '/bin/bash', 07:08:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0uF8YDmu9m/Listeners', 07:08:39 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:08:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:39 INFO - 'USER': 'cltbld', 07:08:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:39 INFO - 'XPC_FLAGS': '0x0', 07:08:39 INFO - 'XPC_SERVICE_NAME': '0', 07:08:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:39 INFO - Downloading/unpacking jsonschema 07:08:39 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 07:08:39 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 07:08:39 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 07:08:39 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 07:08:39 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 07:08:39 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 07:08:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:08:42 INFO - Downloading/unpacking functools32 (from jsonschema) 07:08:42 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 07:08:42 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 07:08:42 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 07:08:42 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 07:08:42 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 07:08:42 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 07:08:42 INFO - Downloading functools32-3.2.3-2.tar.gz 07:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:08:42 INFO - warning: no files found matching '*.txt' 07:08:42 INFO - no previously-included directories found matching 'build' 07:08:42 INFO - no previously-included directories found matching 'dist' 07:08:42 INFO - no previously-included directories found matching '.git*' 07:08:42 INFO - Installing collected packages: jsonschema, functools32 07:08:42 INFO - Running setup.py install for functools32 07:08:43 INFO - warning: no files found matching '*.txt' 07:08:43 INFO - no previously-included directories found matching 'build' 07:08:43 INFO - no previously-included directories found matching 'dist' 07:08:43 INFO - no previously-included directories found matching '.git*' 07:08:43 INFO - Successfully installed jsonschema functools32 07:08:43 INFO - Cleaning up... 07:08:43 INFO - Return code: 0 07:08:43 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:08:43 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')]} 07:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:43 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')]} 07:08:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:43 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 0x10d105a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d10b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe40ac655b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa9d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa0b20>, '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.qP56LBHJTA/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.0uF8YDmu9m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:08:43 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 07:08:43 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 07:08:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qP56LBHJTA/Render', 07:08:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:43 INFO - 'HOME': '/Users/cltbld', 07:08:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:43 INFO - 'LOGNAME': 'cltbld', 07:08:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:43 INFO - 'MOZ_NO_REMOTE': '1', 07:08:43 INFO - 'NO_EM_RESTART': '1', 07:08:43 INFO - 'PAGER': '/bin/cat', 07:08:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:43 INFO - 'PWD': '/builds/slave/test', 07:08:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:43 INFO - 'SHELL': '/bin/bash', 07:08:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0uF8YDmu9m/Listeners', 07:08:43 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:08:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:43 INFO - 'USER': 'cltbld', 07:08:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:43 INFO - 'XPC_FLAGS': '0x0', 07:08:43 INFO - 'XPC_SERVICE_NAME': '0', 07:08:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:43 INFO - Downloading/unpacking flake8 07:08:43 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 07:08:43 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 07:08:43 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 07:08:43 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 07:08:43 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 07:08:43 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 07:08:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:08:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:08:45 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 07:08:45 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 07:08:45 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 07:08:45 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 07:08:45 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 07:08:45 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 07:08:46 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:08:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:08:46 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 07:08:46 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 07:08:46 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 07:08:46 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 07:08:46 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 07:08:46 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 07:08:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:08:46 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 07:08:46 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 07:08:46 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 07:08:46 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 07:08:46 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 07:08:46 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 07:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:08:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:08:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:08:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:08:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:08:47 INFO - no previously-included directories found matching 'docs/_build' 07:08:47 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:08:47 INFO - Running setup.py install for pep8 07:08:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:08:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:08:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:08:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:08:47 INFO - no previously-included directories found matching 'docs/_build' 07:08:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:08:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:08:47 INFO - Cleaning up... 07:08:47 INFO - Return code: 0 07:08:47 INFO - Running post-action listener: _resource_record_post_action 07:08:47 INFO - Running post-action listener: _start_resource_monitoring 07:08:47 INFO - Starting resource monitoring. 07:08:47 INFO - ##### 07:08:47 INFO - ##### Finished create-virtualenv step (success) 07:08:47 INFO - ##### 07:08:47 INFO - ##### 07:08:47 INFO - ##### Running install step. 07:08:47 INFO - ##### 07:08:47 INFO - Running pre-action listener: _resource_record_pre_action 07:08:47 INFO - Running main action method: install 07:08:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:08:47 INFO - Reading from file tmpfile_stdout 07:08:47 INFO - Detecting whether we're running mozinstall >=1.0... 07:08:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:08:47 INFO - Reading from file tmpfile_stdout 07:08:47 INFO - Output received: 07:08:47 INFO - Usage: mozinstall [options] installer 07:08:47 INFO - Options: 07:08:47 INFO - -h, --help show this help message and exit 07:08:47 INFO - -d DEST, --destination=DEST 07:08:47 INFO - Directory to install application into. [default: 07:08:47 INFO - "/builds/slave/test"] 07:08:47 INFO - --app=APP Application being installed. [default: firefox] 07:08:47 INFO - mkdir: /builds/slave/test/build/application 07:08:47 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'] 07:08:47 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 07:09:21 INFO - Reading from file tmpfile_stdout 07:09:21 INFO - Output received: 07:09:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:09:21 INFO - Running post-action listener: _resource_record_post_action 07:09:21 INFO - ##### 07:09:21 INFO - ##### Finished install step (success) 07:09:21 INFO - ##### 07:09:21 INFO - ##### 07:09:21 INFO - ##### Running run-tests step. 07:09:21 INFO - ##### 07:09:21 INFO - Running pre-action listener: _resource_record_pre_action 07:09:21 INFO - Running main action method: run_tests 07:09:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:09:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:09:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:09:21 INFO - 2016-05-10 07:09:21.800 screenresolution[2244:15946] starting screenresolution argv=screenresolution get 07:09:21 INFO - 2016-05-10 07:09:21.803 screenresolution[2244:15946] Display 0: 1600x1200x32@60 07:09:21 INFO - 2016-05-10 07:09:21.809 screenresolution[2245:15948] starting screenresolution argv=screenresolution list 07:09:21 INFO - Available Modes on Display 0 07:09:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:09:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:09:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:09:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:09:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:09:22 INFO - Intel Iris: 07:09:22 INFO - Chipset Model: Intel Iris 07:09:22 INFO - Type: GPU 07:09:22 INFO - Bus: Built-In 07:09:22 INFO - VRAM (Dynamic, Max): 1536 MB 07:09:22 INFO - Vendor: Intel (0x8086) 07:09:22 INFO - Device ID: 0x0a2e 07:09:22 INFO - Revision ID: 0x0009 07:09:22 INFO - Displays: 07:09:22 INFO - Display: 07:09:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:09:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:09:22 INFO - Display Serial Number: 1600x1200 60 07:09:22 INFO - Main Display: Yes 07:09:22 INFO - Mirror: Off 07:09:22 INFO - Online: Yes 07:09:22 INFO - Rotation: Supported 07:09:22 INFO - Return code: 0 07:09:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:09:22 INFO - Python 2.7.3 07:09:22 INFO - Return code: 0 07:09:22 INFO - grabbing minidump binary from tooltool 07:09:22 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')]} 07:09:22 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 0x10cfa9d30>, '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 0x10cdcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfa0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:09:22 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 07:09:22 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 07:09:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:09:22 INFO - Return code: 0 07:09:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:09:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:09:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:09:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:09:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:09:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:09:23 INFO - Return code: 0 07:09:23 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', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:09:23 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 --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0074 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:09:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qP56LBHJTA/Render', 07:09:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:09:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:09:23 INFO - 'HOME': '/Users/cltbld', 07:09:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:09:23 INFO - 'LOGNAME': 'cltbld', 07:09:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:09:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:23 INFO - 'MOZ_NO_REMOTE': '1', 07:09:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:09:23 INFO - 'NO_EM_RESTART': '1', 07:09:23 INFO - 'PAGER': '/bin/cat', 07:09:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:23 INFO - 'PWD': '/builds/slave/test', 07:09:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:09:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:09:23 INFO - 'SHELL': '/bin/bash', 07:09:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0uF8YDmu9m/Listeners', 07:09:23 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:09:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:09:23 INFO - 'USER': 'cltbld', 07:09:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:09:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:23 INFO - 'XPC_FLAGS': '0x0', 07:09:23 INFO - 'XPC_SERVICE_NAME': '0', 07:09:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:09:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:09:23 INFO - mozversion application_buildid: 20160510043413 07:09:23 INFO - mozversion application_changeset: 88e5c19cd98ed6e353d9a762fb460976a7d4b244 07:09:23 INFO - mozversion application_display_name: Nightly 07:09:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:09:23 INFO - mozversion application_name: Firefox 07:09:23 INFO - mozversion application_remotingname: firefox 07:09:23 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:09:23 INFO - mozversion application_vendor: Mozilla 07:09:23 INFO - mozversion application_version: 49.0a1 07:09:23 INFO - mozversion platform_buildid: 20160510043413 07:09:23 INFO - mozversion platform_changeset: 88e5c19cd98ed6e353d9a762fb460976a7d4b244 07:09:23 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:09:23 INFO - mozversion platform_version: 49.0a1 07:09:23 INFO - using testdate: 1462889363 07:09:23 INFO - actual date: 1462889363 07:09:23 INFO - starting webserver on 'localhost:51808' 07:09:23 INFO - SUITE-START | Running 2 tests 07:09:23 INFO - TEST-START | dromaeo_css 07:09:23 INFO - operating with platform_type : mac_ 07:09:23 INFO - Initialising browser for dromaeo_css test... 07:09:23 INFO - TEST-INFO | started process 2263 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpjGDvg_/profile http://localhost:51808/getInfo.html) 07:09:25 INFO - PROCESS | 2263 | __metrics Screen width/height:1600/1200 07:09:25 INFO - PROCESS | 2263 | colorDepth:24 07:09:25 INFO - PROCESS | 2263 | Browser inner width/height: 1024/689 07:09:25 INFO - PROCESS | 2263 | __metrics 07:09:25 INFO - PROCESS | 2263 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:09:25 ERROR - PROCESS | 2263 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:09:26 INFO - TEST-INFO | 2263: exit 0 07:09:26 INFO - Browser initialized. 07:09:26 INFO - Running cycle 1/1 for dromaeo_css test... 07:09:26 INFO - TEST-INFO | started process 2265 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpjGDvg_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:09:38 INFO - PROCESS | 2265 | RSS: Main: 238641152 07:09:38 INFO - PROCESS | 2265 | 07:10:29 INFO - PROCESS | 2265 | 1462889429166 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] 07:12:39 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51808/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51808/tests/dromaeo/cssquery-ext.html) 07:12:41 INFO - PROCESS | 2265 | RSS: Main: 380284928 07:12:41 INFO - PROCESS | 2265 | 07:15:44 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51808/tests/dromaeo/cssquery-ext.html (next: http://localhost:51808/tests/dromaeo/cssquery-jquery.html) 07:15:45 INFO - PROCESS | 2265 | RSS: Main: 374607872 07:15:45 INFO - PROCESS | 2265 | 07:18:47 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51808/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51808/tests/dromaeo/cssquery-mootools.html) 07:18:48 INFO - PROCESS | 2265 | RSS: Main: 367890432 07:18:48 INFO - PROCESS | 2265 | 07:21:50 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51808/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51808/tests/dromaeo/cssquery-prototype.html) 07:21:51 INFO - PROCESS | 2265 | RSS: Main: 369238016 07:21:51 INFO - PROCESS | 2265 | 07:24:54 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51808/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51808/tests/dromaeo/cssquery-yui.html) 07:24:55 INFO - PROCESS | 2265 | RSS: Main: 338128896 07:24:55 INFO - PROCESS | 2265 | 07:27:58 INFO - PROCESS | 2265 | Cycle 1(1): loaded http://localhost:51808/tests/dromaeo/cssquery-yui.html (next: http://localhost:51808/tests/dromaeo/cssquery-dojo.html) 07:27:58 INFO - PROCESS | 2265 | RSS: Main: 377782272 07:27:58 INFO - PROCESS | 2265 | 07:27:58 INFO - PROCESS | 2265 | __start_tp_report 07:27:58 INFO - PROCESS | 2265 | _x_x_mozilla_page_load 07:27:58 INFO - PROCESS | 2265 | _x_x_mozilla_page_load_details 07:27:58 INFO - PROCESS | 2265 | |i|pagename|runs| 07:27:58 INFO - PROCESS | 2265 | |0;dojo.html;3391.2708767614963;3402.9489557656657;3417.3507033663595;3440.3807314683377;3457.567804024497;5598.916016259759;5610.803621873235;5628.296462942132;5682.971585142073;5771.36514983352;4666.650001249911;5622.128570071635;5683.772649094041;5744.741486633101;5792.102224155252;4127.752334859903;5083.059633967728;5144.536991670749;5157.896842063175;5308.362996440433;5218.895622087556;5225.399079105894;5261.473852614727;5282.630215884889;5340.572754179676;5094.184930411154;5120.743962801845;5135.974320128375;5179.766910489018;5204.609654275907;5168.870778230575;5173.094708426048;5178.585713142939;5181.300524429229;5191.558717509016;5058.4435712071645;5114.718690472023;5127.102757017507;5128.461511541309;5154.149565321717;5547.1124620061;5603.915941260843;5606.018946684355;5664.858378540528;5692.9146062809095;6170.7531698732;6182.907256391113;6248.094026366197;6256.718447669866;6265;4955.554000140005;6113.5371179039;6166.506679465632;6291.716872740738;6304.810855674337;5692.686902220376;5708.1723150143;5774.162746401748;5777.942220577825;5781.075027995501;6267.874642507124;6272.059191121369;6274.215723034621;6280.277768056732;6291.591046581958;5914.6746928918465;5915.467607915309;6057.454829065406;6095.66473843934;6120.632762034292;6210.472109870621;6261.436470717657;6268.1851359323;6279.3720627937755;6288.056791481315;5469.261649677323;5819.156222347736;6182.350853160443;6186.876262474725;6225.28409232935;6057;6095.801078121664;6127.816165515042;6137.417646941698;6191.690415479208;4065.735727177724;4863.2461968394955;4913.287573301851;4914.238293064581;4947.5794557462295;4166.83332666696;4350.73895566267;4352.542982986804;4365.388845561624;4383.868483945486;5498.45015498455;5527.751251193706;5600.551955843523;5670;5689.630174038674;6095.861538843069;6164.075388691643;6197.03945888388;6200.255969283715;6210.068489726486;5559.388467268597;5559.610827242135;5570.637908535932;5572.359178694421;5588.3573389060775;5260.184671375943;5263.6578622389425;5272.472752724773;5277.577793776489;5279.47205279469;6203.96898015516;6233.688315584202;6245.313015568375;6294.5279104068295;6303.180586523814;6269.996800511896;6272.560920735505;6283.842903927439;6299.370062993664;6302.684865756602;6246.281677607133;6249.375062493896;6291.496680265659;6306.905396419149;6307.747690092345;6096.695165241631;6127.4570804235245;6136.386361363828;6144.354842741626;6152.323244443018;6187.288461826947;6249.437550620284;6255.53083518747;6277.811665650038;6279.560430769802;5887.8233652990475;5964.671943043174;6118.265808103056;6142.3550527194;6163.599366041193;5884.264466941632;5980.641161530322;6044.818655440344;6072.240969878765;6072.392760723892;5844.736986835687;5903.203067585821;5917.822465326047;5922.674252916131;5963.552733544915;5893.351731309638;5940.762369505172;6095.994046880814;6114.908276375769;6118.449339559461;5823.242978412779;5861.794837180678;5869.706514674334;5877.35349111606;5880.94119058804 07:27:58 INFO - PROCESS | 2265 | |1;ext.html;499.72515116685815;506.8418663354236;520.6511637203074;529.1295818378767;551.9696416697082;30523.694763052365;30693.539596906063;32061.67938320616;42203.36694949578;43242.783786081025;44262.114757704905;44302.55697443025;44541.77729111358;44573.33140002903;44695;9525.904740952588;9695.078967498077;9699;9712.27157963152;9750.756231094243;8633;8833.07252738463;9557.044295570422;11550.249233799776;11771.823422648624;11713;11795.05639548834;11796.174267801258;11801.055915526736;11817.586384476544;8577.442466239674;11541.596044138458;11663.533458661686;11674.766504669858;11679.007284380752;11113.611023614216;11422.771544569063;11427;11510;11531.827022594583;40801.387979180334;41152;41180;41187.7940610298;41221.381679274535;3370.5449764281752;3528.4001719707453;3528.4883691864534;3694.3350196964534;3938.7636741795586;28358;29788.553171702442;29801;30033.399332013454;30113.397732045236;2758.158761577718;2810.269329974201;2818.182727009165;2845.2886778305424;2856.3573196030934;520474;524556.9999999962;524696.3765181226;524989;525375;54010.72994635002;63723.681381592796;65230.67384663141;65921;66095.66952165305;64547;69598;69612;69645.65177174183;69675.3032469679;16891.91554042222;17281.913590432217;17289.913550432168;17290.394836180672;17295.135243237786;11996.280223186463;12214.389280536116;12994.155379900276;13094.345282735825;13125.278109704059;19376.477921461308;20523;20558.28046018382;20595.58808823845;20606.381808545768;8168.877466838002;8211.384146189059;8214.301784348398;8245.95877020611;8294.129116442808;891.0732837848565;909.5452273863068;974.4360301715786;1059.9612379867772;1081.875584307799;29541.30176100678;29904;30377.848110759303;30390;30447.695523044928;933.9346245762731;1081.081081081075;1145.5761368293784;1157.4039369724599;1160.1356989043047;15460.149691767061;16020.759688604447;16265.268062937197;16537.669246615;16602;548878;612214;613250;617567;621662;61341;61598.69200654058;61824;61882.69058654678;62222.377776222565;1300.9154404963647;1431.7422863884601;1442.386985531145;1455.7161353536171;1462.8464011278663;29970;30015.636786915024;30068.050950968765;30186.622265829596;30245;49650;51309;52094.739526303645;52147.217791733914;52151.95696085981;483725;483912.58043709554;496885;497180;499822;23305.76694233036;25339.113131040325;25966.48067038611;26760.330991724582;27161.962852786517;39894.40158397568;44292.778536107115;44441.333379999975;45580.08839823251;46358;33625.66374336225;35620.82189589035;37289;38009.85970420892;38174.42738358983;33899.66100338965;36384.180790959814;36432;36463.45304820483;37300.6269937308;36282.4557631641;36841.63158368382;37796;37798.866034019025;37816.24367512579;28013.57979630348;30091.849540752155;31276;31488;32637.836810815796;20595;24122.638160427257;24231;24591.877040614683;24741.752582474666 07:27:58 INFO - PROCESS | 2265 | |2;jquery.html;387.3086540525162;389.696037091069;392.801635174237;393.9586343433941;414.1861242658176;14210.71578568428;20177.19291228351;21157.04793284302;21716.782832171673;21771.455713607167;15540.456084037061;17936.551586210353;20634.277800276992;20803.583928321423;20964.475888102806;5761.769529218826;5994.910076348859;6032.125341825455;6043.3956604339655;6060.636361818283;5683.488486036256;6011.879762404749;6024.879502409949;6026.216591843055;6067.939320606803;5953.493953013977;5969.373215812342;5973.342932277446;5980.88038239235;5983.222181116471;5666.1784041314195;5856.32652244993;5931.584789064767;5948.018576934801;5953.493953013977;5033.647644664875;5046.873828154289;5058.0895438821;5064.215046667736;5072.847814565569;23825.046998120055;24963.62554561683;25024.374390640198;25341.85961631731;26027.219183424528;2083.312506872728;2228.5208680133687;2228.8662680239077;2259.435141214696;2292.346681195868;9108.68119615817;9842.163416109639;10141.290109692325;14425.639359016004;14936.701265974729;1639.131260431961;1659.759295099835;1681.2266357475576;1782.3405340024144;1807.7198034304572;75922;114568.85431145666;130006;133846;134270.6572934268;11467.827982580267;11484.88515114838;11534.307941523535;11546.76906461866;11689.941550292195;14752.852471475362;14853.554393368217;14859.628509287355;14870.18213998219;14882.330295136744;6406.251501840659;6703.257153140121;6959.965200174068;7604.163542010375;8015.599220038975;6887.5178737487895;8509.829803404022;8513.106123856905;8514.276286515715;8623.956880215559;4478.708883922534;4556.225441674923;4564.132814765184;4604.1252162089095;4640.675152739276;1799.1454059321718;1812.1935738596198;1883.50087226885;1926.2872737087368;1937.2541571495076;872.7207293666092;916.3631276263067;917.0645941140133;998.9950050249702;1023.150784848574;15290.541283761506;18897.622047558973;19521.804781952284;19882.50293742639;20093;895.4403497813867;967.1923943507251;996.2179688944144;1013.1135256650431;1034.7516596017051;10342.638007669846;10515.684529464128;10554.997275258838;10584.470776461147;10599.099076578423;596442;600344;609552.9522352449;614836;617579;133031.3348433271;133112.66887331195;133405;134061.3296933509;138695.30652346875;864.3906046237513;1003.5032658833924;1023.3706270643501;1023.8259495885717;1040.9531571079472;17522.649547008732;17821.821781782015;17894.91052544729;17918.104094795468;18124.27502899922;43342.34986475269;47088;51271.48728512667;53053.7347313261;53861.46138538564;73024;80601.3879722414;82233;82877.58561207155;84003.57998210215;7734.7679569613;8123.79690507742;8222.424430289822;8254.339652827639;8519.062903080532;11673.416329183337;12294.323812190416;12334.136610437183;12636.305003224543;12720.427580758704;10500.789984200426;10725.08836748885;10751.677449676523;10823.296485728402;10911.290766100177;10542.947285263524;10580.047795698423;10695.893041069801;10809.621663241749;10823.56705731762;11057.336559806437;11329.830052549054;11390.544378224778;11453.02649274821;11478.94260528692;6620.801375958729;7988.92011079905;8405.663773448994;11083.612073577386;12059.397030148633;7905.960470197612;10914.126869850552;11598.94200528992;11608.535658573563;11715.824262635897 07:27:58 INFO - PROCESS | 2265 | |3;mootools.html;1398.461592286969;1436.7988481612576;1538.115583539465;1611.363511412992;1791.3372052340635;8000.91999080009;8777.473351598901;9037.683681071163;9039.54802259886;9169.03725108864;7644.847103057936;8893.155150260714;8990.595423205956;9010.68462603809;9027;2150.2796563151364;2225.4213904384897;2228.4428892776805;2427.647991041298;2477.355887469262;2222.20000799712;2407.3981504623844;2495.887685054168;2497.1884137655243;2499.4126380300704;2408.9397765055837;2440.4264997725522;2441.1944058460667;2447.119037146626;2448.4735781806908;2078.5011597216712;2120.925767598142;2400.95198096038;2449.3386785567795;2450.277168235366;2386.880655967212;2460.7047154341412;2461.6307553866886;2462.950740985188;2466.962995555068;8860.645574177026;8907.421017633833;8909.866352004725;8926.955365223197;8930.732078037669;5229.764660590283;5402.189671549259;5439.864003399946;5446.727663616843;5450.781968721247;3410.3179364127272;3524.8061356625376;3611.9886431799137;3616.385214513539;3623.058004918714;1029.2846471702169;1037.667946257198;1039.6932904793105;1041.3803786746873;1041.7343577387794;11867.52529898803;11871.762564748657;11874.16880818343;11885.5840045598;11891.28652280866;4735.242361222188;4806.903648176403;4868.367112275381;4872.4883887192045;4912.263160525878;9933.155681766986;9970.351927124859;10000.14998725102;10000.849987250198;10069.949650251701;5305.31030965972;5453.10023846061;5453.291072160594;5454.290942177571;5460.126379779216;4447.132809102194;4933.531314525115;4980.128477516492;4996.90006199874;5000.174971129795;3304.5208444775853;4019.799010049486;4054.8378064877666;4090.652294554996;4127.174565086994;1326.7678156322604;1337.752515784583;1339.2366351179928;1352.0332961932265;1353.810466534686;779.3959681247079;916.1159481100769;930.7812664023949;944.2210176604328;955.8805149356331;5885.352611212763;6055.2430946131735;6078.696065196723;6197.752089916443;6287.2140982377205;917.3807679816098;958.1759686669459;966.1256562810668;967.5791030901439;996.9950150249348;3858.498395208605;3928.685705143582;4031.496062992126;4045.271851066836;4063.512378514597;10634.415107169025;11121.721956951142;11230.10159187263;11239.269447485887;11257.831132533018;11020.614278500214;11145.108391328675;11145.331280123219;11179.83230251547;11202.775944481065;7953;8364.958175209085;8443.240108390151;8453.281471075028;8478.703245386381;4548.56788605082;5858.062709966385;6015.533301700601;6029.879402412015;6086.330503644507;10007.39955602637;10199.28604997643;10303.9999999997;10326.122279606318;10326.845097323396;9216.354855160344;9652.855207171757;10359.741006474596;10365;10416.635417760343;6551.181102362205;7667.386609071149;7684.731034413769;7783.688652454065;7785.92214077852;8322.16778322163;8365.205305495969;8461.703840365557;8467.745967620982;8522;7611.314981651678;7695.268949449794;7766.456348055582;7792.415568832247;7875.409344299086;7691.30778229962;7768.067831860213;7768.961155194188;7902.604869756604;7925.801854953672;5929.733162007807;6794.150731158606;7018.052562903943;7110.6089165094345;7114.822129446805;5153.587712982878;5158.793648254178;5198.090334191577;5208.869778255574;5311.946880531145;4143.875683729493;5127.564157046693;5235.424103348552;5314.202869569595;5462.289902312674 07:27:58 INFO - PROCESS | 2265 | |4;prototype.html;301.4949958818979;304.4611038461922;320.42483741683685;320.54162547556984;323.6585402400468;5118.059094497235;5259.710715910624;5300.250917197325;5307.734613269331;5336.891927688565;4864.735168856097;5071.644984851062;5226.693326668333;5244.501772331622;5264.684118952856;3969.24584328978;3991.381335892941;4007.7194596378267;4009.378546325317;4010.799460026987;3571.0893722100827;3592.2995015971896;3642.3990041643096;3744.588095309514;4014.578469260715;3014.7136022077866;3971.709194511781;3982.561918188997;4000.519937607491;4040.4949381327333;3330.917451828141;3958.802059897008;3990.5011873515955;4009.8195581198916;4014.2738622392358;3483.3033393321184;3484.494748261488;3498.1079824644808;3498.373256435744;3505.2814173094707;4316.2446571849805;4467.664925130628;4972.453030166679;5012.624053196025;5016.197408414636;3953.723239373245;4007.7996100195164;4542.273236282179;4571.062932098912;4576.336431217454;4295.570442955711;5482.5613950883835;5484.8354549363585;5488.670679759227;5531.695756733378;3043.756499480058;3493.0568746438576;3808;3844.730868839182;3852.6339997700175;4504.310883418718;5116.565091967187;5741.511971482387;5771.740271687742;5858.794942177004;3631.782093074424;3826.234753049401;3839.9648186461222;3852.460655508231;3870.8645197417954;3060.755139588872;3832.96509942314;3908.8631897884006;3909.104084519006;3945.8951493581844;4087.6919385796855;4237.745735255895;4512.330437738058;4528.682992190581;4541.159885421208;4500.662450316174;5453.23654688344;5502.614816962854;5545;5584.636998595079;1187.7505723797926;1272.663703111729;1273.8662440443804;1279.5073896549895;1303.69363199648;702.5492358280135;761.6877080397011;794.364508393283;808.7625932323555;810.9351251899834;4062.898427539276;4235.25882970479;4351.303791393425;4397.570789493428;4408.779561022;4236.368997935755;4766.976165119221;4823.250747074204;4854.218594684233;4856.854294371175;3801.377049835605;4406.127395856748;5183.922241166386;5231.764570594332;5239.764210610531;2677.821758426286;2756.5528097748443;2791.87436565351;2966;2990.2524368907775;5239.947600523946;5245.737713114329;5303.549198318109;5780.855478613068;5891.46976772084;5793.652380857162;5796.087291193896;5846.269216347957;5849.245226432054;5857.456238975444;5896.734646940898;5918.852028699231;5940.524758019435;5951.851203719855;5992.370801065999;4840.95159048405;5372.892542149213;5676.886462270649;5686.2607860977805;5686.57350698691;4899.309738140336;4926.433460152129;5447.836564903059;5622.718864056699;5628.380878103487;5476.205950137288;5549.916751248654;5567.805126820542;5910.113482977587;6001.819945401645;3473.9677288786343;3784.8675296364495;3788.1666033472593;3832.9616703832603;3840.30874442603;4843.418789745253;5281.735913204401;5289.338832645919;5684.545236381162;5685.374608793112;4748.596369308648;4998.1503144464805;5348.930213957146;5544.251526043932;5548.278723765885;5138.254953031715;5490.6980116094;5494.560435165257;5521.254630624814;5568.721563921707;4818.469968303414;4835.685680430761;5258.316418865523;5316.521513063843;5412.83761487156;4629.953700462952;4747.359106520576;5030.522100400456;5349.705766182741;5424.578968140952;5174.404943431554;5390.407055223844;5408.972955135199;5410.729463526886;5419.349678038503 07:27:58 INFO - PROCESS | 2265 | |5;yui.html;655.2693746472683;741.8293792427742;742.9294217049381;743.5463668528029;746.3879618712658;14874.702505949874;15157.242137893116;15173.16547589882;15313.387464501406;15363.15502647354;13971.301434928264;14116.505922292721;14151.292435378242;14167.645808854759;14189.503367382144;706.8798304288283;726.6257877193224;894.3158483759912;912.625823412401;912.7535565397338;878.5958459108817;899.6761165980242;900.7658008917696;913.3606475467167;914.3645166609209;952.0336858089045;965.4110992294694;966.1401352796017;966.8936416994125;972.1639390124487;735.5255859970342;913.4519288426891;929.8837645294338;955.7610597350663;960.7357976556468;698.7868700046483;699.6676578625163;701.7964790210833;701.9192792828838;703.5532436902604;12988.610341689764;14328.785068223984;14825.332860021324;14906.180160091191;14923.552293431214;12217.633470995885;12228.449719762632;12245.571405000872;12590.992720582331;12628;10915.563377464892;11039.006489415991;11061.723456913642;11333.716657083638;11620.825687614693;3999.7600143991162;4004.2191772604356;4049.190161967618;4117.094239267386;4140;79105;79228;82427.5878620609;83818;84052;9437.952810235905;9522.285828562886;9655.468949207721;9757;10049.29654924163;21450.927453627257;21467.926603669755;21490;21499.57000859974;21506.462338441663;7424.740134095388;7652.80867978305;7743.225677432211;7910.050793904768;7911.208879112158;3446.293509830479;4070.5725898780793;4080.9387859182134;4084.65280451159;4154;2970.8217506949654;3412.931741365159;3498.772579782306;3503.3343664703625;3505.94741078884;620.3920158244945;627.1408170806025;642.8071578526424;645.644895307579;649.9967500162469;507.45955557331325;542.926704894842;551.6276513353471;554.3563334712516;575.6948817126846;6669.599824010575;7739.110002349802;7751.689932402754;7811.179826118289;7847.1368149503505;506.3493410966963;575.9136129580596;575.9884802303931;579.2845835393285;582.9242198514166;2974.7341151481146;3699.1122130688977;3744.251149770057;3823.483829683013;4002.519697636302;59236;84686;85719.5714021426;85958;90287;72840.63579682067;74499.62750186214;75735.62132189304;75996;76982.23017769863;50208.74895625498;64782.282657600794;64878.67560662166;65114.67442662851;65318.67340663266;7244;7311.963440182765;7360.374368178765;7482.1021477423055;7748.99263095794;39310.24828261994;43420.56579434165;43619.12761744811;44615.33077003912;45382.77308613436;56305.77706671934;56546;59465.70267148637;61531.69234153801;61879.74022208163;9662.275329350186;9969.700908972742;10037.89962100399;10092.697219083439;10251.28241023151;10386.221033422675;10415.94792026035;10449.791004180026;10588.841167382341;10867.673969780919;8040.758777236459;10191;10263.794724105626;10382.42896640692;10791.6222932197;8703.173198546128;9922.00779922002;10041.146502547364;10043.598256069676;10260.333078349884;9524.047595240421;9910.008999100033;9999.600015999278;10003.099721025143;10026.69919902404;8254.917450825655;8401.495910245405;8530.872036919327;8585.957070214608;8649.870251946351;8516.105808890225;8559.144085591392;8597.742067737978;8611.827763444571;8956.104389560991 07:27:58 INFO - PROCESS | 2265 | __end_tp_report 07:27:58 INFO - PROCESS | 2265 | __start_cc_report 07:27:58 INFO - PROCESS | 2265 | _x_x_mozilla_cycle_collect,1439 07:27:58 INFO - PROCESS | 2265 | __end_cc_report 07:27:58 INFO - PROCESS | 2265 | __startTimestamp1462890478456__endTimestamp 07:27:58 INFO - PROCESS | 2265 | 07:27:58 INFO - PROCESS | 2265 | ------- Summary: start ------- 07:27:58 INFO - PROCESS | 2265 | Number of tests: 6 07:27:58 INFO - PROCESS | 2265 | 07:27:58 INFO - PROCESS | 2265 | [#0] dojo.html Cycles:165 Average:5673.49 Median:5877.35 stddev:658.86 (11.2%) stddev-sans-first:636.09 07:27:58 INFO - PROCESS | 2265 | Values: 3391.3 3402.9 3417.4 3440.4 3457.6 5598.9 5610.8 5628.3 5683.0 5771.4 4666.7 5622.1 5683.8 5744.7 5792.1 4127.8 5083.1 5144.5 5157.9 5308.4 5218.9 5225.4 5261.5 5282.6 5340.6 5094.2 5120.7 5136.0 5179.8 5204.6 5168.9 5173.1 5178.6 5181.3 5191.6 5058.4 5114.7 5127.1 5128.5 5154.1 5547.1 5603.9 5606.0 5664.9 5692.9 6170.8 6182.9 6248.1 6256.7 6265.0 4955.6 6113.5 6166.5 6291.7 6304.8 5692.7 5708.2 5774.2 5777.9 5781.1 6267.9 6272.1 6274.2 6280.3 6291.6 5914.7 5915.5 6057.5 6095.7 6120.6 6210.5 6261.4 6268.2 6279.4 6288.1 5469.3 5819.2 6182.4 6186.9 6225.3 6057.0 6095.8 6127.8 6137.4 6191.7 4065.7 4863.2 4913.3 4914.2 4947.6 4166.8 4350.7 4352.5 4365.4 4383.9 5498.5 5527.8 5600.6 5670.0 5689.6 6095.9 6164.1 6197.0 6200.3 6210.1 5559.4 5559.6 5570.6 5572.4 5588.4 5260.2 5263.7 5272.5 5277.6 5279.5 6204.0 6233.7 6245.3 6294.5 6303.2 6270.0 6272.6 6283.8 6299.4 6302.7 6246.3 6249.4 6291.5 6306.9 6307.7 6096.7 6127.5 6136.4 6144.4 6152.3 6187.3 6249.4 6255.5 6277.8 6279.6 5887.8 5964.7 6118.3 6142.4 6163.6 5884.3 5980.6 6044.8 6072.2 6072.4 5844.7 5903.2 5917.8 5922.7 5963.6 5893.4 5940.8 6096.0 6114.9 6118.4 5823.2 5861.8 5869.7 5877.4 5880.9 07:27:58 INFO - PROCESS | 2265 | 07:27:58 INFO - PROCESS | 2265 | [#1] ext.html Cycles:180 Average:68006.49 Median:35951.64 stddev:144481.79 (401.9%) stddev-sans-first:144798.19 07:27:58 INFO - PROCESS | 2265 | Values: 499.7 506.8 520.7 529.1 552.0 30523.7 30693.5 32061.7 42203.4 43242.8 44262.1 44302.6 44541.8 44573.3 44695.0 9525.9 9695.1 9699.0 9712.3 9750.8 8633.0 8833.1 9557.0 11550.2 11771.8 11713.0 11795.1 11796.2 11801.1 11817.6 8577.4 11541.6 11663.5 11674.8 11679.0 11113.6 11422.8 11427.0 11510.0 11531.8 40801.4 41152.0 41180.0 41187.8 41221.4 3370.5 3528.4 3528.5 3694.3 3938.8 28358.0 29788.6 29801.0 30033.4 30113.4 2758.2 2810.3 2818.2 2845.3 2856.4 520474.0 524557.0 524696.4 524989.0 525375.0 54010.7 63723.7 65230.7 65921.0 66095.7 64547.0 69598.0 69612.0 69645.7 69675.3 16891.9 17281.9 17289.9 17290.4 17295.1 11996.3 12214.4 12994.2 13094.3 13125.3 19376.5 20523.0 20558.3 20595.6 20606.4 8168.9 8211.4 8214.3 8246.0 8294.1 891.1 909.5 974.4 1060.0 1081.9 29541.3 29904.0 30377.8 30390.0 30447.7 933.9 1081.1 1145.6 1157.4 1160.1 15460.1 16020.8 16265.3 16537.7 16602.0 548878.0 612214.0 613250.0 617567.0 621662.0 61341.0 61598.7 61824.0 61882.7 62222.4 1300.9 1431.7 1442.4 1455.7 1462.8 29970.0 30015.6 30068.1 30186.6 30245.0 49650.0 51309.0 52094.7 52147.2 52152.0 483725.0 483912.6 496885.0 497180.0 499822.0 23305.8 25339.1 25966.5 26760.3 27162.0 39894.4 44292.8 44441.3 45580.1 46358.0 33625.7 35620.8 37289.0 38009.9 38174.4 33899.7 36384.2 36432.0 36463.5 37300.6 36282.5 36841.6 37796.0 37798.9 37816.2 28013.6 30091.8 31276.0 31488.0 32637.8 20595.0 24122.6 24231.0 24591.9 24741.8 07:27:58 INFO - PROCESS | 2265 | 07:27:58 INFO - PROCESS | 2265 | [#2] jquery.html Cycles:180 Average:35293.03 Median:11999.99 stddev:101633.98 (847.0%) stddev-sans-first:101885.30 07:27:58 INFO - PROCESS | 2265 | Values: 387.3 389.7 392.8 394.0 414.2 14210.7 20177.2 21157.0 21716.8 21771.5 15540.5 17936.6 20634.3 20803.6 20964.5 5761.8 5994.9 6032.1 6043.4 6060.6 5683.5 6011.9 6024.9 6026.2 6067.9 5953.5 5969.4 5973.3 5980.9 5983.2 5666.2 5856.3 5931.6 5948.0 5953.5 5033.6 5046.9 5058.1 5064.2 5072.8 23825.0 24963.6 25024.4 25341.9 26027.2 2083.3 2228.5 2228.9 2259.4 2292.3 9108.7 9842.2 10141.3 14425.6 14936.7 1639.1 1659.8 1681.2 1782.3 1807.7 75922.0 114568.9 130006.0 133846.0 134270.7 11467.8 11484.9 11534.3 11546.8 11689.9 14752.9 14853.6 14859.6 14870.2 14882.3 6406.3 6703.3 6960.0 7604.2 8015.6 6887.5 8509.8 8513.1 8514.3 8624.0 4478.7 4556.2 4564.1 4604.1 4640.7 1799.1 1812.2 1883.5 1926.3 1937.3 872.7 916.4 917.1 999.0 1023.2 15290.5 18897.6 19521.8 19882.5 20093.0 895.4 967.2 996.2 1013.1 1034.8 10342.6 10515.7 10555.0 10584.5 10599.1 596442.0 600344.0 609553.0 614836.0 617579.0 133031.3 133112.7 133405.0 134061.3 138695.3 864.4 1003.5 1023.4 1023.8 1041.0 17522.6 17821.8 17894.9 17918.1 18124.3 43342.3 47088.0 51271.5 53053.7 53861.5 73024.0 80601.4 82233.0 82877.6 84003.6 7734.8 8123.8 8222.4 8254.3 8519.1 11673.4 12294.3 12334.1 12636.3 12720.4 10500.8 10725.1 10751.7 10823.3 10911.3 10542.9 10580.0 10695.9 10809.6 10823.6 11057.3 11329.8 11390.5 11453.0 11478.9 6620.8 7988.9 8405.7 11083.6 12059.4 7906.0 10914.1 11598.9 11608.5 11715.8 07:27:58 INFO - PROCESS | 2265 | 07:27:58 INFO - PROCESS | 2265 | [#3] mootools.html Cycles:180 Average:5809.97 Median:4988.51 stddev:3275.25 (65.7%) stddev-sans-first:3267.66 07:27:58 INFO - PROCESS | 2265 | Values: 1398.5 1436.8 1538.1 1611.4 1791.3 8000.9 8777.5 9037.7 9039.5 9169.0 7644.8 8893.2 8990.6 9010.7 9027.0 2150.3 2225.4 2228.4 2427.6 2477.4 2222.2 2407.4 2495.9 2497.2 2499.4 2408.9 2440.4 2441.2 2447.1 2448.5 2078.5 2120.9 2401.0 2449.3 2450.3 2386.9 2460.7 2461.6 2463.0 2467.0 8860.6 8907.4 8909.9 8927.0 8930.7 5229.8 5402.2 5439.9 5446.7 5450.8 3410.3 3524.8 3612.0 3616.4 3623.1 1029.3 1037.7 1039.7 1041.4 1041.7 11867.5 11871.8 11874.2 11885.6 11891.3 4735.2 4806.9 4868.4 4872.5 4912.3 9933.2 9970.4 10000.1 10000.8 10069.9 5305.3 5453.1 5453.3 5454.3 5460.1 4447.1 4933.5 4980.1 4996.9 5000.2 3304.5 4019.8 4054.8 4090.7 4127.2 1326.8 1337.8 1339.2 1352.0 1353.8 779.4 916.1 930.8 944.2 955.9 5885.4 6055.2 6078.7 6197.8 6287.2 917.4 958.2 966.1 967.6 997.0 3858.5 3928.7 4031.5 4045.3 4063.5 10634.4 11121.7 11230.1 11239.3 11257.8 11020.6 11145.1 11145.3 11179.8 11202.8 7953.0 8365.0 8443.2 8453.3 8478.7 4548.6 5858.1 6015.5 6029.9 6086.3 10007.4 10199.3 10304.0 10326.1 10326.8 9216.4 9652.9 10359.7 10365.0 10416.6 6551.2 7667.4 7684.7 7783.7 7785.9 8322.2 8365.2 8461.7 8467.7 8522.0 7611.3 7695.3 7766.5 7792.4 7875.4 7691.3 7768.1 7769.0 7902.6 7925.8 5929.7 6794.2 7018.1 7110.6 7114.8 5153.6 5158.8 5198.1 5208.9 5311.9 4143.9 5127.6 5235.4 5314.2 5462.3 07:27:58 INFO - PROCESS | 2265 | 07:27:58 INFO - PROCESS | 2265 | [#4] prototype.html Cycles:180 Average:4384.43 Median:4842.19 stddev:1364.45 (28.2%) stddev-sans-first:1333.42 07:27:58 INFO - PROCESS | 2265 | Values: 301.5 304.5 320.4 320.5 323.7 5118.1 5259.7 5300.3 5307.7 5336.9 4864.7 5071.6 5226.7 5244.5 5264.7 3969.2 3991.4 4007.7 4009.4 4010.8 3571.1 3592.3 3642.4 3744.6 4014.6 3014.7 3971.7 3982.6 4000.5 4040.5 3330.9 3958.8 3990.5 4009.8 4014.3 3483.3 3484.5 3498.1 3498.4 3505.3 4316.2 4467.7 4972.5 5012.6 5016.2 3953.7 4007.8 4542.3 4571.1 4576.3 4295.6 5482.6 5484.8 5488.7 5531.7 3043.8 3493.1 3808.0 3844.7 3852.6 4504.3 5116.6 5741.5 5771.7 5858.8 3631.8 3826.2 3840.0 3852.5 3870.9 3060.8 3833.0 3908.9 3909.1 3945.9 4087.7 4237.7 4512.3 4528.7 4541.2 4500.7 5453.2 5502.6 5545.0 5584.6 1187.8 1272.7 1273.9 1279.5 1303.7 702.5 761.7 794.4 808.8 810.9 4062.9 4235.3 4351.3 4397.6 4408.8 4236.4 4767.0 4823.3 4854.2 4856.9 3801.4 4406.1 5183.9 5231.8 5239.8 2677.8 2756.6 2791.9 2966.0 2990.3 5239.9 5245.7 5303.5 5780.9 5891.5 5793.7 5796.1 5846.3 5849.2 5857.5 5896.7 5918.9 5940.5 5951.9 5992.4 4841.0 5372.9 5676.9 5686.3 5686.6 4899.3 4926.4 5447.8 5622.7 5628.4 5476.2 5549.9 5567.8 5910.1 6001.8 3474.0 3784.9 3788.2 3833.0 3840.3 4843.4 5281.7 5289.3 5684.5 5685.4 4748.6 4998.2 5348.9 5544.3 5548.3 5138.3 5490.7 5494.6 5521.3 5568.7 4818.5 4835.7 5258.3 5316.5 5412.8 4630.0 4747.4 5030.5 5349.7 5424.6 5174.4 5390.4 5409.0 5410.7 5419.3 07:27:58 INFO - PROCESS | 2265 | 07:27:58 INFO - PROCESS | 2265 | [#5] yui.html Cycles:180 Average:17024.18 Median:623.77 stddev:23782.17 (3812.7%) stddev-sans-first:23817.12 07:27:58 INFO - PROCESS | 2265 | Values: 655.3 741.8 742.9 743.5 746.4 14874.7 15157.2 15173.2 15313.4 15363.2 13971.3 14116.5 14151.3 14167.6 14189.5 706.9 726.6 894.3 912.6 912.8 878.6 899.7 900.8 913.4 914.4 952.0 965.4 966.1 966.9 972.2 735.5 913.5 929.9 955.8 960.7 698.8 699.7 701.8 701.9 703.6 12988.6 14328.8 14825.3 14906.2 14923.6 12217.6 12228.4 12245.6 12591.0 12628.0 10915.6 11039.0 11061.7 11333.7 11620.8 3999.8 4004.2 4049.2 4117.1 4140.0 79105.0 79228.0 82427.6 83818.0 84052.0 9438.0 9522.3 9655.5 9757.0 10049.3 21450.9 21467.9 21490.0 21499.6 21506.5 7424.7 7652.8 7743.2 7910.1 7911.2 3446.3 4070.6 4080.9 4084.7 4154.0 2970.8 3412.9 3498.8 3503.3 3505.9 620.4 627.1 642.8 645.6 650.0 507.5 542.9 551.6 554.4 575.7 6669.6 7739.1 7751.7 7811.2 7847.1 506.3 575.9 576.0 579.3 582.9 2974.7 3699.1 3744.3 3823.5 4002.5 59236.0 84686.0 85719.6 85958.0 90287.0 72840.6 74499.6 75735.6 75996.0 76982.2 50208.7 64782.3 64878.7 65114.7 65318.7 7244.0 7312.0 7360.4 7482.1 7749.0 39310.2 43420.6 43619.1 44615.3 45382.8 56305.8 56546.0 59465.7 61531.7 61879.7 9662.3 9969.7 10037.9 10092.7 10251.3 10386.2 10415.9 10449.8 10588.8 10867.7 8040.8 10191.0 10263.8 10382.4 10791.6 8703.2 9922.0 10041.1 10043.6 10260.3 9524.0 9910.0 9999.6 10003.1 10026.7 8254.9 8401.5 8530.9 8586.0 8649.9 8516.1 8559.1 8597.7 8611.8 8956.1 07:27:58 INFO - PROCESS | 2265 | -------- Summary: end -------- 07:27:58 INFO - PROCESS | 2265 | 07:27:59 INFO - TEST-INFO | 2265: exit 0 07:27:59 INFO - TEST-OK | dromaeo_css | took 1115609ms 07:27:59 INFO - TEST-START | kraken 07:27:59 INFO - operating with platform_type : mac_ 07:27:59 INFO - Initialising browser for kraken test... 07:27:59 INFO - TEST-INFO | started process 2268 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp2xGJsF/profile http://localhost:51808/getInfo.html) 07:28:01 INFO - PROCESS | 2268 | __metrics Screen width/height:1600/1200 07:28:01 INFO - PROCESS | 2268 | colorDepth:24 07:28:01 INFO - PROCESS | 2268 | Browser inner width/height: 1024/689 07:28:01 INFO - PROCESS | 2268 | __metrics 07:28:01 INFO - PROCESS | 2268 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:28:01 ERROR - PROCESS | 2268 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:28:01 INFO - TEST-INFO | 2268: exit 0 07:28:01 INFO - Browser initialized. 07:28:01 INFO - Running cycle 1/1 for kraken test... 07:28:01 INFO - TEST-INFO | started process 2270 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp2xGJsF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:28:13 INFO - PROCESS | 2270 | RSS: Main: 238972928 07:28:13 INFO - PROCESS | 2270 | 07:28:16 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51808/tests/kraken/driver.html?testName=audio-beat-detection) 07:28:17 INFO - PROCESS | 2270 | RSS: Main: 286924800 07:28:17 INFO - PROCESS | 2270 | 07:28:20 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51808/tests/kraken/driver.html?testName=audio-dft) 07:28:20 INFO - PROCESS | 2270 | RSS: Main: 296038400 07:28:20 INFO - PROCESS | 2270 | 07:28:24 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51808/tests/kraken/driver.html?testName=audio-fft) 07:28:24 INFO - PROCESS | 2270 | RSS: Main: 382046208 07:28:24 INFO - PROCESS | 2270 | 07:28:26 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51808/tests/kraken/driver.html?testName=audio-oscillator) 07:28:27 INFO - PROCESS | 2270 | RSS: Main: 282333184 07:28:27 INFO - PROCESS | 2270 | 07:28:29 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51808/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:28:30 INFO - PROCESS | 2270 | RSS: Main: 286187520 07:28:30 INFO - PROCESS | 2270 | 07:28:33 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51808/tests/kraken/driver.html?testName=imaging-darkroom) 07:28:34 INFO - PROCESS | 2270 | RSS: Main: 325672960 07:28:34 INFO - PROCESS | 2270 | 07:28:37 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51808/tests/kraken/driver.html?testName=imaging-desaturate) 07:28:37 INFO - PROCESS | 2270 | RSS: Main: 326774784 07:28:37 INFO - PROCESS | 2270 | 07:28:40 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51808/tests/kraken/driver.html?testName=json-parse-financial) 07:28:41 INFO - PROCESS | 2270 | RSS: Main: 323977216 07:28:41 INFO - PROCESS | 2270 | 07:28:43 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51808/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:28:43 INFO - PROCESS | 2270 | RSS: Main: 315863040 07:28:43 INFO - PROCESS | 2270 | 07:28:45 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51808/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:28:46 INFO - PROCESS | 2270 | RSS: Main: 322576384 07:28:46 INFO - PROCESS | 2270 | 07:28:48 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51808/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:28:49 INFO - PROCESS | 2270 | RSS: Main: 324239360 07:28:49 INFO - PROCESS | 2270 | 07:28:52 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51808/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:28:52 INFO - PROCESS | 2270 | RSS: Main: 325722112 07:28:52 INFO - PROCESS | 2270 | 07:28:55 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51808/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:28:56 INFO - PROCESS | 2270 | RSS: Main: 323227648 07:28:56 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:51808/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51808/tests/kraken/driver.html?testName=ai-astar) 07:28:58 INFO - PROCESS | 2270 | RSS: Main: 369623040 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | __start_tp_report 07:28:58 INFO - PROCESS | 2270 | _x_x_mozilla_page_load 07:28:58 INFO - PROCESS | 2270 | _x_x_mozilla_page_load_details 07:28:58 INFO - PROCESS | 2270 | |i|pagename|runs| 07:28:58 INFO - PROCESS | 2270 | |0;ai-astar;112;107;113;111;110;113;112;113;112;112 07:28:58 INFO - PROCESS | 2270 | |1;audio-beat-detection;172;139;142;199;119;138;135;116;174;115 07:28:58 INFO - PROCESS | 2270 | |2;audio-dft;196;185;185;184;184;185;185;182;189;186 07:28:58 INFO - PROCESS | 2270 | |3;audio-fft;79;74;57;126;56;59;57;59;111;56 07:28:58 INFO - PROCESS | 2270 | |4;audio-oscillator;93;98;134;80;84;104;139;119;79;105 07:28:58 INFO - PROCESS | 2270 | |5;imaging-gaussian-blur;89;87;90;88;89;87;88;88;89;85 07:28:58 INFO - PROCESS | 2270 | |6;imaging-darkroom;94;94;96;94;95;94;95;93;95;94 07:28:58 INFO - PROCESS | 2270 | |7;imaging-desaturate;87;85;84;86;85;86;84;86;88;91 07:28:58 INFO - PROCESS | 2270 | |8;json-parse-financial;59;62;62;58;59;60;58;123;59;56 07:28:58 INFO - PROCESS | 2270 | |9;json-stringify-tinderbox;63;73;76;70;71;67;66;69;69;68 07:28:58 INFO - PROCESS | 2270 | |10;stanford-crypto-aes;85;86;85;85;84;84;83;84;84;86 07:28:58 INFO - PROCESS | 2270 | |11;stanford-crypto-ccm;136;137;136;129;129;131;138;149;247;146 07:28:58 INFO - PROCESS | 2270 | |12;stanford-crypto-pbkdf2;171;167;165;164;165;166;166;168;162;227 07:28:58 INFO - PROCESS | 2270 | |13;stanford-crypto-sha256-iterative;68;68;68;66;67;67;64;68;67;66 07:28:58 INFO - PROCESS | 2270 | __end_tp_report 07:28:58 INFO - PROCESS | 2270 | __start_cc_report 07:28:58 INFO - PROCESS | 2270 | _x_x_mozilla_cycle_collect,2272 07:28:58 INFO - PROCESS | 2270 | __end_cc_report 07:28:58 INFO - PROCESS | 2270 | __startTimestamp1462890538586__endTimestamp 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | ------- Summary: start ------- 07:28:58 INFO - PROCESS | 2270 | Number of tests: 14 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:1.84 (1.6%) stddev-sans-first:1.94 07:28:58 INFO - PROCESS | 2270 | Values: 112.0 107.0 113.0 111.0 110.0 113.0 112.0 113.0 112.0 112.0 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#1] audio-beat-detection Cycles:10 Average:144.90 Median:140.50 stddev:28.08 (20.0%) stddev-sans-first:28.02 07:28:58 INFO - PROCESS | 2270 | Values: 172.0 139.0 142.0 199.0 119.0 138.0 135.0 116.0 174.0 115.0 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#2] audio-dft Cycles:10 Average:186.10 Median:185.00 stddev:3.90 (2.1%) stddev-sans-first:1.87 07:28:58 INFO - PROCESS | 2270 | Values: 196.0 185.0 185.0 184.0 184.0 185.0 185.0 182.0 189.0 186.0 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#3] audio-fft Cycles:10 Average:73.40 Median:58.00 stddev:25.32 (43.7%) stddev-sans-first:26.78 07:28:58 INFO - PROCESS | 2270 | Values: 79.0 74.0 57.0 126.0 56.0 59.0 57.0 59.0 111.0 56.0 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#4] audio-oscillator Cycles:10 Average:103.50 Median:79.50 stddev:21.36 (26.9%) stddev-sans-first:22.32 07:28:58 INFO - PROCESS | 2270 | Values: 93.0 98.0 134.0 80.0 84.0 104.0 139.0 119.0 79.0 105.0 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:88.50 stddev:1.41 (1.6%) stddev-sans-first:1.45 07:28:58 INFO - PROCESS | 2270 | Values: 89.0 87.0 90.0 88.0 89.0 87.0 88.0 88.0 89.0 85.0 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:94.50 stddev:0.84 (0.9%) stddev-sans-first:0.88 07:28:58 INFO - PROCESS | 2270 | Values: 94.0 94.0 96.0 94.0 95.0 94.0 95.0 93.0 95.0 94.0 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:86.00 stddev:2.10 (2.4%) stddev-sans-first:2.20 07:28:58 INFO - PROCESS | 2270 | Values: 87.0 85.0 84.0 86.0 85.0 86.0 84.0 86.0 88.0 91.0 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#8] json-parse-financial Cycles:10 Average:65.60 Median:59.00 stddev:20.25 (34.3%) stddev-sans-first:21.34 07:28:58 INFO - PROCESS | 2270 | Values: 59.0 62.0 62.0 58.0 59.0 60.0 58.0 123.0 59.0 56.0 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.50 stddev:3.65 (5.2%) stddev-sans-first:3.10 07:28:58 INFO - PROCESS | 2270 | Values: 63.0 73.0 76.0 70.0 71.0 67.0 66.0 69.0 69.0 68.0 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:85.00 stddev:0.97 (1.1%) stddev-sans-first:1.01 07:28:58 INFO - PROCESS | 2270 | Values: 85.0 86.0 85.0 85.0 84.0 84.0 83.0 84.0 84.0 86.0 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:137.50 stddev:35.47 (25.8%) stddev-sans-first:37.37 07:28:58 INFO - PROCESS | 2270 | Values: 136.0 137.0 136.0 129.0 129.0 131.0 138.0 149.0 247.0 146.0 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.10 Median:166.50 stddev:19.44 (11.7%) stddev-sans-first:20.61 07:28:58 INFO - PROCESS | 2270 | Values: 171.0 167.0 165.0 164.0 165.0 166.0 166.0 168.0 162.0 227.0 07:28:58 INFO - PROCESS | 2270 | 07:28:58 INFO - PROCESS | 2270 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.50 stddev:1.29 (1.9%) stddev-sans-first:1.30 07:28:58 INFO - PROCESS | 2270 | Values: 68.0 68.0 68.0 66.0 67.0 67.0 64.0 68.0 67.0 66.0 07:28:58 INFO - PROCESS | 2270 | -------- Summary: end -------- 07:28:58 INFO - PROCESS | 2270 | 07:28:59 INFO - TEST-INFO | 2270: exit 0 07:28:59 INFO - TEST-OK | kraken | took 60366ms 07:28:59 INFO - SUITE-END | took 1175s 07:28:59 INFO - Completed test suite (00:19:36) 07:28:59 INFO - javascript benchmark 07:28:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3391.2708767614963, 3402.9489557656657, 3417.3507033663595, 3440.3807314683377, 3457.567804024497, 5598.916016259759, 5610.803621873235, 5628.296462942132, 5682.971585142073, 5771.36514983352, 4666.650001249911, 5622.128570071635, 5683.772649094041, 5744.741486633101, 5792.102224155252, 4127.752334859903, 5083.059633967728, 5144.536991670749, 5157.896842063175, 5308.362996440433, 5218.895622087556, 5225.399079105894, 5261.473852614727, 5282.630215884889, 5340.572754179676, 5094.184930411154, 5120.743962801845, 5135.974320128375, 5179.766910489018, 5204.609654275907, 5168.870778230575, 5173.094708426048, 5178.585713142939, 5181.300524429229, 5191.558717509016, 5058.4435712071645, 5114.718690472023, 5127.102757017507, 5128.461511541309, 5154.149565321717, 5547.1124620061, 5603.915941260843, 5606.018946684355, 5664.858378540528, 5692.9146062809095, 6170.7531698732, 6182.907256391113, 6248.094026366197, 6256.718447669866, 6265.0, 4955.554000140005, 6113.5371179039, 6166.506679465632, 6291.716872740738, 6304.810855674337, 5692.686902220376, 5708.1723150143, 5774.162746401748, 5777.942220577825, 5781.075027995501, 6267.874642507124, 6272.059191121369, 6274.215723034621, 6280.277768056732, 6291.591046581958, 5914.6746928918465, 5915.467607915309, 6057.454829065406, 6095.66473843934, 6120.632762034292, 6210.472109870621, 6261.436470717657, 6268.1851359323, 6279.3720627937755, 6288.056791481315, 5469.261649677323, 5819.156222347736, 6182.350853160443, 6186.876262474725, 6225.28409232935, 6057.0, 6095.801078121664, 6127.816165515042, 6137.417646941698, 6191.690415479208, 4065.735727177724, 4863.2461968394955, 4913.287573301851, 4914.238293064581, 4947.5794557462295, 4166.83332666696, 4350.73895566267, 4352.542982986804, 4365.388845561624, 4383.868483945486, 5498.45015498455, 5527.751251193706, 5600.551955843523, 5670.0, 5689.630174038674, 6095.861538843069, 6164.075388691643, 6197.03945888388, 6200.255969283715, 6210.068489726486, 5559.388467268597, 5559.610827242135, 5570.637908535932, 5572.359178694421, 5588.3573389060775, 5260.184671375943, 5263.6578622389425, 5272.472752724773, 5277.577793776489, 5279.47205279469, 6203.96898015516, 6233.688315584202, 6245.313015568375, 6294.5279104068295, 6303.180586523814, 6269.996800511896, 6272.560920735505, 6283.842903927439, 6299.370062993664, 6302.684865756602, 6246.281677607133, 6249.375062493896, 6291.496680265659, 6306.905396419149, 6307.747690092345, 6096.695165241631, 6127.4570804235245, 6136.386361363828, 6144.354842741626, 6152.323244443018, 6187.288461826947, 6249.437550620284, 6255.53083518747, 6277.811665650038, 6279.560430769802, 5887.8233652990475, 5964.671943043174, 6118.265808103056, 6142.3550527194, 6163.599366041193, 5884.264466941632, 5980.641161530322, 6044.818655440344, 6072.240969878765, 6072.392760723892, 5844.736986835687, 5903.203067585821, 5917.822465326047, 5922.674252916131, 5963.552733544915, 5893.351731309638, 5940.762369505172, 6095.994046880814, 6114.908276375769, 6118.449339559461, 5823.242978412779, 5861.794837180678, 5869.706514674334, 5877.35349111606, 5880.94119058804], "value": 5631.8874825380335, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.72515116685815, 506.8418663354236, 520.6511637203074, 529.1295818378767, 551.9696416697082, 30523.694763052365, 30693.539596906063, 32061.67938320616, 42203.36694949578, 43242.783786081025, 44262.114757704905, 44302.55697443025, 44541.77729111358, 44573.33140002903, 44695.0, 9525.904740952588, 9695.078967498077, 9699.0, 9712.27157963152, 9750.756231094243, 8633.0, 8833.07252738463, 9557.044295570422, 11550.249233799776, 11771.823422648624, 11713.0, 11795.05639548834, 11796.174267801258, 11801.055915526736, 11817.586384476544, 8577.442466239674, 11541.596044138458, 11663.533458661686, 11674.766504669858, 11679.007284380752, 11113.611023614216, 11422.771544569063, 11427.0, 11510.0, 11531.827022594583, 40801.387979180334, 41152.0, 41180.0, 41187.7940610298, 41221.381679274535, 3370.5449764281752, 3528.4001719707453, 3528.4883691864534, 3694.3350196964534, 3938.7636741795586, 28358.0, 29788.553171702442, 29801.0, 30033.399332013454, 30113.397732045236, 2758.158761577718, 2810.269329974201, 2818.182727009165, 2845.2886778305424, 2856.3573196030934, 520474.0, 524556.9999999962, 524696.3765181226, 524989.0, 525375.0, 54010.72994635002, 63723.681381592796, 65230.67384663141, 65921.0, 66095.66952165305, 64547.0, 69598.0, 69612.0, 69645.65177174183, 69675.3032469679, 16891.91554042222, 17281.913590432217, 17289.913550432168, 17290.394836180672, 17295.135243237786, 11996.280223186463, 12214.389280536116, 12994.155379900276, 13094.345282735825, 13125.278109704059, 19376.477921461308, 20523.0, 20558.28046018382, 20595.58808823845, 20606.381808545768, 8168.877466838002, 8211.384146189059, 8214.301784348398, 8245.95877020611, 8294.129116442808, 891.0732837848565, 909.5452273863068, 974.4360301715786, 1059.9612379867772, 1081.875584307799, 29541.30176100678, 29904.0, 30377.848110759303, 30390.0, 30447.695523044928, 933.9346245762731, 1081.081081081075, 1145.5761368293784, 1157.4039369724599, 1160.1356989043047, 15460.149691767061, 16020.759688604447, 16265.268062937197, 16537.669246615, 16602.0, 548878.0, 612214.0, 613250.0, 617567.0, 621662.0, 61341.0, 61598.69200654058, 61824.0, 61882.69058654678, 62222.377776222565, 1300.9154404963647, 1431.7422863884601, 1442.386985531145, 1455.7161353536171, 1462.8464011278663, 29970.0, 30015.636786915024, 30068.050950968765, 30186.622265829596, 30245.0, 49650.0, 51309.0, 52094.739526303645, 52147.217791733914, 52151.95696085981, 483725.0, 483912.58043709554, 496885.0, 497180.0, 499822.0, 23305.76694233036, 25339.113131040325, 25966.48067038611, 26760.330991724582, 27161.962852786517, 39894.40158397568, 44292.778536107115, 44441.333379999975, 45580.08839823251, 46358.0, 33625.66374336225, 35620.82189589035, 37289.0, 38009.85970420892, 38174.42738358983, 33899.66100338965, 36384.180790959814, 36432.0, 36463.45304820483, 37300.6269937308, 36282.4557631641, 36841.63158368382, 37796.0, 37798.866034019025, 37816.24367512579, 28013.57979630348, 30091.849540752155, 31276.0, 31488.0, 32637.836810815796, 20595.0, 24122.638160427257, 24231.0, 24591.877040614683, 24741.752582474666], "value": 20240.740482972196, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.3086540525162, 389.696037091069, 392.801635174237, 393.9586343433941, 414.1861242658176, 14210.71578568428, 20177.19291228351, 21157.04793284302, 21716.782832171673, 21771.455713607167, 15540.456084037061, 17936.551586210353, 20634.277800276992, 20803.583928321423, 20964.475888102806, 5761.769529218826, 5994.910076348859, 6032.125341825455, 6043.3956604339655, 6060.636361818283, 5683.488486036256, 6011.879762404749, 6024.879502409949, 6026.216591843055, 6067.939320606803, 5953.493953013977, 5969.373215812342, 5973.342932277446, 5980.88038239235, 5983.222181116471, 5666.1784041314195, 5856.32652244993, 5931.584789064767, 5948.018576934801, 5953.493953013977, 5033.647644664875, 5046.873828154289, 5058.0895438821, 5064.215046667736, 5072.847814565569, 23825.046998120055, 24963.62554561683, 25024.374390640198, 25341.85961631731, 26027.219183424528, 2083.312506872728, 2228.5208680133687, 2228.8662680239077, 2259.435141214696, 2292.346681195868, 9108.68119615817, 9842.163416109639, 10141.290109692325, 14425.639359016004, 14936.701265974729, 1639.131260431961, 1659.759295099835, 1681.2266357475576, 1782.3405340024144, 1807.7198034304572, 75922.0, 114568.85431145666, 130006.0, 133846.0, 134270.6572934268, 11467.827982580267, 11484.88515114838, 11534.307941523535, 11546.76906461866, 11689.941550292195, 14752.852471475362, 14853.554393368217, 14859.628509287355, 14870.18213998219, 14882.330295136744, 6406.251501840659, 6703.257153140121, 6959.965200174068, 7604.163542010375, 8015.599220038975, 6887.5178737487895, 8509.829803404022, 8513.106123856905, 8514.276286515715, 8623.956880215559, 4478.708883922534, 4556.225441674923, 4564.132814765184, 4604.1252162089095, 4640.675152739276, 1799.1454059321718, 1812.1935738596198, 1883.50087226885, 1926.2872737087368, 1937.2541571495076, 872.7207293666092, 916.3631276263067, 917.0645941140133, 998.9950050249702, 1023.150784848574, 15290.541283761506, 18897.622047558973, 19521.804781952284, 19882.50293742639, 20093.0, 895.4403497813867, 967.1923943507251, 996.2179688944144, 1013.1135256650431, 1034.7516596017051, 10342.638007669846, 10515.684529464128, 10554.997275258838, 10584.470776461147, 10599.099076578423, 596442.0, 600344.0, 609552.9522352449, 614836.0, 617579.0, 133031.3348433271, 133112.66887331195, 133405.0, 134061.3296933509, 138695.30652346875, 864.3906046237513, 1003.5032658833924, 1023.3706270643501, 1023.8259495885717, 1040.9531571079472, 17522.649547008732, 17821.821781782015, 17894.91052544729, 17918.104094795468, 18124.27502899922, 43342.34986475269, 47088.0, 51271.48728512667, 53053.7347313261, 53861.46138538564, 73024.0, 80601.3879722414, 82233.0, 82877.58561207155, 84003.57998210215, 7734.7679569613, 8123.79690507742, 8222.424430289822, 8254.339652827639, 8519.062903080532, 11673.416329183337, 12294.323812190416, 12334.136610437183, 12636.305003224543, 12720.427580758704, 10500.789984200426, 10725.08836748885, 10751.677449676523, 10823.296485728402, 10911.290766100177, 10542.947285263524, 10580.047795698423, 10695.893041069801, 10809.621663241749, 10823.56705731762, 11057.336559806437, 11329.830052549054, 11390.544378224778, 11453.02649274821, 11478.94260528692, 6620.801375958729, 7988.92011079905, 8405.663773448994, 11083.612073577386, 12059.397030148633, 7905.960470197612, 10914.126869850552, 11598.94200528992, 11608.535658573563, 11715.824262635897], "value": 9483.661362952442, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.461592286969, 1436.7988481612576, 1538.115583539465, 1611.363511412992, 1791.3372052340635, 8000.91999080009, 8777.473351598901, 9037.683681071163, 9039.54802259886, 9169.03725108864, 7644.847103057936, 8893.155150260714, 8990.595423205956, 9010.68462603809, 9027.0, 2150.2796563151364, 2225.4213904384897, 2228.4428892776805, 2427.647991041298, 2477.355887469262, 2222.20000799712, 2407.3981504623844, 2495.887685054168, 2497.1884137655243, 2499.4126380300704, 2408.9397765055837, 2440.4264997725522, 2441.1944058460667, 2447.119037146626, 2448.4735781806908, 2078.5011597216712, 2120.925767598142, 2400.95198096038, 2449.3386785567795, 2450.277168235366, 2386.880655967212, 2460.7047154341412, 2461.6307553866886, 2462.950740985188, 2466.962995555068, 8860.645574177026, 8907.421017633833, 8909.866352004725, 8926.955365223197, 8930.732078037669, 5229.764660590283, 5402.189671549259, 5439.864003399946, 5446.727663616843, 5450.781968721247, 3410.3179364127272, 3524.8061356625376, 3611.9886431799137, 3616.385214513539, 3623.058004918714, 1029.2846471702169, 1037.667946257198, 1039.6932904793105, 1041.3803786746873, 1041.7343577387794, 11867.52529898803, 11871.762564748657, 11874.16880818343, 11885.5840045598, 11891.28652280866, 4735.242361222188, 4806.903648176403, 4868.367112275381, 4872.4883887192045, 4912.263160525878, 9933.155681766986, 9970.351927124859, 10000.14998725102, 10000.849987250198, 10069.949650251701, 5305.31030965972, 5453.10023846061, 5453.291072160594, 5454.290942177571, 5460.126379779216, 4447.132809102194, 4933.531314525115, 4980.128477516492, 4996.90006199874, 5000.174971129795, 3304.5208444775853, 4019.799010049486, 4054.8378064877666, 4090.652294554996, 4127.174565086994, 1326.7678156322604, 1337.752515784583, 1339.2366351179928, 1352.0332961932265, 1353.810466534686, 779.3959681247079, 916.1159481100769, 930.7812664023949, 944.2210176604328, 955.8805149356331, 5885.352611212763, 6055.2430946131735, 6078.696065196723, 6197.752089916443, 6287.2140982377205, 917.3807679816098, 958.1759686669459, 966.1256562810668, 967.5791030901439, 996.9950150249348, 3858.498395208605, 3928.685705143582, 4031.496062992126, 4045.271851066836, 4063.512378514597, 10634.415107169025, 11121.721956951142, 11230.10159187263, 11239.269447485887, 11257.831132533018, 11020.614278500214, 11145.108391328675, 11145.331280123219, 11179.83230251547, 11202.775944481065, 7953.0, 8364.958175209085, 8443.240108390151, 8453.281471075028, 8478.703245386381, 4548.56788605082, 5858.062709966385, 6015.533301700601, 6029.879402412015, 6086.330503644507, 10007.39955602637, 10199.28604997643, 10303.9999999997, 10326.122279606318, 10326.845097323396, 9216.354855160344, 9652.855207171757, 10359.741006474596, 10365.0, 10416.635417760343, 6551.181102362205, 7667.386609071149, 7684.731034413769, 7783.688652454065, 7785.92214077852, 8322.16778322163, 8365.205305495969, 8461.703840365557, 8467.745967620982, 8522.0, 7611.314981651678, 7695.268949449794, 7766.456348055582, 7792.415568832247, 7875.409344299086, 7691.30778229962, 7768.067831860213, 7768.961155194188, 7902.604869756604, 7925.801854953672, 5929.733162007807, 6794.150731158606, 7018.052562903943, 7110.6089165094345, 7114.822129446805, 5153.587712982878, 5158.793648254178, 5198.090334191577, 5208.869778255574, 5311.946880531145, 4143.875683729493, 5127.564157046693, 5235.424103348552, 5314.202869569595, 5462.289902312674], "value": 4657.438267196888, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.4949958818979, 304.4611038461922, 320.42483741683685, 320.54162547556984, 323.6585402400468, 5118.059094497235, 5259.710715910624, 5300.250917197325, 5307.734613269331, 5336.891927688565, 4864.735168856097, 5071.644984851062, 5226.693326668333, 5244.501772331622, 5264.684118952856, 3969.24584328978, 3991.381335892941, 4007.7194596378267, 4009.378546325317, 4010.799460026987, 3571.0893722100827, 3592.2995015971896, 3642.3990041643096, 3744.588095309514, 4014.578469260715, 3014.7136022077866, 3971.709194511781, 3982.561918188997, 4000.519937607491, 4040.4949381327333, 3330.917451828141, 3958.802059897008, 3990.5011873515955, 4009.8195581198916, 4014.2738622392358, 3483.3033393321184, 3484.494748261488, 3498.1079824644808, 3498.373256435744, 3505.2814173094707, 4316.2446571849805, 4467.664925130628, 4972.453030166679, 5012.624053196025, 5016.197408414636, 3953.723239373245, 4007.7996100195164, 4542.273236282179, 4571.062932098912, 4576.336431217454, 4295.570442955711, 5482.5613950883835, 5484.8354549363585, 5488.670679759227, 5531.695756733378, 3043.756499480058, 3493.0568746438576, 3808.0, 3844.730868839182, 3852.6339997700175, 4504.310883418718, 5116.565091967187, 5741.511971482387, 5771.740271687742, 5858.794942177004, 3631.782093074424, 3826.234753049401, 3839.9648186461222, 3852.460655508231, 3870.8645197417954, 3060.755139588872, 3832.96509942314, 3908.8631897884006, 3909.104084519006, 3945.8951493581844, 4087.6919385796855, 4237.745735255895, 4512.330437738058, 4528.682992190581, 4541.159885421208, 4500.662450316174, 5453.23654688344, 5502.614816962854, 5545.0, 5584.636998595079, 1187.7505723797926, 1272.663703111729, 1273.8662440443804, 1279.5073896549895, 1303.69363199648, 702.5492358280135, 761.6877080397011, 794.364508393283, 808.7625932323555, 810.9351251899834, 4062.898427539276, 4235.25882970479, 4351.303791393425, 4397.570789493428, 4408.779561022, 4236.368997935755, 4766.976165119221, 4823.250747074204, 4854.218594684233, 4856.854294371175, 3801.377049835605, 4406.127395856748, 5183.922241166386, 5231.764570594332, 5239.764210610531, 2677.821758426286, 2756.5528097748443, 2791.87436565351, 2966.0, 2990.2524368907775, 5239.947600523946, 5245.737713114329, 5303.549198318109, 5780.855478613068, 5891.46976772084, 5793.652380857162, 5796.087291193896, 5846.269216347957, 5849.245226432054, 5857.456238975444, 5896.734646940898, 5918.852028699231, 5940.524758019435, 5951.851203719855, 5992.370801065999, 4840.95159048405, 5372.892542149213, 5676.886462270649, 5686.2607860977805, 5686.57350698691, 4899.309738140336, 4926.433460152129, 5447.836564903059, 5622.718864056699, 5628.380878103487, 5476.205950137288, 5549.916751248654, 5567.805126820542, 5910.113482977587, 6001.819945401645, 3473.9677288786343, 3784.8675296364495, 3788.1666033472593, 3832.9616703832603, 3840.30874442603, 4843.418789745253, 5281.735913204401, 5289.338832645919, 5684.545236381162, 5685.374608793112, 4748.596369308648, 4998.1503144464805, 5348.930213957146, 5544.251526043932, 5548.278723765885, 5138.254953031715, 5490.6980116094, 5494.560435165257, 5521.254630624814, 5568.721563921707, 4818.469968303414, 4835.685680430761, 5258.316418865523, 5316.521513063843, 5412.83761487156, 4629.953700462952, 4747.359106520576, 5030.522100400456, 5349.705766182741, 5424.578968140952, 5174.404943431554, 5390.407055223844, 5408.972955135199, 5410.729463526886, 5419.349678038503], "value": 3950.286054740133, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.2693746472683, 741.8293792427742, 742.9294217049381, 743.5463668528029, 746.3879618712658, 14874.702505949874, 15157.242137893116, 15173.16547589882, 15313.387464501406, 15363.15502647354, 13971.301434928264, 14116.505922292721, 14151.292435378242, 14167.645808854759, 14189.503367382144, 706.8798304288283, 726.6257877193224, 894.3158483759912, 912.625823412401, 912.7535565397338, 878.5958459108817, 899.6761165980242, 900.7658008917696, 913.3606475467167, 914.3645166609209, 952.0336858089045, 965.4110992294694, 966.1401352796017, 966.8936416994125, 972.1639390124487, 735.5255859970342, 913.4519288426891, 929.8837645294338, 955.7610597350663, 960.7357976556468, 698.7868700046483, 699.6676578625163, 701.7964790210833, 701.9192792828838, 703.5532436902604, 12988.610341689764, 14328.785068223984, 14825.332860021324, 14906.180160091191, 14923.552293431214, 12217.633470995885, 12228.449719762632, 12245.571405000872, 12590.992720582331, 12628.0, 10915.563377464892, 11039.006489415991, 11061.723456913642, 11333.716657083638, 11620.825687614693, 3999.7600143991162, 4004.2191772604356, 4049.190161967618, 4117.094239267386, 4140.0, 79105.0, 79228.0, 82427.5878620609, 83818.0, 84052.0, 9437.952810235905, 9522.285828562886, 9655.468949207721, 9757.0, 10049.29654924163, 21450.927453627257, 21467.926603669755, 21490.0, 21499.57000859974, 21506.462338441663, 7424.740134095388, 7652.80867978305, 7743.225677432211, 7910.050793904768, 7911.208879112158, 3446.293509830479, 4070.5725898780793, 4080.9387859182134, 4084.65280451159, 4154.0, 2970.8217506949654, 3412.931741365159, 3498.772579782306, 3503.3343664703625, 3505.94741078884, 620.3920158244945, 627.1408170806025, 642.8071578526424, 645.644895307579, 649.9967500162469, 507.45955557331325, 542.926704894842, 551.6276513353471, 554.3563334712516, 575.6948817126846, 6669.599824010575, 7739.110002349802, 7751.689932402754, 7811.179826118289, 7847.1368149503505, 506.3493410966963, 575.9136129580596, 575.9884802303931, 579.2845835393285, 582.9242198514166, 2974.7341151481146, 3699.1122130688977, 3744.251149770057, 3823.483829683013, 4002.519697636302, 59236.0, 84686.0, 85719.5714021426, 85958.0, 90287.0, 72840.63579682067, 74499.62750186214, 75735.62132189304, 75996.0, 76982.23017769863, 50208.74895625498, 64782.282657600794, 64878.67560662166, 65114.67442662851, 65318.67340663266, 7244.0, 7311.963440182765, 7360.374368178765, 7482.1021477423055, 7748.99263095794, 39310.24828261994, 43420.56579434165, 43619.12761744811, 44615.33077003912, 45382.77308613436, 56305.77706671934, 56546.0, 59465.70267148637, 61531.69234153801, 61879.74022208163, 9662.275329350186, 9969.700908972742, 10037.89962100399, 10092.697219083439, 10251.28241023151, 10386.221033422675, 10415.94792026035, 10449.791004180026, 10588.841167382341, 10867.673969780919, 8040.758777236459, 10191.0, 10263.794724105626, 10382.42896640692, 10791.6222932197, 8703.173198546128, 9922.00779922002, 10041.146502547364, 10043.598256069676, 10260.333078349884, 9524.047595240421, 9910.008999100033, 9999.600015999278, 10003.099721025143, 10026.69919902404, 8254.917450825655, 8401.495910245405, 8530.872036919327, 8585.957070214608, 8649.870251946351, 8516.105808890225, 8559.144085591392, 8597.742067737978, 8611.827763444571, 8956.104389560991], "value": 6586.911694792762, "unit": "score"}], "name": "dromaeo_css", "value": 7126.784442077202, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 113.0, 111.0, 110.0, 113.0, 112.0, 113.0, 112.0, 112.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 139.0, 142.0, 199.0, 119.0, 138.0, 135.0, 116.0, 174.0, 115.0], "value": 144.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 185.0, 185.0, 184.0, 184.0, 185.0, 185.0, 182.0, 189.0, 186.0], "value": 186.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 57.0, 126.0, 56.0, 59.0, 57.0, 59.0, 111.0, 56.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 98.0, 134.0, 80.0, 84.0, 104.0, 139.0, 119.0, 79.0, 105.0], "value": 103.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 88.0, 89.0, 87.0, 88.0, 88.0, 89.0, 85.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 94.0, 95.0, 94.0, 95.0, 93.0, 95.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 84.0, 86.0, 85.0, 86.0, 84.0, 86.0, 88.0, 91.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 62.0, 58.0, 59.0, 60.0, 58.0, 123.0, 59.0, 56.0], "value": 65.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 73.0, 76.0, 70.0, 71.0, 67.0, 66.0, 69.0, 69.0, 68.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 85.0, 84.0, 84.0, 83.0, 84.0, 84.0, 86.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 136.0, 129.0, 129.0, 131.0, 138.0, 149.0, 247.0, 146.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 167.0, 165.0, 164.0, 165.0, 166.0, 166.0, 168.0, 162.0, 227.0], "value": 172.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 66.0, 67.0, 67.0, 64.0, 68.0, 67.0, 66.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1494.2, "alertThreshold": 2.0}]} 07:28:59 INFO - Return code: 0 07:28:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:28:59 ERROR - # TBPL SUCCESS # 07:28:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:28:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:28:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:28:59 INFO - 2016-05-10 07:28:59.923 screenresolution[2274:17963] starting screenresolution argv=screenresolution get 07:28:59 INFO - 2016-05-10 07:28:59.925 screenresolution[2274:17963] Display 0: 1600x1200x32@60 07:28:59 INFO - 2016-05-10 07:28:59.931 screenresolution[2275:17966] starting screenresolution argv=screenresolution list 07:28:59 INFO - Available Modes on Display 0 07:28:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:28:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:28:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:28:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:29:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:29:00 INFO - Intel Iris: 07:29:00 INFO - Chipset Model: Intel Iris 07:29:00 INFO - Type: GPU 07:29:00 INFO - Bus: Built-In 07:29:00 INFO - VRAM (Dynamic, Max): 1536 MB 07:29:00 INFO - Vendor: Intel (0x8086) 07:29:00 INFO - Device ID: 0x0a2e 07:29:00 INFO - Revision ID: 0x0009 07:29:00 INFO - Displays: 07:29:00 INFO - Display: 07:29:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:29:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:29:00 INFO - Display Serial Number: 1600x1200 60 07:29:00 INFO - Main Display: Yes 07:29:00 INFO - Mirror: Off 07:29:00 INFO - Online: Yes 07:29:00 INFO - Rotation: Supported 07:29:00 INFO - Return code: 0 07:29:00 INFO - Running post-action listener: _resource_record_post_action 07:29:00 INFO - ##### 07:29:00 INFO - ##### Finished run-tests step (success) 07:29:00 INFO - ##### 07:29:00 INFO - Running post-run listener: _resource_record_post_run 07:29:01 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 708608; Write bytes: 693166080; Read time: 9; Write time: 1978 07:29:01 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 390048256; Write bytes: 384325632; Read time: 29473; Write time: 806 07:29:01 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 708608; Write bytes: 294334976; Read time: 9; Write time: 1131 07:29:01 INFO - Running post-run listener: _upload_blobber_files 07:29:01 INFO - Blob upload gear active. 07:29:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:29:01 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 07:29:01 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'] 07:29:01 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 07:29:01 INFO - (blobuploader) - INFO - Open directory for files ... 07:29:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:29:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:29:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:29:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:29:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:29:02 INFO - (blobuploader) - INFO - Done attempting. 07:29:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:29:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:29:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:29:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:29:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:29:04 INFO - (blobuploader) - INFO - Done attempting. 07:29:04 INFO - (blobuploader) - INFO - Iteration through files over. 07:29:04 INFO - Return code: 0 07:29:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:29:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:29:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8895e91c2f1e4e88bd3608bd7dbe7b8df7d4fe90ddb5abf1485f2075e52e17b967fab99104a3e5c141b8623ea633399e1e394e2af78f0830b670ba9fa300624", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c10d8e67874cf32ee753192848261df5905bed0db9989334add7abd9d522d65dcf78c7638107d46305d940d61e7888d0d0b96fd7635328ded0b950557cb4dc01"} 07:29:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:29:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:29:04 INFO - Contents: 07:29:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8895e91c2f1e4e88bd3608bd7dbe7b8df7d4fe90ddb5abf1485f2075e52e17b967fab99104a3e5c141b8623ea633399e1e394e2af78f0830b670ba9fa300624", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c10d8e67874cf32ee753192848261df5905bed0db9989334add7abd9d522d65dcf78c7638107d46305d940d61e7888d0d0b96fd7635328ded0b950557cb4dc01"} 07:29:04 INFO - Running post-run listener: copy_logs_to_upload_dir 07:29:04 INFO - Copying logs to upload dir... 07:29:04 INFO - mkdir: /builds/slave/test/build/upload/logs 07:29:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.662485 ========= master_lag: 22.53 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2016-05-10 07:29:26.631528) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 07:29:26.634595) ========= 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.qP56LBHJTA/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.0uF8YDmu9m/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8895e91c2f1e4e88bd3608bd7dbe7b8df7d4fe90ddb5abf1485f2075e52e17b967fab99104a3e5c141b8623ea633399e1e394e2af78f0830b670ba9fa300624", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c10d8e67874cf32ee753192848261df5905bed0db9989334add7abd9d522d65dcf78c7638107d46305d940d61e7888d0d0b96fd7635328ded0b950557cb4dc01"} build_url:https://queue.taskcluster.net/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.012049 build_url: 'https://queue.taskcluster.net/v1/task/Be02VjSBQ3aIZS50URsbdQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8895e91c2f1e4e88bd3608bd7dbe7b8df7d4fe90ddb5abf1485f2075e52e17b967fab99104a3e5c141b8623ea633399e1e394e2af78f0830b670ba9fa300624", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c10d8e67874cf32ee753192848261df5905bed0db9989334add7abd9d522d65dcf78c7638107d46305d940d61e7888d0d0b96fd7635328ded0b950557cb4dc01"}' ========= master_lag: 0.98 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 07:29:27.625858) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 07:29:27.626183) ========= 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.qP56LBHJTA/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.0uF8YDmu9m/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.005305 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 07:29:27.713000) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 07:29:27.713328) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 07:29:27.729508) ========= ========= Total master_lag: 23.66 =========