builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0180 starttime: 1462942327.21 results: success (0) buildid: 20160510210207 builduid: 113d6e0aaa074cf89c11d8061eb7bb1d revision: c8241f3e1f532ff2cb38d24ec638ac111f3d9e2c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 21:52:07.212727) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 21:52:07.213843) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 21:52:07.214178) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 21:52:07.233663) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:52:07.233996) ========= 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.qPD88rQXfH/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.gXUBu1b7rO/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 21:52:07-- 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% 15.2M=0.001s 2016-05-10 21:52:07 (15.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117109 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:52:07.368533) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:52:07.368858) ========= 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.qPD88rQXfH/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.gXUBu1b7rO/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.026520 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:52:07.416838) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 21:52:07.417203) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c8241f3e1f532ff2cb38d24ec638ac111f3d9e2c --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 c8241f3e1f532ff2cb38d24ec638ac111f3d9e2c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qPD88rQXfH/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.gXUBu1b7rO/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 21:52:07,495 truncating revision to first 12 chars 2016-05-10 21:52:07,495 Setting DEBUG logging. 2016-05-10 21:52:07,495 attempt 1/10 2016-05-10 21:52:07,495 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c8241f3e1f53?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 21:52:08,363 unpacking tar archive at: fx-team-c8241f3e1f53/testing/mozharness/ program finished with exit code 0 elapsedTime=1.108605 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 21:52:08.544273) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 21:52:08.545152) ========= script_repo_revision: c8241f3e1f532ff2cb38d24ec638ac111f3d9e2c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 21:52:08.546113) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 21:52:08.546968) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 21:52:08.562647) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-10 21:52:08.562951) ========= /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.qPD88rQXfH/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.gXUBu1b7rO/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 21:52:08 INFO - MultiFileLogger online at 20160510 21:52:08 in /builds/slave/test 21:52:08 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 21:52:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:52:08 INFO - {'append_to_log': False, 21:52:08 INFO - 'base_work_dir': '/builds/slave/test', 21:52:08 INFO - 'blob_upload_branch': 'Fx-Team', 21:52:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:52:08 INFO - 'branch': 'Fx-Team', 21:52:08 INFO - 'buildbot_json_path': 'buildprops.json', 21:52:08 INFO - 'config_files': ('talos/mac_config.py',), 21:52:08 INFO - 'default_actions': ('clobber', 21:52:08 INFO - 'read-buildbot-config', 21:52:08 INFO - 'download-and-extract', 21:52:08 INFO - 'populate-webroot', 21:52:08 INFO - 'create-virtualenv', 21:52:08 INFO - 'install', 21:52:08 INFO - 'run-tests'), 21:52:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:52:08 INFO - 'download_minidump_stackwalk': True, 21:52:08 INFO - 'download_symbols': 'ondemand', 21:52:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:52:08 INFO - 'tooltool.py': '/tools/tooltool.py', 21:52:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:52:08 INFO - '/tools/misc-python/virtualenv.py')}, 21:52:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:52:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:52:08 INFO - 'installer_path': 'installer.exe', 21:52:08 INFO - 'log_level': 'info', 21:52:08 INFO - 'log_name': 'talos', 21:52:08 INFO - 'log_to_console': True, 21:52:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:52:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:52:08 INFO - 'opt_config_files': (), 21:52:08 INFO - 'pip_index': False, 21:52:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:52:08 INFO - 'cmd': ('bash', 21:52:08 INFO - '-c', 21:52:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:52:08 INFO - 'enabled': True, 21:52:08 INFO - 'halt_on_failure': False, 21:52:08 INFO - 'name': 'check_screen_resolution'},), 21:52:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:52:08 INFO - 'cmd': ('bash', 21:52:08 INFO - '-c', 21:52:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:52:08 INFO - 'enabled': True, 21:52:08 INFO - 'halt_on_failure': False, 21:52:08 INFO - 'name': 'check_screen_resolution'},), 21:52:08 INFO - 'run_cmd_checks_enabled': True, 21:52:08 INFO - 'sps_profile': False, 21:52:08 INFO - 'sps_profile_interval': 0, 21:52:08 INFO - 'suite': 'dromaeojs', 21:52:08 INFO - 'system_bits': '32', 21:52:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:52:08 INFO - 'title': 't-yosemite-r7-0180', 21:52:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:52:08 INFO - 'use_talos_json': True, 21:52:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:52:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:52:08 INFO - 'work_dir': 'build'} 21:52:08 INFO - ##### 21:52:08 INFO - ##### Running clobber step. 21:52:08 INFO - ##### 21:52:08 INFO - Running pre-action listener: _resource_record_pre_action 21:52:08 INFO - Running main action method: clobber 21:52:08 INFO - rmtree: /builds/slave/test/build 21:52:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:52:10 INFO - Running post-action listener: _resource_record_post_action 21:52:10 INFO - ##### 21:52:10 INFO - ##### Finished clobber step (success) 21:52:10 INFO - ##### 21:52:10 INFO - ##### 21:52:10 INFO - ##### Running read-buildbot-config step. 21:52:10 INFO - ##### 21:52:10 INFO - Running pre-action listener: _resource_record_pre_action 21:52:10 INFO - Running main action method: read_buildbot_config 21:52:10 INFO - Using buildbot properties: 21:52:10 INFO - { 21:52:10 INFO - "project": "", 21:52:10 INFO - "product": "firefox", 21:52:10 INFO - "script_repo_revision": "production", 21:52:10 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 21:52:10 INFO - "repository": "", 21:52:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 21:52:10 INFO - "buildid": "20160510210207", 21:52:10 INFO - "pgo_build": "False", 21:52:10 INFO - "basedir": "/builds/slave/test", 21:52:10 INFO - "buildnumber": 7, 21:52:10 INFO - "slavename": "t-yosemite-r7-0180", 21:52:10 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 21:52:10 INFO - "platform": "yosemite_r7", 21:52:10 INFO - "scheduler": "tests-fx-team-macosx64-talos", 21:52:10 INFO - "branch": "fx-team", 21:52:10 INFO - "repo_path": "integration/fx-team", 21:52:10 INFO - "revision": "c8241f3e1f532ff2cb38d24ec638ac111f3d9e2c", 21:52:10 INFO - "stage_platform": "macosx64", 21:52:10 INFO - "builduid": "113d6e0aaa074cf89c11d8061eb7bb1d", 21:52:10 INFO - "slavebuilddir": "test" 21:52:10 INFO - } 21:52:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 21:52:10 INFO - Running post-action listener: _resource_record_post_action 21:52:10 INFO - ##### 21:52:10 INFO - ##### Finished read-buildbot-config step (success) 21:52:10 INFO - ##### 21:52:10 INFO - ##### 21:52:10 INFO - ##### Running download-and-extract step. 21:52:10 INFO - ##### 21:52:10 INFO - Running pre-action listener: _resource_record_pre_action 21:52:10 INFO - Running main action method: download_and_extract 21:52:10 INFO - mkdir: /builds/slave/test/build/tests 21:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:10 INFO - https://queue.taskcluster.net/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 21:52:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 21:52:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 21:52:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/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 21:52:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/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 21:52:12 INFO - Downloaded 1199 bytes. 21:52:12 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 21:52:12 INFO - Using the following test package requirements: 21:52:12 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 21:52:12 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:52:12 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 21:52:12 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:52:12 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 21:52:12 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:52:12 INFO - u'jsshell-mac.zip'], 21:52:12 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:52:12 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 21:52:12 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 21:52:12 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:52:12 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 21:52:12 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:52:12 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 21:52:12 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:52:12 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 21:52:12 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:52:12 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 21:52:12 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:12 INFO - https://queue.taskcluster.net/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:52:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:52:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:52:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/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 21:52:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/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 21:52:13 INFO - Downloaded 18150485 bytes. 21:52:13 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'] 21:52:13 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 21:52:16 INFO - Return code: 0 21:52:16 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 21:52:16 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')]} 21:52:16 INFO - https://queue.taskcluster.net/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:52:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:52:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:52:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/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 21:52:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/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 21:52:17 INFO - Downloaded 18150485 bytes. 21:52:17 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'] 21:52:17 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 21:52:21 INFO - Return code: 0 21:52: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')]} 21:52:21 INFO - https://queue.taskcluster.net/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 21:52:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:52:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:52:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/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 21:52:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/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 21:52:22 INFO - Downloaded 10840152 bytes. 21:52:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:52:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 21:52:22 INFO - Return code: 0 21:52: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')]} 21:52:22 INFO - https://queue.taskcluster.net/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 21:52:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:52:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:52:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 21:52:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WP2XsnZNQXSegaD_d-y45g/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 21:52:25 INFO - Downloaded 109843635 bytes. 21:52:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:52:25 INFO - mkdir: /builds/slave/test/properties 21:52:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:52:25 INFO - Writing to file /builds/slave/test/properties/build_url 21:52:25 INFO - Contents: 21:52:25 INFO - build_url:https://queue.taskcluster.net/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:52:26 INFO - Running post-action listener: _resource_record_post_action 21:52:26 INFO - Running post-action listener: set_extra_try_arguments 21:52:26 INFO - ##### 21:52:26 INFO - ##### Finished download-and-extract step (success) 21:52:26 INFO - ##### 21:52:26 INFO - ##### 21:52:26 INFO - ##### Running populate-webroot step. 21:52:26 INFO - ##### 21:52:26 INFO - Running pre-action listener: _resource_record_pre_action 21:52:26 INFO - Running main action method: populate_webroot 21:52:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:52:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:52:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:52:26 INFO - '--tppagecycles', 21:52:26 INFO - '7'], 21:52:26 INFO - 'tests': ['tsvgm']}}, 21:52:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:52:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:52:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:52:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:52:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:52:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:52:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:26 INFO - 'talos_options': ['--disable-e10s'], 21:52:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:52:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:52:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:26 INFO - 'talos_options': ['--disable-e10s'], 21:52:26 INFO - 'tests': ['damp', 'tps']}, 21:52:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:26 INFO - 'tests': ['damp', 'tps']}, 21:52:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:52:26 INFO - 'tests': ['dromaeo_dom']}, 21:52:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:52:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:52:26 INFO - 'tests': ['a11yr', 21:52:26 INFO - 'ts_paint', 21:52:26 INFO - 'tpaint', 21:52:26 INFO - 'sessionrestore', 21:52:26 INFO - 'sessionrestore_no_auto_restore', 21:52:26 INFO - 'tabpaint']}, 21:52:26 INFO - 'other-e10s': {'tests': ['a11yr', 21:52:26 INFO - 'ts_paint', 21:52:26 INFO - 'tpaint', 21:52:26 INFO - 'sessionrestore', 21:52:26 INFO - 'sessionrestore_no_auto_restore', 21:52:26 INFO - 'tabpaint']}, 21:52:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:52:26 INFO - 'ts_paint', 21:52:26 INFO - 'tpaint', 21:52:26 INFO - 'sessionrestore', 21:52:26 INFO - 'sessionrestore_no_auto_restore', 21:52:26 INFO - 'tabpaint']}, 21:52:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:52:26 INFO - 'ts_paint', 21:52:26 INFO - 'tpaint', 21:52:26 INFO - 'sessionrestore', 21:52:26 INFO - 'sessionrestore_no_auto_restore', 21:52:26 INFO - 'tabpaint']}, 21:52:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:52:26 INFO - 'tests': ['a11yr', 21:52:26 INFO - 'ts_paint', 21:52:26 INFO - 'tpaint', 21:52:26 INFO - 'sessionrestore', 21:52:26 INFO - 'sessionrestore_no_auto_restore', 21:52:26 INFO - 'tabpaint']}, 21:52:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:52:26 INFO - 'tests': ['a11yr', 21:52:26 INFO - 'ts_paint', 21:52:26 INFO - 'tpaint', 21:52:26 INFO - 'sessionrestore', 21:52:26 INFO - 'sessionrestore_no_auto_restore', 21:52:26 INFO - 'tabpaint']}, 21:52:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:52:26 INFO - 'tests': ['tsvgx', 21:52:26 INFO - 'tsvgr_opacity', 21:52:26 INFO - 'tart', 21:52:26 INFO - 'tscrollx', 21:52:26 INFO - 'cart']}, 21:52:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:52:26 INFO - 'tsvgr_opacity', 21:52:26 INFO - 'tart', 21:52:26 INFO - 'tscrollx', 21:52:26 INFO - 'cart']}, 21:52:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:26 INFO - 'talos_options': ['--disable-e10s'], 21:52:26 INFO - 'tests': ['tp5o']}, 21:52:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:26 INFO - 'tests': ['tp5o']}, 21:52:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:26 INFO - 'talos_options': ['--disable-e10s', 21:52:26 INFO - '--xperf_path', 21:52:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:52:26 INFO - 'tests': ['tp5n']}, 21:52:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:26 INFO - 'talos_options': ['--xperf_path', 21:52:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:52:26 INFO - 'tests': ['tp5n']}}, 21:52:26 INFO - 'talos.zip': {'path': '', 21:52:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:52:26 INFO - Running post-action listener: _resource_record_post_action 21:52:26 INFO - ##### 21:52:26 INFO - ##### Finished populate-webroot step (success) 21:52:26 INFO - ##### 21:52:26 INFO - ##### 21:52:26 INFO - ##### Running create-virtualenv step. 21:52:26 INFO - ##### 21:52:26 INFO - Running pre-action listener: _resource_record_pre_action 21:52:26 INFO - Running main action method: create_virtualenv 21:52:26 INFO - Creating virtualenv /builds/slave/test/build/venv 21:52:26 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 21:52:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:52:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:52:26 INFO - Using real prefix '/tools/python27' 21:52:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:52:27 INFO - Installing distribute.............................................................................................................................................................................................done. 21:52:30 INFO - Installing pip.................done. 21:52:30 INFO - Return code: 0 21:52:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:52:30 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')]} 21:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:30 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')]} 21:52:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:30 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 0x10751da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107523570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe333c91490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c1d30>, '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 0x1071e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.qPD88rQXfH/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.gXUBu1b7rO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 21:52:30 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 21:52:30 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 21:52:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPD88rQXfH/Render', 21:52:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:30 INFO - 'HOME': '/Users/cltbld', 21:52:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:30 INFO - 'LOGNAME': 'cltbld', 21:52:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:30 INFO - 'MOZ_NO_REMOTE': '1', 21:52:30 INFO - 'NO_EM_RESTART': '1', 21:52:30 INFO - 'PAGER': '/bin/cat', 21:52:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:30 INFO - 'PWD': '/builds/slave/test', 21:52:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:30 INFO - 'SHELL': '/bin/bash', 21:52:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXUBu1b7rO/Listeners', 21:52:30 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 21:52:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:30 INFO - 'USER': 'cltbld', 21:52:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:30 INFO - 'XPC_FLAGS': '0x0', 21:52:30 INFO - 'XPC_SERVICE_NAME': '0', 21:52:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:30 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 21:52:30 INFO - Cleaning up... 21:52:30 INFO - Return code: 0 21:52:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 21:52:30 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')]} 21:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:30 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')]} 21:52:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:30 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 0x10751da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107523570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe333c91490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c1d30>, '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 0x1071e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.qPD88rQXfH/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.gXUBu1b7rO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 21:52:30 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 21:52:30 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 21:52:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPD88rQXfH/Render', 21:52:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:30 INFO - 'HOME': '/Users/cltbld', 21:52:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:30 INFO - 'LOGNAME': 'cltbld', 21:52:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:30 INFO - 'MOZ_NO_REMOTE': '1', 21:52:30 INFO - 'NO_EM_RESTART': '1', 21:52:30 INFO - 'PAGER': '/bin/cat', 21:52:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:30 INFO - 'PWD': '/builds/slave/test', 21:52:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:30 INFO - 'SHELL': '/bin/bash', 21:52:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXUBu1b7rO/Listeners', 21:52:30 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 21:52:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:30 INFO - 'USER': 'cltbld', 21:52:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:30 INFO - 'XPC_FLAGS': '0x0', 21:52:30 INFO - 'XPC_SERVICE_NAME': '0', 21:52:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:31 INFO - Downloading/unpacking psutil>=0.7.1 21:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:52:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:52:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:52:34 INFO - Installing collected packages: psutil 21:52:34 INFO - Running setup.py install for psutil 21:52:34 INFO - building 'psutil._psutil_osx' extension 21:52:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 21:52:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 21:52:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 21:52:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 21:52:35 INFO - building 'psutil._psutil_posix' extension 21:52:35 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 21:52:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:52:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:52:35 INFO - ^ 21:52:35 INFO - 1 warning generated. 21:52:35 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 21:52:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:52:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:52:35 INFO - Successfully installed psutil 21:52:35 INFO - Cleaning up... 21:52:35 INFO - Return code: 0 21:52:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 21:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10751da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107523570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe333c91490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c1d30>, '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 0x1071e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.qPD88rQXfH/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.gXUBu1b7rO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 21:52:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 21:52:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 21:52:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPD88rQXfH/Render', 21:52:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:35 INFO - 'HOME': '/Users/cltbld', 21:52:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:35 INFO - 'LOGNAME': 'cltbld', 21:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:35 INFO - 'MOZ_NO_REMOTE': '1', 21:52:35 INFO - 'NO_EM_RESTART': '1', 21:52:35 INFO - 'PAGER': '/bin/cat', 21:52:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:35 INFO - 'PWD': '/builds/slave/test', 21:52:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:35 INFO - 'SHELL': '/bin/bash', 21:52:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXUBu1b7rO/Listeners', 21:52:35 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 21:52:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:35 INFO - 'USER': 'cltbld', 21:52:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:35 INFO - 'XPC_FLAGS': '0x0', 21:52:35 INFO - 'XPC_SERVICE_NAME': '0', 21:52:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:52:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:52:38 INFO - Installing collected packages: mozsystemmonitor 21:52:38 INFO - Running setup.py install for mozsystemmonitor 21:52:38 INFO - Successfully installed mozsystemmonitor 21:52:38 INFO - Cleaning up... 21:52:38 INFO - Return code: 0 21:52:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:52:38 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')]} 21:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:38 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')]} 21:52:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:38 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 0x10751da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107523570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe333c91490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c1d30>, '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 0x1071e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.qPD88rQXfH/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.gXUBu1b7rO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 21:52:38 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 21:52:38 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 21:52:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPD88rQXfH/Render', 21:52:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:38 INFO - 'HOME': '/Users/cltbld', 21:52:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:38 INFO - 'LOGNAME': 'cltbld', 21:52:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:38 INFO - 'MOZ_NO_REMOTE': '1', 21:52:38 INFO - 'NO_EM_RESTART': '1', 21:52:38 INFO - 'PAGER': '/bin/cat', 21:52:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:38 INFO - 'PWD': '/builds/slave/test', 21:52:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:38 INFO - 'SHELL': '/bin/bash', 21:52:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXUBu1b7rO/Listeners', 21:52:38 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 21:52:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:38 INFO - 'USER': 'cltbld', 21:52:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:38 INFO - 'XPC_FLAGS': '0x0', 21:52:38 INFO - 'XPC_SERVICE_NAME': '0', 21:52:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:38 INFO - Downloading/unpacking blobuploader==1.2.4 21:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:40 INFO - Downloading blobuploader-1.2.4.tar.gz 21:52:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:52:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:52:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:42 INFO - Downloading docopt-0.6.1.tar.gz 21:52:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:52:42 INFO - Installing collected packages: blobuploader, requests, docopt 21:52:42 INFO - Running setup.py install for blobuploader 21:52:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:52:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:52:42 INFO - Running setup.py install for requests 21:52:42 INFO - Running setup.py install for docopt 21:52:43 INFO - Successfully installed blobuploader requests docopt 21:52:43 INFO - Cleaning up... 21:52:43 INFO - Return code: 0 21:52:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:52: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')]} 21:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52: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')]} 21:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:43 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 0x10751da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107523570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe333c91490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c1d30>, '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 0x1071e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.qPD88rQXfH/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.gXUBu1b7rO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 21:52:43 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 21:52:43 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 21:52:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPD88rQXfH/Render', 21:52:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:43 INFO - 'HOME': '/Users/cltbld', 21:52:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:43 INFO - 'LOGNAME': 'cltbld', 21:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:43 INFO - 'MOZ_NO_REMOTE': '1', 21:52:43 INFO - 'NO_EM_RESTART': '1', 21:52:43 INFO - 'PAGER': '/bin/cat', 21:52:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:43 INFO - 'PWD': '/builds/slave/test', 21:52:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:43 INFO - 'SHELL': '/bin/bash', 21:52:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXUBu1b7rO/Listeners', 21:52:43 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 21:52:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:43 INFO - 'USER': 'cltbld', 21:52:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:43 INFO - 'XPC_FLAGS': '0x0', 21:52:43 INFO - 'XPC_SERVICE_NAME': '0', 21:52:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:52:43 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-y5pN8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:52:43 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-_7OIql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:52:43 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-VmKrp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:52:43 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Vlxgo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:52:43 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-XnLxVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:52:43 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-AiaDUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:52:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-YlifPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:52:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-lEln2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:52:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Z8KKKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:52:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-9VAhj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:52:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-ksVRJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:52:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-O3W6No-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:52:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-u8IJTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:52:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-a6tZZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:52:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-T4szOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:52:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-ShuSB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:52:45 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Ln7BcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:52:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:52:45 INFO - Running setup.py install for manifestparser 21:52:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:52:45 INFO - Running setup.py install for mozcrash 21:52:45 INFO - Running setup.py install for mozdebug 21:52:45 INFO - Running setup.py install for mozdevice 21:52:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:52:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:52:45 INFO - Running setup.py install for mozfile 21:52:45 INFO - Running setup.py install for mozhttpd 21:52:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:52:46 INFO - Running setup.py install for mozinfo 21:52:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:52:46 INFO - Running setup.py install for mozInstall 21:52:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:52:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:52:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:52:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:52:46 INFO - Running setup.py install for mozleak 21:52:46 INFO - Running setup.py install for mozlog 21:52:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:52:46 INFO - Running setup.py install for moznetwork 21:52:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:52:46 INFO - Running setup.py install for mozprocess 21:52:46 INFO - Running setup.py install for mozprofile 21:52:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:52:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:52:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:52:47 INFO - Running setup.py install for mozrunner 21:52:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:52:47 INFO - Running setup.py install for mozscreenshot 21:52:47 INFO - Running setup.py install for moztest 21:52:47 INFO - Running setup.py install for mozversion 21:52:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:52:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:52:47 INFO - Cleaning up... 21:52:47 INFO - Return code: 0 21:52:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10751da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107523570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe333c91490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c1d30>, '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 0x1071e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.qPD88rQXfH/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.gXUBu1b7rO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 21:52:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:52:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:52:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPD88rQXfH/Render', 21:52:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:47 INFO - 'HOME': '/Users/cltbld', 21:52:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:47 INFO - 'LOGNAME': 'cltbld', 21:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:47 INFO - 'MOZ_NO_REMOTE': '1', 21:52:47 INFO - 'NO_EM_RESTART': '1', 21:52:47 INFO - 'PAGER': '/bin/cat', 21:52:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:47 INFO - 'PWD': '/builds/slave/test', 21:52:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:47 INFO - 'SHELL': '/bin/bash', 21:52:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXUBu1b7rO/Listeners', 21:52:47 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 21:52:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:47 INFO - 'USER': 'cltbld', 21:52:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:47 INFO - 'XPC_FLAGS': '0x0', 21:52:47 INFO - 'XPC_SERVICE_NAME': '0', 21:52:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:52:48 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-G0zQ67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:52:48 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)) 21:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:52:48 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-FZJNUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:52:48 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)) 21:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:52:48 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-SZZfaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:52:48 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)) 21:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:52:48 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-4cyVV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:52:48 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-JmVHms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:52:48 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-dbjrLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:52:48 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-fXss29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:52:48 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-iWBVgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:52:48 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-HQTnxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:52:49 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)) 21:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:52:49 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-2RGt_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:52:49 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)) 21:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:52:49 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-bc_iEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:52:49 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)) 21:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:52:49 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-KUndFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:52:49 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)) 21:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:52:49 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-jadXyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:52:49 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)) 21:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:52:49 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-JO4eOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:52:49 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-yOJBxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:52:49 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-BmXMLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:52:49 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-2MMzSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:52:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:52 INFO - Downloading blessings-1.6.tar.gz 21:52:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:52:52 INFO - Installing collected packages: blessings 21:52:52 INFO - Running setup.py install for blessings 21:52:52 INFO - Successfully installed blessings 21:52:52 INFO - Cleaning up... 21:52:52 INFO - Return code: 0 21:52:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:52:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:52:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:52:52 INFO - Reading from file tmpfile_stdout 21:52:52 INFO - Current package versions: 21:52:52 INFO - blessings == 1.6 21:52:52 INFO - blobuploader == 1.2.4 21:52:52 INFO - docopt == 0.6.1 21:52:52 INFO - manifestparser == 1.1 21:52:52 INFO - mozInstall == 1.12 21:52:52 INFO - mozcrash == 0.17 21:52:52 INFO - mozdebug == 0.1 21:52:52 INFO - mozdevice == 0.48 21:52:52 INFO - mozfile == 1.2 21:52:52 INFO - mozhttpd == 0.7 21:52:52 INFO - mozinfo == 0.9 21:52:52 INFO - mozleak == 0.1 21:52:52 INFO - mozlog == 3.1 21:52:52 INFO - moznetwork == 0.27 21:52:52 INFO - mozprocess == 0.22 21:52:52 INFO - mozprofile == 0.28 21:52:52 INFO - mozrunner == 6.11 21:52:52 INFO - mozscreenshot == 0.1 21:52:52 INFO - mozsystemmonitor == 0.0 21:52:52 INFO - moztest == 0.7 21:52:52 INFO - mozversion == 1.4 21:52:52 INFO - psutil == 3.1.1 21:52:52 INFO - requests == 1.2.3 21:52:52 INFO - wsgiref == 0.1.2 21:52:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10751da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107523570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe333c91490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c1d30>, '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 0x1071e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.qPD88rQXfH/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.gXUBu1b7rO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 21:52:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:52:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:52:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPD88rQXfH/Render', 21:52:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:52 INFO - 'HOME': '/Users/cltbld', 21:52:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:52 INFO - 'LOGNAME': 'cltbld', 21:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:52 INFO - 'MOZ_NO_REMOTE': '1', 21:52:52 INFO - 'NO_EM_RESTART': '1', 21:52:52 INFO - 'PAGER': '/bin/cat', 21:52:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:52 INFO - 'PWD': '/builds/slave/test', 21:52:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:52 INFO - 'SHELL': '/bin/bash', 21:52:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXUBu1b7rO/Listeners', 21:52:52 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 21:52:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:52 INFO - 'USER': 'cltbld', 21:52:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:52 INFO - 'XPC_FLAGS': '0x0', 21:52:52 INFO - 'XPC_SERVICE_NAME': '0', 21:52:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:53 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)) 21:52:53 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)) 21:52:53 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)) 21:52:53 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)) 21:52:53 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)) 21:52:53 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)) 21:52:53 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)) 21:52:53 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)) 21:52:53 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)) 21:52:53 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)) 21:52:53 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)) 21:52:53 INFO - Cleaning up... 21:52:53 INFO - Return code: 0 21:52:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:53 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 0x10751da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107523570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe333c91490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c1d30>, '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 0x1071e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.qPD88rQXfH/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.gXUBu1b7rO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 21:52:53 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 21:52:53 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 21:52:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPD88rQXfH/Render', 21:52:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:53 INFO - 'HOME': '/Users/cltbld', 21:52:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:53 INFO - 'LOGNAME': 'cltbld', 21:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:53 INFO - 'MOZ_NO_REMOTE': '1', 21:52:53 INFO - 'NO_EM_RESTART': '1', 21:52:53 INFO - 'PAGER': '/bin/cat', 21:52:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:53 INFO - 'PWD': '/builds/slave/test', 21:52:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:53 INFO - 'SHELL': '/bin/bash', 21:52:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXUBu1b7rO/Listeners', 21:52:53 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 21:52:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:53 INFO - 'USER': 'cltbld', 21:52:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:53 INFO - 'XPC_FLAGS': '0x0', 21:52:53 INFO - 'XPC_SERVICE_NAME': '0', 21:52:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:53 INFO - Downloading/unpacking jsonschema 21:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:52:55 INFO - Downloading/unpacking functools32 (from jsonschema) 21:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:56 INFO - Downloading functools32-3.2.3-2.tar.gz 21:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:52:56 INFO - warning: no files found matching '*.txt' 21:52:56 INFO - no previously-included directories found matching 'build' 21:52:56 INFO - no previously-included directories found matching 'dist' 21:52:56 INFO - no previously-included directories found matching '.git*' 21:52:56 INFO - Installing collected packages: jsonschema, functools32 21:52:56 INFO - Running setup.py install for functools32 21:52:56 INFO - warning: no files found matching '*.txt' 21:52:56 INFO - no previously-included directories found matching 'build' 21:52:56 INFO - no previously-included directories found matching 'dist' 21:52:56 INFO - no previously-included directories found matching '.git*' 21:52:56 INFO - Successfully installed jsonschema functools32 21:52:56 INFO - Cleaning up... 21:52:56 INFO - Return code: 0 21:52:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10751da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107523570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe333c91490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c1d30>, '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 0x1071e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.qPD88rQXfH/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.gXUBu1b7rO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 21:52:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 21:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 21:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPD88rQXfH/Render', 21:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:56 INFO - 'HOME': '/Users/cltbld', 21:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:56 INFO - 'LOGNAME': 'cltbld', 21:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:56 INFO - 'MOZ_NO_REMOTE': '1', 21:52:56 INFO - 'NO_EM_RESTART': '1', 21:52:56 INFO - 'PAGER': '/bin/cat', 21:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:56 INFO - 'PWD': '/builds/slave/test', 21:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:56 INFO - 'SHELL': '/bin/bash', 21:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXUBu1b7rO/Listeners', 21:52:56 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 21:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:56 INFO - 'USER': 'cltbld', 21:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:56 INFO - 'XPC_FLAGS': '0x0', 21:52:56 INFO - 'XPC_SERVICE_NAME': '0', 21:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:56 INFO - Downloading/unpacking flake8 21:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:52:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:52:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:52:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:53:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:53:00 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 21:53:00 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 21:53:00 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 21:53:00 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 21:53:00 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 21:53:00 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 21:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:53:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:53:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:53:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:53:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:53:00 INFO - no previously-included directories found matching 'docs/_build' 21:53:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:53:00 INFO - Running setup.py install for pep8 21:53:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:53:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:53:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:53:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:53:00 INFO - no previously-included directories found matching 'docs/_build' 21:53:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:53:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:53:00 INFO - Cleaning up... 21:53:00 INFO - Return code: 0 21:53:00 INFO - Running post-action listener: _resource_record_post_action 21:53:00 INFO - Running post-action listener: _start_resource_monitoring 21:53:00 INFO - Starting resource monitoring. 21:53:00 INFO - ##### 21:53:00 INFO - ##### Finished create-virtualenv step (success) 21:53:00 INFO - ##### 21:53:00 INFO - ##### 21:53:00 INFO - ##### Running install step. 21:53:00 INFO - ##### 21:53:00 INFO - Running pre-action listener: _resource_record_pre_action 21:53:00 INFO - Running main action method: install 21:53:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:53:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:53:01 INFO - Reading from file tmpfile_stdout 21:53:01 INFO - Detecting whether we're running mozinstall >=1.0... 21:53:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:53:01 INFO - Reading from file tmpfile_stdout 21:53:01 INFO - Output received: 21:53:01 INFO - Usage: mozinstall [options] installer 21:53:01 INFO - Options: 21:53:01 INFO - -h, --help show this help message and exit 21:53:01 INFO - -d DEST, --destination=DEST 21:53:01 INFO - Directory to install application into. [default: 21:53:01 INFO - "/builds/slave/test"] 21:53:01 INFO - --app=APP Application being installed. [default: firefox] 21:53:01 INFO - mkdir: /builds/slave/test/build/application 21:53:01 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'] 21:53:01 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 21:53:34 INFO - Reading from file tmpfile_stdout 21:53:34 INFO - Output received: 21:53:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:53:34 INFO - Running post-action listener: _resource_record_post_action 21:53:34 INFO - ##### 21:53:34 INFO - ##### Finished install step (success) 21:53:34 INFO - ##### 21:53:34 INFO - ##### 21:53:34 INFO - ##### Running run-tests step. 21:53:34 INFO - ##### 21:53:34 INFO - Running pre-action listener: _resource_record_pre_action 21:53:34 INFO - Running main action method: run_tests 21:53:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:53:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:53:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:53:34 INFO - 2016-05-10 21:53:34.754 screenresolution[1659:7728] starting screenresolution argv=screenresolution get 21:53:34 INFO - 2016-05-10 21:53:34.757 screenresolution[1659:7728] Display 0: 1600x1200x32@60 21:53:34 INFO - 2016-05-10 21:53:34.762 screenresolution[1660:7730] starting screenresolution argv=screenresolution list 21:53:34 INFO - Available Modes on Display 0 21:53:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:53:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:53:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:53:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:53:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:53:34 INFO - Intel Iris: 21:53:34 INFO - Chipset Model: Intel Iris 21:53:34 INFO - Type: GPU 21:53:34 INFO - Bus: Built-In 21:53:34 INFO - VRAM (Dynamic, Max): 1536 MB 21:53:34 INFO - Vendor: Intel (0x8086) 21:53:34 INFO - Device ID: 0x0a2e 21:53:34 INFO - Revision ID: 0x0009 21:53:34 INFO - Displays: 21:53:34 INFO - Display: 21:53:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:53:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:53:34 INFO - Display Serial Number: 1600x1200 60 21:53:34 INFO - Main Display: Yes 21:53:34 INFO - Mirror: Off 21:53:34 INFO - Online: Yes 21:53:34 INFO - Rotation: Supported 21:53:34 INFO - Return code: 0 21:53:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:53:35 INFO - Python 2.7.3 21:53:35 INFO - Return code: 0 21:53:35 INFO - grabbing minidump binary from tooltool 21:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:53:35 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c1d30>, '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 0x1071e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:53:35 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 21:53:35 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 21:53:35 INFO - Calling ['/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'] with output_timeout 600 21:53:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:53:35 INFO - Return code: 0 21:53:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:53:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:53:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:53:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:53:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:53:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:53:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:53:36 INFO - Return code: 0 21:53:36 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/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 21:53:36 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/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0180 --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 21:53:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPD88rQXfH/Render', 21:53:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:53:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:53:36 INFO - 'HOME': '/Users/cltbld', 21:53:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:53:36 INFO - 'LOGNAME': 'cltbld', 21:53:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:53:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:53:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:53:36 INFO - 'MOZ_NO_REMOTE': '1', 21:53:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:53:36 INFO - 'NO_EM_RESTART': '1', 21:53:36 INFO - 'PAGER': '/bin/cat', 21:53:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:53:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:53:36 INFO - 'PWD': '/builds/slave/test', 21:53:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:53:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:53:36 INFO - 'SHELL': '/bin/bash', 21:53:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXUBu1b7rO/Listeners', 21:53:36 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 21:53:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:53:36 INFO - 'USER': 'cltbld', 21:53:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:53:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:53:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:53:36 INFO - 'XPC_FLAGS': '0x0', 21:53:36 INFO - 'XPC_SERVICE_NAME': '0', 21:53:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:53:36 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/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 21:53:36 INFO - mozversion application_buildid: 20160510210207 21:53:36 INFO - mozversion application_changeset: c8241f3e1f532ff2cb38d24ec638ac111f3d9e2c 21:53:36 INFO - mozversion application_display_name: Nightly 21:53:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:53:36 INFO - mozversion application_name: Firefox 21:53:36 INFO - mozversion application_remotingname: firefox 21:53:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:53:36 INFO - mozversion application_vendor: Mozilla 21:53:36 INFO - mozversion application_version: 49.0a1 21:53:36 INFO - mozversion platform_buildid: 20160510210207 21:53:36 INFO - mozversion platform_changeset: c8241f3e1f532ff2cb38d24ec638ac111f3d9e2c 21:53:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:53:36 INFO - mozversion platform_version: 49.0a1 21:53:36 INFO - using testdate: 1462942416 21:53:36 INFO - actual date: 1462942416 21:53:36 INFO - starting webserver on 'localhost:49277' 21:53:36 INFO - SUITE-START | Running 2 tests 21:53:36 INFO - TEST-START | dromaeo_css 21:53:36 INFO - operating with platform_type : mac_ 21:53:36 INFO - Initialising browser for dromaeo_css test... 21:53:36 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpX0lzvM/profile http://localhost:49277/getInfo.html) 21:53:38 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 21:53:38 INFO - PROCESS | 1678 | colorDepth:24 21:53:38 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 21:53:38 INFO - PROCESS | 1678 | __metrics 21:53:39 INFO - PROCESS | 1678 | 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" 21:53:39 ERROR - PROCESS | 1678 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:53:39 INFO - TEST-INFO | 1678: exit 0 21:53:39 INFO - Browser initialized. 21:53:39 INFO - Running cycle 1/1 for dromaeo_css test... 21:53:39 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpX0lzvM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:53:51 INFO - PROCESS | 1680 | RSS: Main: 238608384 21:53:51 INFO - PROCESS | 1680 | 21:54:42 INFO - PROCESS | 1680 | 1462942482720 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] 21:56:54 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 21:56:55 INFO - PROCESS | 1680 | RSS: Main: 371576832 21:56:55 INFO - PROCESS | 1680 | 21:59:59 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 21:59:59 INFO - PROCESS | 1680 | RSS: Main: 375640064 21:59:59 INFO - PROCESS | 1680 | 22:03:02 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 22:03:02 INFO - PROCESS | 1680 | RSS: Main: 371339264 22:03:02 INFO - PROCESS | 1680 | 22:06:05 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 22:06:05 INFO - PROCESS | 1680 | RSS: Main: 370700288 22:06:05 INFO - PROCESS | 1680 | 22:09:08 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 22:09:09 INFO - PROCESS | 1680 | RSS: Main: 334274560 22:09:09 INFO - PROCESS | 1680 | 22:12:12 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 22:12:12 INFO - PROCESS | 1680 | RSS: Main: 417783808 22:12:12 INFO - PROCESS | 1680 | 22:12:12 INFO - PROCESS | 1680 | __start_tp_report 22:12:12 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 22:12:12 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 22:12:12 INFO - PROCESS | 1680 | |i|pagename|runs| 22:12:12 INFO - PROCESS | 1680 | |0;dojo.html;3401.9149521261966;3436.8453419596117;3448.982755086224;3455.170759017835;3502.282032183403;5578.107502799553;5607.691576963265;5628.183913332569;5702.315722113347;5713.085906255;5652.971735141329;5701.315842098944;5706.714664266791;5712.971435142818;5718.056520674094;4726.794667359835;5118.4113826909825;5145.2796608474655;5165.22521621756;5245.396779370364;4832.226843705008;5256.448072952342;5262.342207224097;5264.184051472028;5265.526102650761;5100.336956195708;5126.205438157073;5130.179171332587;5153.0466863630345;5254.474552544734;5215.139501982168;5236.162214045772;5236.738163091868;5250.081235783722;5292.338457692788;5042.0168067226905;5062.569681577071;5070.935103628242;5178.585713142939;5180.1452760294505;5459.617826752129;5511.448855114457;5527.281453411031;5547.639403438763;5605.878824235169;6203.379662033805;6208.286047104596;6212.875742485125;6218.937810621881;6231.9065214021375;5285;6165.84585385369;6181.938180618181;6210.378962103753;6249.968755155394;5753.165790960287;5783.739731712083;5800.651960882361;5808.07070868659;5817.883642327172;6183.598066125687;6316.778912738124;6353.047042943595;6358.427741503287;6364.268109167412;5997;6119.082137679295;6137.285055221596;6144.986077297285;6146.692665366714;5469.644473109235;6319.905201421982;6334.873302533923;6337.493000560036;6339.175907132043;6054.788082417094;6282.905756413657;6283.874322513524;6284.12022316876;6291.779787707408;6021.42796434338;6132.447291485866;6136.028952951454;6178.320384757673;6192.721327540272;4864.075825593126;4887.877803054881;4908.239222920453;4916.385451818523;4968.080905032581;4407.0524837160165;4426.867193984185;4427.092446048552;4427.601515863587;4466.486354069259;5628.2683251177905;5666.688332141689;5690.573207009491;5718.7426565804635;5735.885282294331;6173.9073913891325;6195.752169913153;6252.656103914328;6261.592996455216;6325.715342809584;5737.254156959569;5746.913796293059;5748.942510574924;5758.049921762946;5799.101139323411;5347.3583170019065;5360.839174824761;5365.651232669864;5370.087085195526;5375.623706340519;6168.629882207082;6249.406306400884;6265.122882796321;6285.717142728679;6364.109024736448;6304.464120549805;6306.495480361468;6315.115883776183;6319.46284565817;6320.873582528232;6282.874342513032;6291.96854015727;6334.714937827716;6350.460210881942;6356.7775127872155;6130.938690613037;6139.001538246492;6191.288001879661;6223.097650840512;6229.813105606839;6283.685815709105;6284.24589049317;6294.276158241678;6297.1813664223355;6311.27420346666;5956.374580668968;6033.698315084316;6107.389261073857;6154.415330543591;6157.53818463626;6065.393460654076;6135.32453530862;6140.228185505611;6143.174139895698;6145.139680444652;5899.646021238739;5966.313873904556;5980.970095149496;6004.39956004396;6021.698915054318;5882.588218824641;5943.257092863392;5999.460048595647;6030.969845150747;6157.138000679819;5761.510271626791;5821.27234095738;5868.295804503487;5894.20428242199;5918.674472904032 22:12:12 INFO - PROCESS | 1680 | |1;ext.html;502.8642266588019;523.7538356972226;534.524273396677;535.6464733276038;538.5933620116813;26610.467790644176;42668.71993840186;43822.561774382244;43848.780756096254;43963;44914;45329;45338.7733061335;45341;45567.86080347993;8606.268467180296;9622.326437149384;9845.606175752997;9848.113669769718;9849.113579777819;8729.825403491926;11754.11844111695;11960.461779219913;11971.940140299286;12016.279023258632;11744.530218791238;11823.645290641294;11827.822582661267;11836.644900652995;11847.526098956032;8707.738767836974;11523.539058437653;11723.120765942589;11751.294922304689;11783.528658853636;11695.649130526013;11767.58813441534;11769.941150294193;11774.175807693466;11794.528218871235;40808.59191408107;41129.79435102805;41182.58817411847;41200.587994119975;41366.1726765466;3242.363979905091;3412.3516531859113;3413.300273443963;3453.5165076889257;3509.736769742254;28882.855585722147;29353.85323073371;29460.55809162864;29496;29573.112806615834;2759.572266298727;2761.226856480189;2767.8200916940536;2779.958300625492;2875.3674191677796;486113;486974.99999999645;487005;487184;487751;63992.36007639957;65025.67487162534;65025.67487162629;65030;65047.69904601976;36962.630373696455;37670.62329376671;37751.2449751009;39050.02374940649;39084.609153908095;16964.74552881708;17046.914765426092;17158.31366745341;17181.656366872594;17190.1404929753;11970.88029119715;12167.513299468099;12904.29026403538;13010.674733131747;13159.013074019296;19931.402057938285;19935.508061492314;19939.102740376427;20046.49883752918;20065.39803805888;6974.825629359307;8190.303824174893;8235.38234632393;8290.668373265122;8300;935.9204467620192;977.4965892565334;996.8056229035413;1090.4220762995626;1098.4946924414858;29766.939620264606;29809.701902981127;30394.54408183879;30642.233944151576;30657.233569160504;1010.7068950004416;1166.4517676691942;1191.1602320364123;1206.943924066442;1235.3576140406938;13649;15406.152661603495;15544.455944041903;15848.918152430566;15915.840841591667;190759.09240907413;191423;191676;191947;193263;60764.78470430567;61181.388186118456;61183.08225376623;61317;61512.38487615067;1331.340986211812;1445.5446534341438;1476.5865557643876;1498.8276348219877;1499.5651261134149;24056.15803446871;29299.56050659199;30368.696313036588;31136.844315778275;31512.842435787676;51218.74390628023;51313;51770;51929.48070519398;51930.22104668509;451671;455187;456484;456590;457089;22490;23761.881190594628;25486.872565637794;27164;27273.86363068172;45800.312995305765;46056.76971615254;46559.76720116513;47065.82335441641;47215.29177062278;36527;37357.813210933775;37858;38207.617923820406;38638.499286534694;22640.094396223965;29826.956056537918;30035.699643004165;30090.398192036475;30241.243968900075;37118.257634847694;37798;38301.42547861728;38744.031399215244;38790.4181437273;28334.58327083679;29028.27429314284;30924.53613195759;32159.196020099684;33799.4930076054;20597.79402206019;22112;24433.877830610734;24447.75552244455;25397.619035714848 22:12:12 INFO - PROCESS | 1680 | |2;jquery.html;330.6561176376568;390.2176136845625;401.76697515441026;416.14482238998846;417.54070522425314;20225.393238202858;22417.55164896701;22754.31737047889;22781.772182278193;22876.428089297777;20803.791962080373;20909.686354704692;22236.55526889461;22268.554628907412;22300.553988920252;5037.49625037496;5445.945540544603;5452.645578037414;5496.230527726121;5496.5053145216925;3901.317269477844;4148.751074935499;5394.379646340682;5806.187133801272;6138.447539721425;6137.018077107664;6138.14066030756;6147.784827531037;6150.323464418911;6153.138560601519;5913.704314784243;5988.9101663475085;6106.328303886569;6107.29766076898;6110.419510146551;3768.397056470979;5465.535429488498;5814.883702325929;5815.098659707752;5855.238818953509;13351.866481335257;13592.184468931797;13599.456021759117;21915.67126493104;26447.735522644718;2081.188336548747;2174.826013918883;2249.876256805875;2622.265765585639;2748.69764325924;9430.245580353623;10072.848907266396;10114.089731924088;10426.68719938403;10490.527926243338;1752.386664667369;1765.9028753418556;1775.343123044469;1793.973090403645;1823.7082066869236;114600;126408.36795815962;127470.36264818709;130225.34887325598;131071.34464327713;11563.30620162793;11823.467943942544;11827.290362578273;11844.407779610985;12009.519619215134;15441.768373474408;15508.5347439577;15533.533993980198;15540.300686469136;15572.688546229012;8818;8884.955575222082;8913.55432228399;8941.46351218929;9059.59231834569;8823.073577274423;8826.514541700268;8828.735137945872;8941.150590694004;8952.418092823946;4692.929606055912;4701.623870090384;4717.24524076153;4739.691920025188;4757.833475828329;1580.683863227359;1793.9103044847966;1841.9830285836988;1942.2910637617167;1950.4441234248454;868.9348298877483;891.1934699097327;916.3448134583805;929.7489677787095;952.4333021851987;19575.840208596695;20351.287704930255;20418.876961767244;20521.48696282575;20613.484662883548;913.1827014821697;934.6868798952291;945.7824700318828;950.7242899559188;984.0356450678375;10564.36613803159;10574.41840698769;10776.568937242579;10801.567937282578;10855.728606784893;602545.9872700608;610521;624463;625554;627034;126300.31648575836;127993;128122;128490;132322;955.2835373469898;1023.9334443261164;1026.035526605004;1029.3170665961059;1073.9194560407843;17332.39336623212;17758.644827103126;17816;18181.363652272106;18484.537886552946;49920;50109.74945125251;51983.44049678516;52347.95304093973;53642.92714145773;72420.63789681018;73454.16364590928;73760.25517320819;77137.97206363137;77202.61398693196;5509.449055094458;8076.394270429624;8108.189181081844;8187.426880118334;8571.442856214326;10863.402512861869;11446.542138314375;12237.75524489523;12365;12549.435275412447;8659.350548708746;9811.950940245253;10332.431716255678;10759.677209683721;10760.031597156294;10247.436390998566;10258.28192026551;10542.736431589272;10565.313254638424;10675.57297708083;10821.350718956888;10827.512761925575;11009.834852477059;11183.105351572018;11190.216684832309;7918.287354138155;8013.639386227517;8057.43597948138;8091.554964476775;8378.413511054168;8276.255137037695;10452.163826893679;10521.684349469528;10882.891171088504;11064.17018723615 22:12:12 INFO - PROCESS | 1680 | |3;mootools.html;1441.9927900360497;1633.9264733087014;1637.6560922206336;1892.848572114231;2015.5565775529378;8098.797530061752;8791.604377802998;9011.819763604732;9012.68455604054;9171.037041110687;8765.342599305062;9004.72985810425;9008.144226298507;9015.323850711191;9030.68392606259;2174.358564223558;2253;2254.661800729887;2465.7410971847967;2501.9124330648433;2084.843636727244;2259.53679495703;2288.3135059482174;2452.730199678034;2488.340589743719;2048.467398476392;2449.7795198432136;2457.275103844361;2460.5447992121517;2464.125235541384;2391.5097405032016;2443.2792326263793;2443.853368797878;2445.9143929962365;2447.6206188040705;2112.503561663008;2175.3582693105413;2380.3216083416314;2460.4095017195923;2460.90156393744;8698.608562614634;8836.248918841906;8836.293096552261;8840.646374145026;8893.644254229823;5351.545118664918;5499.312585926759;5521.696306703129;5536.723163841792;5546.77812887484;3563.1092226943265;3654.671079602822;3729.3287208302495;3735.8505659773577;3741.6258374162367;1021.4484178543578;1035.1666908138945;1062.8724553053682;1080.2978064258216;1083.5015892689373;12159.392030398445;12177.756444871053;12178.634640960785;12187.268763874195;12205.267683938991;4093.3041382964966;4878.780454879539;4899.240617704261;5048.924266136011;5056.165732654144;10295.794084118275;10310.32982856127;10314.484275786332;10319.226058045677;10339.483025848678;5436.347638283352;5525.143602741581;5562.276904002535;5564.777408903679;5566.192902029183;4471.485779135376;4930.827421040318;4965.155923492943;5017.54842064216;5090.0583392072585;3679.6201424465826;3826.311263972512;3852.3065848147603;4012.3179059559943;4160.063985603276;1321.3987635625767;1339.216558313378;1345.838499380061;1347.7574036673298;1363.454618152747;839.4879123734516;914.8353296406577;936.990630093704;938.5166639180691;981.7054883534911;5953.136795164677;6037.9433599119975;6067;6186.752529898754;6230.096635987757;888.3071204460531;913.1051569461964;939.4926739560698;986.837171866634;1002.9498525073863;3840;4046.2716710992304;4063.6139566741113;4107.075907920754;4121.196366708463;10524.158067354594;10942.890571094187;10951;10961.397123158304;11088.889111108785;10808.243422960475;10953.726156845983;11048.226624136234;11072.778544429066;11079.944600276947;8157.55133467665;8298.792530186673;8444.24001839825;8445.704400345958;8525.957370213233;5167.6641018333685;5989.550783691154;6031.4270144336215;6086;6128.479079278133;10064.496775161359;10153.949230253802;10206.162890679749;10328.741781455523;10341;8506.659733610833;9731.756706082404;10407.739806504898;10473.109785668303;10548.578056877639;6972.4073453757;7202.171750248579;7653.043369578802;7728;7729.07251129868;8250.834983300181;8537.31701463869;8566.229039386486;8662.090480499457;8679.609417576097;7438.776836694908;7561.357284630648;7595.810104747426;7719.884201737091;7743.806404839924;7292.635368231461;7466.141393739789;7495.662695178839;7572.962135189288;7616.162222155669;7005.474589405713;7034.929650703428;7084.822879427849;7087.149542054986;7091.255418181223;4830.396200474941;4889.3888263967;5032.7483625819295;5047.646664733434;5228.163493840967;4983.925241121458;5050.924236136388;5113.616478764182;5154.664946778447;5343.278657381204 22:12:12 INFO - PROCESS | 1680 | |4;prototype.html;287.65337767989575;305.8088694565898;310.13472411970605;321.47599412956885;323.13561223726526;4256.851010214643;4562.70342427742;5290.941811637668;5326.2809520714645;5378.811741589046;5008.974955125219;5169.379674439073;5178.404483484401;5225.6603320784125;5292.2061690746405;3374.8143852088124;3443.552338196031;3559.686203967429;3958.2281455116263;4038.3538633818594;3363.8731025106617;3402.625711171769;3973.893836266783;4013.6588389986887;4056.492938382702;3760.8529398533574;3975.5472293433363;4044.54396417291;4058.2289365020697;4083.693722970774;3225.016370007147;4038.3538633818744;4047.231026105045;4076.4700588923547;4085.3667681509414;3512.4731290306404;3522.8590856365713;3533.1873669056004;3543.0787995121195;3545.468179773029;5013.699178049329;5026.170681837492;5043.899122017576;5077.187649976023;5135.947130838169;4404.405405270311;4616.530156366328;4643.698159619614;4648.697834640738;4661.324108004321;4532.365468834366;5318.224060916754;5497.471163264903;5499.917501237484;5539.639923405006;3338.749593780452;3929.0766669832565;3951.38753493209;3955.9121241658772;3959.6436320731273;4484.730916145042;5674.836658485001;5828.708564571797;5857.76568937242;5894.964093491855;3963.6432721054666;3974.2647610192207;4024.678025757932;4027.154297597479;4041.9797901010306;3821.6051141333214;4031.213913286881;4061.1268577256033;4072.144849581621;4095.795210239476;3963.326234540135;4130.905310092828;4257.531671516131;4487.461504619467;4659.437309479773;5198.298229738937;5623.971880140573;5651.2935883014625;5656.462636049569;5707.543396528268;1282.7049778550986;1296.3194322980323;1339.3571085878832;1360.1091285208204;1364.747521708487;737.8524295140886;766.1954947305308;805.5166899860154;902.4946030223097;906.3746015249457;5198.662086964335;5202.037623039674;5213.113770658997;5217.295665085165;5222.947770522323;4545.0000999779995;4792.083166733334;5142.254373115952;5678.829635110953;5689.260396148474;4262.637675797592;4661.440627124767;4694.530546945278;5028.446870844205;5255.684658920477;2529;2737.1241202815304;2925.648922129358;2993.8802447902276;3020.9244768880953;5296.2055691646565;5547.143221241064;5937.910931336033;6011.09833524966;6023.969880150659;4906.337644418029;5248.606354523426;5407.4048155794135;5775.036487594154;5881.323647780475;4838.95161048392;5490.478404551482;5790.015697331548;5858.297004359504;5920.615160014585;4659.588188096651;5215.087359712111;5420.728963551728;5513.61404701667;5716.713739408599;4800.7359595221205;5274.235235890851;5586.245856809279;5622.32532096151;5656.943430565806;5516.779328826802;5651.773929042956;5895.351511333664;5932.2584676915385;5939.613925094855;3578;3775.282246577746;3891.5524714657045;3906.3945088511327;3924.3524818404644;5141.126008578625;5731.598788084794;5748.540116790563;5786.42135786418;5803.332616749128;5264.578833693372;5401.300109425057;5642.463965923395;5661.150827375926;5686.545076393962;5253.711045892513;5568.638038527482;5570.5265052470995;5575.776968921197;5575.916361254665;5050.853387310452;5187.3285258092865;5337.745629777077;5349.652272602269;5459.153831156177;4998.600111991104;5077.31456253401;5341.62608617393;5592.468715472024;5663.773449061991;4625.907481850411;5413.025655382069;5467.125259958388;5497.560195184456;5511.944880551143 22:12:12 INFO - PROCESS | 1680 | |5;yui.html;646.4149944300408;662.7614058938781;724.9383802376798;725.5755383100885;749.1310080306841;14861;14910.403583856632;15367.077975321488;15399.538013859574;15404.61488462789;11681.59114430995;11736.882631173687;14198.432062717478;14213.360398782053;14263.572092837232;730.6565914020401;755.2787088330631;906.9365144439892;911.9863202051974;932.8554074118523;928.3965422475411;934.7663084228943;935.1490143968956;936.6487567162314;937.5687183895416;991.4646091110792;992.5979978108842;994.3735446668588;1000.4297550396277;1002.3985608634833;757.613617055303;942.2838642631581;974.4348277998744;978.9755256118583;990.2770977186658;724.1165777751136;725.4631572636264;725.7786375155575;726.5531698005741;726.5713229194747;14041.22773247471;14095.78856317156;14121.858781412157;14123.576292711236;14162.221077840715;9837.458939758311;10547.578096876116;12255.080868934865;12301.261924284572;12565.748685026248;10335.328203666737;10634.852244194555;10684.145268378512;10928.672139835739;11336;4079.9184016319805;4120.17596480705;4126.216018956418;4128.422020917074;4188.183304255671;55752.72123639396;72822.63588682022;73421.26578734198;73817;73842.63078684626;7850.450468467267;9288.349815512984;9300.441973481613;9407.059294070537;9531.332806703604;20813.89593052025;20830.791692082887;20832.1667133313;20836.47908802292;20862.374128776162;7282.4902256841515;7326.3406293433845;7436.330730234305;7461.552306861606;7718.845623087619;3197.392495425862;3980.402939559089;3985.9003524911527;3992.6207010333974;4011.659008984269;3431.5367425397753;3536.6993805526304;3541.5395998520544;3542.326957877995;3542.6988705959766;489.59363728106297;528.545224182601;609.3266940031202;632.4876849751715;655.6623338980429;527.7229454536323;574.7988204128521;575.4906907386909;592.8517870532366;610.664134725899;8038.316743076904;8121.75634730959;8129.309008734205;8243.711470098518;8328.33373330132;537.9408265090836;583.8964357364584;585.077058323937;592.6740292838921;598.619876378494;3371.7976921384798;3884.320243957296;3917;4011.197760447922;4114.218298523271;48479;79172;82876.95008380579;83552.16447835443;83979.16020839714;70886.9366959496;72389;72455.46405875769;73937;73979;67339.31651708648;67571;67651.66174169097;67715.645687085;67724;7567.470277080552;7655.88516172269;7850.332721718718;7852.254035866584;7984.401169912164;33933;40680.79659601683;41804.372934406616;42023;42319;40285.992850178984;56685.71657141688;57362.42637573571;61471.77056458756;61833.69083154555;10945.398003109587;11079.667609971713;11193.384363860067;11393.373364465035;11548;11836.408179591159;11907.642770716546;11999.100067494799;12042.819357709817;12901.225926444444;11462.94268528652;11842.76314473723;12193.451294691937;12524.937375313066;12547.184433011826;11439.59961401347;11550.249233799776;11576.710582235512;11798.882011180121;11816.409179540817;11313.886861131612;11343.376114313793;11441.370724610226;11592.536298547964;11774.587889423829;9840.163586094975;9853.605855765689;9859.35914165577;9985.700428987142;10020.24848136378;8233.629486673233;8368.24685778283;8939.776505587412;9602.951985240308;9637.71086867395 22:12:12 INFO - PROCESS | 1680 | __end_tp_report 22:12:12 INFO - PROCESS | 1680 | __start_cc_report 22:12:12 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1402 22:12:12 INFO - PROCESS | 1680 | __end_cc_report 22:12:12 INFO - PROCESS | 1680 | __startTimestamp1462943532919__endTimestamp 22:12:12 INFO - PROCESS | 1680 | 22:12:12 INFO - PROCESS | 1680 | ------- Summary: start ------- 22:12:12 INFO - PROCESS | 1680 | Number of tests: 6 22:12:12 INFO - PROCESS | 1680 | 22:12:12 INFO - PROCESS | 1680 | [#0] dojo.html Cycles:165 Average:5723.58 Median:5899.65 stddev:639.83 (10.8%) stddev-sans-first:615.32 22:12:12 INFO - PROCESS | 1680 | Values: 3401.9 3436.8 3449.0 3455.2 3502.3 5578.1 5607.7 5628.2 5702.3 5713.1 5653.0 5701.3 5706.7 5713.0 5718.1 4726.8 5118.4 5145.3 5165.2 5245.4 4832.2 5256.4 5262.3 5264.2 5265.5 5100.3 5126.2 5130.2 5153.0 5254.5 5215.1 5236.2 5236.7 5250.1 5292.3 5042.0 5062.6 5070.9 5178.6 5180.1 5459.6 5511.4 5527.3 5547.6 5605.9 6203.4 6208.3 6212.9 6218.9 6231.9 5285.0 6165.8 6181.9 6210.4 6250.0 5753.2 5783.7 5800.7 5808.1 5817.9 6183.6 6316.8 6353.0 6358.4 6364.3 5997.0 6119.1 6137.3 6145.0 6146.7 5469.6 6319.9 6334.9 6337.5 6339.2 6054.8 6282.9 6283.9 6284.1 6291.8 6021.4 6132.4 6136.0 6178.3 6192.7 4864.1 4887.9 4908.2 4916.4 4968.1 4407.1 4426.9 4427.1 4427.6 4466.5 5628.3 5666.7 5690.6 5718.7 5735.9 6173.9 6195.8 6252.7 6261.6 6325.7 5737.3 5746.9 5748.9 5758.0 5799.1 5347.4 5360.8 5365.7 5370.1 5375.6 6168.6 6249.4 6265.1 6285.7 6364.1 6304.5 6306.5 6315.1 6319.5 6320.9 6282.9 6292.0 6334.7 6350.5 6356.8 6130.9 6139.0 6191.3 6223.1 6229.8 6283.7 6284.2 6294.3 6297.2 6311.3 5956.4 6033.7 6107.4 6154.4 6157.5 6065.4 6135.3 6140.2 6143.2 6145.1 5899.6 5966.3 5981.0 6004.4 6021.7 5882.6 5943.3 5999.5 6031.0 6157.1 5761.5 5821.3 5868.3 5894.2 5918.7 22:12:12 INFO - PROCESS | 1680 | 22:12:12 INFO - PROCESS | 1680 | [#1] ext.html Cycles:180 Average:53744.67 Median:30790.88 stddev:106664.20 (346.4%) stddev-sans-first:106888.51 22:12:12 INFO - PROCESS | 1680 | Values: 502.9 523.8 534.5 535.6 538.6 26610.5 42668.7 43822.6 43848.8 43963.0 44914.0 45329.0 45338.8 45341.0 45567.9 8606.3 9622.3 9845.6 9848.1 9849.1 8729.8 11754.1 11960.5 11971.9 12016.3 11744.5 11823.6 11827.8 11836.6 11847.5 8707.7 11523.5 11723.1 11751.3 11783.5 11695.6 11767.6 11769.9 11774.2 11794.5 40808.6 41129.8 41182.6 41200.6 41366.2 3242.4 3412.4 3413.3 3453.5 3509.7 28882.9 29353.9 29460.6 29496.0 29573.1 2759.6 2761.2 2767.8 2780.0 2875.4 486113.0 486975.0 487005.0 487184.0 487751.0 63992.4 65025.7 65025.7 65030.0 65047.7 36962.6 37670.6 37751.2 39050.0 39084.6 16964.7 17046.9 17158.3 17181.7 17190.1 11970.9 12167.5 12904.3 13010.7 13159.0 19931.4 19935.5 19939.1 20046.5 20065.4 6974.8 8190.3 8235.4 8290.7 8300.0 935.9 977.5 996.8 1090.4 1098.5 29766.9 29809.7 30394.5 30642.2 30657.2 1010.7 1166.5 1191.2 1206.9 1235.4 13649.0 15406.2 15544.5 15848.9 15915.8 190759.1 191423.0 191676.0 191947.0 193263.0 60764.8 61181.4 61183.1 61317.0 61512.4 1331.3 1445.5 1476.6 1498.8 1499.6 24056.2 29299.6 30368.7 31136.8 31512.8 51218.7 51313.0 51770.0 51929.5 51930.2 451671.0 455187.0 456484.0 456590.0 457089.0 22490.0 23761.9 25486.9 27164.0 27273.9 45800.3 46056.8 46559.8 47065.8 47215.3 36527.0 37357.8 37858.0 38207.6 38638.5 22640.1 29827.0 30035.7 30090.4 30241.2 37118.3 37798.0 38301.4 38744.0 38790.4 28334.6 29028.3 30924.5 32159.2 33799.5 20597.8 22112.0 24433.9 24447.8 25397.6 22:12:12 INFO - PROCESS | 1680 | 22:12:12 INFO - PROCESS | 1680 | [#2] jquery.html Cycles:180 Average:35452.57 Median:22829.10 stddev:103192.05 (452.0%) stddev-sans-first:103447.83 22:12:12 INFO - PROCESS | 1680 | Values: 330.7 390.2 401.8 416.1 417.5 20225.4 22417.6 22754.3 22781.8 22876.4 20803.8 20909.7 22236.6 22268.6 22300.6 5037.5 5445.9 5452.6 5496.2 5496.5 3901.3 4148.8 5394.4 5806.2 6138.4 6137.0 6138.1 6147.8 6150.3 6153.1 5913.7 5988.9 6106.3 6107.3 6110.4 3768.4 5465.5 5814.9 5815.1 5855.2 13351.9 13592.2 13599.5 21915.7 26447.7 2081.2 2174.8 2249.9 2622.3 2748.7 9430.2 10072.8 10114.1 10426.7 10490.5 1752.4 1765.9 1775.3 1794.0 1823.7 114600.0 126408.4 127470.4 130225.3 131071.3 11563.3 11823.5 11827.3 11844.4 12009.5 15441.8 15508.5 15533.5 15540.3 15572.7 8818.0 8885.0 8913.6 8941.5 9059.6 8823.1 8826.5 8828.7 8941.2 8952.4 4692.9 4701.6 4717.2 4739.7 4757.8 1580.7 1793.9 1842.0 1942.3 1950.4 868.9 891.2 916.3 929.7 952.4 19575.8 20351.3 20418.9 20521.5 20613.5 913.2 934.7 945.8 950.7 984.0 10564.4 10574.4 10776.6 10801.6 10855.7 602546.0 610521.0 624463.0 625554.0 627034.0 126300.3 127993.0 128122.0 128490.0 132322.0 955.3 1023.9 1026.0 1029.3 1073.9 17332.4 17758.6 17816.0 18181.4 18484.5 49920.0 50109.7 51983.4 52348.0 53642.9 72420.6 73454.2 73760.3 77138.0 77202.6 5509.4 8076.4 8108.2 8187.4 8571.4 10863.4 11446.5 12237.8 12365.0 12549.4 8659.4 9812.0 10332.4 10759.7 10760.0 10247.4 10258.3 10542.7 10565.3 10675.6 10821.4 10827.5 11009.8 11183.1 11190.2 7918.3 8013.6 8057.4 8091.6 8378.4 8276.3 10452.2 10521.7 10882.9 11064.2 22:12:12 INFO - PROCESS | 1680 | 22:12:12 INFO - PROCESS | 1680 | [#3] mootools.html Cycles:180 Average:5830.20 Median:4947.99 stddev:3279.32 (66.3%) stddev-sans-first:3271.94 22:12:12 INFO - PROCESS | 1680 | Values: 1442.0 1633.9 1637.7 1892.8 2015.6 8098.8 8791.6 9011.8 9012.7 9171.0 8765.3 9004.7 9008.1 9015.3 9030.7 2174.4 2253.0 2254.7 2465.7 2501.9 2084.8 2259.5 2288.3 2452.7 2488.3 2048.5 2449.8 2457.3 2460.5 2464.1 2391.5 2443.3 2443.9 2445.9 2447.6 2112.5 2175.4 2380.3 2460.4 2460.9 8698.6 8836.2 8836.3 8840.6 8893.6 5351.5 5499.3 5521.7 5536.7 5546.8 3563.1 3654.7 3729.3 3735.9 3741.6 1021.4 1035.2 1062.9 1080.3 1083.5 12159.4 12177.8 12178.6 12187.3 12205.3 4093.3 4878.8 4899.2 5048.9 5056.2 10295.8 10310.3 10314.5 10319.2 10339.5 5436.3 5525.1 5562.3 5564.8 5566.2 4471.5 4930.8 4965.2 5017.5 5090.1 3679.6 3826.3 3852.3 4012.3 4160.1 1321.4 1339.2 1345.8 1347.8 1363.5 839.5 914.8 937.0 938.5 981.7 5953.1 6037.9 6067.0 6186.8 6230.1 888.3 913.1 939.5 986.8 1002.9 3840.0 4046.3 4063.6 4107.1 4121.2 10524.2 10942.9 10951.0 10961.4 11088.9 10808.2 10953.7 11048.2 11072.8 11079.9 8157.6 8298.8 8444.2 8445.7 8526.0 5167.7 5989.6 6031.4 6086.0 6128.5 10064.5 10153.9 10206.2 10328.7 10341.0 8506.7 9731.8 10407.7 10473.1 10548.6 6972.4 7202.2 7653.0 7728.0 7729.1 8250.8 8537.3 8566.2 8662.1 8679.6 7438.8 7561.4 7595.8 7719.9 7743.8 7292.6 7466.1 7495.7 7573.0 7616.2 7005.5 7034.9 7084.8 7087.1 7091.3 4830.4 4889.4 5032.7 5047.6 5228.2 4983.9 5050.9 5113.6 5154.7 5343.3 22:12:12 INFO - PROCESS | 1680 | 22:12:12 INFO - PROCESS | 1680 | [#4] prototype.html Cycles:180 Average:4481.28 Median:5064.02 stddev:1373.28 (27.1%) stddev-sans-first:1340.58 22:12:12 INFO - PROCESS | 1680 | Values: 287.7 305.8 310.1 321.5 323.1 4256.9 4562.7 5290.9 5326.3 5378.8 5009.0 5169.4 5178.4 5225.7 5292.2 3374.8 3443.6 3559.7 3958.2 4038.4 3363.9 3402.6 3973.9 4013.7 4056.5 3760.9 3975.5 4044.5 4058.2 4083.7 3225.0 4038.4 4047.2 4076.5 4085.4 3512.5 3522.9 3533.2 3543.1 3545.5 5013.7 5026.2 5043.9 5077.2 5135.9 4404.4 4616.5 4643.7 4648.7 4661.3 4532.4 5318.2 5497.5 5499.9 5539.6 3338.7 3929.1 3951.4 3955.9 3959.6 4484.7 5674.8 5828.7 5857.8 5895.0 3963.6 3974.3 4024.7 4027.2 4042.0 3821.6 4031.2 4061.1 4072.1 4095.8 3963.3 4130.9 4257.5 4487.5 4659.4 5198.3 5624.0 5651.3 5656.5 5707.5 1282.7 1296.3 1339.4 1360.1 1364.7 737.9 766.2 805.5 902.5 906.4 5198.7 5202.0 5213.1 5217.3 5222.9 4545.0 4792.1 5142.3 5678.8 5689.3 4262.6 4661.4 4694.5 5028.4 5255.7 2529.0 2737.1 2925.6 2993.9 3020.9 5296.2 5547.1 5937.9 6011.1 6024.0 4906.3 5248.6 5407.4 5775.0 5881.3 4839.0 5490.5 5790.0 5858.3 5920.6 4659.6 5215.1 5420.7 5513.6 5716.7 4800.7 5274.2 5586.2 5622.3 5656.9 5516.8 5651.8 5895.4 5932.3 5939.6 3578.0 3775.3 3891.6 3906.4 3924.4 5141.1 5731.6 5748.5 5786.4 5803.3 5264.6 5401.3 5642.5 5661.2 5686.5 5253.7 5568.6 5570.5 5575.8 5575.9 5050.9 5187.3 5337.7 5349.7 5459.2 4998.6 5077.3 5341.6 5592.5 5663.8 4625.9 5413.0 5467.1 5497.6 5511.9 22:12:12 INFO - PROCESS | 1680 | 22:12:12 INFO - PROCESS | 1680 | [#5] yui.html Cycles:180 Average:16648.90 Median:28968.61 stddev:22411.92 (77.4%) stddev-sans-first:22442.58 22:12:12 INFO - PROCESS | 1680 | Values: 646.4 662.8 724.9 725.6 749.1 14861.0 14910.4 15367.1 15399.5 15404.6 11681.6 11736.9 14198.4 14213.4 14263.6 730.7 755.3 906.9 912.0 932.9 928.4 934.8 935.1 936.6 937.6 991.5 992.6 994.4 1000.4 1002.4 757.6 942.3 974.4 979.0 990.3 724.1 725.5 725.8 726.6 726.6 14041.2 14095.8 14121.9 14123.6 14162.2 9837.5 10547.6 12255.1 12301.3 12565.7 10335.3 10634.9 10684.1 10928.7 11336.0 4079.9 4120.2 4126.2 4128.4 4188.2 55752.7 72822.6 73421.3 73817.0 73842.6 7850.5 9288.3 9300.4 9407.1 9531.3 20813.9 20830.8 20832.2 20836.5 20862.4 7282.5 7326.3 7436.3 7461.6 7718.8 3197.4 3980.4 3985.9 3992.6 4011.7 3431.5 3536.7 3541.5 3542.3 3542.7 489.6 528.5 609.3 632.5 655.7 527.7 574.8 575.5 592.9 610.7 8038.3 8121.8 8129.3 8243.7 8328.3 537.9 583.9 585.1 592.7 598.6 3371.8 3884.3 3917.0 4011.2 4114.2 48479.0 79172.0 82877.0 83552.2 83979.2 70886.9 72389.0 72455.5 73937.0 73979.0 67339.3 67571.0 67651.7 67715.6 67724.0 7567.5 7655.9 7850.3 7852.3 7984.4 33933.0 40680.8 41804.4 42023.0 42319.0 40286.0 56685.7 57362.4 61471.8 61833.7 10945.4 11079.7 11193.4 11393.4 11548.0 11836.4 11907.6 11999.1 12042.8 12901.2 11462.9 11842.8 12193.5 12524.9 12547.2 11439.6 11550.2 11576.7 11798.9 11816.4 11313.9 11343.4 11441.4 11592.5 11774.6 9840.2 9853.6 9859.4 9985.7 10020.2 8233.6 8368.2 8939.8 9603.0 9637.7 22:12:12 INFO - PROCESS | 1680 | -------- Summary: end -------- 22:12:12 INFO - PROCESS | 1680 | 22:12:13 INFO - TEST-INFO | 1680: exit 0 22:12:13 INFO - TEST-OK | dromaeo_css | took 1117063ms 22:12:13 INFO - TEST-START | kraken 22:12:13 INFO - operating with platform_type : mac_ 22:12:13 INFO - Initialising browser for kraken test... 22:12:13 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpIQT7qT/profile http://localhost:49277/getInfo.html) 22:12:15 INFO - PROCESS | 1687 | __metrics Screen width/height:1600/1200 22:12:15 INFO - PROCESS | 1687 | colorDepth:24 22:12:15 INFO - PROCESS | 1687 | Browser inner width/height: 1024/689 22:12:15 INFO - PROCESS | 1687 | __metrics 22:12:15 INFO - PROCESS | 1687 | 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" 22:12:15 ERROR - PROCESS | 1687 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:12:16 INFO - TEST-INFO | 1687: exit 0 22:12:16 INFO - Browser initialized. 22:12:16 INFO - Running cycle 1/1 for kraken test... 22:12:16 INFO - TEST-INFO | started process 1689 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpIQT7qT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:12:28 INFO - PROCESS | 1689 | RSS: Main: 238493696 22:12:28 INFO - PROCESS | 1689 | 22:12:31 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 22:12:32 INFO - PROCESS | 1689 | RSS: Main: 286396416 22:12:32 INFO - PROCESS | 1689 | 22:12:35 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 22:12:35 INFO - PROCESS | 1689 | RSS: Main: 295780352 22:12:35 INFO - PROCESS | 1689 | 22:12:39 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 22:12:40 INFO - PROCESS | 1689 | RSS: Main: 388222976 22:12:40 INFO - PROCESS | 1689 | 22:12:42 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 22:12:42 INFO - PROCESS | 1689 | RSS: Main: 285396992 22:12:42 INFO - PROCESS | 1689 | 22:12:45 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:12:45 INFO - PROCESS | 1689 | RSS: Main: 288722944 22:12:45 INFO - PROCESS | 1689 | 22:12:48 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 22:12:49 INFO - PROCESS | 1689 | RSS: Main: 326381568 22:12:49 INFO - PROCESS | 1689 | 22:12:52 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 22:12:53 INFO - PROCESS | 1689 | RSS: Main: 329453568 22:12:53 INFO - PROCESS | 1689 | 22:12:55 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 22:12:56 INFO - PROCESS | 1689 | RSS: Main: 328073216 22:12:56 INFO - PROCESS | 1689 | 22:12:58 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:12:58 INFO - PROCESS | 1689 | RSS: Main: 316293120 22:12:58 INFO - PROCESS | 1689 | 22:13:00 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:13:01 INFO - PROCESS | 1689 | RSS: Main: 323698688 22:13:01 INFO - PROCESS | 1689 | 22:13:03 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:13:04 INFO - PROCESS | 1689 | RSS: Main: 325218304 22:13:04 INFO - PROCESS | 1689 | 22:13:07 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:13:08 INFO - PROCESS | 1689 | RSS: Main: 326479872 22:13:08 INFO - PROCESS | 1689 | 22:13:11 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:13:11 INFO - PROCESS | 1689 | RSS: Main: 323985408 22:13:11 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 22:13:13 INFO - PROCESS | 1689 | RSS: Main: 370663424 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | __start_tp_report 22:13:13 INFO - PROCESS | 1689 | _x_x_mozilla_page_load 22:13:13 INFO - PROCESS | 1689 | _x_x_mozilla_page_load_details 22:13:13 INFO - PROCESS | 1689 | |i|pagename|runs| 22:13:13 INFO - PROCESS | 1689 | |0;ai-astar;112;104;112;112;113;112;112;111;111;110 22:13:13 INFO - PROCESS | 1689 | |1;audio-beat-detection;155;117;132;208;148;172;129;132;203;135 22:13:13 INFO - PROCESS | 1689 | |2;audio-dft;185;186;181;183;184;184;233;259;197;185 22:13:13 INFO - PROCESS | 1689 | |3;audio-fft;78;73;74;164;82;64;58;58;110;57 22:13:13 INFO - PROCESS | 1689 | |4;audio-oscillator;93;101;185;115;93;77;101;80;79;101 22:13:13 INFO - PROCESS | 1689 | |5;imaging-gaussian-blur;88;83;87;84;86;84;87;84;86;82 22:13:13 INFO - PROCESS | 1689 | |6;imaging-darkroom;93;92;93;93;91;93;94;93;92;92 22:13:13 INFO - PROCESS | 1689 | |7;imaging-desaturate;83;84;84;83;84;83;83;83;83;83 22:13:13 INFO - PROCESS | 1689 | |8;json-parse-financial;59;59;61;60;59;60;59;118;57;57 22:13:13 INFO - PROCESS | 1689 | |9;json-stringify-tinderbox;65;65;80;74;71;68;66;68;67;67 22:13:13 INFO - PROCESS | 1689 | |10;stanford-crypto-aes;85;85;85;84;82;83;80;83;85;85 22:13:13 INFO - PROCESS | 1689 | |11;stanford-crypto-ccm;144;159;137;136;147;141;145;135;304;163 22:13:13 INFO - PROCESS | 1689 | |12;stanford-crypto-pbkdf2;166;167;169;171;167;166;167;166;162;166 22:13:13 INFO - PROCESS | 1689 | |13;stanford-crypto-sha256-iterative;68;67;66;67;67;65;65;65;66;66 22:13:13 INFO - PROCESS | 1689 | __end_tp_report 22:13:13 INFO - PROCESS | 1689 | __start_cc_report 22:13:13 INFO - PROCESS | 1689 | _x_x_mozilla_cycle_collect,2187 22:13:13 INFO - PROCESS | 1689 | __end_cc_report 22:13:13 INFO - PROCESS | 1689 | __startTimestamp1462943593742__endTimestamp 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | ------- Summary: start ------- 22:13:13 INFO - PROCESS | 1689 | Number of tests: 14 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:2.56 (2.3%) stddev-sans-first:2.68 22:13:13 INFO - PROCESS | 1689 | Values: 112.0 104.0 112.0 112.0 113.0 112.0 112.0 111.0 111.0 110.0 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#1] audio-beat-detection Cycles:10 Average:153.10 Median:151.50 stddev:31.61 (20.9%) stddev-sans-first:33.52 22:13:13 INFO - PROCESS | 1689 | Values: 155.0 117.0 132.0 208.0 148.0 172.0 129.0 132.0 203.0 135.0 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#2] audio-dft Cycles:10 Average:197.70 Median:185.50 stddev:26.53 (14.3%) stddev-sans-first:27.74 22:13:13 INFO - PROCESS | 1689 | Values: 185.0 186.0 181.0 183.0 184.0 184.0 233.0 259.0 197.0 185.0 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#3] audio-fft Cycles:10 Average:81.80 Median:68.50 stddev:32.95 (48.1%) stddev-sans-first:34.92 22:13:13 INFO - PROCESS | 1689 | Values: 78.0 73.0 74.0 164.0 82.0 64.0 58.0 58.0 110.0 57.0 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#4] audio-oscillator Cycles:10 Average:102.50 Median:78.00 stddev:31.37 (40.2%) stddev-sans-first:33.09 22:13:13 INFO - PROCESS | 1689 | Values: 93.0 101.0 185.0 115.0 93.0 77.0 101.0 80.0 79.0 101.0 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#5] imaging-gaussian-blur Cycles:10 Average:85.10 Median:86.00 stddev:1.97 (2.3%) stddev-sans-first:1.79 22:13:13 INFO - PROCESS | 1689 | Values: 88.0 83.0 87.0 84.0 86.0 84.0 87.0 84.0 86.0 82.0 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:93.00 stddev:0.84 (0.9%) stddev-sans-first:0.88 22:13:13 INFO - PROCESS | 1689 | Values: 93.0 92.0 93.0 93.0 91.0 93.0 94.0 93.0 92.0 92.0 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#7] imaging-desaturate Cycles:10 Average:83.30 Median:83.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 22:13:13 INFO - PROCESS | 1689 | Values: 83.0 84.0 84.0 83.0 84.0 83.0 83.0 83.0 83.0 83.0 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:59.00 stddev:18.70 (31.7%) stddev-sans-first:19.71 22:13:13 INFO - PROCESS | 1689 | Values: 59.0 59.0 61.0 60.0 59.0 60.0 59.0 118.0 57.0 57.0 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:68.00 stddev:4.72 (6.9%) stddev-sans-first:4.77 22:13:13 INFO - PROCESS | 1689 | Values: 65.0 65.0 80.0 74.0 71.0 68.0 66.0 68.0 67.0 67.0 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:85.00 stddev:1.70 (2.0%) stddev-sans-first:1.74 22:13:13 INFO - PROCESS | 1689 | Values: 85.0 85.0 85.0 84.0 82.0 83.0 80.0 83.0 85.0 85.0 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#11] stanford-crypto-ccm Cycles:10 Average:161.10 Median:146.00 stddev:51.07 (35.0%) stddev-sans-first:53.79 22:13:13 INFO - PROCESS | 1689 | Values: 144.0 159.0 137.0 136.0 147.0 141.0 145.0 135.0 304.0 163.0 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.70 Median:167.00 stddev:2.31 (1.4%) stddev-sans-first:2.44 22:13:13 INFO - PROCESS | 1689 | Values: 166.0 167.0 169.0 171.0 167.0 166.0 167.0 166.0 162.0 166.0 22:13:13 INFO - PROCESS | 1689 | 22:13:13 INFO - PROCESS | 1689 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:1.03 (1.6%) stddev-sans-first:0.87 22:13:13 INFO - PROCESS | 1689 | Values: 68.0 67.0 66.0 67.0 67.0 65.0 65.0 65.0 66.0 66.0 22:13:13 INFO - PROCESS | 1689 | -------- Summary: end -------- 22:13:13 INFO - PROCESS | 1689 | 22:13:14 INFO - TEST-INFO | 1689: exit 0 22:13:14 INFO - TEST-OK | kraken | took 61128ms 22:13:14 INFO - SUITE-END | took 1178s 22:13:14 INFO - Completed test suite (00:19:38) 22:13:14 INFO - javascript benchmark 22:13:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3401.9149521261966, 3436.8453419596117, 3448.982755086224, 3455.170759017835, 3502.282032183403, 5578.107502799553, 5607.691576963265, 5628.183913332569, 5702.315722113347, 5713.085906255, 5652.971735141329, 5701.315842098944, 5706.714664266791, 5712.971435142818, 5718.056520674094, 4726.794667359835, 5118.4113826909825, 5145.2796608474655, 5165.22521621756, 5245.396779370364, 4832.226843705008, 5256.448072952342, 5262.342207224097, 5264.184051472028, 5265.526102650761, 5100.336956195708, 5126.205438157073, 5130.179171332587, 5153.0466863630345, 5254.474552544734, 5215.139501982168, 5236.162214045772, 5236.738163091868, 5250.081235783722, 5292.338457692788, 5042.0168067226905, 5062.569681577071, 5070.935103628242, 5178.585713142939, 5180.1452760294505, 5459.617826752129, 5511.448855114457, 5527.281453411031, 5547.639403438763, 5605.878824235169, 6203.379662033805, 6208.286047104596, 6212.875742485125, 6218.937810621881, 6231.9065214021375, 5285.0, 6165.84585385369, 6181.938180618181, 6210.378962103753, 6249.968755155394, 5753.165790960287, 5783.739731712083, 5800.651960882361, 5808.07070868659, 5817.883642327172, 6183.598066125687, 6316.778912738124, 6353.047042943595, 6358.427741503287, 6364.268109167412, 5997.0, 6119.082137679295, 6137.285055221596, 6144.986077297285, 6146.692665366714, 5469.644473109235, 6319.905201421982, 6334.873302533923, 6337.493000560036, 6339.175907132043, 6054.788082417094, 6282.905756413657, 6283.874322513524, 6284.12022316876, 6291.779787707408, 6021.42796434338, 6132.447291485866, 6136.028952951454, 6178.320384757673, 6192.721327540272, 4864.075825593126, 4887.877803054881, 4908.239222920453, 4916.385451818523, 4968.080905032581, 4407.0524837160165, 4426.867193984185, 4427.092446048552, 4427.601515863587, 4466.486354069259, 5628.2683251177905, 5666.688332141689, 5690.573207009491, 5718.7426565804635, 5735.885282294331, 6173.9073913891325, 6195.752169913153, 6252.656103914328, 6261.592996455216, 6325.715342809584, 5737.254156959569, 5746.913796293059, 5748.942510574924, 5758.049921762946, 5799.101139323411, 5347.3583170019065, 5360.839174824761, 5365.651232669864, 5370.087085195526, 5375.623706340519, 6168.629882207082, 6249.406306400884, 6265.122882796321, 6285.717142728679, 6364.109024736448, 6304.464120549805, 6306.495480361468, 6315.115883776183, 6319.46284565817, 6320.873582528232, 6282.874342513032, 6291.96854015727, 6334.714937827716, 6350.460210881942, 6356.7775127872155, 6130.938690613037, 6139.001538246492, 6191.288001879661, 6223.097650840512, 6229.813105606839, 6283.685815709105, 6284.24589049317, 6294.276158241678, 6297.1813664223355, 6311.27420346666, 5956.374580668968, 6033.698315084316, 6107.389261073857, 6154.415330543591, 6157.53818463626, 6065.393460654076, 6135.32453530862, 6140.228185505611, 6143.174139895698, 6145.139680444652, 5899.646021238739, 5966.313873904556, 5980.970095149496, 6004.39956004396, 6021.698915054318, 5882.588218824641, 5943.257092863392, 5999.460048595647, 6030.969845150747, 6157.138000679819, 5761.510271626791, 5821.27234095738, 5868.295804503487, 5894.20428242199, 5918.674472904032], "value": 5683.188748481084, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.8642266588019, 523.7538356972226, 534.524273396677, 535.6464733276038, 538.5933620116813, 26610.467790644176, 42668.71993840186, 43822.561774382244, 43848.780756096254, 43963.0, 44914.0, 45329.0, 45338.7733061335, 45341.0, 45567.86080347993, 8606.268467180296, 9622.326437149384, 9845.606175752997, 9848.113669769718, 9849.113579777819, 8729.825403491926, 11754.11844111695, 11960.461779219913, 11971.940140299286, 12016.279023258632, 11744.530218791238, 11823.645290641294, 11827.822582661267, 11836.644900652995, 11847.526098956032, 8707.738767836974, 11523.539058437653, 11723.120765942589, 11751.294922304689, 11783.528658853636, 11695.649130526013, 11767.58813441534, 11769.941150294193, 11774.175807693466, 11794.528218871235, 40808.59191408107, 41129.79435102805, 41182.58817411847, 41200.587994119975, 41366.1726765466, 3242.363979905091, 3412.3516531859113, 3413.300273443963, 3453.5165076889257, 3509.736769742254, 28882.855585722147, 29353.85323073371, 29460.55809162864, 29496.0, 29573.112806615834, 2759.572266298727, 2761.226856480189, 2767.8200916940536, 2779.958300625492, 2875.3674191677796, 486113.0, 486974.99999999645, 487005.0, 487184.0, 487751.0, 63992.36007639957, 65025.67487162534, 65025.67487162629, 65030.0, 65047.69904601976, 36962.630373696455, 37670.62329376671, 37751.2449751009, 39050.02374940649, 39084.609153908095, 16964.74552881708, 17046.914765426092, 17158.31366745341, 17181.656366872594, 17190.1404929753, 11970.88029119715, 12167.513299468099, 12904.29026403538, 13010.674733131747, 13159.013074019296, 19931.402057938285, 19935.508061492314, 19939.102740376427, 20046.49883752918, 20065.39803805888, 6974.825629359307, 8190.303824174893, 8235.38234632393, 8290.668373265122, 8300.0, 935.9204467620192, 977.4965892565334, 996.8056229035413, 1090.4220762995626, 1098.4946924414858, 29766.939620264606, 29809.701902981127, 30394.54408183879, 30642.233944151576, 30657.233569160504, 1010.7068950004416, 1166.4517676691942, 1191.1602320364123, 1206.943924066442, 1235.3576140406938, 13649.0, 15406.152661603495, 15544.455944041903, 15848.918152430566, 15915.840841591667, 190759.09240907413, 191423.0, 191676.0, 191947.0, 193263.0, 60764.78470430567, 61181.388186118456, 61183.08225376623, 61317.0, 61512.38487615067, 1331.340986211812, 1445.5446534341438, 1476.5865557643876, 1498.8276348219877, 1499.5651261134149, 24056.15803446871, 29299.56050659199, 30368.696313036588, 31136.844315778275, 31512.842435787676, 51218.74390628023, 51313.0, 51770.0, 51929.48070519398, 51930.22104668509, 451671.0, 455187.0, 456484.0, 456590.0, 457089.0, 22490.0, 23761.881190594628, 25486.872565637794, 27164.0, 27273.86363068172, 45800.312995305765, 46056.76971615254, 46559.76720116513, 47065.82335441641, 47215.29177062278, 36527.0, 37357.813210933775, 37858.0, 38207.617923820406, 38638.499286534694, 22640.094396223965, 29826.956056537918, 30035.699643004165, 30090.398192036475, 30241.243968900075, 37118.257634847694, 37798.0, 38301.42547861728, 38744.031399215244, 38790.4181437273, 28334.58327083679, 29028.27429314284, 30924.53613195759, 32159.196020099684, 33799.4930076054, 20597.79402206019, 22112.0, 24433.877830610734, 24447.75552244455, 25397.619035714848], "value": 19241.856016318885, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.6561176376568, 390.2176136845625, 401.76697515441026, 416.14482238998846, 417.54070522425314, 20225.393238202858, 22417.55164896701, 22754.31737047889, 22781.772182278193, 22876.428089297777, 20803.791962080373, 20909.686354704692, 22236.55526889461, 22268.554628907412, 22300.553988920252, 5037.49625037496, 5445.945540544603, 5452.645578037414, 5496.230527726121, 5496.5053145216925, 3901.317269477844, 4148.751074935499, 5394.379646340682, 5806.187133801272, 6138.447539721425, 6137.018077107664, 6138.14066030756, 6147.784827531037, 6150.323464418911, 6153.138560601519, 5913.704314784243, 5988.9101663475085, 6106.328303886569, 6107.29766076898, 6110.419510146551, 3768.397056470979, 5465.535429488498, 5814.883702325929, 5815.098659707752, 5855.238818953509, 13351.866481335257, 13592.184468931797, 13599.456021759117, 21915.67126493104, 26447.735522644718, 2081.188336548747, 2174.826013918883, 2249.876256805875, 2622.265765585639, 2748.69764325924, 9430.245580353623, 10072.848907266396, 10114.089731924088, 10426.68719938403, 10490.527926243338, 1752.386664667369, 1765.9028753418556, 1775.343123044469, 1793.973090403645, 1823.7082066869236, 114600.0, 126408.36795815962, 127470.36264818709, 130225.34887325598, 131071.34464327713, 11563.30620162793, 11823.467943942544, 11827.290362578273, 11844.407779610985, 12009.519619215134, 15441.768373474408, 15508.5347439577, 15533.533993980198, 15540.300686469136, 15572.688546229012, 8818.0, 8884.955575222082, 8913.55432228399, 8941.46351218929, 9059.59231834569, 8823.073577274423, 8826.514541700268, 8828.735137945872, 8941.150590694004, 8952.418092823946, 4692.929606055912, 4701.623870090384, 4717.24524076153, 4739.691920025188, 4757.833475828329, 1580.683863227359, 1793.9103044847966, 1841.9830285836988, 1942.2910637617167, 1950.4441234248454, 868.9348298877483, 891.1934699097327, 916.3448134583805, 929.7489677787095, 952.4333021851987, 19575.840208596695, 20351.287704930255, 20418.876961767244, 20521.48696282575, 20613.484662883548, 913.1827014821697, 934.6868798952291, 945.7824700318828, 950.7242899559188, 984.0356450678375, 10564.36613803159, 10574.41840698769, 10776.568937242579, 10801.567937282578, 10855.728606784893, 602545.9872700608, 610521.0, 624463.0, 625554.0, 627034.0, 126300.31648575836, 127993.0, 128122.0, 128490.0, 132322.0, 955.2835373469898, 1023.9334443261164, 1026.035526605004, 1029.3170665961059, 1073.9194560407843, 17332.39336623212, 17758.644827103126, 17816.0, 18181.363652272106, 18484.537886552946, 49920.0, 50109.74945125251, 51983.44049678516, 52347.95304093973, 53642.92714145773, 72420.63789681018, 73454.16364590928, 73760.25517320819, 77137.97206363137, 77202.61398693196, 5509.449055094458, 8076.394270429624, 8108.189181081844, 8187.426880118334, 8571.442856214326, 10863.402512861869, 11446.542138314375, 12237.75524489523, 12365.0, 12549.435275412447, 8659.350548708746, 9811.950940245253, 10332.431716255678, 10759.677209683721, 10760.031597156294, 10247.436390998566, 10258.28192026551, 10542.736431589272, 10565.313254638424, 10675.57297708083, 10821.350718956888, 10827.512761925575, 11009.834852477059, 11183.105351572018, 11190.216684832309, 7918.287354138155, 8013.639386227517, 8057.43597948138, 8091.554964476775, 8378.413511054168, 8276.255137037695, 10452.163826893679, 10521.684349469528, 10882.891171088504, 11064.17018723615], "value": 9414.63390035325, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.9927900360497, 1633.9264733087014, 1637.6560922206336, 1892.848572114231, 2015.5565775529378, 8098.797530061752, 8791.604377802998, 9011.819763604732, 9012.68455604054, 9171.037041110687, 8765.342599305062, 9004.72985810425, 9008.144226298507, 9015.323850711191, 9030.68392606259, 2174.358564223558, 2253.0, 2254.661800729887, 2465.7410971847967, 2501.9124330648433, 2084.843636727244, 2259.53679495703, 2288.3135059482174, 2452.730199678034, 2488.340589743719, 2048.467398476392, 2449.7795198432136, 2457.275103844361, 2460.5447992121517, 2464.125235541384, 2391.5097405032016, 2443.2792326263793, 2443.853368797878, 2445.9143929962365, 2447.6206188040705, 2112.503561663008, 2175.3582693105413, 2380.3216083416314, 2460.4095017195923, 2460.90156393744, 8698.608562614634, 8836.248918841906, 8836.293096552261, 8840.646374145026, 8893.644254229823, 5351.545118664918, 5499.312585926759, 5521.696306703129, 5536.723163841792, 5546.77812887484, 3563.1092226943265, 3654.671079602822, 3729.3287208302495, 3735.8505659773577, 3741.6258374162367, 1021.4484178543578, 1035.1666908138945, 1062.8724553053682, 1080.2978064258216, 1083.5015892689373, 12159.392030398445, 12177.756444871053, 12178.634640960785, 12187.268763874195, 12205.267683938991, 4093.3041382964966, 4878.780454879539, 4899.240617704261, 5048.924266136011, 5056.165732654144, 10295.794084118275, 10310.32982856127, 10314.484275786332, 10319.226058045677, 10339.483025848678, 5436.347638283352, 5525.143602741581, 5562.276904002535, 5564.777408903679, 5566.192902029183, 4471.485779135376, 4930.827421040318, 4965.155923492943, 5017.54842064216, 5090.0583392072585, 3679.6201424465826, 3826.311263972512, 3852.3065848147603, 4012.3179059559943, 4160.063985603276, 1321.3987635625767, 1339.216558313378, 1345.838499380061, 1347.7574036673298, 1363.454618152747, 839.4879123734516, 914.8353296406577, 936.990630093704, 938.5166639180691, 981.7054883534911, 5953.136795164677, 6037.9433599119975, 6067.0, 6186.752529898754, 6230.096635987757, 888.3071204460531, 913.1051569461964, 939.4926739560698, 986.837171866634, 1002.9498525073863, 3840.0, 4046.2716710992304, 4063.6139566741113, 4107.075907920754, 4121.196366708463, 10524.158067354594, 10942.890571094187, 10951.0, 10961.397123158304, 11088.889111108785, 10808.243422960475, 10953.726156845983, 11048.226624136234, 11072.778544429066, 11079.944600276947, 8157.55133467665, 8298.792530186673, 8444.24001839825, 8445.704400345958, 8525.957370213233, 5167.6641018333685, 5989.550783691154, 6031.4270144336215, 6086.0, 6128.479079278133, 10064.496775161359, 10153.949230253802, 10206.162890679749, 10328.741781455523, 10341.0, 8506.659733610833, 9731.756706082404, 10407.739806504898, 10473.109785668303, 10548.578056877639, 6972.4073453757, 7202.171750248579, 7653.043369578802, 7728.0, 7729.07251129868, 8250.834983300181, 8537.31701463869, 8566.229039386486, 8662.090480499457, 8679.609417576097, 7438.776836694908, 7561.357284630648, 7595.810104747426, 7719.884201737091, 7743.806404839924, 7292.635368231461, 7466.141393739789, 7495.662695178839, 7572.962135189288, 7616.162222155669, 7005.474589405713, 7034.929650703428, 7084.822879427849, 7087.149542054986, 7091.255418181223, 4830.396200474941, 4889.3888263967, 5032.7483625819295, 5047.646664733434, 5228.163493840967, 4983.925241121458, 5050.924236136388, 5113.616478764182, 5154.664946778447, 5343.278657381204], "value": 4680.395239501359, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.65337767989575, 305.8088694565898, 310.13472411970605, 321.47599412956885, 323.13561223726526, 4256.851010214643, 4562.70342427742, 5290.941811637668, 5326.2809520714645, 5378.811741589046, 5008.974955125219, 5169.379674439073, 5178.404483484401, 5225.6603320784125, 5292.2061690746405, 3374.8143852088124, 3443.552338196031, 3559.686203967429, 3958.2281455116263, 4038.3538633818594, 3363.8731025106617, 3402.625711171769, 3973.893836266783, 4013.6588389986887, 4056.492938382702, 3760.8529398533574, 3975.5472293433363, 4044.54396417291, 4058.2289365020697, 4083.693722970774, 3225.016370007147, 4038.3538633818744, 4047.231026105045, 4076.4700588923547, 4085.3667681509414, 3512.4731290306404, 3522.8590856365713, 3533.1873669056004, 3543.0787995121195, 3545.468179773029, 5013.699178049329, 5026.170681837492, 5043.899122017576, 5077.187649976023, 5135.947130838169, 4404.405405270311, 4616.530156366328, 4643.698159619614, 4648.697834640738, 4661.324108004321, 4532.365468834366, 5318.224060916754, 5497.471163264903, 5499.917501237484, 5539.639923405006, 3338.749593780452, 3929.0766669832565, 3951.38753493209, 3955.9121241658772, 3959.6436320731273, 4484.730916145042, 5674.836658485001, 5828.708564571797, 5857.76568937242, 5894.964093491855, 3963.6432721054666, 3974.2647610192207, 4024.678025757932, 4027.154297597479, 4041.9797901010306, 3821.6051141333214, 4031.213913286881, 4061.1268577256033, 4072.144849581621, 4095.795210239476, 3963.326234540135, 4130.905310092828, 4257.531671516131, 4487.461504619467, 4659.437309479773, 5198.298229738937, 5623.971880140573, 5651.2935883014625, 5656.462636049569, 5707.543396528268, 1282.7049778550986, 1296.3194322980323, 1339.3571085878832, 1360.1091285208204, 1364.747521708487, 737.8524295140886, 766.1954947305308, 805.5166899860154, 902.4946030223097, 906.3746015249457, 5198.662086964335, 5202.037623039674, 5213.113770658997, 5217.295665085165, 5222.947770522323, 4545.0000999779995, 4792.083166733334, 5142.254373115952, 5678.829635110953, 5689.260396148474, 4262.637675797592, 4661.440627124767, 4694.530546945278, 5028.446870844205, 5255.684658920477, 2529.0, 2737.1241202815304, 2925.648922129358, 2993.8802447902276, 3020.9244768880953, 5296.2055691646565, 5547.143221241064, 5937.910931336033, 6011.09833524966, 6023.969880150659, 4906.337644418029, 5248.606354523426, 5407.4048155794135, 5775.036487594154, 5881.323647780475, 4838.95161048392, 5490.478404551482, 5790.015697331548, 5858.297004359504, 5920.615160014585, 4659.588188096651, 5215.087359712111, 5420.728963551728, 5513.61404701667, 5716.713739408599, 4800.7359595221205, 5274.235235890851, 5586.245856809279, 5622.32532096151, 5656.943430565806, 5516.779328826802, 5651.773929042956, 5895.351511333664, 5932.2584676915385, 5939.613925094855, 3578.0, 3775.282246577746, 3891.5524714657045, 3906.3945088511327, 3924.3524818404644, 5141.126008578625, 5731.598788084794, 5748.540116790563, 5786.42135786418, 5803.332616749128, 5264.578833693372, 5401.300109425057, 5642.463965923395, 5661.150827375926, 5686.545076393962, 5253.711045892513, 5568.638038527482, 5570.5265052470995, 5575.776968921197, 5575.916361254665, 5050.853387310452, 5187.3285258092865, 5337.745629777077, 5349.652272602269, 5459.153831156177, 4998.600111991104, 5077.31456253401, 5341.62608617393, 5592.468715472024, 5663.773449061991, 4625.907481850411, 5413.025655382069, 5467.125259958388, 5497.560195184456, 5511.944880551143], "value": 4042.164016056285, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.4149944300408, 662.7614058938781, 724.9383802376798, 725.5755383100885, 749.1310080306841, 14861.0, 14910.403583856632, 15367.077975321488, 15399.538013859574, 15404.61488462789, 11681.59114430995, 11736.882631173687, 14198.432062717478, 14213.360398782053, 14263.572092837232, 730.6565914020401, 755.2787088330631, 906.9365144439892, 911.9863202051974, 932.8554074118523, 928.3965422475411, 934.7663084228943, 935.1490143968956, 936.6487567162314, 937.5687183895416, 991.4646091110792, 992.5979978108842, 994.3735446668588, 1000.4297550396277, 1002.3985608634833, 757.613617055303, 942.2838642631581, 974.4348277998744, 978.9755256118583, 990.2770977186658, 724.1165777751136, 725.4631572636264, 725.7786375155575, 726.5531698005741, 726.5713229194747, 14041.22773247471, 14095.78856317156, 14121.858781412157, 14123.576292711236, 14162.221077840715, 9837.458939758311, 10547.578096876116, 12255.080868934865, 12301.261924284572, 12565.748685026248, 10335.328203666737, 10634.852244194555, 10684.145268378512, 10928.672139835739, 11336.0, 4079.9184016319805, 4120.17596480705, 4126.216018956418, 4128.422020917074, 4188.183304255671, 55752.72123639396, 72822.63588682022, 73421.26578734198, 73817.0, 73842.63078684626, 7850.450468467267, 9288.349815512984, 9300.441973481613, 9407.059294070537, 9531.332806703604, 20813.89593052025, 20830.791692082887, 20832.1667133313, 20836.47908802292, 20862.374128776162, 7282.4902256841515, 7326.3406293433845, 7436.330730234305, 7461.552306861606, 7718.845623087619, 3197.392495425862, 3980.402939559089, 3985.9003524911527, 3992.6207010333974, 4011.659008984269, 3431.5367425397753, 3536.6993805526304, 3541.5395998520544, 3542.326957877995, 3542.6988705959766, 489.59363728106297, 528.545224182601, 609.3266940031202, 632.4876849751715, 655.6623338980429, 527.7229454536323, 574.7988204128521, 575.4906907386909, 592.8517870532366, 610.664134725899, 8038.316743076904, 8121.75634730959, 8129.309008734205, 8243.711470098518, 8328.33373330132, 537.9408265090836, 583.8964357364584, 585.077058323937, 592.6740292838921, 598.619876378494, 3371.7976921384798, 3884.320243957296, 3917.0, 4011.197760447922, 4114.218298523271, 48479.0, 79172.0, 82876.95008380579, 83552.16447835443, 83979.16020839714, 70886.9366959496, 72389.0, 72455.46405875769, 73937.0, 73979.0, 67339.31651708648, 67571.0, 67651.66174169097, 67715.645687085, 67724.0, 7567.470277080552, 7655.88516172269, 7850.332721718718, 7852.254035866584, 7984.401169912164, 33933.0, 40680.79659601683, 41804.372934406616, 42023.0, 42319.0, 40285.992850178984, 56685.71657141688, 57362.42637573571, 61471.77056458756, 61833.69083154555, 10945.398003109587, 11079.667609971713, 11193.384363860067, 11393.373364465035, 11548.0, 11836.408179591159, 11907.642770716546, 11999.100067494799, 12042.819357709817, 12901.225926444444, 11462.94268528652, 11842.76314473723, 12193.451294691937, 12524.937375313066, 12547.184433011826, 11439.59961401347, 11550.249233799776, 11576.710582235512, 11798.882011180121, 11816.409179540817, 11313.886861131612, 11343.376114313793, 11441.370724610226, 11592.536298547964, 11774.587889423829, 9840.163586094975, 9853.605855765689, 9859.35914165577, 9985.700428987142, 10020.24848136378, 8233.629486673233, 8368.24685778283, 8939.776505587412, 9602.951985240308, 9637.71086867395], "value": 6705.426674365403, "unit": "score"}], "name": "dromaeo_css", "value": 7123.087402105898, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 112.0, 112.0, 113.0, 112.0, 112.0, 111.0, 111.0, 110.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 117.0, 132.0, 208.0, 148.0, 172.0, 129.0, 132.0, 203.0, 135.0], "value": 153.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 181.0, 183.0, 184.0, 184.0, 233.0, 259.0, 197.0, 185.0], "value": 197.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 74.0, 164.0, 82.0, 64.0, 58.0, 58.0, 110.0, 57.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 101.0, 185.0, 115.0, 93.0, 77.0, 101.0, 80.0, 79.0, 101.0], "value": 102.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 87.0, 84.0, 86.0, 84.0, 87.0, 84.0, 86.0, 82.0], "value": 85.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 93.0, 91.0, 93.0, 94.0, 93.0, 92.0, 92.0], "value": 92.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 83.0, 84.0, 83.0, 83.0, 83.0, 83.0, 83.0], "value": 83.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 61.0, 60.0, 59.0, 60.0, 59.0, 118.0, 57.0, 57.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 80.0, 74.0, 71.0, 68.0, 66.0, 68.0, 67.0, 67.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 84.0, 82.0, 83.0, 80.0, 83.0, 85.0, 85.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 159.0, 137.0, 136.0, 147.0, 141.0, 145.0, 135.0, 304.0, 163.0], "value": 161.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 169.0, 171.0, 167.0, 166.0, 167.0, 166.0, 162.0, 166.0], "value": 166.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 67.0, 67.0, 65.0, 65.0, 65.0, 66.0, 66.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1518.7, "alertThreshold": 2.0}]} 22:13:14 INFO - Return code: 0 22:13:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:13:15 ERROR - # TBPL SUCCESS # 22:13:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:13:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:13:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:13:15 INFO - 2016-05-10 22:13:15.035 screenresolution[1693:9833] starting screenresolution argv=screenresolution get 22:13:15 INFO - 2016-05-10 22:13:15.038 screenresolution[1693:9833] Display 0: 1600x1200x32@60 22:13:15 INFO - 2016-05-10 22:13:15.044 screenresolution[1694:9836] starting screenresolution argv=screenresolution list 22:13:15 INFO - Available Modes on Display 0 22:13:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:13:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:13:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:13:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:13:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:13:15 INFO - Intel Iris: 22:13:15 INFO - Chipset Model: Intel Iris 22:13:15 INFO - Type: GPU 22:13:15 INFO - Bus: Built-In 22:13:15 INFO - VRAM (Dynamic, Max): 1536 MB 22:13:15 INFO - Vendor: Intel (0x8086) 22:13:15 INFO - Device ID: 0x0a2e 22:13:15 INFO - Revision ID: 0x0009 22:13:15 INFO - Displays: 22:13:15 INFO - Display: 22:13:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:13:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:13:15 INFO - Display Serial Number: 1600x1200 60 22:13:15 INFO - Main Display: Yes 22:13:15 INFO - Mirror: Off 22:13:15 INFO - Online: Yes 22:13:15 INFO - Rotation: Supported 22:13:15 INFO - Return code: 0 22:13:15 INFO - Running post-action listener: _resource_record_post_action 22:13:15 INFO - ##### 22:13:15 INFO - ##### Finished run-tests step (success) 22:13:15 INFO - ##### 22:13:15 INFO - Running post-run listener: _resource_record_post_run 22:13:16 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37570048; Write bytes: 690547200; Read time: 400; Write time: 2620 22:13:16 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 382822912; Write bytes: 372172800; Read time: 28460; Write time: 686 22:13:16 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 31387136; Write bytes: 293528064; Read time: 312; Write time: 1885 22:13:16 INFO - Running post-run listener: _upload_blobber_files 22:13:16 INFO - Blob upload gear active. 22:13:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:13:16 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 22:13:16 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'] 22:13:16 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 22:13:16 INFO - (blobuploader) - INFO - Open directory for files ... 22:13:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:13:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:13:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:13:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:13:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:13:17 INFO - (blobuploader) - INFO - Done attempting. 22:13:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:13:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:13:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:13:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:13:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:13:20 INFO - (blobuploader) - INFO - Done attempting. 22:13:20 INFO - (blobuploader) - INFO - Iteration through files over. 22:13:20 INFO - Return code: 0 22:13:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:13:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:13:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/18139e87a79a200d0e3ee8a093919b9103e449041bfd610d4b6fc3ab36fe908218814ed4905c39e9e13ab9142e126646ebba2df86705c1be2436932b4e7fe1f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5b7deafabc894b9a68c37381b262b88802fbb2ee9abbde3dc5394e888945086d8a4ef609b3885d463f0b8617c4d087d28f8329e4e47f999953b478af189ce4d"} 22:13:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:13:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:13:20 INFO - Contents: 22:13:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/18139e87a79a200d0e3ee8a093919b9103e449041bfd610d4b6fc3ab36fe908218814ed4905c39e9e13ab9142e126646ebba2df86705c1be2436932b4e7fe1f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5b7deafabc894b9a68c37381b262b88802fbb2ee9abbde3dc5394e888945086d8a4ef609b3885d463f0b8617c4d087d28f8329e4e47f999953b478af189ce4d"} 22:13:20 INFO - Running post-run listener: copy_logs_to_upload_dir 22:13:20 INFO - Copying logs to upload dir... 22:13:20 INFO - mkdir: /builds/slave/test/build/upload/logs 22:13:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.375125 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-10 22:13:20.983136) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 22:13:20.986711) ========= 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.qPD88rQXfH/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.gXUBu1b7rO/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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/18139e87a79a200d0e3ee8a093919b9103e449041bfd610d4b6fc3ab36fe908218814ed4905c39e9e13ab9142e126646ebba2df86705c1be2436932b4e7fe1f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5b7deafabc894b9a68c37381b262b88802fbb2ee9abbde3dc5394e888945086d8a4ef609b3885d463f0b8617c4d087d28f8329e4e47f999953b478af189ce4d"} build_url:https://queue.taskcluster.net/v1/task/WP2XsnZNQXSegaD_d-y45g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011138 build_url: 'https://queue.taskcluster.net/v1/task/WP2XsnZNQXSegaD_d-y45g/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/18139e87a79a200d0e3ee8a093919b9103e449041bfd610d4b6fc3ab36fe908218814ed4905c39e9e13ab9142e126646ebba2df86705c1be2436932b4e7fe1f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b5b7deafabc894b9a68c37381b262b88802fbb2ee9abbde3dc5394e888945086d8a4ef609b3885d463f0b8617c4d087d28f8329e4e47f999953b478af189ce4d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 22:13:21.015729) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 22:13:21.016062) ========= 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.qPD88rQXfH/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.gXUBu1b7rO/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.005295 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 22:13:21.063920) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 22:13:21.064247) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 22:13:21.064568) ========= ========= Total master_lag: 0.16 =========