builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0138 starttime: 1462927461.31 results: success (0) buildid: 20160510153811 builduid: f6bf2a15babe4408b1fb2a870752449d revision: 5467a8d9d844fff2b8916999ebd1f4e90289e2d1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 17:44:21.312588) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 17:44:21.313007) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 17:44:21.313342) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 17:44:21.331089) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 17:44:21.331521) ========= 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.qsNTJr7zOs/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.z75GoxkKby/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 17:44:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.35M=0.001s 2016-05-10 17:44:21 (8.35 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.074894 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 17:44:21.423119) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 17:44:21.423429) ========= 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.qsNTJr7zOs/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.z75GoxkKby/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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.019852 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 17:44:21.471771) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 17:44:21.472128) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5467a8d9d844fff2b8916999ebd1f4e90289e2d1 --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 5467a8d9d844fff2b8916999ebd1f4e90289e2d1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qsNTJr7zOs/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.z75GoxkKby/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 17:44:21,545 truncating revision to first 12 chars 2016-05-10 17:44:21,545 Setting DEBUG logging. 2016-05-10 17:44:21,546 attempt 1/10 2016-05-10 17:44:21,546 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5467a8d9d844?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 17:44:22,579 unpacking tar archive at: fx-team-5467a8d9d844/testing/mozharness/ program finished with exit code 0 elapsedTime=1.277603 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 17:44:22.766541) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 17:44:22.766856) ========= script_repo_revision: 5467a8d9d844fff2b8916999ebd1f4e90289e2d1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 17:44:22.767249) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 17:44:22.767506) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 17:44:22.781377) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-10 17:44:22.781769) ========= /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.qsNTJr7zOs/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.z75GoxkKby/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 17:44:22 INFO - MultiFileLogger online at 20160510 17:44:22 in /builds/slave/test 17:44:22 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 17:44:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:44:22 INFO - {'append_to_log': False, 17:44:22 INFO - 'base_work_dir': '/builds/slave/test', 17:44:22 INFO - 'blob_upload_branch': 'Fx-Team', 17:44:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:44:22 INFO - 'branch': 'Fx-Team', 17:44:22 INFO - 'buildbot_json_path': 'buildprops.json', 17:44:22 INFO - 'config_files': ('talos/mac_config.py',), 17:44:22 INFO - 'default_actions': ('clobber', 17:44:22 INFO - 'read-buildbot-config', 17:44:22 INFO - 'download-and-extract', 17:44:22 INFO - 'populate-webroot', 17:44:22 INFO - 'create-virtualenv', 17:44:22 INFO - 'install', 17:44:22 INFO - 'run-tests'), 17:44:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:44:22 INFO - 'download_minidump_stackwalk': True, 17:44:22 INFO - 'download_symbols': 'ondemand', 17:44:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:44:22 INFO - 'tooltool.py': '/tools/tooltool.py', 17:44:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:44:22 INFO - '/tools/misc-python/virtualenv.py')}, 17:44:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:44:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:44:22 INFO - 'installer_path': 'installer.exe', 17:44:22 INFO - 'log_level': 'info', 17:44:22 INFO - 'log_name': 'talos', 17:44:22 INFO - 'log_to_console': True, 17:44:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:44:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:44:22 INFO - 'opt_config_files': (), 17:44:22 INFO - 'pip_index': False, 17:44:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:44:22 INFO - 'cmd': ('bash', 17:44:22 INFO - '-c', 17:44:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:44:22 INFO - 'enabled': True, 17:44:22 INFO - 'halt_on_failure': False, 17:44:22 INFO - 'name': 'check_screen_resolution'},), 17:44:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:44:22 INFO - 'cmd': ('bash', 17:44:22 INFO - '-c', 17:44:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:44:22 INFO - 'enabled': True, 17:44:22 INFO - 'halt_on_failure': False, 17:44:22 INFO - 'name': 'check_screen_resolution'},), 17:44:22 INFO - 'run_cmd_checks_enabled': True, 17:44:22 INFO - 'sps_profile': False, 17:44:22 INFO - 'sps_profile_interval': 0, 17:44:22 INFO - 'suite': 'dromaeojs', 17:44:22 INFO - 'system_bits': '32', 17:44:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:44:22 INFO - 'title': 't-yosemite-r7-0138', 17:44:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:44:22 INFO - 'use_talos_json': True, 17:44:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:44:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:44:22 INFO - 'work_dir': 'build'} 17:44:22 INFO - ##### 17:44:22 INFO - ##### Running clobber step. 17:44:22 INFO - ##### 17:44:22 INFO - Running pre-action listener: _resource_record_pre_action 17:44:22 INFO - Running main action method: clobber 17:44:22 INFO - rmtree: /builds/slave/test/build 17:44:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:44:23 INFO - Running post-action listener: _resource_record_post_action 17:44:23 INFO - ##### 17:44:23 INFO - ##### Finished clobber step (success) 17:44:23 INFO - ##### 17:44:23 INFO - ##### 17:44:23 INFO - ##### Running read-buildbot-config step. 17:44:23 INFO - ##### 17:44:23 INFO - Running pre-action listener: _resource_record_pre_action 17:44:23 INFO - Running main action method: read_buildbot_config 17:44:23 INFO - Using buildbot properties: 17:44:23 INFO - { 17:44:23 INFO - "project": "", 17:44:23 INFO - "product": "firefox", 17:44:23 INFO - "script_repo_revision": "production", 17:44:23 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 17:44:23 INFO - "repository": "", 17:44:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 17:44:23 INFO - "buildid": "20160510153811", 17:44:23 INFO - "pgo_build": "False", 17:44:23 INFO - "basedir": "/builds/slave/test", 17:44:23 INFO - "buildnumber": 6, 17:44:23 INFO - "slavename": "t-yosemite-r7-0138", 17:44:23 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 17:44:23 INFO - "platform": "yosemite_r7", 17:44:23 INFO - "scheduler": "tests-fx-team-macosx64-talos", 17:44:23 INFO - "branch": "fx-team", 17:44:23 INFO - "repo_path": "integration/fx-team", 17:44:23 INFO - "revision": "5467a8d9d844fff2b8916999ebd1f4e90289e2d1", 17:44:23 INFO - "stage_platform": "macosx64", 17:44:23 INFO - "builduid": "f6bf2a15babe4408b1fb2a870752449d", 17:44:23 INFO - "slavebuilddir": "test" 17:44:23 INFO - } 17:44:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 17:44:23 INFO - Running post-action listener: _resource_record_post_action 17:44:23 INFO - ##### 17:44:23 INFO - ##### Finished read-buildbot-config step (success) 17:44:23 INFO - ##### 17:44:23 INFO - ##### 17:44:23 INFO - ##### Running download-and-extract step. 17:44:23 INFO - ##### 17:44:23 INFO - Running pre-action listener: _resource_record_pre_action 17:44:23 INFO - Running main action method: download_and_extract 17:44:23 INFO - mkdir: /builds/slave/test/build/tests 17:44:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:23 INFO - https://queue.taskcluster.net/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:44:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:44:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:44:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/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 17:44:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/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 17:44:25 INFO - Downloaded 1199 bytes. 17:44:25 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 17:44:25 INFO - Using the following test package requirements: 17:44:25 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:44:25 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:44:25 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 17:44:25 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:44:25 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 17:44:25 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:44:25 INFO - u'jsshell-mac.zip'], 17:44:25 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:44:25 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 17:44:25 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:44:25 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:44:25 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 17:44:25 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:44:25 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 17:44:25 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:44:25 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 17:44:25 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:44:25 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 17:44:25 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:44:25 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')]} 17:44:25 INFO - https://queue.taskcluster.net/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:44:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:44:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:44:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/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 17:44:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/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 17:44:26 INFO - Downloaded 18150607 bytes. 17:44:26 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'] 17:44:26 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 17:44:29 INFO - Return code: 0 17:44:29 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 17:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:29 INFO - https://queue.taskcluster.net/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:44:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:44:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:44:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/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 17:44:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/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 17:44:30 INFO - Downloaded 18150607 bytes. 17:44:30 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'] 17:44:30 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 17:44:34 INFO - Return code: 0 17:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:34 INFO - https://queue.taskcluster.net/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:44:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:44:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:44:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/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 17:44:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/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 17:44:35 INFO - Downloaded 10840152 bytes. 17:44:35 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'] 17:44:35 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 17:44:35 INFO - Return code: 0 17:44: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')]} 17:44:35 INFO - https://queue.taskcluster.net/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:44:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:44:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:44:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 17:44:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMh3proTSlSy7JMDDhhUzQ/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 17:44:38 INFO - Downloaded 109846663 bytes. 17:44:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:44:38 INFO - mkdir: /builds/slave/test/properties 17:44:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:44:38 INFO - Writing to file /builds/slave/test/properties/build_url 17:44:38 INFO - Contents: 17:44:38 INFO - build_url:https://queue.taskcluster.net/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:44:40 INFO - Running post-action listener: _resource_record_post_action 17:44:40 INFO - Running post-action listener: set_extra_try_arguments 17:44:40 INFO - ##### 17:44:40 INFO - ##### Finished download-and-extract step (success) 17:44:40 INFO - ##### 17:44:40 INFO - ##### 17:44:40 INFO - ##### Running populate-webroot step. 17:44:40 INFO - ##### 17:44:40 INFO - Running pre-action listener: _resource_record_pre_action 17:44:40 INFO - Running main action method: populate_webroot 17:44:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:44:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:44:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:44:40 INFO - '--tppagecycles', 17:44:40 INFO - '7'], 17:44:40 INFO - 'tests': ['tsvgm']}}, 17:44:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:44:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:44:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:44:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:44:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:44:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:44:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['damp', 'tps']}, 17:44:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'tests': ['damp', 'tps']}, 17:44:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['dromaeo_dom']}, 17:44:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:44:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['a11yr', 17:44:40 INFO - 'ts_paint', 17:44:40 INFO - 'tpaint', 17:44:40 INFO - 'sessionrestore', 17:44:40 INFO - 'sessionrestore_no_auto_restore', 17:44:40 INFO - 'tabpaint']}, 17:44:40 INFO - 'other-e10s': {'tests': ['a11yr', 17:44:40 INFO - 'ts_paint', 17:44:40 INFO - 'tpaint', 17:44:40 INFO - 'sessionrestore', 17:44:40 INFO - 'sessionrestore_no_auto_restore', 17:44:40 INFO - 'tabpaint']}, 17:44:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:44:40 INFO - 'ts_paint', 17:44:40 INFO - 'tpaint', 17:44:40 INFO - 'sessionrestore', 17:44:40 INFO - 'sessionrestore_no_auto_restore', 17:44:40 INFO - 'tabpaint']}, 17:44:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:44:40 INFO - 'ts_paint', 17:44:40 INFO - 'tpaint', 17:44:40 INFO - 'sessionrestore', 17:44:40 INFO - 'sessionrestore_no_auto_restore', 17:44:40 INFO - 'tabpaint']}, 17:44:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['a11yr', 17:44:40 INFO - 'ts_paint', 17:44:40 INFO - 'tpaint', 17:44:40 INFO - 'sessionrestore', 17:44:40 INFO - 'sessionrestore_no_auto_restore', 17:44:40 INFO - 'tabpaint']}, 17:44:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['a11yr', 17:44:40 INFO - 'ts_paint', 17:44:40 INFO - 'tpaint', 17:44:40 INFO - 'sessionrestore', 17:44:40 INFO - 'sessionrestore_no_auto_restore', 17:44:40 INFO - 'tabpaint']}, 17:44:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['tsvgx', 17:44:40 INFO - 'tsvgr_opacity', 17:44:40 INFO - 'tart', 17:44:40 INFO - 'tscrollx', 17:44:40 INFO - 'cart']}, 17:44:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:44:40 INFO - 'tsvgr_opacity', 17:44:40 INFO - 'tart', 17:44:40 INFO - 'tscrollx', 17:44:40 INFO - 'cart']}, 17:44:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'talos_options': ['--disable-e10s'], 17:44:40 INFO - 'tests': ['tp5o']}, 17:44:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'tests': ['tp5o']}, 17:44:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'talos_options': ['--disable-e10s', 17:44:40 INFO - '--xperf_path', 17:44:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:44:40 INFO - 'tests': ['tp5n']}, 17:44:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:40 INFO - 'talos_options': ['--xperf_path', 17:44:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:44:40 INFO - 'tests': ['tp5n']}}, 17:44:40 INFO - 'talos.zip': {'path': '', 17:44:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:44:40 INFO - Running post-action listener: _resource_record_post_action 17:44:40 INFO - ##### 17:44:40 INFO - ##### Finished populate-webroot step (success) 17:44:40 INFO - ##### 17:44:40 INFO - ##### 17:44:40 INFO - ##### Running create-virtualenv step. 17:44:40 INFO - ##### 17:44:40 INFO - Running pre-action listener: _resource_record_pre_action 17:44:40 INFO - Running main action method: create_virtualenv 17:44:40 INFO - Creating virtualenv /builds/slave/test/build/venv 17:44:40 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 17:44:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:44:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:44:40 INFO - Using real prefix '/tools/python27' 17:44:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:44:40 INFO - Installing distribute.............................................................................................................................................................................................done. 17:44:43 INFO - Installing pip.................done. 17:44:43 INFO - Return code: 0 17:44:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:44: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')]} 17:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44: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')]} 17:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100feea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b49457f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e92d30>, '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 0x100cb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e89b20>, '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.qsNTJr7zOs/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.z75GoxkKby/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 17:44:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:44:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsNTJr7zOs/Render', 17:44:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:44:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:44:43 INFO - 'HOME': '/Users/cltbld', 17:44:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:44:43 INFO - 'LOGNAME': 'cltbld', 17:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:43 INFO - 'MOZ_NO_REMOTE': '1', 17:44:43 INFO - 'NO_EM_RESTART': '1', 17:44:43 INFO - 'PAGER': '/bin/cat', 17:44:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:43 INFO - 'PWD': '/builds/slave/test', 17:44:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:44:43 INFO - 'SHELL': '/bin/bash', 17:44:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z75GoxkKby/Listeners', 17:44:43 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 17:44:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:44:43 INFO - 'USER': 'cltbld', 17:44:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:44:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:43 INFO - 'XPC_FLAGS': '0x0', 17:44:43 INFO - 'XPC_SERVICE_NAME': '0', 17:44:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:43 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 17:44:43 INFO - Cleaning up... 17:44:43 INFO - Return code: 0 17:44:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:44: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')]} 17:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44: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')]} 17:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100feea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b49457f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e92d30>, '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 0x100cb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e89b20>, '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.qsNTJr7zOs/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.z75GoxkKby/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 17:44:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 17:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 17:44:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsNTJr7zOs/Render', 17:44:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:44:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:44:43 INFO - 'HOME': '/Users/cltbld', 17:44:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:44:43 INFO - 'LOGNAME': 'cltbld', 17:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:43 INFO - 'MOZ_NO_REMOTE': '1', 17:44:43 INFO - 'NO_EM_RESTART': '1', 17:44:43 INFO - 'PAGER': '/bin/cat', 17:44:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:43 INFO - 'PWD': '/builds/slave/test', 17:44:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:44:43 INFO - 'SHELL': '/bin/bash', 17:44:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z75GoxkKby/Listeners', 17:44:43 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 17:44:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:44:43 INFO - 'USER': 'cltbld', 17:44:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:44:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:43 INFO - 'XPC_FLAGS': '0x0', 17:44:43 INFO - 'XPC_SERVICE_NAME': '0', 17:44:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:44 INFO - Downloading/unpacking psutil>=0.7.1 17:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:44:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:44:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:44:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:44:47 INFO - Installing collected packages: psutil 17:44:47 INFO - Running setup.py install for psutil 17:44:47 INFO - building 'psutil._psutil_osx' extension 17:44:47 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 17:44:47 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 17:44:47 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 17:44:47 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 17:44:47 INFO - building 'psutil._psutil_posix' extension 17:44:47 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 17:44:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:44:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:44:47 INFO - ^ 17:44:47 INFO - 1 warning generated. 17:44:47 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 17:44:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:44:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:44:47 INFO - Successfully installed psutil 17:44:47 INFO - Cleaning up... 17:44:48 INFO - Return code: 0 17:44:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:44:48 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')]} 17:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:48 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')]} 17:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:48 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 0x100feea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b49457f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e92d30>, '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 0x100cb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e89b20>, '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.qsNTJr7zOs/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.z75GoxkKby/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 17:44:48 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 17:44:48 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 17:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsNTJr7zOs/Render', 17:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:44:48 INFO - 'HOME': '/Users/cltbld', 17:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:44:48 INFO - 'LOGNAME': 'cltbld', 17:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:48 INFO - 'MOZ_NO_REMOTE': '1', 17:44:48 INFO - 'NO_EM_RESTART': '1', 17:44:48 INFO - 'PAGER': '/bin/cat', 17:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:48 INFO - 'PWD': '/builds/slave/test', 17:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:44:48 INFO - 'SHELL': '/bin/bash', 17:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z75GoxkKby/Listeners', 17:44:48 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 17:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:44:48 INFO - 'USER': 'cltbld', 17:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:48 INFO - 'XPC_FLAGS': '0x0', 17:44:48 INFO - 'XPC_SERVICE_NAME': '0', 17:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:44:48 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 17:44:48 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 17:44:48 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 17:44:48 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 17:44:48 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 17:44:48 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 17:44:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:44:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:44:50 INFO - Installing collected packages: mozsystemmonitor 17:44:50 INFO - Running setup.py install for mozsystemmonitor 17:44:50 INFO - Successfully installed mozsystemmonitor 17:44:50 INFO - Cleaning up... 17:44:51 INFO - Return code: 0 17:44:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100feea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b49457f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e92d30>, '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 0x100cb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e89b20>, '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.qsNTJr7zOs/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.z75GoxkKby/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 17:44:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:44:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsNTJr7zOs/Render', 17:44:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:44:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:44:51 INFO - 'HOME': '/Users/cltbld', 17:44:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:44:51 INFO - 'LOGNAME': 'cltbld', 17:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:51 INFO - 'MOZ_NO_REMOTE': '1', 17:44:51 INFO - 'NO_EM_RESTART': '1', 17:44:51 INFO - 'PAGER': '/bin/cat', 17:44:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:51 INFO - 'PWD': '/builds/slave/test', 17:44:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:44:51 INFO - 'SHELL': '/bin/bash', 17:44:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z75GoxkKby/Listeners', 17:44:51 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 17:44:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:44:51 INFO - 'USER': 'cltbld', 17:44:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:44:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:51 INFO - 'XPC_FLAGS': '0x0', 17:44:51 INFO - 'XPC_SERVICE_NAME': '0', 17:44:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:51 INFO - Downloading/unpacking blobuploader==1.2.4 17:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:44:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:53 INFO - Downloading blobuploader-1.2.4.tar.gz 17:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:44:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:44: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 17:44: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 17:44: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 17:44: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 17:44: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 17:44: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 17:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:44:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:44:54 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 17:44:54 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 17:44:54 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 17:44:54 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 17:44:54 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 17:44:54 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 17:44:54 INFO - Downloading docopt-0.6.1.tar.gz 17:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:44:54 INFO - Installing collected packages: blobuploader, requests, docopt 17:44:54 INFO - Running setup.py install for blobuploader 17:44:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:44:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:44:55 INFO - Running setup.py install for requests 17:44:55 INFO - Running setup.py install for docopt 17:44:55 INFO - Successfully installed blobuploader requests docopt 17:44:55 INFO - Cleaning up... 17:44:55 INFO - Return code: 0 17:44:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:55 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 0x100feea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b49457f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e92d30>, '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 0x100cb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e89b20>, '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.qsNTJr7zOs/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.z75GoxkKby/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 17:44:55 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 17:44:55 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 17:44:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsNTJr7zOs/Render', 17:44:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:44:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:44:55 INFO - 'HOME': '/Users/cltbld', 17:44:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:44:55 INFO - 'LOGNAME': 'cltbld', 17:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:55 INFO - 'MOZ_NO_REMOTE': '1', 17:44:55 INFO - 'NO_EM_RESTART': '1', 17:44:55 INFO - 'PAGER': '/bin/cat', 17:44:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:55 INFO - 'PWD': '/builds/slave/test', 17:44:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:44:55 INFO - 'SHELL': '/bin/bash', 17:44:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z75GoxkKby/Listeners', 17:44:55 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 17:44:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:44:55 INFO - 'USER': 'cltbld', 17:44:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:44:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:55 INFO - 'XPC_FLAGS': '0x0', 17:44:55 INFO - 'XPC_SERVICE_NAME': '0', 17:44:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:44:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-Qzt086-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:44:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-dD5qOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:44:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-k24QlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:44:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-fD69Xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:44:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-27OnVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:44:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-uTqxi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:44:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-uF_QEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:44:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-JBpajD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:44:56 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-hG_nL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:44:57 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-R3IMpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:44:57 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-iWR_ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:44:57 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-4f31CQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:44:57 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-_JD7Hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:44:57 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-ChtbFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:44:57 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-UOt1q6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:44:57 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-2NM_sI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:44:57 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-S8mQdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:44:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:44:57 INFO - Running setup.py install for manifestparser 17:44:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:44:57 INFO - Running setup.py install for mozcrash 17:44:58 INFO - Running setup.py install for mozdebug 17:44:58 INFO - Running setup.py install for mozdevice 17:44:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Running setup.py install for mozfile 17:44:58 INFO - Running setup.py install for mozhttpd 17:44:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Running setup.py install for mozinfo 17:44:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Running setup.py install for mozInstall 17:44:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:44:58 INFO - Running setup.py install for mozleak 17:44:59 INFO - Running setup.py install for mozlog 17:44:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:44:59 INFO - Running setup.py install for moznetwork 17:44:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:44:59 INFO - Running setup.py install for mozprocess 17:44:59 INFO - Running setup.py install for mozprofile 17:44:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:44:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:44:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:44:59 INFO - Running setup.py install for mozrunner 17:44:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:44:59 INFO - Running setup.py install for mozscreenshot 17:45:00 INFO - Running setup.py install for moztest 17:45:00 INFO - Running setup.py install for mozversion 17:45:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:45:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:45:00 INFO - Cleaning up... 17:45:00 INFO - Return code: 0 17:45:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:00 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 0x100feea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b49457f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e92d30>, '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 0x100cb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e89b20>, '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.qsNTJr7zOs/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.z75GoxkKby/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 17:45:00 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 17:45:00 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 17:45:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsNTJr7zOs/Render', 17:45:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:00 INFO - 'HOME': '/Users/cltbld', 17:45:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:00 INFO - 'LOGNAME': 'cltbld', 17:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:00 INFO - 'MOZ_NO_REMOTE': '1', 17:45:00 INFO - 'NO_EM_RESTART': '1', 17:45:00 INFO - 'PAGER': '/bin/cat', 17:45:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:00 INFO - 'PWD': '/builds/slave/test', 17:45:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:00 INFO - 'SHELL': '/bin/bash', 17:45:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z75GoxkKby/Listeners', 17:45:00 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 17:45:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:00 INFO - 'USER': 'cltbld', 17:45:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:00 INFO - 'XPC_FLAGS': '0x0', 17:45:00 INFO - 'XPC_SERVICE_NAME': '0', 17:45:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:45:00 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-_AnNWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:45:00 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)) 17:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:45:00 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-gxbCEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:45:00 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)) 17:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:45:00 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-QA0wBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:45:00 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)) 17:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:45:01 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-Iwv5P7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:45:01 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)) 17:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:45:01 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-X14JWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:45:01 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)) 17:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:45:01 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-vQkg7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:45:01 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)) 17:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:45:01 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-PFnHuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:45:01 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)) 17:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:45:01 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-GDOeYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:45:01 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)) 17:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:45:01 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-Mr7qqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:45:01 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)) 17:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:45:01 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-xL186O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:45:01 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)) 17:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:45:01 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-Fn2gRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:45:01 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)) 17:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:45:01 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-IvCa48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:45:01 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)) 17:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:45:01 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-2i0lrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:45:02 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)) 17:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:45:02 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-w2B_z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:45:02 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)) 17:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:45:02 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-i43EKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:45:02 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)) 17:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:45:02 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-FI9jzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:45:02 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)) 17:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:45:02 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-RGf6QO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:45:02 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)) 17:45:02 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)) 17:45:02 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)) 17:45:02 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)) 17:45:02 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)) 17:45:02 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)) 17:45:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:04 INFO - Downloading blessings-1.6.tar.gz 17:45:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:45:05 INFO - Installing collected packages: blessings 17:45:05 INFO - Running setup.py install for blessings 17:45:05 INFO - Successfully installed blessings 17:45:05 INFO - Cleaning up... 17:45:05 INFO - Return code: 0 17:45:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:45:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:45:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:45:05 INFO - Reading from file tmpfile_stdout 17:45:05 INFO - Current package versions: 17:45:05 INFO - blessings == 1.6 17:45:05 INFO - blobuploader == 1.2.4 17:45:05 INFO - docopt == 0.6.1 17:45:05 INFO - manifestparser == 1.1 17:45:05 INFO - mozInstall == 1.12 17:45:05 INFO - mozcrash == 0.17 17:45:05 INFO - mozdebug == 0.1 17:45:05 INFO - mozdevice == 0.48 17:45:05 INFO - mozfile == 1.2 17:45:05 INFO - mozhttpd == 0.7 17:45:05 INFO - mozinfo == 0.9 17:45:05 INFO - mozleak == 0.1 17:45:05 INFO - mozlog == 3.1 17:45:05 INFO - moznetwork == 0.27 17:45:05 INFO - mozprocess == 0.22 17:45:05 INFO - mozprofile == 0.28 17:45:05 INFO - mozrunner == 6.11 17:45:05 INFO - mozscreenshot == 0.1 17:45:05 INFO - mozsystemmonitor == 0.0 17:45:05 INFO - moztest == 0.7 17:45:05 INFO - mozversion == 1.4 17:45:05 INFO - psutil == 3.1.1 17:45:05 INFO - requests == 1.2.3 17:45:05 INFO - wsgiref == 0.1.2 17:45:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:45:05 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')]} 17:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:05 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')]} 17:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:05 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 0x100feea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b49457f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e92d30>, '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 0x100cb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e89b20>, '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.qsNTJr7zOs/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.z75GoxkKby/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 17:45:05 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 17:45:05 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 17:45:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsNTJr7zOs/Render', 17:45:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:05 INFO - 'HOME': '/Users/cltbld', 17:45:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:05 INFO - 'LOGNAME': 'cltbld', 17:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:05 INFO - 'MOZ_NO_REMOTE': '1', 17:45:05 INFO - 'NO_EM_RESTART': '1', 17:45:05 INFO - 'PAGER': '/bin/cat', 17:45:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:05 INFO - 'PWD': '/builds/slave/test', 17:45:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:05 INFO - 'SHELL': '/bin/bash', 17:45:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z75GoxkKby/Listeners', 17:45:05 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 17:45:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:05 INFO - 'USER': 'cltbld', 17:45:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:05 INFO - 'XPC_FLAGS': '0x0', 17:45:05 INFO - 'XPC_SERVICE_NAME': '0', 17:45:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:05 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)) 17:45:05 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)) 17:45:05 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)) 17:45:05 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)) 17:45:05 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)) 17:45:05 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)) 17:45:05 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)) 17:45:05 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)) 17:45:05 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)) 17:45:05 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)) 17:45:05 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)) 17:45:05 INFO - Cleaning up... 17:45:05 INFO - Return code: 0 17:45:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:45:05 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')]} 17:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:05 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')]} 17:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:05 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 0x100feea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b49457f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e92d30>, '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 0x100cb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e89b20>, '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.qsNTJr7zOs/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.z75GoxkKby/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 17:45:05 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 17:45:05 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 17:45:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsNTJr7zOs/Render', 17:45:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:05 INFO - 'HOME': '/Users/cltbld', 17:45:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:05 INFO - 'LOGNAME': 'cltbld', 17:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:05 INFO - 'MOZ_NO_REMOTE': '1', 17:45:05 INFO - 'NO_EM_RESTART': '1', 17:45:05 INFO - 'PAGER': '/bin/cat', 17:45:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:05 INFO - 'PWD': '/builds/slave/test', 17:45:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:05 INFO - 'SHELL': '/bin/bash', 17:45:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z75GoxkKby/Listeners', 17:45:05 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 17:45:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:05 INFO - 'USER': 'cltbld', 17:45:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:05 INFO - 'XPC_FLAGS': '0x0', 17:45:05 INFO - 'XPC_SERVICE_NAME': '0', 17:45:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:06 INFO - Downloading/unpacking jsonschema 17:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:45:08 INFO - Downloading/unpacking functools32 (from jsonschema) 17:45:08 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 17:45:08 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 17:45:08 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 17:45:08 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 17:45:08 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 17:45:08 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 17:45:08 INFO - Downloading functools32-3.2.3-2.tar.gz 17:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:45:08 INFO - warning: no files found matching '*.txt' 17:45:08 INFO - no previously-included directories found matching 'build' 17:45:08 INFO - no previously-included directories found matching 'dist' 17:45:08 INFO - no previously-included directories found matching '.git*' 17:45:08 INFO - Installing collected packages: jsonschema, functools32 17:45:08 INFO - Running setup.py install for functools32 17:45:09 INFO - warning: no files found matching '*.txt' 17:45:09 INFO - no previously-included directories found matching 'build' 17:45:09 INFO - no previously-included directories found matching 'dist' 17:45:09 INFO - no previously-included directories found matching '.git*' 17:45:09 INFO - Successfully installed jsonschema functools32 17:45:09 INFO - Cleaning up... 17:45:09 INFO - Return code: 0 17:45:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100feea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b49457f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e92d30>, '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 0x100cb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e89b20>, '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.qsNTJr7zOs/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.z75GoxkKby/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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 17:45:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsNTJr7zOs/Render', 17:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:09 INFO - 'HOME': '/Users/cltbld', 17:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:09 INFO - 'LOGNAME': 'cltbld', 17:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:09 INFO - 'MOZ_NO_REMOTE': '1', 17:45:09 INFO - 'NO_EM_RESTART': '1', 17:45:09 INFO - 'PAGER': '/bin/cat', 17:45:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:09 INFO - 'PWD': '/builds/slave/test', 17:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:09 INFO - 'SHELL': '/bin/bash', 17:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z75GoxkKby/Listeners', 17:45:09 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 17:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:09 INFO - 'USER': 'cltbld', 17:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:09 INFO - 'XPC_FLAGS': '0x0', 17:45:09 INFO - 'XPC_SERVICE_NAME': '0', 17:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:09 INFO - Downloading/unpacking flake8 17:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:45:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:45:11 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 17:45:11 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 17:45:11 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 17:45:11 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 17:45:11 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 17:45:11 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 17:45:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:45:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:45:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:45:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:45:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:45:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:45:13 INFO - no previously-included directories found matching 'docs/_build' 17:45:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:45:13 INFO - Running setup.py install for pep8 17:45:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:45:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:45:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:45:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:45:13 INFO - no previously-included directories found matching 'docs/_build' 17:45:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:45:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:45:13 INFO - Cleaning up... 17:45:13 INFO - Return code: 0 17:45:13 INFO - Running post-action listener: _resource_record_post_action 17:45:13 INFO - Running post-action listener: _start_resource_monitoring 17:45:13 INFO - Starting resource monitoring. 17:45:13 INFO - ##### 17:45:13 INFO - ##### Finished create-virtualenv step (success) 17:45:13 INFO - ##### 17:45:13 INFO - ##### 17:45:13 INFO - ##### Running install step. 17:45:13 INFO - ##### 17:45:13 INFO - Running pre-action listener: _resource_record_pre_action 17:45:13 INFO - Running main action method: install 17:45:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:45:13 INFO - Reading from file tmpfile_stdout 17:45:13 INFO - Detecting whether we're running mozinstall >=1.0... 17:45:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:45:13 INFO - Reading from file tmpfile_stdout 17:45:13 INFO - Output received: 17:45:13 INFO - Usage: mozinstall [options] installer 17:45:13 INFO - Options: 17:45:13 INFO - -h, --help show this help message and exit 17:45:13 INFO - -d DEST, --destination=DEST 17:45:13 INFO - Directory to install application into. [default: 17:45:13 INFO - "/builds/slave/test"] 17:45:13 INFO - --app=APP Application being installed. [default: firefox] 17:45:13 INFO - mkdir: /builds/slave/test/build/application 17:45:13 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'] 17:45:13 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 17:45:47 INFO - Reading from file tmpfile_stdout 17:45:47 INFO - Output received: 17:45:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:45:47 INFO - Running post-action listener: _resource_record_post_action 17:45:47 INFO - ##### 17:45:47 INFO - ##### Finished install step (success) 17:45:47 INFO - ##### 17:45:47 INFO - ##### 17:45:47 INFO - ##### Running run-tests step. 17:45:47 INFO - ##### 17:45:47 INFO - Running pre-action listener: _resource_record_pre_action 17:45:47 INFO - Running main action method: run_tests 17:45:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:45:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:45:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:45:48 INFO - 2016-05-10 17:45:48.007 screenresolution[2032:15353] starting screenresolution argv=screenresolution get 17:45:48 INFO - 2016-05-10 17:45:48.010 screenresolution[2032:15353] Display 0: 1600x1200x32@60 17:45:48 INFO - 2016-05-10 17:45:48.015 screenresolution[2033:15355] starting screenresolution argv=screenresolution list 17:45:48 INFO - Available Modes on Display 0 17:45:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:45:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:45:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:45:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:45:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:45:48 INFO - Intel Iris: 17:45:48 INFO - Chipset Model: Intel Iris 17:45:48 INFO - Type: GPU 17:45:48 INFO - Bus: Built-In 17:45:48 INFO - VRAM (Dynamic, Max): 1536 MB 17:45:48 INFO - Vendor: Intel (0x8086) 17:45:48 INFO - Device ID: 0x0a2e 17:45:48 INFO - Revision ID: 0x0009 17:45:48 INFO - Displays: 17:45:48 INFO - Display: 17:45:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:45:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:45:48 INFO - Display Serial Number: 1600x1200 60 17:45:48 INFO - Main Display: Yes 17:45:48 INFO - Mirror: Off 17:45:48 INFO - Online: Yes 17:45:48 INFO - Rotation: Supported 17:45:48 INFO - Return code: 0 17:45:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:45:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:45:48 INFO - Python 2.7.3 17:45:48 INFO - Return code: 0 17:45:48 INFO - grabbing minidump binary from tooltool 17:45:48 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')]} 17:45:48 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 0x100e92d30>, '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 0x100cb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e89b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:45:48 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 17:45:48 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 17:45:48 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 17:45:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:45:48 INFO - Return code: 0 17:45:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:45:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:45:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:45:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:45:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:45:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:45:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:45:49 INFO - Return code: 0 17:45:49 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/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0138', '--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 17:45:49 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/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0138 --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 17:45:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qsNTJr7zOs/Render', 17:45:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:49 INFO - 'HOME': '/Users/cltbld', 17:45:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:49 INFO - 'LOGNAME': 'cltbld', 17:45:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:45:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:45:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:49 INFO - 'MOZ_NO_REMOTE': '1', 17:45:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:45:49 INFO - 'NO_EM_RESTART': '1', 17:45:49 INFO - 'PAGER': '/bin/cat', 17:45:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:49 INFO - 'PWD': '/builds/slave/test', 17:45:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:45:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:49 INFO - 'SHELL': '/bin/bash', 17:45:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z75GoxkKby/Listeners', 17:45:49 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 17:45:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:49 INFO - 'USER': 'cltbld', 17:45:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:49 INFO - 'XPC_FLAGS': '0x0', 17:45:49 INFO - 'XPC_SERVICE_NAME': '0', 17:45:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:49 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/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0138', '--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 17:45:49 INFO - mozversion application_buildid: 20160510153811 17:45:49 INFO - mozversion application_changeset: 5467a8d9d844fff2b8916999ebd1f4e90289e2d1 17:45:49 INFO - mozversion application_display_name: Nightly 17:45:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:45:49 INFO - mozversion application_name: Firefox 17:45:49 INFO - mozversion application_remotingname: firefox 17:45:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:45:49 INFO - mozversion application_vendor: Mozilla 17:45:49 INFO - mozversion application_version: 49.0a1 17:45:49 INFO - mozversion platform_buildid: 20160510153811 17:45:49 INFO - mozversion platform_changeset: 5467a8d9d844fff2b8916999ebd1f4e90289e2d1 17:45:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:45:49 INFO - mozversion platform_version: 49.0a1 17:45:49 INFO - using testdate: 1462927549 17:45:49 INFO - actual date: 1462927549 17:45:49 INFO - starting webserver on 'localhost:51527' 17:45:49 INFO - SUITE-START | Running 2 tests 17:45:49 INFO - TEST-START | dromaeo_css 17:45:49 INFO - operating with platform_type : mac_ 17:45:49 INFO - Initialising browser for dromaeo_css test... 17:45:49 INFO - TEST-INFO | started process 2051 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/tmp6thZDl/profile http://localhost:51527/getInfo.html) 17:45:52 INFO - PROCESS | 2051 | __metrics Screen width/height:1600/1200 17:45:52 INFO - PROCESS | 2051 | colorDepth:24 17:45:52 INFO - PROCESS | 2051 | Browser inner width/height: 1024/689 17:45:52 INFO - PROCESS | 2051 | __metrics 17:45:52 INFO - PROCESS | 2051 | 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" 17:45:52 ERROR - PROCESS | 2051 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:45:52 INFO - TEST-INFO | 2051: exit 0 17:45:52 INFO - Browser initialized. 17:45:52 INFO - Running cycle 1/1 for dromaeo_css test... 17:45:52 INFO - TEST-INFO | started process 2053 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/tmp6thZDl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:46:04 INFO - PROCESS | 2053 | RSS: Main: 238612480 17:46:04 INFO - PROCESS | 2053 | 17:46:55 INFO - PROCESS | 2053 | 1462927615821 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] 17:49:07 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51527/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51527/tests/dromaeo/cssquery-ext.html) 17:49:09 INFO - PROCESS | 2053 | RSS: Main: 350277632 17:49:09 INFO - PROCESS | 2053 | 17:52:12 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51527/tests/dromaeo/cssquery-ext.html (next: http://localhost:51527/tests/dromaeo/cssquery-jquery.html) 17:52:12 INFO - PROCESS | 2053 | RSS: Main: 374280192 17:52:12 INFO - PROCESS | 2053 | 17:55:14 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51527/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51527/tests/dromaeo/cssquery-mootools.html) 17:55:15 INFO - PROCESS | 2053 | RSS: Main: 370339840 17:55:15 INFO - PROCESS | 2053 | 17:58:17 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51527/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51527/tests/dromaeo/cssquery-prototype.html) 17:58:18 INFO - PROCESS | 2053 | RSS: Main: 371920896 17:58:18 INFO - PROCESS | 2053 | 18:01:21 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51527/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51527/tests/dromaeo/cssquery-yui.html) 18:01:22 INFO - PROCESS | 2053 | RSS: Main: 343683072 18:01:22 INFO - PROCESS | 2053 | 18:04:25 INFO - PROCESS | 2053 | Cycle 1(1): loaded http://localhost:51527/tests/dromaeo/cssquery-yui.html (next: http://localhost:51527/tests/dromaeo/cssquery-dojo.html) 18:04:25 INFO - PROCESS | 2053 | RSS: Main: 389591040 18:04:25 INFO - PROCESS | 2053 | 18:04:25 INFO - PROCESS | 2053 | __start_tp_report 18:04:25 INFO - PROCESS | 2053 | _x_x_mozilla_page_load 18:04:25 INFO - PROCESS | 2053 | _x_x_mozilla_page_load_details 18:04:25 INFO - PROCESS | 2053 | |i|pagename|runs| 18:04:25 INFO - PROCESS | 2053 | |0;dojo.html;3408.7613867029318;3413.1467133216697;3419.9316013679736;3436.690697837194;3469.4448888177894;5647.971760141204;5660.179274005277;5673.205751194826;5696.658200507973;5721.370649228586;5720.914186287209;5741.511971482429;5753;5753.942460575393;5754.223179870716;5151.253068305094;5186.014657215117;5218.060749065166;5236.214567814839;5286.894262114755;5273.973630131844;5279.656822306557;5291.206319052135;5300.416954135042;5305.257263983026;5174.637775355726;5194.71429071401;5206.661566998152;5222.477752224785;5286.65636733613;5282.656627319231;5297.841064768063;5302.072137375943;5303.310569625924;5344.198370244456;5091.949080509185;5099.184130539096;5107.131787596118;5199.922001169985;5219.451957544479;5566.081596536566;5577.163425486169;5595.832125036255;5609.52319052881;5639.210510528529;6278.497720182374;6279.434850863444;6285.87428251437;6289.930711779009;6296.590721603081;5068.594512439033;6253.249610046778;6271.6864156792435;6363.9045414318825;6378.107065010902;5796.594238403313;5830.7084645767545;5841.9415805842245;5862.560307976919;5876.265466816648;6322.747090116344;6327.525435592257;6329.082283069021;6336;6373;5151.304573882478;5999.370066142992;6043.516518678495;6046.153538504613;6175.44421002112;5292.603054770908;6208.00671892495;6229.470495007885;6278.717457714414;6279.215098112736;6171.382861713792;6231.813045608639;6261.060840873905;6266.216722909637;6278.372162783685;5753.3958934311295;5973.342932277446;6293.181886354744;6302.1177035215105;6304.779332723333;4105.712600118023;4828.348172996671;4834.371531700856;4846.3699719037;4898.191798353303;4381;4436.578525040116;4451.955480445219;4483.036147228361;4498.212812757754;5692.743826527816;5714.428557144335;5720.856978575485;5734.39788822176;5746.712664366765;6344.143540622049;6344.270408902943;6347.460465860361;6364.427201551883;6378.936210637927;5620.634658747168;5671.886562268732;5688.317401911797;5752.3097228332035;5764;5369.5972802040005;5384.19237114436;5393.460653934575;5402.729863506809;5434.619576629677;6342.39747224013;6359.332270111757;6363.268224154282;6364.363563643598;6377.585456945284;6142.253225532511;6276.058591211355;6311.90532142009;6321.905171422341;6381.361863813581;6315.652639104709;6333.36666333363;6341.968290158704;6348.111264422892;6350.555461117678;6233.594816336924;6256.468200203034;6263.561550691408;6267.122602835698;6274.717637706405;6113.537117903944;6153.876922461436;6241.78153764616;6299.4645455135;6329.683515824282;6173.629582225081;6183.319834818257;6188.412100850412;6196.380361963767;6200.534959878118;6075.665838378932;6164.352742962104;6165.475934545434;6172.660503672341;6176.227971503562;5814.5348372130975;5886.58793884424;5898.144769008384;5966.582339236212;6024.307204671519;6089.311220441179;6097.024476083805;6119.704772054934;6168.907466388097;6189.907151392643;6031.125486804301;6047.637141771508;6047.758089676364;6049.153118563493;6064.757409703739 18:04:25 INFO - PROCESS | 2053 | |1;ext.html;499.6427554298677;524.7035424984883;528.5533724003216;531.6012990257307;541.721013677956;30326.393472130545;32159.03522894317;32209.51685724713;32315.676843231562;32336.514952275706;44602.7769861151;45896;45901.770491147494;48143.27785083218;48268.517314826844;9612.951935240331;9754.41473511588;9754.707358779247;9766.462844543546;9793.951030244838;8391.951006124234;8437.831243375129;8713.259372953307;9677.225821934227;9754.658586949457;11077.446127693624;11910.523579056871;11931.701707457296;11991.220570662923;12052.517899284017;11847.703807404798;11948;11955.880441195564;11969.341686207255;11996.700082497964;8614.440061396052;11366.943165284203;11644.76710465786;11660.825087623693;11797.351145686984;41672.583274167475;42093.57906420927;42111.57888421107;42158.367624485654;42289;3464.930701385958;3595.334863050344;3671.8898433046784;3783.5459744830796;3974.8012599370204;29001.419971600662;30274.243143921358;30556.54165187524;30866.69133308683;30876.53685194724;2768.4878297514824;2827.081198610439;2832.4759919414973;2858.8427636479987;2871.3826527296524;482216;483141;484209;484827;485183;64192.679036605456;64642;64779.676101620134;64819.67590162019;65010.67494662497;68767;68879.65560172166;68968.62062758717;68989.3101068993;69199;17014.57463563421;17257.913710431367;17263.309467621155;17265.654686906193;17267.91366043162;13259.337033148304;13345;13377.73244535104;13380.397882095134;13387.259656244625;16346.264418101215;16817.243224054946;18324.541886452946;18744.25022999065;18770.155343009596;6235.750569977241;6328.398802113884;6369.649669268235;6398.968005160037;6407.743690252338;919.3932004876749;983.8966908474507;993.2897977945659;1004.8191325561323;1051.3376572759266;29735.107946761633;29739.553906691417;29981.400371992437;30076.548851767242;31203.84398077995;1116.8273313021457;1124.9381284029457;1132.9773404532027;1139.1228753859648;1231.8090695942144;15176.165310908005;15189.316480758393;15304.387824486896;15529.844701553066;15624.76562851558;646853;650829;650915;651422.4857751457;653988;55008.624784380714;55898.72050639802;55999.880002399725;58082;58153.70923145442;1411.9576412707636;1433.8996270261398;1442.0554536778427;1466.9413223471156;1471.433498103238;24063.917123729123;29820.65807038645;30813.691863081083;30991;31479.685203147674;54608.45391546033;55230.79076836882;55634.72182639061;55832.44167558435;55925.88148237094;589324;592921;593398;637758;835689;36411;37745;38689;38773.41839872461;38836.61163388443;45462.54537454583;45776.08447830958;45794.31308530441;47385.763071184425;47546.762266188445;35997.64002360048;36390;37337;37364;38479.807600961816;36889;36915.89252322435;37261;37376.62623373731;37703.81148094242;37266.441003384425;37396.81301593475;38204.80897595494;38296.80851595836;38329.23341533098;30065.54901676433;30646.540301895937;32084.67915320817;32963.505547417284;33165.83417082899;21728.456788580414;24134.155304564258;24364.87817560901;24685.62971555392;24909.75090249147 18:04:25 INFO - PROCESS | 2053 | |2;jquery.html;393.44917116037544;394.5364197068446;394.67439362525926;397.5904818037421;417.4281234708448;20619.17523299068;20703.689444658343;21761.80310082945;21777.782222177775;21792.564148717036;16874.99999999997;20595.1761929523;20753.481162970933;20933.267335643257;20992.580148397024;5978.491828194608;6236.438720515152;6245.406686364788;6250.874982500347;6251;6072.605280656765;6133.969330153343;6170.228721409824;6172.259328880541;6231.813045608616;5974.402559744034;6124.693765311717;6133.049377346496;6135.5398345124295;6173.660448675321;6036.0945858121195;6107.816765497042;6130.785422510213;6133.478654314388;6145.631262124264;4571.497135315146;5211.765470553834;5370.355557333145;5372.785088596452;5391.110466772978;23578.76421235783;24966;25687;26203.606945895826;26659.600105998426;2097.0668052716396;2369.644553316999;2522.255934499327;2800.551911694119;2806.733360330765;9868.90131098687;9911.157551608121;9964.202863770954;10025.147862431702;10275.948620256926;1741.2860727101954;1747.7465767463773;1768.3810666266832;1848.5009047557085;1850;115943.42028289805;118314.40842795817;126297;126827;129677;9519.571619277149;10179.389236645677;10281.383117013003;10431.269811113149;10584.470776461147;12688.80966785499;12696.238225706487;12792.488300467878;12802.16785908913;12811.295378754072;7442.8883566746545;7901.565413902175;8178.959105204436;8335.911690437513;8493.575321234037;6995.79012629622;8669.523176225368;8674.56627168639;8772.78068048291;8863.64545418189;3698.167912219783;3761.717871159674;4030.536488303824;4052.797360131982;4057.431959525669;1951.200007996743;1990.9502262443266;2003.4590660521578;2015.083137172583;2042.458748431667;734.98050962616;925.2413021322585;957.1577012229333;1007.4610083605236;1065.9200559957878;15594.688106237812;15907.443239486562;20868.89565552193;21186.78813211879;21194.682079768816;909.4361495872602;957.2485598804905;968.3366893677853;979.715882394112;983.1446641422009;10810.783784324269;11103;11140;11172.888271117346;11194.496247668874;549901;551279;551825;589870;592774;129061;130593;131791;132393.73243873028;134875.65124348828;1003.1573478278282;1023.9846402303972;1025.7281820317473;1027.1525991057408;1028.228828378716;13538;18369.632607348045;18610.720839187692;19064.332748354296;19295.324663636704;38030;50681.49318506768;52046.739766300925;53531.46468535421;55726.16410753923;78351;78680.60659696665;79079;79308.01729956797;79675.80486292594;7976;8033.076196237507;8113.959430202811;8176.877346839683;8371.83256334882;7594.962025189839;8667.4366166199;10513.001264879824;12277.079219058427;12616.810747838606;5606;7319.560826350436;10906.890931090587;10959.671209863716;11186.049185819296;10269.332493387907;10793.02862742357;10819.56721731122;10895.291806032583;10914.23600347968;11037.227394082338;11468.770624587629;11490.310581365145;11517.942410287895;11533.884661153617;7044.506884518035;7767.3786097113225;8318.958405207935;8349.916500834914;8413.45312554682;10439.269251152346;11137.777244455228;11296.83054754163;11438.885611143782;11460.426978651201 18:04:25 INFO - PROCESS | 2053 | |3;mootools.html;1433.2188957018425;1599.6800639872024;1623.1802939515546;1875.8311751942322;1985.7815640279557;7909.92090079099;8353.415260931737;8364.665413383473;8376.874346884802;8378.748637540868;7775.883361749578;8179.713710020151;8362.456440331374;8461.323094152469;8804.77988050299;2345.824063195263;2434.9756502435016;2435.914742983996;2436.293474892288;2443.7189723181796;2392.8085753139794;2423.321469988406;2445.425325048612;2448.081969261527;2450.8774561271866;2406.061635961976;2423.6364545318165;2436.512697460506;2439.7560243975636;2443.059422122478;2078.771335153132;2385.2963375804006;2385.3440303916386;2406.74729153439;2425.4906469641396;1993.5813479169392;2230.3309007297744;2258.085475382473;2398.7721166489155;2439.4633180700216;8650.87023694645;8734.737957861274;8738.475691458469;8740.257078148365;8741.388102832805;5276.472352764731;5331.093714068617;5423.430539793344;5470.56235501159;5546.417626149277;3478.7738796978115;3637.3089113068695;3682.6869716073898;3695.6304369562827;3703.574088979748;1058.6929790360864;1058.772363941756;1068.5405275731432;1080.286022370968;1080.6487891435315;11520.366379849105;11594.594189203337;11617.361045142514;11657.76684466306;11660.416979150923;4573.222552166139;4592.7014744041535;4652.464966528825;4701.318308845267;4714.316424118484;9152.405093668891;9159.267258619295;9168.083191680778;9178.678746243982;9181.219596334386;5248.580113590903;5297.443768404339;5324.8136315228785;5328.8401347959625;5328.946710532845;4470.508244093147;4630.791614377361;4821;4886.975565122151;4954.950450495521;3203.7757356985253;3836.731428799957;3920.8235629396745;3948.9012774680864;3958.218801539319;1199.9580014699443;1207.7765613361555;1218.8171774233936;1228.5085965613648;1233.6114124050896;597.5548216578677;819.4468733604793;903.6837107012625;928.2434815625244;975.8682577852042;5668.404817494186;5852.531797456193;6030.7889223876955;6072.483838873657;6087.147799308101;468.62458683763265;470.50361868228663;483.4223103391484;507.2467385931888;643.7703985387886;3847.461355410189;4003.7597744135446;4080.836766529365;4087.550369459357;4095;10333.276670633133;10840.512176952056;10963.890361096446;11118;11123.721906952229;10922;11060.778784424267;11099.056580190612;11102.167337449724;11153.832692509619;8239.670413183405;8399.244068033906;8418.284445822173;8481.915180848237;8506.064332923374;4626.560476754703;5803.970980145241;5961.552883533665;6065;6192.071189321638;10234.590616375559;10385.273030887765;10517.10604598587;10522.526486307981;10526.52630631608;9670.85493717609;10089.192864570963;10109.292349535463;10264.948675256575;10360.326578772356;7495.512791668524;7644.732434364993;7650.158482567024;7667.616619169131;7692.846143076995;8269.338452923632;8297.419180657296;8356.164383561596;8401.285890699115;8425;7498.812529686801;7610.391168706601;7627.5042122261875;7639.312461878458;7690.154083050748;7439.367653749492;7465.664045117874;7517.624118794148;7590.506617069873;7592.126905405728;6977.33715297046;7175.820604484721;7203.387712044535;7204.423646108194;7212.3148300911325;4917.041176970456;4936.4569897311985;4967.850964471072;5014.824481143142;5180.326557547494;4926.866820631203;5027.874303142451;5062.443131255485;5093.694378337312;5235.083860324504 18:04:25 INFO - PROCESS | 2053 | |4;prototype.html;304.82777230864565;310.68775880240355;314.88349310755024;317.37160422363735;321.53377602476417;5229.477052294768;5239.52844244018;5250.65870718403;5263.47365263474;5273.6572122811995;4794.801299675081;5161.354830646169;5199.402068762093;5238.659487133334;5241.030409374269;3295.4397752382033;3622.5471816022996;3651.470536772173;3921.1569512554797;3940.074082590589;3812.237552489499;3991.900202494932;4010.5387880393837;4016.0361513236903;4016.9154328331333;3416.5387672664247;3559.056849934769;3864.2078373933277;3929.9214015719667;3940.625640564142;3260.983695081533;3629.6007439181667;3645.1798345372213;3917.5886531914166;3923.391874259466;2985.3132979866523;3068.787828807631;3440.088376580208;3456.9110730120183;3472.3402553515;4858.643557874428;5055.469175736532;5066.164082926312;5066.214736715815;5086.100922474191;3663.725220608465;3887.8833634990715;4480.977595112036;4502.392177056121;4534.478534968488;4441.022974945507;4604.654650901196;5241.554467870235;5429.99090336574;5466.289382380265;3271.1985563536846;3647.3434781739275;3736.7010639148803;3759.642833930772;3792.3742582474133;5469.15228139639;5541.806036788693;5783.132530120473;5797.536197104221;5817.912731309034;3540.7961293160447;3613.7351926825927;3688.2807852469045;3691.412692542179;3828.349180639242;3862.343401621731;3874.49631547897;3893.4159876018257;3907.706921980806;3910.687145028391;3818.6181381861593;4478.171538265431;4492.213862574036;4549.7042692224895;4561.7262964222255;4848.236505192007;4908.213714917322;5082.695038297714;5232.424433312332;5325.520703136736;1248.5754843353122;1295.6501744528925;1302.374860067154;1308.5616318533207;1322.5833862333334;764.9349805266614;785.8389030248785;801.8276070644839;810.1372038778682;810.7770363150063;4936.555709986047;4961.875953101201;5092.606474956764;5102.413222479462;5139;4782.641301902371;5383.461653834585;5579.414161513064;5604.60767746262;5638.577106717013;3956.188981258835;4502.752348620857;4565.634749220055;5132.5124113209185;5134.974325128425;2435.269419174276;2952.630921134858;2969.391274788663;2972.7175918288162;2992.5361568956846;5672.75199456119;5899.823005309848;5900.64596124234;5909.734061967221;5919.200907877382;5002.574781143643;5399;5584.492187109539;5663.32040155184;5851.297844258631;5849.824505264849;5855.355910849803;5885.47030767233;5890.849839808439;5919.9936010878255;5401.918971215357;5450.863728406822;5614.719264036864;5637.49262566371;5654.575906806924;5542.307211598551;5566;5571.7492712828835;5577.609567330248;5635;5278.604104692086;5510.724463776875;5819.82540523785;5885.675722962299;5906.527477801851;3666.973247490707;3708.905872767486;3769.2649933263847;3810.809459527068;3817.61823817616;4761.595264402565;4977.9502204978935;5237.973810130925;5623.69069701154;5657.60396772222;4862.294967229803;5440;5487.176923461513;5489.204065410573;5499.010178167968;5247.449017853223;5416.320940508297;5416.75624596902;5428.294321738149;5526.91709624364;5122.257272695513;5328.495607064673;5352.5450336721815;5362.410134885082;5382.434844341287;5184.507471790174;5320.308359913187;5337.22610221508;5347.732613369239;5375.596830237826;4461.531539188339;4754.836549369959;5272.6309158358545;5298.44366341544;5303.920441193308 18:04:25 INFO - PROCESS | 2053 | |5;yui.html;652.4226059936956;724.6920058974935;742.109468637635;755.4522970846135;788.2669117720521;15152;15531;15562.84437155628;15563.766543501857;15614.219289035545;14083.788743168861;14130.897358498038;14199.077059991094;14243.501477448266;14312.212828294465;913.6436789652041;924.5515924776478;928.9581968811386;930.6370515498961;932.4265576670333;908.318760929303;922.8062106957514;928.0858354520792;931.4783721116197;934.4626839567276;981.2836629260644;982.3663736889702;982.5283863745407;983.4394395194741;984.916282116021;976.1751320134475;976.7313988653107;977.1986970684042;977.2426369563574;977.4965892565299;717.9174394944596;718.5257729898294;719.1442183801312;719.327428854018;720.020771750419;13482.86517134826;13491.527796527173;13496;13513.189208647513;13532.45870165202;11894.286342819458;11894.881051189464;12135.208901952601;12230.938845305805;12430.813537796941;9292.674756383562;9954.104130628279;9985.450800205987;10487.685369438928;10670.466476676134;3354.1614596350914;3439.2605589798063;3441.397755392796;3541.7166626669805;3686.686631636314;66099;80955.19044809535;81630.18369816286;87067;89280.99999999935;9377;9461.38500997433;9495.857562136573;9508.191803696764;10135.493225338703;21088.78911210899;21090.789092108884;21121.471963200736;21139.894300528398;21161.15355385798;7614.162442131361;7655.885161722578;7664.6934122635585;7781.961090194626;7899.368050555941;4136.9793151034655;4183.288840897055;4205.537390887;4209.536950935395;4327.307630779123;3632.8365223564474;3733.775973441602;3736.682381997506;3740.0275928258848;3755.830987605564;670.1656437734954;683.3951952521954;683.6820878291618;688.7348370877248;691.761342336893;449.8551187228511;471.530826827309;476.4235275316836;528.9550388217044;602.7076867719153;7963.243491868263;7967.521948683097;7990.320822730133;8132.308753755997;8245.980434283007;527.5384038965905;571.8913406452843;592.5851903667364;598.9341172471024;611.0803241122171;3828.636279553438;3971.9801400993388;4139.461869956886;4254.29804082323;4275.893102672396;82643.10749123752;89508;93059;93738;98308;79989.80015299775;83360;83862.58068709617;84308;85424.57287713522;73335.26664733284;74147.25852741403;74516;74803.62598186974;74880.62559687167;8037.31682806968;8244.793880152803;8358.832823343377;8452.366072544706;8508.872366914378;42579.148417032105;43358.56641433672;43904.12191756083;44797.520247976245;44851.551484484735;57233.42766572448;59224;60051.0992335124;60082.39917600943;61346;11007.504662290055;11198.776024479628;11201.66395008151;11458.541658333574;11674.824877627012;11840.881591184323;12030.27818330903;12139.150259481752;12201.267923924594;13250.867491325349;11809.232399894323;11835.704107397383;12033.977111945233;12056.75886482248;12136.453859576164;9764.804703906024;11120.777584448428;11753.177277590486;11899.643010709693;12042.337671428155;11263.380514071805;11397.259178153394;11517.82723259135;11628.069754419796;11833.644990650295;8178.263956243966;9550.522473876139;9654.2276617869;9773.267004974514;9788.363756356099;9073.954630226806;9449.102335278138;9617.903820961981;9750.80498390014;10007.199424045913 18:04:25 INFO - PROCESS | 2053 | __end_tp_report 18:04:25 INFO - PROCESS | 2053 | __start_cc_report 18:04:25 INFO - PROCESS | 2053 | _x_x_mozilla_cycle_collect,1487 18:04:25 INFO - PROCESS | 2053 | __end_cc_report 18:04:25 INFO - PROCESS | 2053 | __startTimestamp1462928665341__endTimestamp 18:04:25 INFO - PROCESS | 2053 | 18:04:25 INFO - PROCESS | 2053 | ------- Summary: start ------- 18:04:25 INFO - PROCESS | 2053 | Number of tests: 6 18:04:25 INFO - PROCESS | 2053 | 18:04:25 INFO - PROCESS | 2053 | [#0] dojo.html Cycles:165 Average:5748.56 Median:5966.58 stddev:655.74 (11.0%) stddev-sans-first:631.54 18:04:25 INFO - PROCESS | 2053 | Values: 3408.8 3413.1 3419.9 3436.7 3469.4 5648.0 5660.2 5673.2 5696.7 5721.4 5720.9 5741.5 5753.0 5753.9 5754.2 5151.3 5186.0 5218.1 5236.2 5286.9 5274.0 5279.7 5291.2 5300.4 5305.3 5174.6 5194.7 5206.7 5222.5 5286.7 5282.7 5297.8 5302.1 5303.3 5344.2 5091.9 5099.2 5107.1 5199.9 5219.5 5566.1 5577.2 5595.8 5609.5 5639.2 6278.5 6279.4 6285.9 6289.9 6296.6 5068.6 6253.2 6271.7 6363.9 6378.1 5796.6 5830.7 5841.9 5862.6 5876.3 6322.7 6327.5 6329.1 6336.0 6373.0 5151.3 5999.4 6043.5 6046.2 6175.4 5292.6 6208.0 6229.5 6278.7 6279.2 6171.4 6231.8 6261.1 6266.2 6278.4 5753.4 5973.3 6293.2 6302.1 6304.8 4105.7 4828.3 4834.4 4846.4 4898.2 4381.0 4436.6 4452.0 4483.0 4498.2 5692.7 5714.4 5720.9 5734.4 5746.7 6344.1 6344.3 6347.5 6364.4 6378.9 5620.6 5671.9 5688.3 5752.3 5764.0 5369.6 5384.2 5393.5 5402.7 5434.6 6342.4 6359.3 6363.3 6364.4 6377.6 6142.3 6276.1 6311.9 6321.9 6381.4 6315.7 6333.4 6342.0 6348.1 6350.6 6233.6 6256.5 6263.6 6267.1 6274.7 6113.5 6153.9 6241.8 6299.5 6329.7 6173.6 6183.3 6188.4 6196.4 6200.5 6075.7 6164.4 6165.5 6172.7 6176.2 5814.5 5886.6 5898.1 5966.6 6024.3 6089.3 6097.0 6119.7 6168.9 6189.9 6031.1 6047.6 6047.8 6049.2 6064.8 18:04:25 INFO - PROCESS | 2053 | 18:04:25 INFO - PROCESS | 2053 | [#1] ext.html Cycles:180 Average:72981.39 Median:37301.72 stddev:161275.09 (432.4%) stddev-sans-first:161635.70 18:04:25 INFO - PROCESS | 2053 | Values: 499.6 524.7 528.6 531.6 541.7 30326.4 32159.0 32209.5 32315.7 32336.5 44602.8 45896.0 45901.8 48143.3 48268.5 9613.0 9754.4 9754.7 9766.5 9794.0 8392.0 8437.8 8713.3 9677.2 9754.7 11077.4 11910.5 11931.7 11991.2 12052.5 11847.7 11948.0 11955.9 11969.3 11996.7 8614.4 11366.9 11644.8 11660.8 11797.4 41672.6 42093.6 42111.6 42158.4 42289.0 3464.9 3595.3 3671.9 3783.5 3974.8 29001.4 30274.2 30556.5 30866.7 30876.5 2768.5 2827.1 2832.5 2858.8 2871.4 482216.0 483141.0 484209.0 484827.0 485183.0 64192.7 64642.0 64779.7 64819.7 65010.7 68767.0 68879.7 68968.6 68989.3 69199.0 17014.6 17257.9 17263.3 17265.7 17267.9 13259.3 13345.0 13377.7 13380.4 13387.3 16346.3 16817.2 18324.5 18744.3 18770.2 6235.8 6328.4 6369.6 6399.0 6407.7 919.4 983.9 993.3 1004.8 1051.3 29735.1 29739.6 29981.4 30076.5 31203.8 1116.8 1124.9 1133.0 1139.1 1231.8 15176.2 15189.3 15304.4 15529.8 15624.8 646853.0 650829.0 650915.0 651422.5 653988.0 55008.6 55898.7 55999.9 58082.0 58153.7 1412.0 1433.9 1442.1 1466.9 1471.4 24063.9 29820.7 30813.7 30991.0 31479.7 54608.5 55230.8 55634.7 55832.4 55925.9 589324.0 592921.0 593398.0 637758.0 835689.0 36411.0 37745.0 38689.0 38773.4 38836.6 45462.5 45776.1 45794.3 47385.8 47546.8 35997.6 36390.0 37337.0 37364.0 38479.8 36889.0 36915.9 37261.0 37376.6 37703.8 37266.4 37396.8 38204.8 38296.8 38329.2 30065.5 30646.5 32084.7 32963.5 33165.8 21728.5 24134.2 24364.9 24685.6 24909.8 18:04:25 INFO - PROCESS | 2053 | 18:04:25 INFO - PROCESS | 2053 | [#2] jquery.html Cycles:180 Average:34099.32 Median:26431.60 stddev:95323.57 (360.6%) stddev-sans-first:95557.38 18:04:25 INFO - PROCESS | 2053 | Values: 393.4 394.5 394.7 397.6 417.4 20619.2 20703.7 21761.8 21777.8 21792.6 16875.0 20595.2 20753.5 20933.3 20992.6 5978.5 6236.4 6245.4 6250.9 6251.0 6072.6 6134.0 6170.2 6172.3 6231.8 5974.4 6124.7 6133.0 6135.5 6173.7 6036.1 6107.8 6130.8 6133.5 6145.6 4571.5 5211.8 5370.4 5372.8 5391.1 23578.8 24966.0 25687.0 26203.6 26659.6 2097.1 2369.6 2522.3 2800.6 2806.7 9868.9 9911.2 9964.2 10025.1 10275.9 1741.3 1747.7 1768.4 1848.5 1850.0 115943.4 118314.4 126297.0 126827.0 129677.0 9519.6 10179.4 10281.4 10431.3 10584.5 12688.8 12696.2 12792.5 12802.2 12811.3 7442.9 7901.6 8179.0 8335.9 8493.6 6995.8 8669.5 8674.6 8772.8 8863.6 3698.2 3761.7 4030.5 4052.8 4057.4 1951.2 1991.0 2003.5 2015.1 2042.5 735.0 925.2 957.2 1007.5 1065.9 15594.7 15907.4 20868.9 21186.8 21194.7 909.4 957.2 968.3 979.7 983.1 10810.8 11103.0 11140.0 11172.9 11194.5 549901.0 551279.0 551825.0 589870.0 592774.0 129061.0 130593.0 131791.0 132393.7 134875.7 1003.2 1024.0 1025.7 1027.2 1028.2 13538.0 18369.6 18610.7 19064.3 19295.3 38030.0 50681.5 52046.7 53531.5 55726.2 78351.0 78680.6 79079.0 79308.0 79675.8 7976.0 8033.1 8114.0 8176.9 8371.8 7595.0 8667.4 10513.0 12277.1 12616.8 5606.0 7319.6 10906.9 10959.7 11186.0 10269.3 10793.0 10819.6 10895.3 10914.2 11037.2 11468.8 11490.3 11517.9 11533.9 7044.5 7767.4 8319.0 8349.9 8413.5 10439.3 11137.8 11296.8 11438.9 11460.4 18:04:25 INFO - PROCESS | 2053 | 18:04:25 INFO - PROCESS | 2053 | [#3] mootools.html Cycles:180 Average:5716.06 Median:2685.20 stddev:3224.68 (120.1%) stddev-sans-first:3217.67 18:04:25 INFO - PROCESS | 2053 | Values: 1433.2 1599.7 1623.2 1875.8 1985.8 7909.9 8353.4 8364.7 8376.9 8378.7 7775.9 8179.7 8362.5 8461.3 8804.8 2345.8 2435.0 2435.9 2436.3 2443.7 2392.8 2423.3 2445.4 2448.1 2450.9 2406.1 2423.6 2436.5 2439.8 2443.1 2078.8 2385.3 2385.3 2406.7 2425.5 1993.6 2230.3 2258.1 2398.8 2439.5 8650.9 8734.7 8738.5 8740.3 8741.4 5276.5 5331.1 5423.4 5470.6 5546.4 3478.8 3637.3 3682.7 3695.6 3703.6 1058.7 1058.8 1068.5 1080.3 1080.6 11520.4 11594.6 11617.4 11657.8 11660.4 4573.2 4592.7 4652.5 4701.3 4714.3 9152.4 9159.3 9168.1 9178.7 9181.2 5248.6 5297.4 5324.8 5328.8 5328.9 4470.5 4630.8 4821.0 4887.0 4955.0 3203.8 3836.7 3920.8 3948.9 3958.2 1200.0 1207.8 1218.8 1228.5 1233.6 597.6 819.4 903.7 928.2 975.9 5668.4 5852.5 6030.8 6072.5 6087.1 468.6 470.5 483.4 507.2 643.8 3847.5 4003.8 4080.8 4087.6 4095.0 10333.3 10840.5 10963.9 11118.0 11123.7 10922.0 11060.8 11099.1 11102.2 11153.8 8239.7 8399.2 8418.3 8481.9 8506.1 4626.6 5804.0 5961.6 6065.0 6192.1 10234.6 10385.3 10517.1 10522.5 10526.5 9670.9 10089.2 10109.3 10264.9 10360.3 7495.5 7644.7 7650.2 7667.6 7692.8 8269.3 8297.4 8356.2 8401.3 8425.0 7498.8 7610.4 7627.5 7639.3 7690.2 7439.4 7465.7 7517.6 7590.5 7592.1 6977.3 7175.8 7203.4 7204.4 7212.3 4917.0 4936.5 4967.9 5014.8 5180.3 4926.9 5027.9 5062.4 5093.7 5235.1 18:04:25 INFO - PROCESS | 2053 | 18:04:25 INFO - PROCESS | 2053 | [#4] prototype.html Cycles:180 Average:4421.98 Median:5074.45 stddev:1380.24 (27.2%) stddev-sans-first:1349.08 18:04:25 INFO - PROCESS | 2053 | Values: 304.8 310.7 314.9 317.4 321.5 5229.5 5239.5 5250.7 5263.5 5273.7 4794.8 5161.4 5199.4 5238.7 5241.0 3295.4 3622.5 3651.5 3921.2 3940.1 3812.2 3991.9 4010.5 4016.0 4016.9 3416.5 3559.1 3864.2 3929.9 3940.6 3261.0 3629.6 3645.2 3917.6 3923.4 2985.3 3068.8 3440.1 3456.9 3472.3 4858.6 5055.5 5066.2 5066.2 5086.1 3663.7 3887.9 4481.0 4502.4 4534.5 4441.0 4604.7 5241.6 5430.0 5466.3 3271.2 3647.3 3736.7 3759.6 3792.4 5469.2 5541.8 5783.1 5797.5 5817.9 3540.8 3613.7 3688.3 3691.4 3828.3 3862.3 3874.5 3893.4 3907.7 3910.7 3818.6 4478.2 4492.2 4549.7 4561.7 4848.2 4908.2 5082.7 5232.4 5325.5 1248.6 1295.7 1302.4 1308.6 1322.6 764.9 785.8 801.8 810.1 810.8 4936.6 4961.9 5092.6 5102.4 5139.0 4782.6 5383.5 5579.4 5604.6 5638.6 3956.2 4502.8 4565.6 5132.5 5135.0 2435.3 2952.6 2969.4 2972.7 2992.5 5672.8 5899.8 5900.6 5909.7 5919.2 5002.6 5399.0 5584.5 5663.3 5851.3 5849.8 5855.4 5885.5 5890.8 5920.0 5401.9 5450.9 5614.7 5637.5 5654.6 5542.3 5566.0 5571.7 5577.6 5635.0 5278.6 5510.7 5819.8 5885.7 5906.5 3667.0 3708.9 3769.3 3810.8 3817.6 4761.6 4978.0 5238.0 5623.7 5657.6 4862.3 5440.0 5487.2 5489.2 5499.0 5247.4 5416.3 5416.8 5428.3 5526.9 5122.3 5328.5 5352.5 5362.4 5382.4 5184.5 5320.3 5337.2 5347.7 5375.6 4461.5 4754.8 5272.6 5298.4 5303.9 18:04:25 INFO - PROCESS | 2053 | 18:04:25 INFO - PROCESS | 2053 | [#5] yui.html Cycles:180 Average:18161.25 Median:606.89 stddev:25698.83 (4234.5%) stddev-sans-first:25737.30 18:04:25 INFO - PROCESS | 2053 | Values: 652.4 724.7 742.1 755.5 788.3 15152.0 15531.0 15562.8 15563.8 15614.2 14083.8 14130.9 14199.1 14243.5 14312.2 913.6 924.6 929.0 930.6 932.4 908.3 922.8 928.1 931.5 934.5 981.3 982.4 982.5 983.4 984.9 976.2 976.7 977.2 977.2 977.5 717.9 718.5 719.1 719.3 720.0 13482.9 13491.5 13496.0 13513.2 13532.5 11894.3 11894.9 12135.2 12230.9 12430.8 9292.7 9954.1 9985.5 10487.7 10670.5 3354.2 3439.3 3441.4 3541.7 3686.7 66099.0 80955.2 81630.2 87067.0 89281.0 9377.0 9461.4 9495.9 9508.2 10135.5 21088.8 21090.8 21121.5 21139.9 21161.2 7614.2 7655.9 7664.7 7782.0 7899.4 4137.0 4183.3 4205.5 4209.5 4327.3 3632.8 3733.8 3736.7 3740.0 3755.8 670.2 683.4 683.7 688.7 691.8 449.9 471.5 476.4 529.0 602.7 7963.2 7967.5 7990.3 8132.3 8246.0 527.5 571.9 592.6 598.9 611.1 3828.6 3972.0 4139.5 4254.3 4275.9 82643.1 89508.0 93059.0 93738.0 98308.0 79989.8 83360.0 83862.6 84308.0 85424.6 73335.3 74147.3 74516.0 74803.6 74880.6 8037.3 8244.8 8358.8 8452.4 8508.9 42579.1 43358.6 43904.1 44797.5 44851.6 57233.4 59224.0 60051.1 60082.4 61346.0 11007.5 11198.8 11201.7 11458.5 11674.8 11840.9 12030.3 12139.2 12201.3 13250.9 11809.2 11835.7 12034.0 12056.8 12136.5 9764.8 11120.8 11753.2 11899.6 12042.3 11263.4 11397.3 11517.8 11628.1 11833.6 8178.3 9550.5 9654.2 9773.3 9788.4 9074.0 9449.1 9617.9 9750.8 10007.2 18:04:25 INFO - PROCESS | 2053 | -------- Summary: end -------- 18:04:25 INFO - PROCESS | 2053 | 18:04:25 INFO - TEST-INFO | 2053: exit 0 18:04:25 INFO - TEST-OK | dromaeo_css | took 1116233ms 18:04:25 INFO - TEST-START | kraken 18:04:25 INFO - operating with platform_type : mac_ 18:04:25 INFO - Initialising browser for kraken test... 18:04:25 INFO - TEST-INFO | started process 2056 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/tmpl0vogv/profile http://localhost:51527/getInfo.html) 18:04:28 INFO - PROCESS | 2056 | __metrics Screen width/height:1600/1200 18:04:28 INFO - PROCESS | 2056 | colorDepth:24 18:04:28 INFO - PROCESS | 2056 | Browser inner width/height: 1024/689 18:04:28 INFO - PROCESS | 2056 | __metrics 18:04:28 INFO - PROCESS | 2056 | 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" 18:04:28 ERROR - PROCESS | 2056 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:04:29 INFO - TEST-INFO | 2056: exit 0 18:04:29 INFO - Browser initialized. 18:04:29 INFO - Running cycle 1/1 for kraken test... 18:04:29 INFO - TEST-INFO | started process 2058 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/tmpl0vogv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:04:40 INFO - PROCESS | 2058 | RSS: Main: 238854144 18:04:40 INFO - PROCESS | 2058 | 18:04:43 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51527/tests/kraken/driver.html?testName=audio-beat-detection) 18:04:44 INFO - PROCESS | 2058 | RSS: Main: 286760960 18:04:44 INFO - PROCESS | 2058 | 18:04:47 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51527/tests/kraken/driver.html?testName=audio-dft) 18:04:47 INFO - PROCESS | 2058 | RSS: Main: 296103936 18:04:47 INFO - PROCESS | 2058 | 18:04:51 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51527/tests/kraken/driver.html?testName=audio-fft) 18:04:52 INFO - PROCESS | 2058 | RSS: Main: 386142208 18:04:52 INFO - PROCESS | 2058 | 18:04:54 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51527/tests/kraken/driver.html?testName=audio-oscillator) 18:04:54 INFO - PROCESS | 2058 | RSS: Main: 286396416 18:04:54 INFO - PROCESS | 2058 | 18:04:57 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51527/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:04:57 INFO - PROCESS | 2058 | RSS: Main: 290131968 18:04:57 INFO - PROCESS | 2058 | 18:05:00 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51527/tests/kraken/driver.html?testName=imaging-darkroom) 18:05:01 INFO - PROCESS | 2058 | RSS: Main: 326402048 18:05:01 INFO - PROCESS | 2058 | 18:05:04 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51527/tests/kraken/driver.html?testName=imaging-desaturate) 18:05:05 INFO - PROCESS | 2058 | RSS: Main: 330305536 18:05:05 INFO - PROCESS | 2058 | 18:05:08 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51527/tests/kraken/driver.html?testName=json-parse-financial) 18:05:08 INFO - PROCESS | 2058 | RSS: Main: 330137600 18:05:08 INFO - PROCESS | 2058 | 18:05:10 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51527/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:05:11 INFO - PROCESS | 2058 | RSS: Main: 315424768 18:05:11 INFO - PROCESS | 2058 | 18:05:12 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51527/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:05:13 INFO - PROCESS | 2058 | RSS: Main: 322084864 18:05:13 INFO - PROCESS | 2058 | 18:05:15 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51527/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:05:16 INFO - PROCESS | 2058 | RSS: Main: 323751936 18:05:16 INFO - PROCESS | 2058 | 18:05:19 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51527/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:05:20 INFO - PROCESS | 2058 | RSS: Main: 329359360 18:05:20 INFO - PROCESS | 2058 | 18:05:23 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51527/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:05:23 INFO - PROCESS | 2058 | RSS: Main: 327081984 18:05:23 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | Cycle 1(1): loaded http://localhost:51527/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51527/tests/kraken/driver.html?testName=ai-astar) 18:05:25 INFO - PROCESS | 2058 | RSS: Main: 373788672 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | __start_tp_report 18:05:25 INFO - PROCESS | 2058 | _x_x_mozilla_page_load 18:05:25 INFO - PROCESS | 2058 | _x_x_mozilla_page_load_details 18:05:25 INFO - PROCESS | 2058 | |i|pagename|runs| 18:05:25 INFO - PROCESS | 2058 | |0;ai-astar;112;107;111;112;112;113;112;113;112;110 18:05:25 INFO - PROCESS | 2058 | |1;audio-beat-detection;157;119;144;199;115;142;138;119;167;117 18:05:25 INFO - PROCESS | 2058 | |2;audio-dft;187;186;185;185;187;187;182;184;185;185 18:05:25 INFO - PROCESS | 2058 | |3;audio-fft;81;73;57;125;57;62;57;59;113;56 18:05:25 INFO - PROCESS | 2058 | |4;audio-oscillator;95;124;193;90;79;78;102;79;79;101 18:05:25 INFO - PROCESS | 2058 | |5;imaging-gaussian-blur;95;87;94;91;95;91;93;92;94;86 18:05:25 INFO - PROCESS | 2058 | |6;imaging-darkroom;95;96;115;151;94;95;95;96;96;95 18:05:25 INFO - PROCESS | 2058 | |7;imaging-desaturate;85;87;84;84;84;88;86;85;85;86 18:05:25 INFO - PROCESS | 2058 | |8;json-parse-financial;60;58;58;58;57;59;60;123;59;57 18:05:25 INFO - PROCESS | 2058 | |9;json-stringify-tinderbox;63;65;78;76;72;70;66;67;71;69 18:05:25 INFO - PROCESS | 2058 | |10;stanford-crypto-aes;84;84;85;83;83;82;81;84;84;84 18:05:25 INFO - PROCESS | 2058 | |11;stanford-crypto-ccm;158;153;144;146;135;136;139;137;233;132 18:05:25 INFO - PROCESS | 2058 | |12;stanford-crypto-pbkdf2;168;167;165;167;166;170;226;204;167;165 18:05:25 INFO - PROCESS | 2058 | |13;stanford-crypto-sha256-iterative;66;67;67;65;67;66;66;65;66;65 18:05:25 INFO - PROCESS | 2058 | __end_tp_report 18:05:25 INFO - PROCESS | 2058 | __start_cc_report 18:05:25 INFO - PROCESS | 2058 | _x_x_mozilla_cycle_collect,2207 18:05:25 INFO - PROCESS | 2058 | __end_cc_report 18:05:25 INFO - PROCESS | 2058 | __startTimestamp1462928725783__endTimestamp 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | ------- Summary: start ------- 18:05:25 INFO - PROCESS | 2058 | Number of tests: 14 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:1.78 (1.6%) stddev-sans-first:1.87 18:05:25 INFO - PROCESS | 2058 | Values: 112.0 107.0 111.0 112.0 112.0 113.0 112.0 113.0 112.0 110.0 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:143.00 stddev:26.94 (18.8%) stddev-sans-first:28.00 18:05:25 INFO - PROCESS | 2058 | Values: 157.0 119.0 144.0 199.0 115.0 142.0 138.0 119.0 167.0 117.0 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#2] audio-dft Cycles:10 Average:185.30 Median:185.50 stddev:1.57 (0.8%) stddev-sans-first:1.54 18:05:25 INFO - PROCESS | 2058 | Values: 187.0 186.0 185.0 185.0 187.0 187.0 182.0 184.0 185.0 185.0 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#3] audio-fft Cycles:10 Average:74.00 Median:58.00 stddev:25.24 (43.5%) stddev-sans-first:26.64 18:05:25 INFO - PROCESS | 2058 | Values: 81.0 73.0 57.0 125.0 57.0 62.0 57.0 59.0 113.0 56.0 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#4] audio-oscillator Cycles:10 Average:102.00 Median:79.00 stddev:35.15 (44.5%) stddev-sans-first:37.19 18:05:25 INFO - PROCESS | 2058 | Values: 95.0 124.0 193.0 90.0 79.0 78.0 102.0 79.0 79.0 101.0 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#5] imaging-gaussian-blur Cycles:10 Average:91.80 Median:93.50 stddev:3.16 (3.4%) stddev-sans-first:3.13 18:05:25 INFO - PROCESS | 2058 | Values: 95.0 87.0 94.0 91.0 95.0 91.0 93.0 92.0 94.0 86.0 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#6] imaging-darkroom Cycles:10 Average:102.80 Median:95.00 stddev:18.05 (19.0%) stddev-sans-first:18.92 18:05:25 INFO - PROCESS | 2058 | Values: 95.0 96.0 115.0 151.0 94.0 95.0 95.0 96.0 96.0 95.0 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:85.50 stddev:1.35 (1.6%) stddev-sans-first:1.42 18:05:25 INFO - PROCESS | 2058 | Values: 85.0 87.0 84.0 84.0 84.0 88.0 86.0 85.0 85.0 86.0 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:58.50 stddev:20.44 (34.9%) stddev-sans-first:21.61 18:05:25 INFO - PROCESS | 2058 | Values: 60.0 58.0 58.0 58.0 57.0 59.0 60.0 123.0 59.0 57.0 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:70.50 stddev:4.76 (6.8%) stddev-sans-first:4.39 18:05:25 INFO - PROCESS | 2058 | Values: 63.0 65.0 78.0 76.0 72.0 70.0 66.0 67.0 71.0 69.0 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:84.00 stddev:1.17 (1.4%) stddev-sans-first:1.22 18:05:25 INFO - PROCESS | 2058 | Values: 84.0 84.0 85.0 83.0 83.0 82.0 81.0 84.0 84.0 84.0 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#11] stanford-crypto-ccm Cycles:10 Average:151.30 Median:145.00 stddev:29.87 (20.6%) stddev-sans-first:31.59 18:05:25 INFO - PROCESS | 2058 | Values: 158.0 153.0 144.0 146.0 135.0 136.0 139.0 137.0 233.0 132.0 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.50 Median:167.50 stddev:20.99 (12.5%) stddev-sans-first:22.04 18:05:25 INFO - PROCESS | 2058 | Values: 168.0 167.0 165.0 167.0 166.0 170.0 226.0 204.0 167.0 165.0 18:05:25 INFO - PROCESS | 2058 | 18:05:25 INFO - PROCESS | 2058 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 18:05:25 INFO - PROCESS | 2058 | Values: 66.0 67.0 67.0 65.0 67.0 66.0 66.0 65.0 66.0 65.0 18:05:25 INFO - PROCESS | 2058 | -------- Summary: end -------- 18:05:25 INFO - PROCESS | 2058 | 18:05:26 INFO - TEST-INFO | 2058: exit 0 18:05:26 INFO - TEST-OK | kraken | took 60685ms 18:05:26 INFO - SUITE-END | took 1176s 18:05:26 INFO - Completed test suite (00:19:37) 18:05:26 INFO - javascript benchmark 18:05:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3408.7613867029318, 3413.1467133216697, 3419.9316013679736, 3436.690697837194, 3469.4448888177894, 5647.971760141204, 5660.179274005277, 5673.205751194826, 5696.658200507973, 5721.370649228586, 5720.914186287209, 5741.511971482429, 5753.0, 5753.942460575393, 5754.223179870716, 5151.253068305094, 5186.014657215117, 5218.060749065166, 5236.214567814839, 5286.894262114755, 5273.973630131844, 5279.656822306557, 5291.206319052135, 5300.416954135042, 5305.257263983026, 5174.637775355726, 5194.71429071401, 5206.661566998152, 5222.477752224785, 5286.65636733613, 5282.656627319231, 5297.841064768063, 5302.072137375943, 5303.310569625924, 5344.198370244456, 5091.949080509185, 5099.184130539096, 5107.131787596118, 5199.922001169985, 5219.451957544479, 5566.081596536566, 5577.163425486169, 5595.832125036255, 5609.52319052881, 5639.210510528529, 6278.497720182374, 6279.434850863444, 6285.87428251437, 6289.930711779009, 6296.590721603081, 5068.594512439033, 6253.249610046778, 6271.6864156792435, 6363.9045414318825, 6378.107065010902, 5796.594238403313, 5830.7084645767545, 5841.9415805842245, 5862.560307976919, 5876.265466816648, 6322.747090116344, 6327.525435592257, 6329.082283069021, 6336.0, 6373.0, 5151.304573882478, 5999.370066142992, 6043.516518678495, 6046.153538504613, 6175.44421002112, 5292.603054770908, 6208.00671892495, 6229.470495007885, 6278.717457714414, 6279.215098112736, 6171.382861713792, 6231.813045608639, 6261.060840873905, 6266.216722909637, 6278.372162783685, 5753.3958934311295, 5973.342932277446, 6293.181886354744, 6302.1177035215105, 6304.779332723333, 4105.712600118023, 4828.348172996671, 4834.371531700856, 4846.3699719037, 4898.191798353303, 4381.0, 4436.578525040116, 4451.955480445219, 4483.036147228361, 4498.212812757754, 5692.743826527816, 5714.428557144335, 5720.856978575485, 5734.39788822176, 5746.712664366765, 6344.143540622049, 6344.270408902943, 6347.460465860361, 6364.427201551883, 6378.936210637927, 5620.634658747168, 5671.886562268732, 5688.317401911797, 5752.3097228332035, 5764.0, 5369.5972802040005, 5384.19237114436, 5393.460653934575, 5402.729863506809, 5434.619576629677, 6342.39747224013, 6359.332270111757, 6363.268224154282, 6364.363563643598, 6377.585456945284, 6142.253225532511, 6276.058591211355, 6311.90532142009, 6321.905171422341, 6381.361863813581, 6315.652639104709, 6333.36666333363, 6341.968290158704, 6348.111264422892, 6350.555461117678, 6233.594816336924, 6256.468200203034, 6263.561550691408, 6267.122602835698, 6274.717637706405, 6113.537117903944, 6153.876922461436, 6241.78153764616, 6299.4645455135, 6329.683515824282, 6173.629582225081, 6183.319834818257, 6188.412100850412, 6196.380361963767, 6200.534959878118, 6075.665838378932, 6164.352742962104, 6165.475934545434, 6172.660503672341, 6176.227971503562, 5814.5348372130975, 5886.58793884424, 5898.144769008384, 5966.582339236212, 6024.307204671519, 6089.311220441179, 6097.024476083805, 6119.704772054934, 6168.907466388097, 6189.907151392643, 6031.125486804301, 6047.637141771508, 6047.758089676364, 6049.153118563493, 6064.757409703739], "value": 5706.983088495552, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.6427554298677, 524.7035424984883, 528.5533724003216, 531.6012990257307, 541.721013677956, 30326.393472130545, 32159.03522894317, 32209.51685724713, 32315.676843231562, 32336.514952275706, 44602.7769861151, 45896.0, 45901.770491147494, 48143.27785083218, 48268.517314826844, 9612.951935240331, 9754.41473511588, 9754.707358779247, 9766.462844543546, 9793.951030244838, 8391.951006124234, 8437.831243375129, 8713.259372953307, 9677.225821934227, 9754.658586949457, 11077.446127693624, 11910.523579056871, 11931.701707457296, 11991.220570662923, 12052.517899284017, 11847.703807404798, 11948.0, 11955.880441195564, 11969.341686207255, 11996.700082497964, 8614.440061396052, 11366.943165284203, 11644.76710465786, 11660.825087623693, 11797.351145686984, 41672.583274167475, 42093.57906420927, 42111.57888421107, 42158.367624485654, 42289.0, 3464.930701385958, 3595.334863050344, 3671.8898433046784, 3783.5459744830796, 3974.8012599370204, 29001.419971600662, 30274.243143921358, 30556.54165187524, 30866.69133308683, 30876.53685194724, 2768.4878297514824, 2827.081198610439, 2832.4759919414973, 2858.8427636479987, 2871.3826527296524, 482216.0, 483141.0, 484209.0, 484827.0, 485183.0, 64192.679036605456, 64642.0, 64779.676101620134, 64819.67590162019, 65010.67494662497, 68767.0, 68879.65560172166, 68968.62062758717, 68989.3101068993, 69199.0, 17014.57463563421, 17257.913710431367, 17263.309467621155, 17265.654686906193, 17267.91366043162, 13259.337033148304, 13345.0, 13377.73244535104, 13380.397882095134, 13387.259656244625, 16346.264418101215, 16817.243224054946, 18324.541886452946, 18744.25022999065, 18770.155343009596, 6235.750569977241, 6328.398802113884, 6369.649669268235, 6398.968005160037, 6407.743690252338, 919.3932004876749, 983.8966908474507, 993.2897977945659, 1004.8191325561323, 1051.3376572759266, 29735.107946761633, 29739.553906691417, 29981.400371992437, 30076.548851767242, 31203.84398077995, 1116.8273313021457, 1124.9381284029457, 1132.9773404532027, 1139.1228753859648, 1231.8090695942144, 15176.165310908005, 15189.316480758393, 15304.387824486896, 15529.844701553066, 15624.76562851558, 646853.0, 650829.0, 650915.0, 651422.4857751457, 653988.0, 55008.624784380714, 55898.72050639802, 55999.880002399725, 58082.0, 58153.70923145442, 1411.9576412707636, 1433.8996270261398, 1442.0554536778427, 1466.9413223471156, 1471.433498103238, 24063.917123729123, 29820.65807038645, 30813.691863081083, 30991.0, 31479.685203147674, 54608.45391546033, 55230.79076836882, 55634.72182639061, 55832.44167558435, 55925.88148237094, 589324.0, 592921.0, 593398.0, 637758.0, 835689.0, 36411.0, 37745.0, 38689.0, 38773.41839872461, 38836.61163388443, 45462.54537454583, 45776.08447830958, 45794.31308530441, 47385.763071184425, 47546.762266188445, 35997.64002360048, 36390.0, 37337.0, 37364.0, 38479.807600961816, 36889.0, 36915.89252322435, 37261.0, 37376.62623373731, 37703.81148094242, 37266.441003384425, 37396.81301593475, 38204.80897595494, 38296.80851595836, 38329.23341533098, 30065.54901676433, 30646.540301895937, 32084.67915320817, 32963.505547417284, 33165.83417082899, 21728.456788580414, 24134.155304564258, 24364.87817560901, 24685.62971555392, 24909.75090249147], "value": 20500.24017314817, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.44917116037544, 394.5364197068446, 394.67439362525926, 397.5904818037421, 417.4281234708448, 20619.17523299068, 20703.689444658343, 21761.80310082945, 21777.782222177775, 21792.564148717036, 16874.99999999997, 20595.1761929523, 20753.481162970933, 20933.267335643257, 20992.580148397024, 5978.491828194608, 6236.438720515152, 6245.406686364788, 6250.874982500347, 6251.0, 6072.605280656765, 6133.969330153343, 6170.228721409824, 6172.259328880541, 6231.813045608616, 5974.402559744034, 6124.693765311717, 6133.049377346496, 6135.5398345124295, 6173.660448675321, 6036.0945858121195, 6107.816765497042, 6130.785422510213, 6133.478654314388, 6145.631262124264, 4571.497135315146, 5211.765470553834, 5370.355557333145, 5372.785088596452, 5391.110466772978, 23578.76421235783, 24966.0, 25687.0, 26203.606945895826, 26659.600105998426, 2097.0668052716396, 2369.644553316999, 2522.255934499327, 2800.551911694119, 2806.733360330765, 9868.90131098687, 9911.157551608121, 9964.202863770954, 10025.147862431702, 10275.948620256926, 1741.2860727101954, 1747.7465767463773, 1768.3810666266832, 1848.5009047557085, 1850.0, 115943.42028289805, 118314.40842795817, 126297.0, 126827.0, 129677.0, 9519.571619277149, 10179.389236645677, 10281.383117013003, 10431.269811113149, 10584.470776461147, 12688.80966785499, 12696.238225706487, 12792.488300467878, 12802.16785908913, 12811.295378754072, 7442.8883566746545, 7901.565413902175, 8178.959105204436, 8335.911690437513, 8493.575321234037, 6995.79012629622, 8669.523176225368, 8674.56627168639, 8772.78068048291, 8863.64545418189, 3698.167912219783, 3761.717871159674, 4030.536488303824, 4052.797360131982, 4057.431959525669, 1951.200007996743, 1990.9502262443266, 2003.4590660521578, 2015.083137172583, 2042.458748431667, 734.98050962616, 925.2413021322585, 957.1577012229333, 1007.4610083605236, 1065.9200559957878, 15594.688106237812, 15907.443239486562, 20868.89565552193, 21186.78813211879, 21194.682079768816, 909.4361495872602, 957.2485598804905, 968.3366893677853, 979.715882394112, 983.1446641422009, 10810.783784324269, 11103.0, 11140.0, 11172.888271117346, 11194.496247668874, 549901.0, 551279.0, 551825.0, 589870.0, 592774.0, 129061.0, 130593.0, 131791.0, 132393.73243873028, 134875.65124348828, 1003.1573478278282, 1023.9846402303972, 1025.7281820317473, 1027.1525991057408, 1028.228828378716, 13538.0, 18369.632607348045, 18610.720839187692, 19064.332748354296, 19295.324663636704, 38030.0, 50681.49318506768, 52046.739766300925, 53531.46468535421, 55726.16410753923, 78351.0, 78680.60659696665, 79079.0, 79308.01729956797, 79675.80486292594, 7976.0, 8033.076196237507, 8113.959430202811, 8176.877346839683, 8371.83256334882, 7594.962025189839, 8667.4366166199, 10513.001264879824, 12277.079219058427, 12616.810747838606, 5606.0, 7319.560826350436, 10906.890931090587, 10959.671209863716, 11186.049185819296, 10269.332493387907, 10793.02862742357, 10819.56721731122, 10895.291806032583, 10914.23600347968, 11037.227394082338, 11468.770624587629, 11490.310581365145, 11517.942410287895, 11533.884661153617, 7044.506884518035, 7767.3786097113225, 8318.958405207935, 8349.916500834914, 8413.45312554682, 10439.269251152346, 11137.777244455228, 11296.83054754163, 11438.885611143782, 11460.426978651201], "value": 9395.68795486057, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1433.2188957018425, 1599.6800639872024, 1623.1802939515546, 1875.8311751942322, 1985.7815640279557, 7909.92090079099, 8353.415260931737, 8364.665413383473, 8376.874346884802, 8378.748637540868, 7775.883361749578, 8179.713710020151, 8362.456440331374, 8461.323094152469, 8804.77988050299, 2345.824063195263, 2434.9756502435016, 2435.914742983996, 2436.293474892288, 2443.7189723181796, 2392.8085753139794, 2423.321469988406, 2445.425325048612, 2448.081969261527, 2450.8774561271866, 2406.061635961976, 2423.6364545318165, 2436.512697460506, 2439.7560243975636, 2443.059422122478, 2078.771335153132, 2385.2963375804006, 2385.3440303916386, 2406.74729153439, 2425.4906469641396, 1993.5813479169392, 2230.3309007297744, 2258.085475382473, 2398.7721166489155, 2439.4633180700216, 8650.87023694645, 8734.737957861274, 8738.475691458469, 8740.257078148365, 8741.388102832805, 5276.472352764731, 5331.093714068617, 5423.430539793344, 5470.56235501159, 5546.417626149277, 3478.7738796978115, 3637.3089113068695, 3682.6869716073898, 3695.6304369562827, 3703.574088979748, 1058.6929790360864, 1058.772363941756, 1068.5405275731432, 1080.286022370968, 1080.6487891435315, 11520.366379849105, 11594.594189203337, 11617.361045142514, 11657.76684466306, 11660.416979150923, 4573.222552166139, 4592.7014744041535, 4652.464966528825, 4701.318308845267, 4714.316424118484, 9152.405093668891, 9159.267258619295, 9168.083191680778, 9178.678746243982, 9181.219596334386, 5248.580113590903, 5297.443768404339, 5324.8136315228785, 5328.8401347959625, 5328.946710532845, 4470.508244093147, 4630.791614377361, 4821.0, 4886.975565122151, 4954.950450495521, 3203.7757356985253, 3836.731428799957, 3920.8235629396745, 3948.9012774680864, 3958.218801539319, 1199.9580014699443, 1207.7765613361555, 1218.8171774233936, 1228.5085965613648, 1233.6114124050896, 597.5548216578677, 819.4468733604793, 903.6837107012625, 928.2434815625244, 975.8682577852042, 5668.404817494186, 5852.531797456193, 6030.7889223876955, 6072.483838873657, 6087.147799308101, 468.62458683763265, 470.50361868228663, 483.4223103391484, 507.2467385931888, 643.7703985387886, 3847.461355410189, 4003.7597744135446, 4080.836766529365, 4087.550369459357, 4095.0, 10333.276670633133, 10840.512176952056, 10963.890361096446, 11118.0, 11123.721906952229, 10922.0, 11060.778784424267, 11099.056580190612, 11102.167337449724, 11153.832692509619, 8239.670413183405, 8399.244068033906, 8418.284445822173, 8481.915180848237, 8506.064332923374, 4626.560476754703, 5803.970980145241, 5961.552883533665, 6065.0, 6192.071189321638, 10234.590616375559, 10385.273030887765, 10517.10604598587, 10522.526486307981, 10526.52630631608, 9670.85493717609, 10089.192864570963, 10109.292349535463, 10264.948675256575, 10360.326578772356, 7495.512791668524, 7644.732434364993, 7650.158482567024, 7667.616619169131, 7692.846143076995, 8269.338452923632, 8297.419180657296, 8356.164383561596, 8401.285890699115, 8425.0, 7498.812529686801, 7610.391168706601, 7627.5042122261875, 7639.312461878458, 7690.154083050748, 7439.367653749492, 7465.664045117874, 7517.624118794148, 7590.506617069873, 7592.126905405728, 6977.33715297046, 7175.820604484721, 7203.387712044535, 7204.423646108194, 7212.3148300911325, 4917.041176970456, 4936.4569897311985, 4967.850964471072, 5014.824481143142, 5180.326557547494, 4926.866820631203, 5027.874303142451, 5062.443131255485, 5093.694378337312, 5235.083860324504], "value": 4523.079156798493, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.82777230864565, 310.68775880240355, 314.88349310755024, 317.37160422363735, 321.53377602476417, 5229.477052294768, 5239.52844244018, 5250.65870718403, 5263.47365263474, 5273.6572122811995, 4794.801299675081, 5161.354830646169, 5199.402068762093, 5238.659487133334, 5241.030409374269, 3295.4397752382033, 3622.5471816022996, 3651.470536772173, 3921.1569512554797, 3940.074082590589, 3812.237552489499, 3991.900202494932, 4010.5387880393837, 4016.0361513236903, 4016.9154328331333, 3416.5387672664247, 3559.056849934769, 3864.2078373933277, 3929.9214015719667, 3940.625640564142, 3260.983695081533, 3629.6007439181667, 3645.1798345372213, 3917.5886531914166, 3923.391874259466, 2985.3132979866523, 3068.787828807631, 3440.088376580208, 3456.9110730120183, 3472.3402553515, 4858.643557874428, 5055.469175736532, 5066.164082926312, 5066.214736715815, 5086.100922474191, 3663.725220608465, 3887.8833634990715, 4480.977595112036, 4502.392177056121, 4534.478534968488, 4441.022974945507, 4604.654650901196, 5241.554467870235, 5429.99090336574, 5466.289382380265, 3271.1985563536846, 3647.3434781739275, 3736.7010639148803, 3759.642833930772, 3792.3742582474133, 5469.15228139639, 5541.806036788693, 5783.132530120473, 5797.536197104221, 5817.912731309034, 3540.7961293160447, 3613.7351926825927, 3688.2807852469045, 3691.412692542179, 3828.349180639242, 3862.343401621731, 3874.49631547897, 3893.4159876018257, 3907.706921980806, 3910.687145028391, 3818.6181381861593, 4478.171538265431, 4492.213862574036, 4549.7042692224895, 4561.7262964222255, 4848.236505192007, 4908.213714917322, 5082.695038297714, 5232.424433312332, 5325.520703136736, 1248.5754843353122, 1295.6501744528925, 1302.374860067154, 1308.5616318533207, 1322.5833862333334, 764.9349805266614, 785.8389030248785, 801.8276070644839, 810.1372038778682, 810.7770363150063, 4936.555709986047, 4961.875953101201, 5092.606474956764, 5102.413222479462, 5139.0, 4782.641301902371, 5383.461653834585, 5579.414161513064, 5604.60767746262, 5638.577106717013, 3956.188981258835, 4502.752348620857, 4565.634749220055, 5132.5124113209185, 5134.974325128425, 2435.269419174276, 2952.630921134858, 2969.391274788663, 2972.7175918288162, 2992.5361568956846, 5672.75199456119, 5899.823005309848, 5900.64596124234, 5909.734061967221, 5919.200907877382, 5002.574781143643, 5399.0, 5584.492187109539, 5663.32040155184, 5851.297844258631, 5849.824505264849, 5855.355910849803, 5885.47030767233, 5890.849839808439, 5919.9936010878255, 5401.918971215357, 5450.863728406822, 5614.719264036864, 5637.49262566371, 5654.575906806924, 5542.307211598551, 5566.0, 5571.7492712828835, 5577.609567330248, 5635.0, 5278.604104692086, 5510.724463776875, 5819.82540523785, 5885.675722962299, 5906.527477801851, 3666.973247490707, 3708.905872767486, 3769.2649933263847, 3810.809459527068, 3817.61823817616, 4761.595264402565, 4977.9502204978935, 5237.973810130925, 5623.69069701154, 5657.60396772222, 4862.294967229803, 5440.0, 5487.176923461513, 5489.204065410573, 5499.010178167968, 5247.449017853223, 5416.320940508297, 5416.75624596902, 5428.294321738149, 5526.91709624364, 5122.257272695513, 5328.495607064673, 5352.5450336721815, 5362.410134885082, 5382.434844341287, 5184.507471790174, 5320.308359913187, 5337.22610221508, 5347.732613369239, 5375.596830237826, 4461.531539188339, 4754.836549369959, 5272.6309158358545, 5298.44366341544, 5303.920441193308], "value": 3980.6642710332503, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.4226059936956, 724.6920058974935, 742.109468637635, 755.4522970846135, 788.2669117720521, 15152.0, 15531.0, 15562.84437155628, 15563.766543501857, 15614.219289035545, 14083.788743168861, 14130.897358498038, 14199.077059991094, 14243.501477448266, 14312.212828294465, 913.6436789652041, 924.5515924776478, 928.9581968811386, 930.6370515498961, 932.4265576670333, 908.318760929303, 922.8062106957514, 928.0858354520792, 931.4783721116197, 934.4626839567276, 981.2836629260644, 982.3663736889702, 982.5283863745407, 983.4394395194741, 984.916282116021, 976.1751320134475, 976.7313988653107, 977.1986970684042, 977.2426369563574, 977.4965892565299, 717.9174394944596, 718.5257729898294, 719.1442183801312, 719.327428854018, 720.020771750419, 13482.86517134826, 13491.527796527173, 13496.0, 13513.189208647513, 13532.45870165202, 11894.286342819458, 11894.881051189464, 12135.208901952601, 12230.938845305805, 12430.813537796941, 9292.674756383562, 9954.104130628279, 9985.450800205987, 10487.685369438928, 10670.466476676134, 3354.1614596350914, 3439.2605589798063, 3441.397755392796, 3541.7166626669805, 3686.686631636314, 66099.0, 80955.19044809535, 81630.18369816286, 87067.0, 89280.99999999935, 9377.0, 9461.38500997433, 9495.857562136573, 9508.191803696764, 10135.493225338703, 21088.78911210899, 21090.789092108884, 21121.471963200736, 21139.894300528398, 21161.15355385798, 7614.162442131361, 7655.885161722578, 7664.6934122635585, 7781.961090194626, 7899.368050555941, 4136.9793151034655, 4183.288840897055, 4205.537390887, 4209.536950935395, 4327.307630779123, 3632.8365223564474, 3733.775973441602, 3736.682381997506, 3740.0275928258848, 3755.830987605564, 670.1656437734954, 683.3951952521954, 683.6820878291618, 688.7348370877248, 691.761342336893, 449.8551187228511, 471.530826827309, 476.4235275316836, 528.9550388217044, 602.7076867719153, 7963.243491868263, 7967.521948683097, 7990.320822730133, 8132.308753755997, 8245.980434283007, 527.5384038965905, 571.8913406452843, 592.5851903667364, 598.9341172471024, 611.0803241122171, 3828.636279553438, 3971.9801400993388, 4139.461869956886, 4254.29804082323, 4275.893102672396, 82643.10749123752, 89508.0, 93059.0, 93738.0, 98308.0, 79989.80015299775, 83360.0, 83862.58068709617, 84308.0, 85424.57287713522, 73335.26664733284, 74147.25852741403, 74516.0, 74803.62598186974, 74880.62559687167, 8037.31682806968, 8244.793880152803, 8358.832823343377, 8452.366072544706, 8508.872366914378, 42579.148417032105, 43358.56641433672, 43904.12191756083, 44797.520247976245, 44851.551484484735, 57233.42766572448, 59224.0, 60051.0992335124, 60082.39917600943, 61346.0, 11007.504662290055, 11198.776024479628, 11201.66395008151, 11458.541658333574, 11674.824877627012, 11840.881591184323, 12030.27818330903, 12139.150259481752, 12201.267923924594, 13250.867491325349, 11809.232399894323, 11835.704107397383, 12033.977111945233, 12056.75886482248, 12136.453859576164, 9764.804703906024, 11120.777584448428, 11753.177277590486, 11899.643010709693, 12042.337671428155, 11263.380514071805, 11397.259178153394, 11517.82723259135, 11628.069754419796, 11833.644990650295, 8178.263956243966, 9550.522473876139, 9654.2276617869, 9773.267004974514, 9788.363756356099, 9073.954630226806, 9449.102335278138, 9617.903820961981, 9750.80498390014, 10007.199424045913], "value": 6920.287891675106, "unit": "score"}], "name": "dromaeo_css", "value": 7179.751438831166, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 111.0, 112.0, 112.0, 113.0, 112.0, 113.0, 112.0, 110.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 119.0, 144.0, 199.0, 115.0, 142.0, 138.0, 119.0, 167.0, 117.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 185.0, 185.0, 187.0, 187.0, 182.0, 184.0, 185.0, 185.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 57.0, 125.0, 57.0, 62.0, 57.0, 59.0, 113.0, 56.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 124.0, 193.0, 90.0, 79.0, 78.0, 102.0, 79.0, 79.0, 101.0], "value": 102.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 87.0, 94.0, 91.0, 95.0, 91.0, 93.0, 92.0, 94.0, 86.0], "value": 91.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 115.0, 151.0, 94.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 102.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 84.0, 84.0, 84.0, 88.0, 86.0, 85.0, 85.0, 86.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 58.0, 58.0, 57.0, 59.0, 60.0, 123.0, 59.0, 57.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 78.0, 76.0, 72.0, 70.0, 66.0, 67.0, 71.0, 69.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 83.0, 83.0, 82.0, 81.0, 84.0, 84.0, 84.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 153.0, 144.0, 146.0, 135.0, 136.0, 139.0, 137.0, 233.0, 132.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 165.0, 167.0, 166.0, 170.0, 226.0, 204.0, 167.0, 165.0], "value": 176.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 65.0, 67.0, 66.0, 66.0, 65.0, 66.0, 65.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1506.1999999999998, "alertThreshold": 2.0}]} 18:05:27 INFO - Return code: 0 18:05:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:05:27 ERROR - # TBPL SUCCESS # 18:05:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:05:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:05:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:05:27 INFO - 2016-05-10 18:05:27.091 screenresolution[2062:17239] starting screenresolution argv=screenresolution get 18:05:27 INFO - 2016-05-10 18:05:27.093 screenresolution[2062:17239] Display 0: 1600x1200x32@60 18:05:27 INFO - 2016-05-10 18:05:27.099 screenresolution[2063:17242] starting screenresolution argv=screenresolution list 18:05:27 INFO - Available Modes on Display 0 18:05:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:05:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:05:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:05:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:05:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:05:27 INFO - Intel Iris: 18:05:27 INFO - Chipset Model: Intel Iris 18:05:27 INFO - Type: GPU 18:05:27 INFO - Bus: Built-In 18:05:27 INFO - VRAM (Dynamic, Max): 1536 MB 18:05:27 INFO - Vendor: Intel (0x8086) 18:05:27 INFO - Device ID: 0x0a2e 18:05:27 INFO - Revision ID: 0x0009 18:05:27 INFO - Displays: 18:05:27 INFO - Display: 18:05:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:05:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:05:27 INFO - Display Serial Number: 1600x1200 60 18:05:27 INFO - Main Display: Yes 18:05:27 INFO - Mirror: Off 18:05:27 INFO - Online: Yes 18:05:27 INFO - Rotation: Supported 18:05:27 INFO - Return code: 0 18:05:27 INFO - Running post-action listener: _resource_record_post_action 18:05:27 INFO - ##### 18:05:27 INFO - ##### Finished run-tests step (success) 18:05:27 INFO - ##### 18:05:27 INFO - Running post-run listener: _resource_record_post_run 18:05:28 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 741888; Write bytes: 691280384; Read time: 9; Write time: 1858 18:05:28 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 383656448; Write bytes: 378435072; Read time: 29867; Write time: 729 18:05:28 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 741888; Write bytes: 293405696; Read time: 9; Write time: 1086 18:05:28 INFO - Running post-run listener: _upload_blobber_files 18:05:28 INFO - Blob upload gear active. 18:05:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:05:28 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 18:05:28 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'] 18:05:28 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 18:05:28 INFO - (blobuploader) - INFO - Open directory for files ... 18:05:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:05:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:05:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:05:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:29 INFO - (blobuploader) - INFO - Done attempting. 18:05:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:05:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:05:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:05:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:31 INFO - (blobuploader) - INFO - Done attempting. 18:05:31 INFO - (blobuploader) - INFO - Iteration through files over. 18:05:31 INFO - Return code: 0 18:05:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:05:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:05:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/730cdb8f19403e1993f66aed0aa7f7ecf6d06c86343ba36d1b5336df47688049de4731cfb31c2d3df2d28e40fc10f4fc25369fc75e8595c37210e1c007263b52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/818f4a165b0a9f615c90624fee0ee6b0aaed177840f9c3763c3ac64d9aac07379d4c89fb08f294377865446842cdec8c9f780f8d3c38280ff41383ba21cbb7d3"} 18:05:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:05:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:05:31 INFO - Contents: 18:05:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/730cdb8f19403e1993f66aed0aa7f7ecf6d06c86343ba36d1b5336df47688049de4731cfb31c2d3df2d28e40fc10f4fc25369fc75e8595c37210e1c007263b52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/818f4a165b0a9f615c90624fee0ee6b0aaed177840f9c3763c3ac64d9aac07379d4c89fb08f294377865446842cdec8c9f780f8d3c38280ff41383ba21cbb7d3"} 18:05:31 INFO - Running post-run listener: copy_logs_to_upload_dir 18:05:31 INFO - Copying logs to upload dir... 18:05:31 INFO - mkdir: /builds/slave/test/build/upload/logs 18:05:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.323487 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-10 18:05:31.141154) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 18:05:31.144418) ========= 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.qsNTJr7zOs/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.z75GoxkKby/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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/730cdb8f19403e1993f66aed0aa7f7ecf6d06c86343ba36d1b5336df47688049de4731cfb31c2d3df2d28e40fc10f4fc25369fc75e8595c37210e1c007263b52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/818f4a165b0a9f615c90624fee0ee6b0aaed177840f9c3763c3ac64d9aac07379d4c89fb08f294377865446842cdec8c9f780f8d3c38280ff41383ba21cbb7d3"} build_url:https://queue.taskcluster.net/v1/task/aMh3proTSlSy7JMDDhhUzQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011018 build_url: 'https://queue.taskcluster.net/v1/task/aMh3proTSlSy7JMDDhhUzQ/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/730cdb8f19403e1993f66aed0aa7f7ecf6d06c86343ba36d1b5336df47688049de4731cfb31c2d3df2d28e40fc10f4fc25369fc75e8595c37210e1c007263b52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/818f4a165b0a9f615c90624fee0ee6b0aaed177840f9c3763c3ac64d9aac07379d4c89fb08f294377865446842cdec8c9f780f8d3c38280ff41383ba21cbb7d3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 18:05:31.171458) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:05:31.171751) ========= 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.qsNTJr7zOs/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.z75GoxkKby/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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.005154 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 18:05:31.219838) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 18:05:31.220152) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 18:05:31.220463) ========= ========= Total master_lag: 0.16 =========