builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0104 starttime: 1462708533.24 results: success (0) buildid: 20160508030214 builduid: ca7f59a48dfd4613a2d6e6db21fb44f8 revision: bae525a694e2dc0aa433885be8751330d4995a49 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-08 04:55:33.242969) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-08 04:55:33.243337) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-08 04:55:33.243748) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-08 04:55:33.286348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 04:55:33.286615) ========= 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.LRSLdeA8SG/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.n74UFuqsFa/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-08 04:55:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 163M=0s 2016-05-08 04:55:33 (163 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.215307 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 04:55:33.519094) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 04:55:33.519363) ========= 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.LRSLdeA8SG/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.n74UFuqsFa/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.028532 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 04:55:33.566884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-08 04:55:33.567186) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bae525a694e2dc0aa433885be8751330d4995a49 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev bae525a694e2dc0aa433885be8751330d4995a49 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LRSLdeA8SG/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.n74UFuqsFa/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-08 04:55:33,643 truncating revision to first 12 chars 2016-05-08 04:55:33,643 Setting DEBUG logging. 2016-05-08 04:55:33,643 attempt 1/10 2016-05-08 04:55:33,643 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bae525a694e2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-08 04:55:34,470 unpacking tar archive at: mozilla-central-bae525a694e2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.077017 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-08 04:55:34.660511) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-08 04:55:34.660793) ========= script_repo_revision: bae525a694e2dc0aa433885be8751330d4995a49 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-08 04:55:34.661156) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-08 04:55:34.661427) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-08 04:55:34.674528) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-08 04:55:34.674810) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LRSLdeA8SG/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.n74UFuqsFa/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 04:55:34 INFO - MultiFileLogger online at 20160508 04:55:34 in /builds/slave/test 04:55:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:55:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:55:34 INFO - {'append_to_log': False, 04:55:34 INFO - 'base_work_dir': '/builds/slave/test', 04:55:34 INFO - 'blob_upload_branch': 'Firefox', 04:55:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:55:34 INFO - 'branch': 'Firefox', 04:55:34 INFO - 'buildbot_json_path': 'buildprops.json', 04:55:34 INFO - 'config_files': ('talos/mac_config.py',), 04:55:34 INFO - 'default_actions': ('clobber', 04:55:34 INFO - 'read-buildbot-config', 04:55:34 INFO - 'download-and-extract', 04:55:34 INFO - 'populate-webroot', 04:55:34 INFO - 'create-virtualenv', 04:55:34 INFO - 'install', 04:55:34 INFO - 'run-tests'), 04:55:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:55:34 INFO - 'download_minidump_stackwalk': True, 04:55:34 INFO - 'download_symbols': 'ondemand', 04:55:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:55:34 INFO - 'tooltool.py': '/tools/tooltool.py', 04:55:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:55:34 INFO - '/tools/misc-python/virtualenv.py')}, 04:55:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:55:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:55:34 INFO - 'installer_path': 'installer.exe', 04:55:34 INFO - 'log_level': 'info', 04:55:34 INFO - 'log_name': 'talos', 04:55:34 INFO - 'log_to_console': True, 04:55:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:55:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:55:34 INFO - 'opt_config_files': (), 04:55:34 INFO - 'pip_index': False, 04:55:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:55:34 INFO - 'cmd': ('bash', 04:55:34 INFO - '-c', 04:55:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:55:34 INFO - 'enabled': True, 04:55:34 INFO - 'halt_on_failure': False, 04:55:34 INFO - 'name': 'check_screen_resolution'},), 04:55:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:55:34 INFO - 'cmd': ('bash', 04:55:34 INFO - '-c', 04:55:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:55:34 INFO - 'enabled': True, 04:55:34 INFO - 'halt_on_failure': False, 04:55:34 INFO - 'name': 'check_screen_resolution'},), 04:55:34 INFO - 'run_cmd_checks_enabled': True, 04:55:34 INFO - 'sps_profile': False, 04:55:34 INFO - 'sps_profile_interval': 0, 04:55:34 INFO - 'suite': 'dromaeojs', 04:55:34 INFO - 'system_bits': '32', 04:55:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:55:34 INFO - 'title': 't-yosemite-r7-0104', 04:55:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:55:34 INFO - 'use_talos_json': True, 04:55:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:55:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:55:34 INFO - 'work_dir': 'build'} 04:55:34 INFO - ##### 04:55:34 INFO - ##### Running clobber step. 04:55:34 INFO - ##### 04:55:34 INFO - Running pre-action listener: _resource_record_pre_action 04:55:34 INFO - Running main action method: clobber 04:55:34 INFO - rmtree: /builds/slave/test/build 04:55:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:55:36 INFO - Running post-action listener: _resource_record_post_action 04:55:36 INFO - ##### 04:55:36 INFO - ##### Finished clobber step (success) 04:55:36 INFO - ##### 04:55:36 INFO - ##### 04:55:36 INFO - ##### Running read-buildbot-config step. 04:55:36 INFO - ##### 04:55:36 INFO - Running pre-action listener: _resource_record_pre_action 04:55:36 INFO - Running main action method: read_buildbot_config 04:55:36 INFO - Using buildbot properties: 04:55:36 INFO - { 04:55:36 INFO - "project": "", 04:55:36 INFO - "product": "firefox", 04:55:36 INFO - "script_repo_revision": "production", 04:55:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:55:36 INFO - "repository": "", 04:55:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:55:36 INFO - "buildid": "20160508030214", 04:55:36 INFO - "stage_platform": "macosx64", 04:55:36 INFO - "basedir": "/builds/slave/test", 04:55:36 INFO - "nightly_build": "True", 04:55:36 INFO - "buildnumber": 1, 04:55:36 INFO - "slavename": "t-yosemite-r7-0104", 04:55:36 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:55:36 INFO - "platform": "yosemite_r7", 04:55:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:55:36 INFO - "branch": "mozilla-central", 04:55:36 INFO - "repo_path": "mozilla-central", 04:55:36 INFO - "revision": "bae525a694e2dc0aa433885be8751330d4995a49", 04:55:36 INFO - "pgo_build": "False", 04:55:36 INFO - "builduid": "ca7f59a48dfd4613a2d6e6db21fb44f8", 04:55:36 INFO - "slavebuilddir": "test" 04:55:36 INFO - } 04:55:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:55:36 INFO - Running post-action listener: _resource_record_post_action 04:55:36 INFO - ##### 04:55:36 INFO - ##### Finished read-buildbot-config step (success) 04:55:36 INFO - ##### 04:55:36 INFO - ##### 04:55:36 INFO - ##### Running download-and-extract step. 04:55:36 INFO - ##### 04:55:36 INFO - Running pre-action listener: _resource_record_pre_action 04:55:36 INFO - Running main action method: download_and_extract 04:55:36 INFO - mkdir: /builds/slave/test/build/tests 04:55:36 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')]} 04:55:36 INFO - https://queue.taskcluster.net/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:55:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:55:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:55:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/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 04:55:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/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 04:55:38 INFO - Downloaded 1199 bytes. 04:55:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:55:38 INFO - Using the following test package requirements: 04:55:38 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:55:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:55:38 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:55:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:55:38 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:55:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:55:38 INFO - u'jsshell-mac.zip'], 04:55:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:55:38 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:55:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:55:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:55:38 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:55:38 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:55:38 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:55:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:55:38 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:55:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:55:38 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:55:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:55:38 INFO - https://queue.taskcluster.net/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:55:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:55:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:55:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/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 04:55:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/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 04:55:40 INFO - Downloaded 18145051 bytes. 04:55:40 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'] 04:55:40 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 04:55:43 INFO - Return code: 0 04:55:43 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 04:55: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')]} 04:55:43 INFO - https://queue.taskcluster.net/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:55:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:55:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:55:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/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 04:55:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/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 04:55:46 INFO - Downloaded 18145051 bytes. 04:55:46 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'] 04:55:46 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 04:55:49 INFO - Return code: 0 04:55:49 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')]} 04:55:49 INFO - https://queue.taskcluster.net/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:55:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:55:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:55:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/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 04:55:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/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 04:55:51 INFO - Downloaded 10840152 bytes. 04:55:51 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'] 04:55:51 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 04:55:51 INFO - Return code: 0 04:55: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')]} 04:55:51 INFO - https://queue.taskcluster.net/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:55:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:55:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:55:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:55:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PnBETnI5SOi6sWu7ntR9nA/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 04:55:56 INFO - Downloaded 110570556 bytes. 04:55:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:55:56 INFO - mkdir: /builds/slave/test/properties 04:55:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:55:56 INFO - Writing to file /builds/slave/test/properties/build_url 04:55:56 INFO - Contents: 04:55:56 INFO - build_url:https://queue.taskcluster.net/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:55:58 INFO - Running post-action listener: _resource_record_post_action 04:55:58 INFO - Running post-action listener: set_extra_try_arguments 04:55:58 INFO - ##### 04:55:58 INFO - ##### Finished download-and-extract step (success) 04:55:58 INFO - ##### 04:55:58 INFO - ##### 04:55:58 INFO - ##### Running populate-webroot step. 04:55:58 INFO - ##### 04:55:58 INFO - Running pre-action listener: _resource_record_pre_action 04:55:58 INFO - Running main action method: populate_webroot 04:55:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:55:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:55:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:55:58 INFO - '--tppagecycles', 04:55:58 INFO - '7'], 04:55:58 INFO - 'tests': ['tsvgm']}}, 04:55:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:55:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:55:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:55:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:55:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:55:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:55:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:58 INFO - 'talos_options': ['--disable-e10s'], 04:55:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:55:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:55:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:58 INFO - 'talos_options': ['--disable-e10s'], 04:55:58 INFO - 'tests': ['damp', 'tps']}, 04:55:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:58 INFO - 'tests': ['damp', 'tps']}, 04:55:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:55:58 INFO - 'tests': ['dromaeo_dom']}, 04:55:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:55:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:55:58 INFO - 'tests': ['a11yr', 04:55:58 INFO - 'ts_paint', 04:55:58 INFO - 'tpaint', 04:55:58 INFO - 'sessionrestore', 04:55:58 INFO - 'sessionrestore_no_auto_restore', 04:55:58 INFO - 'tabpaint']}, 04:55:58 INFO - 'other-e10s': {'tests': ['a11yr', 04:55:58 INFO - 'ts_paint', 04:55:58 INFO - 'tpaint', 04:55:58 INFO - 'sessionrestore', 04:55:58 INFO - 'sessionrestore_no_auto_restore', 04:55:58 INFO - 'tabpaint']}, 04:55:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:55:58 INFO - 'ts_paint', 04:55:58 INFO - 'tpaint', 04:55:58 INFO - 'sessionrestore', 04:55:58 INFO - 'sessionrestore_no_auto_restore', 04:55:58 INFO - 'tabpaint']}, 04:55:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:55:58 INFO - 'ts_paint', 04:55:58 INFO - 'tpaint', 04:55:58 INFO - 'sessionrestore', 04:55:58 INFO - 'sessionrestore_no_auto_restore', 04:55:58 INFO - 'tabpaint']}, 04:55:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:55:58 INFO - 'tests': ['a11yr', 04:55:58 INFO - 'ts_paint', 04:55:58 INFO - 'tpaint', 04:55:58 INFO - 'sessionrestore', 04:55:58 INFO - 'sessionrestore_no_auto_restore', 04:55:58 INFO - 'tabpaint']}, 04:55:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:55:58 INFO - 'tests': ['a11yr', 04:55:58 INFO - 'ts_paint', 04:55:58 INFO - 'tpaint', 04:55:58 INFO - 'sessionrestore', 04:55:58 INFO - 'sessionrestore_no_auto_restore', 04:55:58 INFO - 'tabpaint']}, 04:55:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:55:58 INFO - 'tests': ['tsvgx', 04:55:58 INFO - 'tsvgr_opacity', 04:55:58 INFO - 'tart', 04:55:58 INFO - 'tscrollx', 04:55:58 INFO - 'cart']}, 04:55:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:55:58 INFO - 'tsvgr_opacity', 04:55:58 INFO - 'tart', 04:55:58 INFO - 'tscrollx', 04:55:58 INFO - 'cart']}, 04:55:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:58 INFO - 'talos_options': ['--disable-e10s'], 04:55:58 INFO - 'tests': ['tp5o']}, 04:55:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:58 INFO - 'tests': ['tp5o']}, 04:55:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:58 INFO - 'talos_options': ['--disable-e10s', 04:55:58 INFO - '--xperf_path', 04:55:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:55:58 INFO - 'tests': ['tp5n']}, 04:55:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:58 INFO - 'talos_options': ['--xperf_path', 04:55:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:55:58 INFO - 'tests': ['tp5n']}}, 04:55:58 INFO - 'talos.zip': {'path': '', 04:55:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:55:58 INFO - Running post-action listener: _resource_record_post_action 04:55:58 INFO - ##### 04:55:58 INFO - ##### Finished populate-webroot step (success) 04:55:58 INFO - ##### 04:55:58 INFO - ##### 04:55:58 INFO - ##### Running create-virtualenv step. 04:55:58 INFO - ##### 04:55:58 INFO - Running pre-action listener: _resource_record_pre_action 04:55:58 INFO - Running main action method: create_virtualenv 04:55:58 INFO - Creating virtualenv /builds/slave/test/build/venv 04:55:58 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 04:55:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:55:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:55:58 INFO - Using real prefix '/tools/python27' 04:55:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:55:58 INFO - Installing distribute.............................................................................................................................................................................................done. 04:56:01 INFO - Installing pip.................done. 04:56:01 INFO - Return code: 0 04:56:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:56:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:01 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 0x100ac8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd3c71fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10096cd30>, '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 0x100790db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100963b20>, '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.LRSLdeA8SG/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.n74UFuqsFa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 04:56:01 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 04:56:01 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 04:56:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LRSLdeA8SG/Render', 04:56:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:01 INFO - 'HOME': '/Users/cltbld', 04:56:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:01 INFO - 'LOGNAME': 'cltbld', 04:56:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:01 INFO - 'MOZ_NO_REMOTE': '1', 04:56:01 INFO - 'NO_EM_RESTART': '1', 04:56:01 INFO - 'PAGER': '/bin/cat', 04:56:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:01 INFO - 'PWD': '/builds/slave/test', 04:56:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:01 INFO - 'SHELL': '/bin/bash', 04:56:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n74UFuqsFa/Listeners', 04:56:01 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 04:56:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:01 INFO - 'USER': 'cltbld', 04:56:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:01 INFO - 'XPC_FLAGS': '0x0', 04:56:01 INFO - 'XPC_SERVICE_NAME': '0', 04:56:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:02 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 04:56:02 INFO - Cleaning up... 04:56:02 INFO - Return code: 0 04:56:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100ac8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd3c71fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10096cd30>, '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 0x100790db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100963b20>, '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.LRSLdeA8SG/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.n74UFuqsFa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 04:56:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:56:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:56:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LRSLdeA8SG/Render', 04:56:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:02 INFO - 'HOME': '/Users/cltbld', 04:56:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:02 INFO - 'LOGNAME': 'cltbld', 04:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:02 INFO - 'MOZ_NO_REMOTE': '1', 04:56:02 INFO - 'NO_EM_RESTART': '1', 04:56:02 INFO - 'PAGER': '/bin/cat', 04:56:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:02 INFO - 'PWD': '/builds/slave/test', 04:56:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:02 INFO - 'SHELL': '/bin/bash', 04:56:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n74UFuqsFa/Listeners', 04:56:02 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 04:56:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:02 INFO - 'USER': 'cltbld', 04:56:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:02 INFO - 'XPC_FLAGS': '0x0', 04:56:02 INFO - 'XPC_SERVICE_NAME': '0', 04:56:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:02 INFO - Downloading/unpacking psutil>=0.7.1 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:56:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:56:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:56:05 INFO - Installing collected packages: psutil 04:56:05 INFO - Running setup.py install for psutil 04:56:05 INFO - building 'psutil._psutil_osx' extension 04:56:05 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 04:56:06 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 04:56:06 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 04:56:06 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 04:56:06 INFO - building 'psutil._psutil_posix' extension 04:56:06 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 04:56:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:56:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:56:06 INFO - ^ 04:56:06 INFO - 1 warning generated. 04:56:06 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 04:56:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:56:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:56:06 INFO - Successfully installed psutil 04:56:06 INFO - Cleaning up... 04:56:06 INFO - Return code: 0 04:56:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:56:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100ac8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd3c71fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10096cd30>, '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 0x100790db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100963b20>, '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.LRSLdeA8SG/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.n74UFuqsFa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 04:56:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:56:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:56:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LRSLdeA8SG/Render', 04:56:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:06 INFO - 'HOME': '/Users/cltbld', 04:56:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:06 INFO - 'LOGNAME': 'cltbld', 04:56:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:06 INFO - 'MOZ_NO_REMOTE': '1', 04:56:06 INFO - 'NO_EM_RESTART': '1', 04:56:06 INFO - 'PAGER': '/bin/cat', 04:56:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:06 INFO - 'PWD': '/builds/slave/test', 04:56:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:06 INFO - 'SHELL': '/bin/bash', 04:56:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n74UFuqsFa/Listeners', 04:56:06 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 04:56:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:06 INFO - 'USER': 'cltbld', 04:56:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:06 INFO - 'XPC_FLAGS': '0x0', 04:56:06 INFO - 'XPC_SERVICE_NAME': '0', 04:56:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:56:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:56:09 INFO - Installing collected packages: mozsystemmonitor 04:56:09 INFO - Running setup.py install for mozsystemmonitor 04:56:09 INFO - Successfully installed mozsystemmonitor 04:56:09 INFO - Cleaning up... 04:56:09 INFO - Return code: 0 04:56:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:56: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')]} 04:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56: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')]} 04:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56: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', '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 0x100ac8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd3c71fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10096cd30>, '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 0x100790db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100963b20>, '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.LRSLdeA8SG/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.n74UFuqsFa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 04:56: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:56: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 blobuploader==1.2.4 04:56:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LRSLdeA8SG/Render', 04:56:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:09 INFO - 'HOME': '/Users/cltbld', 04:56:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:09 INFO - 'LOGNAME': 'cltbld', 04:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:09 INFO - 'MOZ_NO_REMOTE': '1', 04:56:09 INFO - 'NO_EM_RESTART': '1', 04:56:09 INFO - 'PAGER': '/bin/cat', 04:56:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:09 INFO - 'PWD': '/builds/slave/test', 04:56:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:09 INFO - 'SHELL': '/bin/bash', 04:56:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n74UFuqsFa/Listeners', 04:56:09 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 04:56:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:09 INFO - 'USER': 'cltbld', 04:56:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:09 INFO - 'XPC_FLAGS': '0x0', 04:56:09 INFO - 'XPC_SERVICE_NAME': '0', 04:56:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:09 INFO - Downloading/unpacking blobuploader==1.2.4 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56:12 INFO - Downloading blobuploader-1.2.4.tar.gz 04:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:56:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:56:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:56:13 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 04:56:13 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 04:56:13 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 04:56:13 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 04:56:13 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 04:56:13 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 04:56:13 INFO - Downloading docopt-0.6.1.tar.gz 04:56:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:56:13 INFO - Installing collected packages: blobuploader, requests, docopt 04:56:13 INFO - Running setup.py install for blobuploader 04:56:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:56:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:56:13 INFO - Running setup.py install for requests 04:56:14 INFO - Running setup.py install for docopt 04:56:14 INFO - Successfully installed blobuploader requests docopt 04:56:14 INFO - Cleaning up... 04:56:14 INFO - Return code: 0 04:56:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:14 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')]} 04:56:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:14 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')]} 04:56:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:14 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 0x100ac8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd3c71fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10096cd30>, '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 0x100790db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100963b20>, '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.LRSLdeA8SG/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.n74UFuqsFa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 04:56:14 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 04:56:14 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 04:56:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LRSLdeA8SG/Render', 04:56:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:14 INFO - 'HOME': '/Users/cltbld', 04:56:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:14 INFO - 'LOGNAME': 'cltbld', 04:56:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:14 INFO - 'MOZ_NO_REMOTE': '1', 04:56:14 INFO - 'NO_EM_RESTART': '1', 04:56:14 INFO - 'PAGER': '/bin/cat', 04:56:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:14 INFO - 'PWD': '/builds/slave/test', 04:56:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:14 INFO - 'SHELL': '/bin/bash', 04:56:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n74UFuqsFa/Listeners', 04:56:14 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 04:56:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:14 INFO - 'USER': 'cltbld', 04:56:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:14 INFO - 'XPC_FLAGS': '0x0', 04:56:14 INFO - 'XPC_SERVICE_NAME': '0', 04:56:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:56:14 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-h_szvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:56:14 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-zPSII8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:56:14 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-XapVUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:56:15 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-U3ZtIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:56:15 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-MNxNLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:56:15 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-0Uuy7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:56:15 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-ro4gMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:56:15 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-prNU94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:56:15 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-eG2DKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:56:15 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-phCgIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:56:15 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-vczJU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:56:15 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-FajCj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:56:15 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-X5j1qG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:56:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-DiuAPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-HN3drO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:56:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-AktqSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:56:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-o15vmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:56:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:56:16 INFO - Running setup.py install for manifestparser 04:56:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:56:16 INFO - Running setup.py install for mozcrash 04:56:16 INFO - Running setup.py install for mozdebug 04:56:16 INFO - Running setup.py install for mozdevice 04:56:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:56:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:56:17 INFO - Running setup.py install for mozfile 04:56:17 INFO - Running setup.py install for mozhttpd 04:56:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:56:17 INFO - Running setup.py install for mozinfo 04:56:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:56:17 INFO - Running setup.py install for mozInstall 04:56:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:56:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:56:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:56:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:56:17 INFO - Running setup.py install for mozleak 04:56:17 INFO - Running setup.py install for mozlog 04:56:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:56:18 INFO - Running setup.py install for moznetwork 04:56:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:56:18 INFO - Running setup.py install for mozprocess 04:56:18 INFO - Running setup.py install for mozprofile 04:56:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:56:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:56:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:56:18 INFO - Running setup.py install for mozrunner 04:56:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:56:18 INFO - Running setup.py install for mozscreenshot 04:56:18 INFO - Running setup.py install for moztest 04:56:18 INFO - Running setup.py install for mozversion 04:56:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:56:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:56:19 INFO - Cleaning up... 04:56:19 INFO - Return code: 0 04:56:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:19 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')]} 04:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:19 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')]} 04:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:19 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 0x100ac8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd3c71fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10096cd30>, '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 0x100790db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100963b20>, '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.LRSLdeA8SG/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.n74UFuqsFa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 04:56:19 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 04:56:19 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 04:56:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LRSLdeA8SG/Render', 04:56:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:19 INFO - 'HOME': '/Users/cltbld', 04:56:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:19 INFO - 'LOGNAME': 'cltbld', 04:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:19 INFO - 'MOZ_NO_REMOTE': '1', 04:56:19 INFO - 'NO_EM_RESTART': '1', 04:56:19 INFO - 'PAGER': '/bin/cat', 04:56:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:19 INFO - 'PWD': '/builds/slave/test', 04:56:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:19 INFO - 'SHELL': '/bin/bash', 04:56:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n74UFuqsFa/Listeners', 04:56:19 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 04:56:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:19 INFO - 'USER': 'cltbld', 04:56:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:19 INFO - 'XPC_FLAGS': '0x0', 04:56:19 INFO - 'XPC_SERVICE_NAME': '0', 04:56:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:56:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-_AlAlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:56:19 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)) 04:56:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:56:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-y2BkkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:56:19 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)) 04:56:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:56:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-28TUjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:56:19 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)) 04:56:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:56:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-1kxoZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:56:19 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)) 04:56:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:56:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-pD0VaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:56:19 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)) 04:56:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:56:19 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-9f0moM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:56:20 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)) 04:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:56:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-U41QAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:56:20 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)) 04:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:56:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-v4suL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:56:20 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)) 04:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:56:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-l2s9S7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:56:20 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)) 04:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:56:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-12tkla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:56:20 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)) 04:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:56:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-taQNtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:56:20 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)) 04:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:56:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-_XrZK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:56:20 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)) 04:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:56:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-4wCIkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:56:20 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)) 04:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:56:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-3aBvHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:56:20 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)) 04:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-rPpUIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:20 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)) 04:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:56:20 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-HcGkrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:56:21 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)) 04:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:56:21 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-7jw6Zm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:56:21 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)) 04:56:21 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)) 04:56:21 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)) 04:56:21 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)) 04:56:21 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)) 04:56:21 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)) 04:56:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:56:21 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 04:56:21 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 04:56:21 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 04:56:21 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 04:56:21 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 04:56:21 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 04:56:23 INFO - Downloading blessings-1.6.tar.gz 04:56:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:56:23 INFO - Installing collected packages: blessings 04:56:23 INFO - Running setup.py install for blessings 04:56:23 INFO - Successfully installed blessings 04:56:23 INFO - Cleaning up... 04:56:23 INFO - Return code: 0 04:56:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:56:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:56:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:56:24 INFO - Reading from file tmpfile_stdout 04:56:24 INFO - Current package versions: 04:56:24 INFO - blessings == 1.6 04:56:24 INFO - blobuploader == 1.2.4 04:56:24 INFO - docopt == 0.6.1 04:56:24 INFO - manifestparser == 1.1 04:56:24 INFO - mozInstall == 1.12 04:56:24 INFO - mozcrash == 0.17 04:56:24 INFO - mozdebug == 0.1 04:56:24 INFO - mozdevice == 0.48 04:56:24 INFO - mozfile == 1.2 04:56:24 INFO - mozhttpd == 0.7 04:56:24 INFO - mozinfo == 0.9 04:56:24 INFO - mozleak == 0.1 04:56:24 INFO - mozlog == 3.1 04:56:24 INFO - moznetwork == 0.27 04:56:24 INFO - mozprocess == 0.22 04:56:24 INFO - mozprofile == 0.28 04:56:24 INFO - mozrunner == 6.11 04:56:24 INFO - mozscreenshot == 0.1 04:56:24 INFO - mozsystemmonitor == 0.0 04:56:24 INFO - moztest == 0.7 04:56:24 INFO - mozversion == 1.4 04:56:24 INFO - psutil == 3.1.1 04:56:24 INFO - requests == 1.2.3 04:56:24 INFO - wsgiref == 0.1.2 04:56:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:24 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 0x100ac8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd3c71fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10096cd30>, '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 0x100790db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100963b20>, '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.LRSLdeA8SG/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.n74UFuqsFa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 04:56:24 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 04:56:24 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 04:56:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LRSLdeA8SG/Render', 04:56:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:24 INFO - 'HOME': '/Users/cltbld', 04:56:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:24 INFO - 'LOGNAME': 'cltbld', 04:56:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:24 INFO - 'MOZ_NO_REMOTE': '1', 04:56:24 INFO - 'NO_EM_RESTART': '1', 04:56:24 INFO - 'PAGER': '/bin/cat', 04:56:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:24 INFO - 'PWD': '/builds/slave/test', 04:56:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:24 INFO - 'SHELL': '/bin/bash', 04:56:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n74UFuqsFa/Listeners', 04:56:24 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 04:56:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:24 INFO - 'USER': 'cltbld', 04:56:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:24 INFO - 'XPC_FLAGS': '0x0', 04:56:24 INFO - 'XPC_SERVICE_NAME': '0', 04:56:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:24 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)) 04:56:24 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)) 04:56:24 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)) 04:56:24 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)) 04:56:24 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)) 04:56:24 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)) 04:56:24 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)) 04:56:24 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)) 04:56:24 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)) 04:56:24 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)) 04:56:24 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)) 04:56:24 INFO - Cleaning up... 04:56:24 INFO - Return code: 0 04:56:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100ac8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd3c71fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10096cd30>, '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 0x100790db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100963b20>, '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.LRSLdeA8SG/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.n74UFuqsFa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 04:56:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:56:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:56:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LRSLdeA8SG/Render', 04:56:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:24 INFO - 'HOME': '/Users/cltbld', 04:56:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:24 INFO - 'LOGNAME': 'cltbld', 04:56:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:24 INFO - 'MOZ_NO_REMOTE': '1', 04:56:24 INFO - 'NO_EM_RESTART': '1', 04:56:24 INFO - 'PAGER': '/bin/cat', 04:56:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:24 INFO - 'PWD': '/builds/slave/test', 04:56:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:24 INFO - 'SHELL': '/bin/bash', 04:56:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n74UFuqsFa/Listeners', 04:56:24 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 04:56:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:24 INFO - 'USER': 'cltbld', 04:56:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:24 INFO - 'XPC_FLAGS': '0x0', 04:56:24 INFO - 'XPC_SERVICE_NAME': '0', 04:56:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:24 INFO - Downloading/unpacking jsonschema 04:56:24 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 04:56:24 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 04:56:24 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 04:56:24 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 04:56:24 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 04:56:24 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 04:56:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:56:27 INFO - Downloading/unpacking functools32 (from jsonschema) 04:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:27 INFO - Downloading functools32-3.2.3-2.tar.gz 04:56:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:56:27 INFO - warning: no files found matching '*.txt' 04:56:27 INFO - no previously-included directories found matching 'build' 04:56:27 INFO - no previously-included directories found matching 'dist' 04:56:27 INFO - no previously-included directories found matching '.git*' 04:56:27 INFO - Installing collected packages: jsonschema, functools32 04:56:27 INFO - Running setup.py install for functools32 04:56:27 INFO - warning: no files found matching '*.txt' 04:56:27 INFO - no previously-included directories found matching 'build' 04:56:27 INFO - no previously-included directories found matching 'dist' 04:56:27 INFO - no previously-included directories found matching '.git*' 04:56:27 INFO - Successfully installed jsonschema functools32 04:56:27 INFO - Cleaning up... 04:56:28 INFO - Return code: 0 04:56:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:56:28 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')]} 04:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:28 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')]} 04:56:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:28 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 0x100ac8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ace570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd3c71fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10096cd30>, '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 0x100790db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100963b20>, '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.LRSLdeA8SG/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.n74UFuqsFa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 04:56:28 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 04:56:28 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 04:56:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LRSLdeA8SG/Render', 04:56:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:28 INFO - 'HOME': '/Users/cltbld', 04:56:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:28 INFO - 'LOGNAME': 'cltbld', 04:56:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:28 INFO - 'MOZ_NO_REMOTE': '1', 04:56:28 INFO - 'NO_EM_RESTART': '1', 04:56:28 INFO - 'PAGER': '/bin/cat', 04:56:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:28 INFO - 'PWD': '/builds/slave/test', 04:56:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:28 INFO - 'SHELL': '/bin/bash', 04:56:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n74UFuqsFa/Listeners', 04:56:28 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 04:56:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:28 INFO - 'USER': 'cltbld', 04:56:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:28 INFO - 'XPC_FLAGS': '0x0', 04:56:28 INFO - 'XPC_SERVICE_NAME': '0', 04:56:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:28 INFO - Downloading/unpacking flake8 04:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:56:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:56:30 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 04:56:30 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 04:56:30 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 04:56:30 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 04:56:30 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 04:56:30 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 04:56:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:56:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:56:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:56:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:56:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:56:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:56:32 INFO - no previously-included directories found matching 'docs/_build' 04:56:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:56:32 INFO - Running setup.py install for pep8 04:56:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:56:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:56:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:56:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:56:32 INFO - no previously-included directories found matching 'docs/_build' 04:56:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:56:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:56:32 INFO - Cleaning up... 04:56:32 INFO - Return code: 0 04:56:32 INFO - Running post-action listener: _resource_record_post_action 04:56:32 INFO - Running post-action listener: _start_resource_monitoring 04:56:32 INFO - Starting resource monitoring. 04:56:32 INFO - ##### 04:56:32 INFO - ##### Finished create-virtualenv step (success) 04:56:32 INFO - ##### 04:56:32 INFO - ##### 04:56:32 INFO - ##### Running install step. 04:56:32 INFO - ##### 04:56:32 INFO - Running pre-action listener: _resource_record_pre_action 04:56:32 INFO - Running main action method: install 04:56:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:56:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:56:32 INFO - Reading from file tmpfile_stdout 04:56:32 INFO - Detecting whether we're running mozinstall >=1.0... 04:56:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:56:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:56:32 INFO - Reading from file tmpfile_stdout 04:56:32 INFO - Output received: 04:56:32 INFO - Usage: mozinstall [options] installer 04:56:32 INFO - Options: 04:56:32 INFO - -h, --help show this help message and exit 04:56:32 INFO - -d DEST, --destination=DEST 04:56:32 INFO - Directory to install application into. [default: 04:56:32 INFO - "/builds/slave/test"] 04:56:32 INFO - --app=APP Application being installed. [default: firefox] 04:56:32 INFO - mkdir: /builds/slave/test/build/application 04:56:32 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'] 04:56:32 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 04:57:06 INFO - Reading from file tmpfile_stdout 04:57:06 INFO - Output received: 04:57:06 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:57:06 INFO - Running post-action listener: _resource_record_post_action 04:57:06 INFO - ##### 04:57:06 INFO - ##### Finished install step (success) 04:57:06 INFO - ##### 04:57:06 INFO - ##### 04:57:06 INFO - ##### Running run-tests step. 04:57:06 INFO - ##### 04:57:06 INFO - Running pre-action listener: _resource_record_pre_action 04:57:06 INFO - Running main action method: run_tests 04:57:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:57:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:57:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:57:06 INFO - 2016-05-08 04:57:06.337 screenresolution[1640:8575] starting screenresolution argv=screenresolution get 04:57:06 INFO - 2016-05-08 04:57:06.340 screenresolution[1640:8575] Display 0: 1600x1200x32@60 04:57:06 INFO - 2016-05-08 04:57:06.346 screenresolution[1641:8578] starting screenresolution argv=screenresolution list 04:57:06 INFO - Available Modes on Display 0 04:57:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:57:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:57:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:57:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:57:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:57:06 INFO - Intel Iris: 04:57:06 INFO - Chipset Model: Intel Iris 04:57:06 INFO - Type: GPU 04:57:06 INFO - Bus: Built-In 04:57:06 INFO - VRAM (Dynamic, Max): 1536 MB 04:57:06 INFO - Vendor: Intel (0x8086) 04:57:06 INFO - Device ID: 0x0a2e 04:57:06 INFO - Revision ID: 0x0009 04:57:06 INFO - Displays: 04:57:06 INFO - Display: 04:57:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:57:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:57:06 INFO - Display Serial Number: 1600x1200 60 04:57:06 INFO - Main Display: Yes 04:57:06 INFO - Mirror: Off 04:57:06 INFO - Online: Yes 04:57:06 INFO - Rotation: Supported 04:57:06 INFO - Return code: 0 04:57:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:57:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:57:06 INFO - Python 2.7.3 04:57:06 INFO - Return code: 0 04:57:06 INFO - grabbing minidump binary from tooltool 04:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:06 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10096cd30>, '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 0x100790db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100963b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:57:06 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 04:57:06 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 04:57:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:57:06 INFO - Return code: 0 04:57:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:57:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:57:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:57:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:57:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:57:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:57:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:57:07 INFO - Return code: 0 04:57:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 04:57:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0104 --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 04:57:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LRSLdeA8SG/Render', 04:57:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:07 INFO - 'HOME': '/Users/cltbld', 04:57:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:07 INFO - 'LOGNAME': 'cltbld', 04:57:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:57:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:07 INFO - 'MOZ_NO_REMOTE': '1', 04:57:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:57:07 INFO - 'NO_EM_RESTART': '1', 04:57:07 INFO - 'PAGER': '/bin/cat', 04:57:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:07 INFO - 'PWD': '/builds/slave/test', 04:57:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:57:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:07 INFO - 'SHELL': '/bin/bash', 04:57:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n74UFuqsFa/Listeners', 04:57:07 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 04:57:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:07 INFO - 'USER': 'cltbld', 04:57:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:07 INFO - 'XPC_FLAGS': '0x0', 04:57:07 INFO - 'XPC_SERVICE_NAME': '0', 04:57:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 04:57:08 INFO - mozversion application_buildid: 20160508030214 04:57:08 INFO - mozversion application_changeset: bae525a694e2dc0aa433885be8751330d4995a49 04:57:08 INFO - mozversion application_display_name: Nightly 04:57:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:57:08 INFO - mozversion application_name: Firefox 04:57:08 INFO - mozversion application_remotingname: firefox 04:57:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:57:08 INFO - mozversion application_vendor: Mozilla 04:57:08 INFO - mozversion application_version: 49.0a1 04:57:08 INFO - mozversion platform_buildid: 20160508030214 04:57:08 INFO - mozversion platform_changeset: bae525a694e2dc0aa433885be8751330d4995a49 04:57:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:57:08 INFO - mozversion platform_version: 49.0a1 04:57:08 INFO - using testdate: 1462708628 04:57:08 INFO - actual date: 1462708628 04:57:08 INFO - starting webserver on 'localhost:49269' 04:57:08 INFO - SUITE-START | Running 2 tests 04:57:08 INFO - TEST-START | dromaeo_css 04:57:08 INFO - operating with platform_type : mac_ 04:57:08 INFO - Initialising browser for dromaeo_css test... 04:57:08 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp2jTjjo/profile http://localhost:49269/getInfo.html) 04:57:10 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 04:57:10 INFO - PROCESS | 1659 | colorDepth:24 04:57:10 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 04:57:10 INFO - PROCESS | 1659 | __metrics 04:57:10 INFO - PROCESS | 1659 | 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" 04:57:10 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:57:11 INFO - TEST-INFO | 1659: exit 0 04:57:11 INFO - Browser initialized. 04:57:11 INFO - Running cycle 1/1 for dromaeo_css test... 04:57:11 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp2jTjjo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:57:23 INFO - PROCESS | 1663 | RSS: Main: 239202304 04:57:23 INFO - PROCESS | 1663 | 04:58:14 INFO - PROCESS | 1663 | 1462708694782 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] 05:00:25 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 05:00:26 INFO - PROCESS | 1663 | RSS: Main: 328015872 05:00:26 INFO - PROCESS | 1663 | 05:03:29 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 05:03:30 INFO - PROCESS | 1663 | RSS: Main: 369532928 05:03:30 INFO - PROCESS | 1663 | 05:06:32 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 05:06:33 INFO - PROCESS | 1663 | RSS: Main: 368275456 05:06:33 INFO - PROCESS | 1663 | 05:09:35 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 05:09:35 INFO - PROCESS | 1663 | RSS: Main: 367157248 05:09:35 INFO - PROCESS | 1663 | 05:12:39 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 05:12:39 INFO - PROCESS | 1663 | RSS: Main: 334635008 05:12:39 INFO - PROCESS | 1663 | 05:15:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 05:15:43 INFO - PROCESS | 1663 | RSS: Main: 373432320 05:15:43 INFO - PROCESS | 1663 | 05:15:43 INFO - PROCESS | 1663 | __start_tp_report 05:15:43 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 05:15:43 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 05:15:43 INFO - PROCESS | 1663 | |i|pagename|runs| 05:15:43 INFO - PROCESS | 1663 | |0;dojo.html;3271.525628783826;3407.403704351739;3427.708644765195;3474.6004209515913;3501.859925602976;5728.598998070137;5751.223584816049;5818.272715910512;5829.271341082364;5862.853428664285;5669.971650141743;5801.07182850744;5914;5916.378780228068;5943.108533719944;5070.72111033893;5124.718140502271;5151.793928242884;5179.689218646874;5291.841244762663;5246.344206974129;5291.947080529203;5304.283921670583;5329.946700532983;5353.330833645794;4377.649788016951;5215.113430716769;5266.183741520071;5299.60252981026;5335.7865685372535;5221.686698798084;5235.031519168966;5288.497592728723;5297.549708274802;5318.1225097858805;5219.6085293602755;5225.268462415227;5227;5264.789408423659;5328.094223981932;5768.048272035151;5769.480746732772;5796.855078623026;5805.941940580625;5808.854778630526;6193.94702900508;6226.066090086478;6255.06174073888;6291.433770960635;6329.3354197809485;6240.095186197976;6254;6289.622622642611;6297.307296197415;6302;5967.16459695643;6049.727762250709;6050.878982420327;6056.424639659182;6062.514998800174;6184.752609895644;6216.191895053614;6247;6265.843353916189;6329;6111.8380790797555;6186.783462578788;6191.25704915413;6238.750449982041;6257.374262573706;6224.1597384352535;6268.561200715997;6278.843028924222;6291.402316779899;6308.936910630835;6265.4361107500545;6309.274433440213;6320.336364681734;6324.051392291101;6352.841178970562;6174.783882564088;6210.31686514483;6270.2475702915945;6338.809835704844;6350.4602108820345;4951.232558953368;4957.281194226889;4962.851114466572;4964.553190212898;5006;4396.384506169139;4480.484744254387;4496.055828276033;4518.9832287735;4524.389207456951;5727.4845263926445;5759.740811663485;5796.82609521715;5803.216565763652;5809.099589563624;6249.9062514062325;6251.374862513712;6277.843053923597;6287.182666253358;6295.18162638854;5863.824085277449;5869.765209391577;5874.236349274567;5879.853003674943;5908.704564771744;5276.39321478035;5440.809571665052;5471.9179212311055;5486.1222204447095;5487.780488780484;6327.082573026977;6401.967990160019;6404.103425520524;6417.8716425672155;6433.163688720437;6215.533834962305;6220.968895155495;6267.091271765659;6272.560920735505;6278.937210627835;6219.440250377488;6275.058741188858;6287.779927702509;6417.005364168562;6448.580842245239;6267.185265915402;6289.49684025286;6293.37066293367;6296.937030629635;6312.526560508072;6246.250449946036;6268.498520118471;6268.811935641938;6290.905636415366;6308.52686048539;6057.757689692539;6137.5302083125;6159.538034647329;6162.753489860355;6205.6586887721605;5002.499750024968;5651.660900345828;6208.9689551551955;6217.160683307696;6237.937620623918;6012.458878700763;6061.545384096122;6093.6039157454625;6100.054491553817;6110.572259941761;6068.787592434243;6156.722947467285;6193.163922870355;6210.813675589739;6300.180976473029;6155.815325540241;6161.229846269216;6167.321594624498;6191.226096737908;6198.504119670325 05:15:43 INFO - PROCESS | 1663 | |1;ext.html;512.7718165416392;513.4916432731595;533.1070456984552;546.5873265684409;564.4863174511196;30575.38849223014;42357.57642423575;43282.350764738556;43335;43398.13203735923;43989.78005109978;44372;44440.33339499902;44507.109857802905;44510.77744611281;8454.830903381928;8649.567521623925;9502.904970950305;9708.66019689311;9713.514324283757;11207.495662695157;11479.25384849982;11503.07975361969;11671.066314694805;11672.591459298927;9860.161886239643;11439.5424183033;11537.942310288438;11543.249688770245;11556.075513958906;8487.023992240847;9453.669121580711;11020.834687479693;11516.366599837005;11654.766904661901;11266.887331126665;11333.829992550118;11349.943250283779;11358.772824543463;11369.374684392355;39445.80277098625;39781.80654580338;39844.60155398438;39849.00377490557;39913.80043099766;2980.3741214345014;3262.2496825730213;3270.9182270443193;3283.337878374684;3711.5731690855628;27976.860115699496;28345.858270708515;28780;29011.564826527618;29354.41291174186;2767.239009272446;2768;2782.2070709847594;2806.424682939992;2848.245215018022;448002;450326.74836625607;451526;451877;452010;35559.64440355615;61515.07727384093;62777.74444511084;62930;63078;36236;37612.43581346282;37643.43534846979;37688.43467347992;37701.43447848284;15028.17345046033;15190.088594684354;15194.544163675107;15198.924005379902;15230.771538426932;11295.209335346612;11353.545858165746;12854.293013884326;12896.677583060498;12932.288724120264;8228.382871284677;8243.340532757366;8256.793580160544;8292.12932642076;8315.501069935823;3338.082027442424;3346.0798280473064;3349.71527420172;3350.66493350663;3377.746668999835;982.94102353859;1063.9521221545049;1093.7211011192255;1113.6714669172613;1127.4588197665003;29103.56344654832;29423.823047077876;29534;29864.40271194564;30062.849685751433;907.0022974727747;1063.8138325792954;1072.383379556758;1104.3484344236938;1153.9019183369508;15151.393944242327;15175.620609484851;15413.383464661512;15499;15603.375864965274;654222;665715;666910;667686;669152;58629.4137058624;60332.39667603355;60389.69805150946;60432;60465;1311.8294621699308;1346.2124657075524;1447.6453268948753;1466.5160497035952;1499.137995652504;29312.41375172527;30310.69689303167;30807.537886931266;30857.69142308548;30990.380192395576;45173.322400163364;51109.23336150035;51757.96484070373;51799;51811.74094129505;508207;510228.44885775336;510373;511013;511390.4430477824;22498.437539061655;23268.95289712001;26074;26775.598366024136;27169;45830;46027.53972460367;46299.768501157276;46971;47242.52757472519;36323;37929.65985987768;37984.43023354707;38199.80900095482;38950.220995580494;37164.44253336148;37177.4423383644;37184;37921.43117853179;38109.4283585752;36775;37250.8137459311;37514.87455376343;37995.620043800314;38090.61909380871;31077.844610776803;33026.33947321088;33045.504317434774;33169.00492985214;33222.83388583138;21090.789092108884;23304.300870973897;23705.40736481602;24086.87956560206;24722.752772472046 05:15:43 INFO - PROCESS | 1663 | |2;jquery.html;327.85574347287195;390.60743952327914;400.9639132478078;413.8551506972558;422.31163203977513;19165;21109.57780844382;21975.560488790215;22134.22530211441;22193.223237186703;20634.48413789656;21215.36353909385;21378.679319810217;21406.357809265708;21425.785742142572;5563.527100196488;6070.726817293233;6079.908801367983;6085.78699745509;6091.2690477142805;4181.310083836163;4411.823527058914;5943.138244954479;6382.744690212386;6727.831804204885;6337.714802833883;6649.667516624174;6662.333766623347;6664.966675166617;6673.766418175364;6626.6686665667;6640;6652.767153149641;6660.933390666104;6674.232463266713;3724.944125838115;5426.728663566845;5760.415885631485;5772.134179873053;5784.68184249866;25630.84661190251;25696.614550781753;25849.870750646314;25926.481470370487;26203.737962620322;2070.958580828375;2071.099071903727;2071.492484341346;2219.3785739992827;2305.342977251492;12428;14468.638284042878;14998.700025999527;15092.39630414782;15433.836063607047;1660.1865086107841;1674.2214870085347;1689.3833750681035;1750.4923572164057;1875.456117725858;113770;115509.4224528872;115788;116749;118155;10736.946315268373;10921.235513513977;11380.658580242605;11517.021053210403;11554.017908477705;12176.512939482322;13381.063325567311;14269.714605707828;14424.855751442561;14435.711285774225;8576.828463430697;8792.34057445694;8793.516356600321;8903.154200350957;8904.465732056098;8865.68970086044;8871.556422178865;8889.4666320021;9038.276937844958;9055.773605659779;6593.769218077345;6701.932980670131;6712;6730.115567641068;6730.831729206809;1691.289658343474;1826.844718198968;1828.8719789614865;1857.1828395505934;1880.7555017847592;907.3421769217397;954.2080073538946;1023.4422239879284;1026.2713473433946;1048;21109.68335474969;21275.8936205318;22450.188395626083;22801.087956481886;22989.88505057464;999.4103478947455;1014.5890914179769;1016.4968340671415;1031.257494603884;1031.257494603899;10981.835272470918;11586.652400428;11601.883981160081;11726;12241.571544995883;593402.0329898323;612808;620753;630973;633311;123281.76718232883;133808;134706.32646836704;138455;154883.4511654869;852.5353682243192;1076.5639915834256;1094.102835674755;1096.7313008313015;1097.7859317433024;16091;17104.23030963585;18238.544036399195;18666.253349865852;18967.05164741785;51534.74232628813;51685.7415712919;52246.21630675467;53698.194527081345;54479;73693;74806.62596686982;76074.61962690373;77629.44741105259;79737;7013.333733295329;8193.877091843508;8325.084240733635;8407.621657025325;8713.607887645197;8179.67281308741;12130.165741459325;12702.30137342455;12916.612501624966;12979.610611681665;10881.292715973437;11242.156838237328;11517.193796434169;11572;11661.475233614337;10971.341719496855;11104.500297486795;11328.490217940378;11369.602063927723;11463.446331636886;11038.613648522263;11597.6520704379;11603;11885;11979;8304.211099945496;8338;8398.83202335962;8556.144385561394;8770.210681038738;9934.254930880357;11429.371384573928;11457.541698331974;11782.587609433629;11826.52693892269 05:15:43 INFO - PROCESS | 1663 | |3;mootools.html;1376.2774543364726;1597.616572022714;1623.2451909861916;1863.8229368210025;1980.4949737816855;7612.238776122385;8070.55611941343;8083.797905052376;8097.068837083737;8107.067687215972;8310.542920139389;8617.526036068013;8623.827523449527;8707.825843483126;8714.302855771539;2352.4942137440403;2429.8906549205326;2430.3559556717482;2431.4286142756437;2431.4772323950347;2385.797207237387;2410.4455975125734;2433.3794882304987;2436.561418944589;2437.00082965984;2230.464688474771;2233.9776602233933;2386.8448550844228;2398.9760102398927;2430.987845060772;2368.86971216583;2381.6189409694366;2412.0592968742203;2416.3355077353694;2424.260600516842;2398.2445529658276;2398.78410943016;2434.57394955882;2439.3779586205583;2441.1333976438286;8604.741857744277;8686.478811271343;8688.609012594385;8690.739277821676;8703.52130632815;5197.714125723083;5214.374275086976;5384.030874442598;5389;5446.428125046853;3416.3167366526795;3513.8770143045126;3586.444101164324;3596.478510615972;3600.2979419013304;853.4367317570436;1019.2304809868576;1020.0972139656383;1037.3723897042257;1058.867641544807;11587.652370428901;11682.766344673144;11685.591004314809;11691.473883675255;11692.356920369377;4073.165001174752;4211.473565804275;4620.838270660511;4723.409573803275;5065.8733531662;9147.359684822131;9160.770980725401;9242.584083716249;9244;9255.120763527455;5323.068463018956;5341.305630268041;5345.65253258537;5348.117560602457;5361.973190134102;4352.804123814436;4365.825366985348;4400.933985990213;4785.856424307276;4998.875028124253;3821.522309711286;3861.0347413146715;3867.690584753213;3905.414187871842;3974.6025397460603;1200.3518100225954;1208.7763763703742;1214.9149559530924;1222.5720997650928;1222.718774681828;902.5757893789909;914.5610107148606;931.092185119503;934.238595544629;965.9130678238993;5648.209250704905;5723.456271654186;5929.466348028698;5963.7912673056235;6068.393160683984;407.71663693733166;442.239348320894;454.057830002739;454.78852333665003;483.3619622098866;3819.3698039823676;3889.3776995680555;3997.2405243003736;3997.320455522568;4262.339337402707;10670.199735019905;10690.679279621623;10714.196435267386;10996.615118470816;11015.944920275348;10861.565537378574;10942.507587158598;10946.726331841608;10956.397398143019;10957.17821163416;7817.648205830751;8076.4750291230885;8318.209770071833;8342.541160236124;8414.537200453911;5945.672987985702;6050.394960503914;6136.5090792737365;6151.446369826737;6199.752009919733;8557.529335886336;8889.688860889837;9921.702348929544;10017.39895606266;10232.283740138118;9962.053604907522;10038.598456061676;10143.594256229668;10292.897071029493;10326.638567650096;6877.209120951155;7125.572465652078;7414.073240844895;7626.504277222185;7645.541267523967;6724.73101075971;8389.622466988878;8479.91520084816;8480.279176270085;8492.830143397221;6831.829204269933;7168.462365322517;7257.782266532013;7475.88786168197;7533.7363192288;7257.310555497219;7311.305425984523;7334.926650733424;7470.850582988419;7481.7755467336065;7031.367176954099;7077;7112.715491380287;7113.253108423541;7127.501074924706;4701.294805779024;4864.513548645107;4867.780949857194;4984.277279794482;5046.444891061907;4755.690880092783;4925.581325587365;5076.466970967996;5082.085224659597;5267.420583735862 05:15:43 INFO - PROCESS | 1663 | |4;prototype.html;256.40386102312124;310.46444882577555;327.52672388398764;329.78069583726824;330.5900683152891;4780.0917825613105;5059.418166910807;5309.575233981282;5318.46815318468;5374.973125134374;4325.783710814462;4500.302453119763;4658.906821863569;4784.330193772875;5157.896842063165;3548.6983606393487;3882.8835134945857;3977.701672374569;4076.30702780528;4080.8571699990507;3249.317643294911;3971.126352202525;4037.777922214277;4057.9594204058026;4060.9796951015205;3898.298306304864;4030.20655808164;4033.878983630496;4059.5940405959464;4067.5525692173837;3365.0409633254526;3986.3223252047223;4027.6979226557833;4033.7612624993412;4059.0664147246152;2999.1002699190376;3145.6697046810214;3534.593521744981;3536.3634545781747;3537;4623.32961720552;5012.598992080625;5017.218887294995;5052.8484145475695;5123.487651234884;4422.911541769179;4670.509596492353;4690.812367505296;4696.694714843524;4700.247960326331;4494.68537202396;5207.82322783102;5220.050671370664;5570.916436253459;5607.8878422431735;3331.6678325172475;3491.3715531204375;3709.5733990591157;3893.532776066862;3966.3257246268204;5117.151916691988;5436.836894893159;5802.651840889518;5828.3297420796725;5847.327557330919;3665.541807274091;3950.4666869972184;3958.44581758554;3985.668383995337;3992.4210989406374;3646.6871926106583;4011.3180759270995;4014.9598504014584;4041.818118184689;4062.674986001172;3934.783586902691;4127.844203622991;4417.823287068545;4484.573965473275;4675.158471475099;5317.995332637048;5533.833984980457;5551.694656793834;5591.440855914376;5604.010576245456;1231.7228623559806;1242.3912282981273;1301.212766276408;1317.4598414650138;1326.7346530693899;787.7833595761211;802.2298593350449;822.9135940726137;902.179017094441;921.9124183202586;4537.342085397599;4657.347971284023;4832.9275060874115;4860.937609306115;4871.878203044952;4785.301218067536;5181.35233095863;5279.947200528022;5471.15499940029;5520.199571062173;4457.353683715843;4481.327800829837;4794.328793968847;4803.351547541107;4849.030193961222;2490.414752533153;2501.299116600729;2582.6771653543306;2833.993932154097;2874.9712502874704;4939.580135688507;4948.554630083309;4996.975015124974;5544.473275038865;5580.469855363815;4568.956473520958;5225.6603320784025;5270.789168433296;5546;5569.66582005081;5040.546350828443;5579;5614.326280846324;5652.9152062719095;5683.89164112994;4605.844996177629;4665.720056796603;5001.924971125509;5121.974390128025;5387.326584176978;4963.659811850852;5190.0555577760415;5400.732556803077;5401.378841433128;5407.56659503233;4795.95204047969;4852.519981405705;4913.533214344775;5431.8098866539485;5637.278162664817;3497.597776255763;3515.0122395963226;3762.529683789526;3768.7550309229814;3793.7480631392127;4724.929126063181;4777.259524773665;4912.975435122801;5021.874453138555;5257.605679574123;4862.756862156949;4995.177724585743;5172.5344718975475;5210.426853046238;5242.868928276824;4630.837920672895;4958.25626156065;5185.351831021122;5217.6086793491395;5255.894882102413;4340.457442819647;4413.757243351646;4673.658334290998;5215.295935048872;5345.33183352081;5339.813106541253;5559.749811258584;5566.304211973504;5572.3034620672415;5576.525995290446;4660.4174478190225;5417.347708948809;5441.945580544144;5453.64551304164;5476.507114359727 05:15:43 INFO - PROCESS | 1663 | |5;yui.html;648.9610623362598;689.2108535726593;717.1179449277389;744.8063503489094;752.259024860512;14830.184339861316;15570.377184912604;15579.143147126932;15580.298886550105;15589.610259743513;14049.297535123254;14074.64813379666;14115.294235288246;14132.081414708062;14176.574702758933;724.8151721311058;754.1440465072128;903.7333986474006;936.180841763457;940.9717708468756;900.5722281916103;911.7492689510372;913.5295322908707;918.7014220378404;919.2002957427048;990.8612794208816;995.6564985080172;997.2171845101597;998.2962011781713;998.6854140945616;766.7086507127311;943.9669611563596;976.8046390721813;978.706388083572;987.3878195518753;729.1905984357359;732.4140687450018;733.3693023999356;733.4389192267919;736.1975446763;14850.777238341434;14893.851061489355;14900.925495372561;14903.403863845542;14904.776428353583;12259.570915018021;12347.506099756;12429.129960902741;12441.626751197478;12598;10941;11111.388873611948;11223.551057957671;11237.775244495064;11459.828102578467;4082.8775136745944;4089.8364065437345;4143.233501802146;4225.302825033866;4392.275274579691;76740.61629691871;78842;79286.6035669818;85871.14128858756;86089;9082.818343633222;9157.489014312698;9158;9268.582913768896;9479.261939793967;19306.613867722568;19511.02444877779;19514.70727939082;19522.609547808963;19546.31587894417;6671.42276785265;7709.190534993857;7773.067231932205;7865.213478652204;8158.755237342889;4111.157212771374;4143.150654115899;4146.212219678312;4228.281192197333;4346.174226896879;3513.191965847869;3698.7239402406126;3701.1117331840164;3722.925541489155;3723.3484140275345;529.3251104841281;643.9742410303629;708.5996412027188;718.741253148866;719.1909102259957;556.7327682712321;600.1477901380051;600.6756351570103;600.9939900600938;604.6432604763211;7232;7992.68029278834;8105.473144245605;8300.169983001771;8364.916350836535;565.8528782516576;590.1590233916652;602.135934933372;604.9304330002018;612.4182027074297;3748.2690875279604;3754.342989976743;3766.60450652683;3783.659470647655;3956.5252169739815;87136;89194;89237.10762892418;93772;94164.0583594169;80999;82669;85726;86708;88231.55884220666;76772.61613692007;77761.61119194368;77885.8317125255;77937.05157371111;79293;7559.395248380007;7637.656305466378;7645.426593005435;7951.960240198762;7973.561454120079;46859.76570117127;47392.52607473881;48257.5174248253;48317;48720;64432;66362.67274654577;66664;66906.66546667235;68216.63566728737;8937;8978.595963181802;10652.041316281571;10728.249022568658;11096.389698566656;11056.944715276371;11564.248323858923;11784.175107742376;11859.822102668295;12382.380880955736;7876.4092693047105;9398.436093834393;10618.893811061791;11597.7100572483;12226.388680565758;9335.859962100438;10039.49802509857;11275.928786765244;11676.94161529187;11853.466594003463;11058.225924185552;11072.52951492093;11128.44357782124;11164.776704466027;11411.714707132056;9347.532623368941;9378.249740020918;9643.228541716506;9705.5147242639;9725.80548389014;8602.838616786654;9274.072592740671;9495.192908602847;9722.611095556098;9776.26677999167 05:15:43 INFO - PROCESS | 1663 | __end_tp_report 05:15:43 INFO - PROCESS | 1663 | __start_cc_report 05:15:43 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1274 05:15:43 INFO - PROCESS | 1663 | __end_cc_report 05:15:43 INFO - PROCESS | 1663 | __startTimestamp1462709743267__endTimestamp 05:15:43 INFO - PROCESS | 1663 | 05:15:43 INFO - PROCESS | 1663 | ------- Summary: start ------- 05:15:43 INFO - PROCESS | 1663 | Number of tests: 6 05:15:43 INFO - PROCESS | 1663 | 05:15:43 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5806.93 Median:6100.05 stddev:653.14 (10.7%) stddev-sans-first:624.12 05:15:43 INFO - PROCESS | 1663 | Values: 3271.5 3407.4 3427.7 3474.6 3501.9 5728.6 5751.2 5818.3 5829.3 5862.9 5670.0 5801.1 5914.0 5916.4 5943.1 5070.7 5124.7 5151.8 5179.7 5291.8 5246.3 5291.9 5304.3 5329.9 5353.3 4377.6 5215.1 5266.2 5299.6 5335.8 5221.7 5235.0 5288.5 5297.5 5318.1 5219.6 5225.3 5227.0 5264.8 5328.1 5768.0 5769.5 5796.9 5805.9 5808.9 6193.9 6226.1 6255.1 6291.4 6329.3 6240.1 6254.0 6289.6 6297.3 6302.0 5967.2 6049.7 6050.9 6056.4 6062.5 6184.8 6216.2 6247.0 6265.8 6329.0 6111.8 6186.8 6191.3 6238.8 6257.4 6224.2 6268.6 6278.8 6291.4 6308.9 6265.4 6309.3 6320.3 6324.1 6352.8 6174.8 6210.3 6270.2 6338.8 6350.5 4951.2 4957.3 4962.9 4964.6 5006.0 4396.4 4480.5 4496.1 4519.0 4524.4 5727.5 5759.7 5796.8 5803.2 5809.1 6249.9 6251.4 6277.8 6287.2 6295.2 5863.8 5869.8 5874.2 5879.9 5908.7 5276.4 5440.8 5471.9 5486.1 5487.8 6327.1 6402.0 6404.1 6417.9 6433.2 6215.5 6221.0 6267.1 6272.6 6278.9 6219.4 6275.1 6287.8 6417.0 6448.6 6267.2 6289.5 6293.4 6296.9 6312.5 6246.3 6268.5 6268.8 6290.9 6308.5 6057.8 6137.5 6159.5 6162.8 6205.7 5002.5 5651.7 6209.0 6217.2 6237.9 6012.5 6061.5 6093.6 6100.1 6110.6 6068.8 6156.7 6193.2 6210.8 6300.2 6155.8 6161.2 6167.3 6191.2 6198.5 05:15:43 INFO - PROCESS | 1663 | 05:15:43 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:66750.73 Median:35897.82 stddev:147016.25 (409.5%) stddev-sans-first:147344.55 05:15:43 INFO - PROCESS | 1663 | Values: 512.8 513.5 533.1 546.6 564.5 30575.4 42357.6 43282.4 43335.0 43398.1 43989.8 44372.0 44440.3 44507.1 44510.8 8454.8 8649.6 9502.9 9708.7 9713.5 11207.5 11479.3 11503.1 11671.1 11672.6 9860.2 11439.5 11537.9 11543.2 11556.1 8487.0 9453.7 11020.8 11516.4 11654.8 11266.9 11333.8 11349.9 11358.8 11369.4 39445.8 39781.8 39844.6 39849.0 39913.8 2980.4 3262.2 3270.9 3283.3 3711.6 27976.9 28345.9 28780.0 29011.6 29354.4 2767.2 2768.0 2782.2 2806.4 2848.2 448002.0 450326.7 451526.0 451877.0 452010.0 35559.6 61515.1 62777.7 62930.0 63078.0 36236.0 37612.4 37643.4 37688.4 37701.4 15028.2 15190.1 15194.5 15198.9 15230.8 11295.2 11353.5 12854.3 12896.7 12932.3 8228.4 8243.3 8256.8 8292.1 8315.5 3338.1 3346.1 3349.7 3350.7 3377.7 982.9 1064.0 1093.7 1113.7 1127.5 29103.6 29423.8 29534.0 29864.4 30062.8 907.0 1063.8 1072.4 1104.3 1153.9 15151.4 15175.6 15413.4 15499.0 15603.4 654222.0 665715.0 666910.0 667686.0 669152.0 58629.4 60332.4 60389.7 60432.0 60465.0 1311.8 1346.2 1447.6 1466.5 1499.1 29312.4 30310.7 30807.5 30857.7 30990.4 45173.3 51109.2 51758.0 51799.0 51811.7 508207.0 510228.4 510373.0 511013.0 511390.4 22498.4 23269.0 26074.0 26775.6 27169.0 45830.0 46027.5 46299.8 46971.0 47242.5 36323.0 37929.7 37984.4 38199.8 38950.2 37164.4 37177.4 37184.0 37921.4 38109.4 36775.0 37250.8 37514.9 37995.6 38090.6 31077.8 33026.3 33045.5 33169.0 33222.8 21090.8 23304.3 23705.4 24086.9 24722.8 05:15:43 INFO - PROCESS | 1663 | 05:15:43 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:36036.76 Median:21700.67 stddev:103147.89 (475.3%) stddev-sans-first:103402.40 05:15:43 INFO - PROCESS | 1663 | Values: 327.9 390.6 401.0 413.9 422.3 19165.0 21109.6 21975.6 22134.2 22193.2 20634.5 21215.4 21378.7 21406.4 21425.8 5563.5 6070.7 6079.9 6085.8 6091.3 4181.3 4411.8 5943.1 6382.7 6727.8 6337.7 6649.7 6662.3 6665.0 6673.8 6626.7 6640.0 6652.8 6660.9 6674.2 3724.9 5426.7 5760.4 5772.1 5784.7 25630.8 25696.6 25849.9 25926.5 26203.7 2071.0 2071.1 2071.5 2219.4 2305.3 12428.0 14468.6 14998.7 15092.4 15433.8 1660.2 1674.2 1689.4 1750.5 1875.5 113770.0 115509.4 115788.0 116749.0 118155.0 10736.9 10921.2 11380.7 11517.0 11554.0 12176.5 13381.1 14269.7 14424.9 14435.7 8576.8 8792.3 8793.5 8903.2 8904.5 8865.7 8871.6 8889.5 9038.3 9055.8 6593.8 6701.9 6712.0 6730.1 6730.8 1691.3 1826.8 1828.9 1857.2 1880.8 907.3 954.2 1023.4 1026.3 1048.0 21109.7 21275.9 22450.2 22801.1 22989.9 999.4 1014.6 1016.5 1031.3 1031.3 10981.8 11586.7 11601.9 11726.0 12241.6 593402.0 612808.0 620753.0 630973.0 633311.0 123281.8 133808.0 134706.3 138455.0 154883.5 852.5 1076.6 1094.1 1096.7 1097.8 16091.0 17104.2 18238.5 18666.3 18967.1 51534.7 51685.7 52246.2 53698.2 54479.0 73693.0 74806.6 76074.6 77629.4 79737.0 7013.3 8193.9 8325.1 8407.6 8713.6 8179.7 12130.2 12702.3 12916.6 12979.6 10881.3 11242.2 11517.2 11572.0 11661.5 10971.3 11104.5 11328.5 11369.6 11463.4 11038.6 11597.7 11603.0 11885.0 11979.0 8304.2 8338.0 8398.8 8556.1 8770.2 9934.3 11429.4 11457.5 11782.6 11826.5 05:15:43 INFO - PROCESS | 1663 | 05:15:43 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5647.05 Median:4866.15 stddev:3171.18 (65.2%) stddev-sans-first:3163.83 05:15:43 INFO - PROCESS | 1663 | Values: 1376.3 1597.6 1623.2 1863.8 1980.5 7612.2 8070.6 8083.8 8097.1 8107.1 8310.5 8617.5 8623.8 8707.8 8714.3 2352.5 2429.9 2430.4 2431.4 2431.5 2385.8 2410.4 2433.4 2436.6 2437.0 2230.5 2234.0 2386.8 2399.0 2431.0 2368.9 2381.6 2412.1 2416.3 2424.3 2398.2 2398.8 2434.6 2439.4 2441.1 8604.7 8686.5 8688.6 8690.7 8703.5 5197.7 5214.4 5384.0 5389.0 5446.4 3416.3 3513.9 3586.4 3596.5 3600.3 853.4 1019.2 1020.1 1037.4 1058.9 11587.7 11682.8 11685.6 11691.5 11692.4 4073.2 4211.5 4620.8 4723.4 5065.9 9147.4 9160.8 9242.6 9244.0 9255.1 5323.1 5341.3 5345.7 5348.1 5362.0 4352.8 4365.8 4400.9 4785.9 4998.9 3821.5 3861.0 3867.7 3905.4 3974.6 1200.4 1208.8 1214.9 1222.6 1222.7 902.6 914.6 931.1 934.2 965.9 5648.2 5723.5 5929.5 5963.8 6068.4 407.7 442.2 454.1 454.8 483.4 3819.4 3889.4 3997.2 3997.3 4262.3 10670.2 10690.7 10714.2 10996.6 11015.9 10861.6 10942.5 10946.7 10956.4 10957.2 7817.6 8076.5 8318.2 8342.5 8414.5 5945.7 6050.4 6136.5 6151.4 6199.8 8557.5 8889.7 9921.7 10017.4 10232.3 9962.1 10038.6 10143.6 10292.9 10326.6 6877.2 7125.6 7414.1 7626.5 7645.5 6724.7 8389.6 8479.9 8480.3 8492.8 6831.8 7168.5 7257.8 7475.9 7533.7 7257.3 7311.3 7334.9 7470.9 7481.8 7031.4 7077.0 7112.7 7113.3 7127.5 4701.3 4864.5 4867.8 4984.3 5046.4 4755.7 4925.6 5076.5 5082.1 5267.4 05:15:43 INFO - PROCESS | 1663 | 05:15:43 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4338.16 Median:4784.82 stddev:1298.12 (27.1%) stddev-sans-first:1265.09 05:15:43 INFO - PROCESS | 1663 | Values: 256.4 310.5 327.5 329.8 330.6 4780.1 5059.4 5309.6 5318.5 5375.0 4325.8 4500.3 4658.9 4784.3 5157.9 3548.7 3882.9 3977.7 4076.3 4080.9 3249.3 3971.1 4037.8 4058.0 4061.0 3898.3 4030.2 4033.9 4059.6 4067.6 3365.0 3986.3 4027.7 4033.8 4059.1 2999.1 3145.7 3534.6 3536.4 3537.0 4623.3 5012.6 5017.2 5052.8 5123.5 4422.9 4670.5 4690.8 4696.7 4700.2 4494.7 5207.8 5220.1 5570.9 5607.9 3331.7 3491.4 3709.6 3893.5 3966.3 5117.2 5436.8 5802.7 5828.3 5847.3 3665.5 3950.5 3958.4 3985.7 3992.4 3646.7 4011.3 4015.0 4041.8 4062.7 3934.8 4127.8 4417.8 4484.6 4675.2 5318.0 5533.8 5551.7 5591.4 5604.0 1231.7 1242.4 1301.2 1317.5 1326.7 787.8 802.2 822.9 902.2 921.9 4537.3 4657.3 4832.9 4860.9 4871.9 4785.3 5181.4 5279.9 5471.2 5520.2 4457.4 4481.3 4794.3 4803.4 4849.0 2490.4 2501.3 2582.7 2834.0 2875.0 4939.6 4948.6 4997.0 5544.5 5580.5 4569.0 5225.7 5270.8 5546.0 5569.7 5040.5 5579.0 5614.3 5652.9 5683.9 4605.8 4665.7 5001.9 5122.0 5387.3 4963.7 5190.1 5400.7 5401.4 5407.6 4796.0 4852.5 4913.5 5431.8 5637.3 3497.6 3515.0 3762.5 3768.8 3793.7 4724.9 4777.3 4913.0 5021.9 5257.6 4862.8 4995.2 5172.5 5210.4 5242.9 4630.8 4958.3 5185.4 5217.6 5255.9 4340.5 4413.8 4673.7 5215.3 5345.3 5339.8 5559.7 5566.3 5572.3 5576.5 4660.4 5417.3 5441.9 5453.6 5476.5 05:15:43 INFO - PROCESS | 1663 | 05:15:43 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:18482.17 Median:36667.71 stddev:26428.04 (72.1%) stddev-sans-first:26468.25 05:15:43 INFO - PROCESS | 1663 | Values: 649.0 689.2 717.1 744.8 752.3 14830.2 15570.4 15579.1 15580.3 15589.6 14049.3 14074.6 14115.3 14132.1 14176.6 724.8 754.1 903.7 936.2 941.0 900.6 911.7 913.5 918.7 919.2 990.9 995.7 997.2 998.3 998.7 766.7 944.0 976.8 978.7 987.4 729.2 732.4 733.4 733.4 736.2 14850.8 14893.9 14900.9 14903.4 14904.8 12259.6 12347.5 12429.1 12441.6 12598.0 10941.0 11111.4 11223.6 11237.8 11459.8 4082.9 4089.8 4143.2 4225.3 4392.3 76740.6 78842.0 79286.6 85871.1 86089.0 9082.8 9157.5 9158.0 9268.6 9479.3 19306.6 19511.0 19514.7 19522.6 19546.3 6671.4 7709.2 7773.1 7865.2 8158.8 4111.2 4143.2 4146.2 4228.3 4346.2 3513.2 3698.7 3701.1 3722.9 3723.3 529.3 644.0 708.6 718.7 719.2 556.7 600.1 600.7 601.0 604.6 7232.0 7992.7 8105.5 8300.2 8364.9 565.9 590.2 602.1 604.9 612.4 3748.3 3754.3 3766.6 3783.7 3956.5 87136.0 89194.0 89237.1 93772.0 94164.1 80999.0 82669.0 85726.0 86708.0 88231.6 76772.6 77761.6 77885.8 77937.1 79293.0 7559.4 7637.7 7645.4 7952.0 7973.6 46859.8 47392.5 48257.5 48317.0 48720.0 64432.0 66362.7 66664.0 66906.7 68216.6 8937.0 8978.6 10652.0 10728.2 11096.4 11056.9 11564.2 11784.2 11859.8 12382.4 7876.4 9398.4 10618.9 11597.7 12226.4 9335.9 10039.5 11275.9 11676.9 11853.5 11058.2 11072.5 11128.4 11164.8 11411.7 9347.5 9378.2 9643.2 9705.5 9725.8 8602.8 9274.1 9495.2 9722.6 9776.3 05:15:43 INFO - PROCESS | 1663 | -------- Summary: end -------- 05:15:43 INFO - PROCESS | 1663 | 05:15:43 INFO - TEST-INFO | 1663: exit 0 05:15:43 INFO - TEST-OK | dromaeo_css | took 1115837ms 05:15:43 INFO - TEST-START | kraken 05:15:43 INFO - operating with platform_type : mac_ 05:15:43 INFO - Initialising browser for kraken test... 05:15:43 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpaHSYN8/profile http://localhost:49269/getInfo.html) 05:15:46 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 05:15:46 INFO - PROCESS | 1670 | colorDepth:24 05:15:46 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 05:15:46 INFO - PROCESS | 1670 | __metrics 05:15:46 INFO - PROCESS | 1670 | 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" 05:15:46 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:15:46 INFO - TEST-INFO | 1670: exit 0 05:15:46 INFO - Browser initialized. 05:15:46 INFO - Running cycle 1/1 for kraken test... 05:15:46 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpaHSYN8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:58 INFO - PROCESS | 1673 | RSS: Main: 239210496 05:15:58 INFO - PROCESS | 1673 | 05:16:01 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 05:16:02 INFO - PROCESS | 1673 | RSS: Main: 286302208 05:16:02 INFO - PROCESS | 1673 | 05:16:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 05:16:05 INFO - PROCESS | 1673 | RSS: Main: 295055360 05:16:05 INFO - PROCESS | 1673 | 05:16:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 05:16:09 INFO - PROCESS | 1673 | RSS: Main: 384684032 05:16:09 INFO - PROCESS | 1673 | 05:16:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 05:16:12 INFO - PROCESS | 1673 | RSS: Main: 283615232 05:16:12 INFO - PROCESS | 1673 | 05:16:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:16:15 INFO - PROCESS | 1673 | RSS: Main: 287813632 05:16:15 INFO - PROCESS | 1673 | 05:16:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 05:16:18 INFO - PROCESS | 1673 | RSS: Main: 326934528 05:16:18 INFO - PROCESS | 1673 | 05:16:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 05:16:22 INFO - PROCESS | 1673 | RSS: Main: 326443008 05:16:22 INFO - PROCESS | 1673 | 05:16:24 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 05:16:25 INFO - PROCESS | 1673 | RSS: Main: 324841472 05:16:25 INFO - PROCESS | 1673 | 05:16:27 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:16:27 INFO - PROCESS | 1673 | RSS: Main: 312836096 05:16:27 INFO - PROCESS | 1673 | 05:16:29 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:16:30 INFO - PROCESS | 1673 | RSS: Main: 319098880 05:16:30 INFO - PROCESS | 1673 | 05:16:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:16:33 INFO - PROCESS | 1673 | RSS: Main: 320880640 05:16:33 INFO - PROCESS | 1673 | 05:16:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:16:36 INFO - PROCESS | 1673 | RSS: Main: 325791744 05:16:36 INFO - PROCESS | 1673 | 05:16:39 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:16:40 INFO - PROCESS | 1673 | RSS: Main: 323383296 05:16:40 INFO - PROCESS | 1673 | 05:16:41 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 05:16:41 INFO - PROCESS | 1673 | RSS: Main: 370348032 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | __start_tp_report 05:16:42 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 05:16:42 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 05:16:42 INFO - PROCESS | 1673 | |i|pagename|runs| 05:16:42 INFO - PROCESS | 1673 | |0;ai-astar;112;109;112;112;113;113;111;113;113;112 05:16:42 INFO - PROCESS | 1673 | |1;audio-beat-detection;157;140;171;191;144;139;138;119;168;118 05:16:42 INFO - PROCESS | 1673 | |2;audio-dft;188;185;186;185;185;179;183;185;184;181 05:16:42 INFO - PROCESS | 1673 | |3;audio-fft;78;75;58;107;55;61;56;57;113;56 05:16:42 INFO - PROCESS | 1673 | |4;audio-oscillator;93;93;104;78;80;78;130;113;113;101 05:16:42 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;88;85;87;85;88;85;87;84;87;84 05:16:42 INFO - PROCESS | 1673 | |6;imaging-darkroom;93;103;149;94;93;92;93;94;92;93 05:16:42 INFO - PROCESS | 1673 | |7;imaging-desaturate;85;86;87;85;84;86;84;85;84;84 05:16:42 INFO - PROCESS | 1673 | |8;json-parse-financial;57;58;58;58;57;56;57;75;58;56 05:16:42 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;63;63;73;66;72;68;68;67;69;69 05:16:42 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;86;85;82;85;84;77;85;85;85;85 05:16:42 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;151;157;141;133;134;138;135;137;183;137 05:16:42 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;180;243;166;167;167;168;169;168;166;165 05:16:42 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;67;67;66;67;66;66;67;67;66;66 05:16:42 INFO - PROCESS | 1673 | __end_tp_report 05:16:42 INFO - PROCESS | 1673 | __start_cc_report 05:16:42 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1940 05:16:42 INFO - PROCESS | 1673 | __end_cc_report 05:16:42 INFO - PROCESS | 1673 | __startTimestamp1462709801996__endTimestamp 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | ------- Summary: start ------- 05:16:42 INFO - PROCESS | 1673 | Number of tests: 14 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.50 stddev:1.25 (1.1%) stddev-sans-first:1.32 05:16:42 INFO - PROCESS | 1673 | Values: 112.0 109.0 112.0 112.0 113.0 113.0 111.0 113.0 113.0 112.0 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:148.50 Median:150.50 stddev:23.23 (15.4%) stddev-sans-first:24.44 05:16:42 INFO - PROCESS | 1673 | Values: 157.0 140.0 171.0 191.0 144.0 139.0 138.0 119.0 168.0 118.0 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:184.10 Median:185.00 stddev:2.56 (1.4%) stddev-sans-first:2.29 05:16:42 INFO - PROCESS | 1673 | Values: 188.0 185.0 186.0 185.0 185.0 179.0 183.0 185.0 184.0 181.0 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:71.60 Median:57.50 stddev:21.84 (38.0%) stddev-sans-first:23.04 05:16:42 INFO - PROCESS | 1673 | Values: 78.0 75.0 58.0 107.0 55.0 61.0 56.0 57.0 113.0 56.0 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:98.30 Median:78.00 stddev:17.30 (22.2%) stddev-sans-first:18.24 05:16:42 INFO - PROCESS | 1673 | Values: 93.0 93.0 104.0 78.0 80.0 78.0 130.0 113.0 113.0 101.0 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.56 (1.8%) stddev-sans-first:1.48 05:16:42 INFO - PROCESS | 1673 | Values: 88.0 85.0 87.0 85.0 88.0 85.0 87.0 84.0 87.0 84.0 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:99.60 Median:93.00 stddev:17.65 (19.0%) stddev-sans-first:18.56 05:16:42 INFO - PROCESS | 1673 | Values: 93.0 103.0 149.0 94.0 93.0 92.0 93.0 94.0 92.0 93.0 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:1.05 (1.2%) stddev-sans-first:1.12 05:16:42 INFO - PROCESS | 1673 | Values: 85.0 86.0 87.0 85.0 84.0 86.0 84.0 85.0 84.0 84.0 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:58.00 stddev:5.68 (9.8%) stddev-sans-first:5.97 05:16:42 INFO - PROCESS | 1673 | Values: 57.0 58.0 58.0 58.0 57.0 56.0 57.0 75.0 58.0 56.0 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.50 stddev:3.29 (4.8%) stddev-sans-first:3.00 05:16:42 INFO - PROCESS | 1673 | Values: 63.0 63.0 73.0 66.0 72.0 68.0 68.0 67.0 69.0 69.0 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:85.00 stddev:2.64 (3.1%) stddev-sans-first:2.69 05:16:42 INFO - PROCESS | 1673 | Values: 86.0 85.0 82.0 85.0 84.0 77.0 85.0 85.0 85.0 85.0 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:144.60 Median:139.50 stddev:15.56 (11.2%) stddev-sans-first:16.34 05:16:42 INFO - PROCESS | 1673 | Values: 151.0 157.0 141.0 133.0 134.0 138.0 135.0 137.0 183.0 137.0 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:168.00 stddev:23.96 (14.3%) stddev-sans-first:25.36 05:16:42 INFO - PROCESS | 1673 | Values: 180.0 243.0 166.0 167.0 167.0 168.0 169.0 168.0 166.0 165.0 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 05:16:42 INFO - PROCESS | 1673 | Values: 67.0 67.0 66.0 67.0 66.0 66.0 67.0 67.0 66.0 66.0 05:16:42 INFO - PROCESS | 1673 | -------- Summary: end -------- 05:16:42 INFO - PROCESS | 1673 | 05:16:42 INFO - TEST-INFO | 1673: exit 0 05:16:42 INFO - TEST-OK | kraken | took 59097ms 05:16:42 INFO - SUITE-END | took 1174s 05:16:43 INFO - Completed test suite (00:19:35) 05:16:43 INFO - javascript benchmark 05:16:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3271.525628783826, 3407.403704351739, 3427.708644765195, 3474.6004209515913, 3501.859925602976, 5728.598998070137, 5751.223584816049, 5818.272715910512, 5829.271341082364, 5862.853428664285, 5669.971650141743, 5801.07182850744, 5914.0, 5916.378780228068, 5943.108533719944, 5070.72111033893, 5124.718140502271, 5151.793928242884, 5179.689218646874, 5291.841244762663, 5246.344206974129, 5291.947080529203, 5304.283921670583, 5329.946700532983, 5353.330833645794, 4377.649788016951, 5215.113430716769, 5266.183741520071, 5299.60252981026, 5335.7865685372535, 5221.686698798084, 5235.031519168966, 5288.497592728723, 5297.549708274802, 5318.1225097858805, 5219.6085293602755, 5225.268462415227, 5227.0, 5264.789408423659, 5328.094223981932, 5768.048272035151, 5769.480746732772, 5796.855078623026, 5805.941940580625, 5808.854778630526, 6193.94702900508, 6226.066090086478, 6255.06174073888, 6291.433770960635, 6329.3354197809485, 6240.095186197976, 6254.0, 6289.622622642611, 6297.307296197415, 6302.0, 5967.16459695643, 6049.727762250709, 6050.878982420327, 6056.424639659182, 6062.514998800174, 6184.752609895644, 6216.191895053614, 6247.0, 6265.843353916189, 6329.0, 6111.8380790797555, 6186.783462578788, 6191.25704915413, 6238.750449982041, 6257.374262573706, 6224.1597384352535, 6268.561200715997, 6278.843028924222, 6291.402316779899, 6308.936910630835, 6265.4361107500545, 6309.274433440213, 6320.336364681734, 6324.051392291101, 6352.841178970562, 6174.783882564088, 6210.31686514483, 6270.2475702915945, 6338.809835704844, 6350.4602108820345, 4951.232558953368, 4957.281194226889, 4962.851114466572, 4964.553190212898, 5006.0, 4396.384506169139, 4480.484744254387, 4496.055828276033, 4518.9832287735, 4524.389207456951, 5727.4845263926445, 5759.740811663485, 5796.82609521715, 5803.216565763652, 5809.099589563624, 6249.9062514062325, 6251.374862513712, 6277.843053923597, 6287.182666253358, 6295.18162638854, 5863.824085277449, 5869.765209391577, 5874.236349274567, 5879.853003674943, 5908.704564771744, 5276.39321478035, 5440.809571665052, 5471.9179212311055, 5486.1222204447095, 5487.780488780484, 6327.082573026977, 6401.967990160019, 6404.103425520524, 6417.8716425672155, 6433.163688720437, 6215.533834962305, 6220.968895155495, 6267.091271765659, 6272.560920735505, 6278.937210627835, 6219.440250377488, 6275.058741188858, 6287.779927702509, 6417.005364168562, 6448.580842245239, 6267.185265915402, 6289.49684025286, 6293.37066293367, 6296.937030629635, 6312.526560508072, 6246.250449946036, 6268.498520118471, 6268.811935641938, 6290.905636415366, 6308.52686048539, 6057.757689692539, 6137.5302083125, 6159.538034647329, 6162.753489860355, 6205.6586887721605, 5002.499750024968, 5651.660900345828, 6208.9689551551955, 6217.160683307696, 6237.937620623918, 6012.458878700763, 6061.545384096122, 6093.6039157454625, 6100.054491553817, 6110.572259941761, 6068.787592434243, 6156.722947467285, 6193.163922870355, 6210.813675589739, 6300.180976473029, 6155.815325540241, 6161.229846269216, 6167.321594624498, 6191.226096737908, 6198.504119670325], "value": 5764.790123757791, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.7718165416392, 513.4916432731595, 533.1070456984552, 546.5873265684409, 564.4863174511196, 30575.38849223014, 42357.57642423575, 43282.350764738556, 43335.0, 43398.13203735923, 43989.78005109978, 44372.0, 44440.33339499902, 44507.109857802905, 44510.77744611281, 8454.830903381928, 8649.567521623925, 9502.904970950305, 9708.66019689311, 9713.514324283757, 11207.495662695157, 11479.25384849982, 11503.07975361969, 11671.066314694805, 11672.591459298927, 9860.161886239643, 11439.5424183033, 11537.942310288438, 11543.249688770245, 11556.075513958906, 8487.023992240847, 9453.669121580711, 11020.834687479693, 11516.366599837005, 11654.766904661901, 11266.887331126665, 11333.829992550118, 11349.943250283779, 11358.772824543463, 11369.374684392355, 39445.80277098625, 39781.80654580338, 39844.60155398438, 39849.00377490557, 39913.80043099766, 2980.3741214345014, 3262.2496825730213, 3270.9182270443193, 3283.337878374684, 3711.5731690855628, 27976.860115699496, 28345.858270708515, 28780.0, 29011.564826527618, 29354.41291174186, 2767.239009272446, 2768.0, 2782.2070709847594, 2806.424682939992, 2848.245215018022, 448002.0, 450326.74836625607, 451526.0, 451877.0, 452010.0, 35559.64440355615, 61515.07727384093, 62777.74444511084, 62930.0, 63078.0, 36236.0, 37612.43581346282, 37643.43534846979, 37688.43467347992, 37701.43447848284, 15028.17345046033, 15190.088594684354, 15194.544163675107, 15198.924005379902, 15230.771538426932, 11295.209335346612, 11353.545858165746, 12854.293013884326, 12896.677583060498, 12932.288724120264, 8228.382871284677, 8243.340532757366, 8256.793580160544, 8292.12932642076, 8315.501069935823, 3338.082027442424, 3346.0798280473064, 3349.71527420172, 3350.66493350663, 3377.746668999835, 982.94102353859, 1063.9521221545049, 1093.7211011192255, 1113.6714669172613, 1127.4588197665003, 29103.56344654832, 29423.823047077876, 29534.0, 29864.40271194564, 30062.849685751433, 907.0022974727747, 1063.8138325792954, 1072.383379556758, 1104.3484344236938, 1153.9019183369508, 15151.393944242327, 15175.620609484851, 15413.383464661512, 15499.0, 15603.375864965274, 654222.0, 665715.0, 666910.0, 667686.0, 669152.0, 58629.4137058624, 60332.39667603355, 60389.69805150946, 60432.0, 60465.0, 1311.8294621699308, 1346.2124657075524, 1447.6453268948753, 1466.5160497035952, 1499.137995652504, 29312.41375172527, 30310.69689303167, 30807.537886931266, 30857.69142308548, 30990.380192395576, 45173.322400163364, 51109.23336150035, 51757.96484070373, 51799.0, 51811.74094129505, 508207.0, 510228.44885775336, 510373.0, 511013.0, 511390.4430477824, 22498.437539061655, 23268.95289712001, 26074.0, 26775.598366024136, 27169.0, 45830.0, 46027.53972460367, 46299.768501157276, 46971.0, 47242.52757472519, 36323.0, 37929.65985987768, 37984.43023354707, 38199.80900095482, 38950.220995580494, 37164.44253336148, 37177.4423383644, 37184.0, 37921.43117853179, 38109.4283585752, 36775.0, 37250.8137459311, 37514.87455376343, 37995.620043800314, 38090.61909380871, 31077.844610776803, 33026.33947321088, 33045.504317434774, 33169.00492985214, 33222.83388583138, 21090.789092108884, 23304.300870973897, 23705.40736481602, 24086.87956560206, 24722.752772472046], "value": 18822.549791683603, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.85574347287195, 390.60743952327914, 400.9639132478078, 413.8551506972558, 422.31163203977513, 19165.0, 21109.57780844382, 21975.560488790215, 22134.22530211441, 22193.223237186703, 20634.48413789656, 21215.36353909385, 21378.679319810217, 21406.357809265708, 21425.785742142572, 5563.527100196488, 6070.726817293233, 6079.908801367983, 6085.78699745509, 6091.2690477142805, 4181.310083836163, 4411.823527058914, 5943.138244954479, 6382.744690212386, 6727.831804204885, 6337.714802833883, 6649.667516624174, 6662.333766623347, 6664.966675166617, 6673.766418175364, 6626.6686665667, 6640.0, 6652.767153149641, 6660.933390666104, 6674.232463266713, 3724.944125838115, 5426.728663566845, 5760.415885631485, 5772.134179873053, 5784.68184249866, 25630.84661190251, 25696.614550781753, 25849.870750646314, 25926.481470370487, 26203.737962620322, 2070.958580828375, 2071.099071903727, 2071.492484341346, 2219.3785739992827, 2305.342977251492, 12428.0, 14468.638284042878, 14998.700025999527, 15092.39630414782, 15433.836063607047, 1660.1865086107841, 1674.2214870085347, 1689.3833750681035, 1750.4923572164057, 1875.456117725858, 113770.0, 115509.4224528872, 115788.0, 116749.0, 118155.0, 10736.946315268373, 10921.235513513977, 11380.658580242605, 11517.021053210403, 11554.017908477705, 12176.512939482322, 13381.063325567311, 14269.714605707828, 14424.855751442561, 14435.711285774225, 8576.828463430697, 8792.34057445694, 8793.516356600321, 8903.154200350957, 8904.465732056098, 8865.68970086044, 8871.556422178865, 8889.4666320021, 9038.276937844958, 9055.773605659779, 6593.769218077345, 6701.932980670131, 6712.0, 6730.115567641068, 6730.831729206809, 1691.289658343474, 1826.844718198968, 1828.8719789614865, 1857.1828395505934, 1880.7555017847592, 907.3421769217397, 954.2080073538946, 1023.4422239879284, 1026.2713473433946, 1048.0, 21109.68335474969, 21275.8936205318, 22450.188395626083, 22801.087956481886, 22989.88505057464, 999.4103478947455, 1014.5890914179769, 1016.4968340671415, 1031.257494603884, 1031.257494603899, 10981.835272470918, 11586.652400428, 11601.883981160081, 11726.0, 12241.571544995883, 593402.0329898323, 612808.0, 620753.0, 630973.0, 633311.0, 123281.76718232883, 133808.0, 134706.32646836704, 138455.0, 154883.4511654869, 852.5353682243192, 1076.5639915834256, 1094.102835674755, 1096.7313008313015, 1097.7859317433024, 16091.0, 17104.23030963585, 18238.544036399195, 18666.253349865852, 18967.05164741785, 51534.74232628813, 51685.7415712919, 52246.21630675467, 53698.194527081345, 54479.0, 73693.0, 74806.62596686982, 76074.61962690373, 77629.44741105259, 79737.0, 7013.333733295329, 8193.877091843508, 8325.084240733635, 8407.621657025325, 8713.607887645197, 8179.67281308741, 12130.165741459325, 12702.30137342455, 12916.612501624966, 12979.610611681665, 10881.292715973437, 11242.156838237328, 11517.193796434169, 11572.0, 11661.475233614337, 10971.341719496855, 11104.500297486795, 11328.490217940378, 11369.602063927723, 11463.446331636886, 11038.613648522263, 11597.6520704379, 11603.0, 11885.0, 11979.0, 8304.211099945496, 8338.0, 8398.83202335962, 8556.144385561394, 8770.210681038738, 9934.254930880357, 11429.371384573928, 11457.541698331974, 11782.587609433629, 11826.52693892269], "value": 9913.061040544057, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1376.2774543364726, 1597.616572022714, 1623.2451909861916, 1863.8229368210025, 1980.4949737816855, 7612.238776122385, 8070.55611941343, 8083.797905052376, 8097.068837083737, 8107.067687215972, 8310.542920139389, 8617.526036068013, 8623.827523449527, 8707.825843483126, 8714.302855771539, 2352.4942137440403, 2429.8906549205326, 2430.3559556717482, 2431.4286142756437, 2431.4772323950347, 2385.797207237387, 2410.4455975125734, 2433.3794882304987, 2436.561418944589, 2437.00082965984, 2230.464688474771, 2233.9776602233933, 2386.8448550844228, 2398.9760102398927, 2430.987845060772, 2368.86971216583, 2381.6189409694366, 2412.0592968742203, 2416.3355077353694, 2424.260600516842, 2398.2445529658276, 2398.78410943016, 2434.57394955882, 2439.3779586205583, 2441.1333976438286, 8604.741857744277, 8686.478811271343, 8688.609012594385, 8690.739277821676, 8703.52130632815, 5197.714125723083, 5214.374275086976, 5384.030874442598, 5389.0, 5446.428125046853, 3416.3167366526795, 3513.8770143045126, 3586.444101164324, 3596.478510615972, 3600.2979419013304, 853.4367317570436, 1019.2304809868576, 1020.0972139656383, 1037.3723897042257, 1058.867641544807, 11587.652370428901, 11682.766344673144, 11685.591004314809, 11691.473883675255, 11692.356920369377, 4073.165001174752, 4211.473565804275, 4620.838270660511, 4723.409573803275, 5065.8733531662, 9147.359684822131, 9160.770980725401, 9242.584083716249, 9244.0, 9255.120763527455, 5323.068463018956, 5341.305630268041, 5345.65253258537, 5348.117560602457, 5361.973190134102, 4352.804123814436, 4365.825366985348, 4400.933985990213, 4785.856424307276, 4998.875028124253, 3821.522309711286, 3861.0347413146715, 3867.690584753213, 3905.414187871842, 3974.6025397460603, 1200.3518100225954, 1208.7763763703742, 1214.9149559530924, 1222.5720997650928, 1222.718774681828, 902.5757893789909, 914.5610107148606, 931.092185119503, 934.238595544629, 965.9130678238993, 5648.209250704905, 5723.456271654186, 5929.466348028698, 5963.7912673056235, 6068.393160683984, 407.71663693733166, 442.239348320894, 454.057830002739, 454.78852333665003, 483.3619622098866, 3819.3698039823676, 3889.3776995680555, 3997.2405243003736, 3997.320455522568, 4262.339337402707, 10670.199735019905, 10690.679279621623, 10714.196435267386, 10996.615118470816, 11015.944920275348, 10861.565537378574, 10942.507587158598, 10946.726331841608, 10956.397398143019, 10957.17821163416, 7817.648205830751, 8076.4750291230885, 8318.209770071833, 8342.541160236124, 8414.537200453911, 5945.672987985702, 6050.394960503914, 6136.5090792737365, 6151.446369826737, 6199.752009919733, 8557.529335886336, 8889.688860889837, 9921.702348929544, 10017.39895606266, 10232.283740138118, 9962.053604907522, 10038.598456061676, 10143.594256229668, 10292.897071029493, 10326.638567650096, 6877.209120951155, 7125.572465652078, 7414.073240844895, 7626.504277222185, 7645.541267523967, 6724.73101075971, 8389.622466988878, 8479.91520084816, 8480.279176270085, 8492.830143397221, 6831.829204269933, 7168.462365322517, 7257.782266532013, 7475.88786168197, 7533.7363192288, 7257.310555497219, 7311.305425984523, 7334.926650733424, 7470.850582988419, 7481.7755467336065, 7031.367176954099, 7077.0, 7112.715491380287, 7113.253108423541, 7127.501074924706, 4701.294805779024, 4864.513548645107, 4867.780949857194, 4984.277279794482, 5046.444891061907, 4755.690880092783, 4925.581325587365, 5076.466970967996, 5082.085224659597, 5267.420583735862], "value": 4472.688363334737, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.40386102312124, 310.46444882577555, 327.52672388398764, 329.78069583726824, 330.5900683152891, 4780.0917825613105, 5059.418166910807, 5309.575233981282, 5318.46815318468, 5374.973125134374, 4325.783710814462, 4500.302453119763, 4658.906821863569, 4784.330193772875, 5157.896842063165, 3548.6983606393487, 3882.8835134945857, 3977.701672374569, 4076.30702780528, 4080.8571699990507, 3249.317643294911, 3971.126352202525, 4037.777922214277, 4057.9594204058026, 4060.9796951015205, 3898.298306304864, 4030.20655808164, 4033.878983630496, 4059.5940405959464, 4067.5525692173837, 3365.0409633254526, 3986.3223252047223, 4027.6979226557833, 4033.7612624993412, 4059.0664147246152, 2999.1002699190376, 3145.6697046810214, 3534.593521744981, 3536.3634545781747, 3537.0, 4623.32961720552, 5012.598992080625, 5017.218887294995, 5052.8484145475695, 5123.487651234884, 4422.911541769179, 4670.509596492353, 4690.812367505296, 4696.694714843524, 4700.247960326331, 4494.68537202396, 5207.82322783102, 5220.050671370664, 5570.916436253459, 5607.8878422431735, 3331.6678325172475, 3491.3715531204375, 3709.5733990591157, 3893.532776066862, 3966.3257246268204, 5117.151916691988, 5436.836894893159, 5802.651840889518, 5828.3297420796725, 5847.327557330919, 3665.541807274091, 3950.4666869972184, 3958.44581758554, 3985.668383995337, 3992.4210989406374, 3646.6871926106583, 4011.3180759270995, 4014.9598504014584, 4041.818118184689, 4062.674986001172, 3934.783586902691, 4127.844203622991, 4417.823287068545, 4484.573965473275, 4675.158471475099, 5317.995332637048, 5533.833984980457, 5551.694656793834, 5591.440855914376, 5604.010576245456, 1231.7228623559806, 1242.3912282981273, 1301.212766276408, 1317.4598414650138, 1326.7346530693899, 787.7833595761211, 802.2298593350449, 822.9135940726137, 902.179017094441, 921.9124183202586, 4537.342085397599, 4657.347971284023, 4832.9275060874115, 4860.937609306115, 4871.878203044952, 4785.301218067536, 5181.35233095863, 5279.947200528022, 5471.15499940029, 5520.199571062173, 4457.353683715843, 4481.327800829837, 4794.328793968847, 4803.351547541107, 4849.030193961222, 2490.414752533153, 2501.299116600729, 2582.6771653543306, 2833.993932154097, 2874.9712502874704, 4939.580135688507, 4948.554630083309, 4996.975015124974, 5544.473275038865, 5580.469855363815, 4568.956473520958, 5225.6603320784025, 5270.789168433296, 5546.0, 5569.66582005081, 5040.546350828443, 5579.0, 5614.326280846324, 5652.9152062719095, 5683.89164112994, 4605.844996177629, 4665.720056796603, 5001.924971125509, 5121.974390128025, 5387.326584176978, 4963.659811850852, 5190.0555577760415, 5400.732556803077, 5401.378841433128, 5407.56659503233, 4795.95204047969, 4852.519981405705, 4913.533214344775, 5431.8098866539485, 5637.278162664817, 3497.597776255763, 3515.0122395963226, 3762.529683789526, 3768.7550309229814, 3793.7480631392127, 4724.929126063181, 4777.259524773665, 4912.975435122801, 5021.874453138555, 5257.605679574123, 4862.756862156949, 4995.177724585743, 5172.5344718975475, 5210.426853046238, 5242.868928276824, 4630.837920672895, 4958.25626156065, 5185.351831021122, 5217.6086793491395, 5255.894882102413, 4340.457442819647, 4413.757243351646, 4673.658334290998, 5215.295935048872, 5345.33183352081, 5339.813106541253, 5559.749811258584, 5566.304211973504, 5572.3034620672415, 5576.525995290446, 4660.4174478190225, 5417.347708948809, 5441.945580544144, 5453.64551304164, 5476.507114359727], "value": 3927.250055919889, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.9610623362598, 689.2108535726593, 717.1179449277389, 744.8063503489094, 752.259024860512, 14830.184339861316, 15570.377184912604, 15579.143147126932, 15580.298886550105, 15589.610259743513, 14049.297535123254, 14074.64813379666, 14115.294235288246, 14132.081414708062, 14176.574702758933, 724.8151721311058, 754.1440465072128, 903.7333986474006, 936.180841763457, 940.9717708468756, 900.5722281916103, 911.7492689510372, 913.5295322908707, 918.7014220378404, 919.2002957427048, 990.8612794208816, 995.6564985080172, 997.2171845101597, 998.2962011781713, 998.6854140945616, 766.7086507127311, 943.9669611563596, 976.8046390721813, 978.706388083572, 987.3878195518753, 729.1905984357359, 732.4140687450018, 733.3693023999356, 733.4389192267919, 736.1975446763, 14850.777238341434, 14893.851061489355, 14900.925495372561, 14903.403863845542, 14904.776428353583, 12259.570915018021, 12347.506099756, 12429.129960902741, 12441.626751197478, 12598.0, 10941.0, 11111.388873611948, 11223.551057957671, 11237.775244495064, 11459.828102578467, 4082.8775136745944, 4089.8364065437345, 4143.233501802146, 4225.302825033866, 4392.275274579691, 76740.61629691871, 78842.0, 79286.6035669818, 85871.14128858756, 86089.0, 9082.818343633222, 9157.489014312698, 9158.0, 9268.582913768896, 9479.261939793967, 19306.613867722568, 19511.02444877779, 19514.70727939082, 19522.609547808963, 19546.31587894417, 6671.42276785265, 7709.190534993857, 7773.067231932205, 7865.213478652204, 8158.755237342889, 4111.157212771374, 4143.150654115899, 4146.212219678312, 4228.281192197333, 4346.174226896879, 3513.191965847869, 3698.7239402406126, 3701.1117331840164, 3722.925541489155, 3723.3484140275345, 529.3251104841281, 643.9742410303629, 708.5996412027188, 718.741253148866, 719.1909102259957, 556.7327682712321, 600.1477901380051, 600.6756351570103, 600.9939900600938, 604.6432604763211, 7232.0, 7992.68029278834, 8105.473144245605, 8300.169983001771, 8364.916350836535, 565.8528782516576, 590.1590233916652, 602.135934933372, 604.9304330002018, 612.4182027074297, 3748.2690875279604, 3754.342989976743, 3766.60450652683, 3783.659470647655, 3956.5252169739815, 87136.0, 89194.0, 89237.10762892418, 93772.0, 94164.0583594169, 80999.0, 82669.0, 85726.0, 86708.0, 88231.55884220666, 76772.61613692007, 77761.61119194368, 77885.8317125255, 77937.05157371111, 79293.0, 7559.395248380007, 7637.656305466378, 7645.426593005435, 7951.960240198762, 7973.561454120079, 46859.76570117127, 47392.52607473881, 48257.5174248253, 48317.0, 48720.0, 64432.0, 66362.67274654577, 66664.0, 66906.66546667235, 68216.63566728737, 8937.0, 8978.595963181802, 10652.041316281571, 10728.249022568658, 11096.389698566656, 11056.944715276371, 11564.248323858923, 11784.175107742376, 11859.822102668295, 12382.380880955736, 7876.4092693047105, 9398.436093834393, 10618.893811061791, 11597.7100572483, 12226.388680565758, 9335.859962100438, 10039.49802509857, 11275.928786765244, 11676.94161529187, 11853.466594003463, 11058.225924185552, 11072.52951492093, 11128.44357782124, 11164.776704466027, 11411.714707132056, 9347.532623368941, 9378.249740020918, 9643.228541716506, 9705.5147242639, 9725.80548389014, 8602.838616786654, 9274.072592740671, 9495.192908602847, 9722.611095556098, 9776.26677999167], "value": 6916.519060755289, "unit": "score"}], "name": "dromaeo_css", "value": 7123.775655966578, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 109.0, 112.0, 112.0, 113.0, 113.0, 111.0, 113.0, 113.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 140.0, 171.0, 191.0, 144.0, 139.0, 138.0, 119.0, 168.0, 118.0], "value": 148.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 185.0, 186.0, 185.0, 185.0, 179.0, 183.0, 185.0, 184.0, 181.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 58.0, 107.0, 55.0, 61.0, 56.0, 57.0, 113.0, 56.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 104.0, 78.0, 80.0, 78.0, 130.0, 113.0, 113.0, 101.0], "value": 98.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 85.0, 88.0, 85.0, 87.0, 84.0, 87.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 103.0, 149.0, 94.0, 93.0, 92.0, 93.0, 94.0, 92.0, 93.0], "value": 99.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 87.0, 85.0, 84.0, 86.0, 84.0, 85.0, 84.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 58.0, 57.0, 56.0, 57.0, 75.0, 58.0, 56.0], "value": 59.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 73.0, 66.0, 72.0, 68.0, 68.0, 67.0, 69.0, 69.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 82.0, 85.0, 84.0, 77.0, 85.0, 85.0, 85.0, 85.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 157.0, 141.0, 133.0, 134.0, 138.0, 135.0, 137.0, 183.0, 137.0], "value": 144.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 243.0, 166.0, 167.0, 167.0, 168.0, 169.0, 168.0, 166.0, 165.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 67.0, 66.0, 66.0, 67.0, 67.0, 66.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1482.8, "alertThreshold": 2.0}]} 05:16:43 INFO - Return code: 0 05:16:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:16:43 ERROR - # TBPL SUCCESS # 05:16:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:16:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:16:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:16:43 INFO - 2016-05-08 05:16:43.282 screenresolution[1677:10882] starting screenresolution argv=screenresolution get 05:16:43 INFO - 2016-05-08 05:16:43.284 screenresolution[1677:10882] Display 0: 1600x1200x32@60 05:16:43 INFO - 2016-05-08 05:16:43.291 screenresolution[1678:10885] starting screenresolution argv=screenresolution list 05:16:43 INFO - Available Modes on Display 0 05:16:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:16:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:16:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:16:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:16:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:16:43 INFO - Intel Iris: 05:16:43 INFO - Chipset Model: Intel Iris 05:16:43 INFO - Type: GPU 05:16:43 INFO - Bus: Built-In 05:16:43 INFO - VRAM (Dynamic, Max): 1536 MB 05:16:43 INFO - Vendor: Intel (0x8086) 05:16:43 INFO - Device ID: 0x0a2e 05:16:43 INFO - Revision ID: 0x0009 05:16:43 INFO - Displays: 05:16:43 INFO - Display: 05:16:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:16:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:16:43 INFO - Display Serial Number: 1600x1200 60 05:16:43 INFO - Main Display: Yes 05:16:43 INFO - Mirror: Off 05:16:43 INFO - Online: Yes 05:16:43 INFO - Rotation: Supported 05:16:43 INFO - Return code: 0 05:16:43 INFO - Running post-action listener: _resource_record_post_action 05:16:43 INFO - ##### 05:16:43 INFO - ##### Finished run-tests step (success) 05:16:43 INFO - ##### 05:16:43 INFO - Running post-run listener: _resource_record_post_run 05:16:44 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 32529920; Write bytes: 699772928; Read time: 317; Write time: 1943 05:16:44 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 377989632; Write bytes: 371930624; Read time: 27999; Write time: 714 05:16:44 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 31608320; Write bytes: 299352576; Read time: 303; Write time: 1165 05:16:44 INFO - Running post-run listener: _upload_blobber_files 05:16:44 INFO - Blob upload gear active. 05:16:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:16:44 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 05:16:44 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:16:44 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:16:45 INFO - (blobuploader) - INFO - Open directory for files ... 05:16:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:16:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:16:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:46 INFO - (blobuploader) - INFO - Done attempting. 05:16:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:16:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:16:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:47 INFO - (blobuploader) - INFO - Done attempting. 05:16:47 INFO - (blobuploader) - INFO - Iteration through files over. 05:16:47 INFO - Return code: 0 05:16:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:16:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:16:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ada02239d1d05b13eed9bca48895b6ab107a6758a8eaef64f260a4449c65dc2caea86673ad6cb3c29e54c8654b62b259161e38b46957a2a3c7d717f694aa2ecc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d74c56a0b0fb9fa9424d28a48fcbf5b0d6ca17f6cc6e78c6c690ee48da64c7ead82de5a80c1397f7e677da8b38cd75131ccb9797daf3d5e96206d5cad6afeee"} 05:16:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:16:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:16:47 INFO - Contents: 05:16:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ada02239d1d05b13eed9bca48895b6ab107a6758a8eaef64f260a4449c65dc2caea86673ad6cb3c29e54c8654b62b259161e38b46957a2a3c7d717f694aa2ecc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d74c56a0b0fb9fa9424d28a48fcbf5b0d6ca17f6cc6e78c6c690ee48da64c7ead82de5a80c1397f7e677da8b38cd75131ccb9797daf3d5e96206d5cad6afeee"} 05:16:47 INFO - Running post-run listener: copy_logs_to_upload_dir 05:16:47 INFO - Copying logs to upload dir... 05:16:47 INFO - mkdir: /builds/slave/test/build/upload/logs 05:16:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.375131 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-08 05:16:47.083033) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-08 05:16:47.087054) ========= 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.LRSLdeA8SG/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.n74UFuqsFa/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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/Firefox/sha512/ada02239d1d05b13eed9bca48895b6ab107a6758a8eaef64f260a4449c65dc2caea86673ad6cb3c29e54c8654b62b259161e38b46957a2a3c7d717f694aa2ecc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d74c56a0b0fb9fa9424d28a48fcbf5b0d6ca17f6cc6e78c6c690ee48da64c7ead82de5a80c1397f7e677da8b38cd75131ccb9797daf3d5e96206d5cad6afeee"} build_url:https://queue.taskcluster.net/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011335 build_url: 'https://queue.taskcluster.net/v1/task/PnBETnI5SOi6sWu7ntR9nA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ada02239d1d05b13eed9bca48895b6ab107a6758a8eaef64f260a4449c65dc2caea86673ad6cb3c29e54c8654b62b259161e38b46957a2a3c7d717f694aa2ecc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d74c56a0b0fb9fa9424d28a48fcbf5b0d6ca17f6cc6e78c6c690ee48da64c7ead82de5a80c1397f7e677da8b38cd75131ccb9797daf3d5e96206d5cad6afeee"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-08 05:16:47.114774) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 05:16:47.115078) ========= 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.LRSLdeA8SG/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.n74UFuqsFa/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.005196 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 05:16:47.163201) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-08 05:16:47.163490) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-08 05:16:47.163791) ========= ========= Total master_lag: 0.14 =========