builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0326 starttime: 1463054852.84 results: success (0) buildid: 20160512025312 builduid: 6b01ee3aadba4fc1bea6ae0b98bc2f77 revision: 70b53120eb1820043a1739afb2e1574645b92ac3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:32.840250) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:32.840665) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:32.840930) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:32.887903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:32.888161) ========= 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.GrratGgryN/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.2cdsy1pN7b/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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-12 05:07:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 241M=0s 2016-05-12 05:07:33 (241 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.139281 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:33.046366) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:33.046671) ========= 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.GrratGgryN/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.2cdsy1pN7b/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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.025198 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:33.095020) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:33.095351) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 70b53120eb1820043a1739afb2e1574645b92ac3 --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 70b53120eb1820043a1739afb2e1574645b92ac3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GrratGgryN/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.2cdsy1pN7b/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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-12 05:07:33,154 truncating revision to first 12 chars 2016-05-12 05:07:33,154 Setting DEBUG logging. 2016-05-12 05:07:33,154 attempt 1/10 2016-05-12 05:07:33,154 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/70b53120eb18?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 05:07:33,818 unpacking tar archive at: mozilla-central-70b53120eb18/testing/mozharness/ program finished with exit code 0 elapsedTime=0.918910 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:34.033224) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:34.033595) ========= script_repo_revision: 70b53120eb1820043a1739afb2e1574645b92ac3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:34.034056) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:34.034366) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 05:07:34.047751) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2016-05-12 05:07:34.048085) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.GrratGgryN/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.2cdsy1pN7b/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 05:07:34 INFO - MultiFileLogger online at 20160512 05:07:34 in /builds/slave/test 05:07:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:07:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:07:34 INFO - {'append_to_log': False, 05:07:34 INFO - 'base_work_dir': '/builds/slave/test', 05:07:34 INFO - 'blob_upload_branch': 'Firefox', 05:07:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:07:34 INFO - 'branch': 'Firefox', 05:07:34 INFO - 'buildbot_json_path': 'buildprops.json', 05:07:34 INFO - 'config_files': ('talos/mac_config.py',), 05:07:34 INFO - 'default_actions': ('clobber', 05:07:34 INFO - 'read-buildbot-config', 05:07:34 INFO - 'download-and-extract', 05:07:34 INFO - 'populate-webroot', 05:07:34 INFO - 'create-virtualenv', 05:07:34 INFO - 'install', 05:07:34 INFO - 'run-tests'), 05:07:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:07:34 INFO - 'download_minidump_stackwalk': True, 05:07:34 INFO - 'download_symbols': 'ondemand', 05:07:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:07:34 INFO - 'tooltool.py': '/tools/tooltool.py', 05:07:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:07:34 INFO - '/tools/misc-python/virtualenv.py')}, 05:07:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:07:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:07:34 INFO - 'installer_path': 'installer.exe', 05:07:34 INFO - 'log_level': 'info', 05:07:34 INFO - 'log_name': 'talos', 05:07:34 INFO - 'log_to_console': True, 05:07:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:07:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:07:34 INFO - 'opt_config_files': (), 05:07:34 INFO - 'pip_index': False, 05:07:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:07:34 INFO - 'cmd': ('bash', 05:07:34 INFO - '-c', 05:07:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:07:34 INFO - 'enabled': True, 05:07:34 INFO - 'halt_on_failure': False, 05:07:34 INFO - 'name': 'check_screen_resolution'},), 05:07:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:07:34 INFO - 'cmd': ('bash', 05:07:34 INFO - '-c', 05:07:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:07:34 INFO - 'enabled': True, 05:07:34 INFO - 'halt_on_failure': False, 05:07:34 INFO - 'name': 'check_screen_resolution'},), 05:07:34 INFO - 'run_cmd_checks_enabled': True, 05:07:34 INFO - 'sps_profile': False, 05:07:34 INFO - 'sps_profile_interval': 0, 05:07:34 INFO - 'suite': 'dromaeojs-e10s', 05:07:34 INFO - 'system_bits': '32', 05:07:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:07:34 INFO - 'title': 't-yosemite-r7-0326', 05:07:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:07:34 INFO - 'use_talos_json': True, 05:07:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:07:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:07:34 INFO - 'work_dir': 'build'} 05:07:34 INFO - ##### 05:07:34 INFO - ##### Running clobber step. 05:07:34 INFO - ##### 05:07:34 INFO - Running pre-action listener: _resource_record_pre_action 05:07:34 INFO - Running main action method: clobber 05:07:34 INFO - rmtree: /builds/slave/test/build 05:07:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:07:35 INFO - Running post-action listener: _resource_record_post_action 05:07:35 INFO - ##### 05:07:35 INFO - ##### Finished clobber step (success) 05:07:35 INFO - ##### 05:07:35 INFO - ##### 05:07:35 INFO - ##### Running read-buildbot-config step. 05:07:35 INFO - ##### 05:07:35 INFO - Running pre-action listener: _resource_record_pre_action 05:07:35 INFO - Running main action method: read_buildbot_config 05:07:35 INFO - Using buildbot properties: 05:07:35 INFO - { 05:07:35 INFO - "project": "", 05:07:35 INFO - "product": "firefox", 05:07:35 INFO - "script_repo_revision": "production", 05:07:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:07:35 INFO - "repository": "", 05:07:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:07:35 INFO - "buildid": "20160512025312", 05:07:35 INFO - "pgo_build": "False", 05:07:35 INFO - "basedir": "/builds/slave/test", 05:07:35 INFO - "buildnumber": 3, 05:07:35 INFO - "slavename": "t-yosemite-r7-0326", 05:07:35 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:07:35 INFO - "platform": "yosemite_r7", 05:07:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:07:35 INFO - "branch": "mozilla-central", 05:07:35 INFO - "repo_path": "mozilla-central", 05:07:35 INFO - "revision": "70b53120eb1820043a1739afb2e1574645b92ac3", 05:07:35 INFO - "stage_platform": "macosx64", 05:07:35 INFO - "builduid": "6b01ee3aadba4fc1bea6ae0b98bc2f77", 05:07:35 INFO - "slavebuilddir": "test" 05:07:35 INFO - } 05:07:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:07:35 INFO - Running post-action listener: _resource_record_post_action 05:07:35 INFO - ##### 05:07:35 INFO - ##### Finished read-buildbot-config step (success) 05:07:35 INFO - ##### 05:07:35 INFO - ##### 05:07:35 INFO - ##### Running download-and-extract step. 05:07:35 INFO - ##### 05:07:35 INFO - Running pre-action listener: _resource_record_pre_action 05:07:35 INFO - Running main action method: download_and_extract 05:07:35 INFO - mkdir: /builds/slave/test/build/tests 05:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:07:35 INFO - https://queue.taskcluster.net/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:07:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:07:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:07:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/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 05:07:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/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 05:08:06 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json: timed out 05:08:06 INFO - retry: attempt #1 caught exception: timed out 05:08:06 INFO - retry: Failed, sleeping 30 seconds before retrying 05:08:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/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 #2 05:08:43 INFO - Downloaded 1199 bytes. 05:08:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:08:43 INFO - Using the following test package requirements: 05:08:43 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:08:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:08:43 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:08:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:08:43 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:08:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:08:43 INFO - u'jsshell-mac.zip'], 05:08:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:08:43 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:08:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:08:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:08:43 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:08:43 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:08:43 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:08:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:08:43 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:08:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:08:43 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:08:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:43 INFO - https://queue.taskcluster.net/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:08:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:08:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:08:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/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 05:08:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/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 05:08:45 INFO - Downloaded 18150598 bytes. 05:08:45 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'] 05:08:45 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 05:08:48 INFO - Return code: 0 05:08:48 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 05:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:48 INFO - https://queue.taskcluster.net/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:08:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:08:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:08:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/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 05:08:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/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 05:08:51 INFO - Downloaded 18150598 bytes. 05:08:51 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'] 05:08:51 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 05:08:54 INFO - Return code: 0 05:08:54 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')]} 05:08:54 INFO - https://queue.taskcluster.net/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:08:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:08:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:08:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/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 05:08:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/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 05:08:55 INFO - Downloaded 10840152 bytes. 05:08:55 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'] 05:08:55 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 05:08:56 INFO - Return code: 0 05:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:56 INFO - https://queue.taskcluster.net/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:08:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:08:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:08:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:08:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aMF_ObgkROqfKzisD8wqOg/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 05:09:01 INFO - Downloaded 109809782 bytes. 05:09:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:09:01 INFO - mkdir: /builds/slave/test/properties 05:09:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:09:01 INFO - Writing to file /builds/slave/test/properties/build_url 05:09:01 INFO - Contents: 05:09:01 INFO - build_url:https://queue.taskcluster.net/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:09:02 INFO - Running post-action listener: _resource_record_post_action 05:09:02 INFO - Running post-action listener: set_extra_try_arguments 05:09:02 INFO - ##### 05:09:02 INFO - ##### Finished download-and-extract step (success) 05:09:02 INFO - ##### 05:09:02 INFO - ##### 05:09:02 INFO - ##### Running populate-webroot step. 05:09:02 INFO - ##### 05:09:02 INFO - Running pre-action listener: _resource_record_pre_action 05:09:02 INFO - Running main action method: populate_webroot 05:09:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:09:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:09:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:09:02 INFO - '--tppagecycles', 05:09:02 INFO - '7'], 05:09:02 INFO - 'tests': ['tsvgm']}}, 05:09:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:09:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:09:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:09:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:09:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:09:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:09:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:02 INFO - 'talos_options': ['--disable-e10s'], 05:09:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:02 INFO - 'talos_options': ['--disable-e10s'], 05:09:02 INFO - 'tests': ['damp', 'tps']}, 05:09:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:02 INFO - 'tests': ['damp', 'tps']}, 05:09:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:09:02 INFO - 'tests': ['dromaeo_dom']}, 05:09:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:09:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:09:02 INFO - 'tests': ['a11yr', 05:09:02 INFO - 'ts_paint', 05:09:02 INFO - 'tpaint', 05:09:02 INFO - 'sessionrestore', 05:09:02 INFO - 'sessionrestore_no_auto_restore', 05:09:02 INFO - 'tabpaint']}, 05:09:02 INFO - 'other-e10s': {'tests': ['a11yr', 05:09:02 INFO - 'ts_paint', 05:09:02 INFO - 'tpaint', 05:09:02 INFO - 'sessionrestore', 05:09:02 INFO - 'sessionrestore_no_auto_restore', 05:09:02 INFO - 'tabpaint']}, 05:09:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:09:02 INFO - 'ts_paint', 05:09:02 INFO - 'tpaint', 05:09:02 INFO - 'sessionrestore', 05:09:02 INFO - 'sessionrestore_no_auto_restore', 05:09:02 INFO - 'tabpaint']}, 05:09:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:09:02 INFO - 'ts_paint', 05:09:02 INFO - 'tpaint', 05:09:02 INFO - 'sessionrestore', 05:09:02 INFO - 'sessionrestore_no_auto_restore', 05:09:02 INFO - 'tabpaint']}, 05:09:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:09:02 INFO - 'tests': ['a11yr', 05:09:02 INFO - 'ts_paint', 05:09:02 INFO - 'tpaint', 05:09:02 INFO - 'sessionrestore', 05:09:02 INFO - 'sessionrestore_no_auto_restore', 05:09:02 INFO - 'tabpaint']}, 05:09:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:09:02 INFO - 'tests': ['a11yr', 05:09:02 INFO - 'ts_paint', 05:09:02 INFO - 'tpaint', 05:09:02 INFO - 'sessionrestore', 05:09:02 INFO - 'sessionrestore_no_auto_restore', 05:09:02 INFO - 'tabpaint']}, 05:09:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:09:02 INFO - 'tests': ['tsvgx', 05:09:02 INFO - 'tsvgr_opacity', 05:09:02 INFO - 'tart', 05:09:02 INFO - 'tscrollx', 05:09:02 INFO - 'cart']}, 05:09:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:09:02 INFO - 'tsvgr_opacity', 05:09:02 INFO - 'tart', 05:09:02 INFO - 'tscrollx', 05:09:02 INFO - 'cart']}, 05:09:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:02 INFO - 'talos_options': ['--disable-e10s'], 05:09:02 INFO - 'tests': ['tp5o']}, 05:09:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:02 INFO - 'tests': ['tp5o']}, 05:09:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:02 INFO - 'talos_options': ['--disable-e10s', 05:09:02 INFO - '--xperf_path', 05:09:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:02 INFO - 'tests': ['tp5n']}, 05:09:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:02 INFO - 'talos_options': ['--xperf_path', 05:09:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:02 INFO - 'tests': ['tp5n']}}, 05:09:02 INFO - 'talos.zip': {'path': '', 05:09:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:09:02 INFO - Running post-action listener: _resource_record_post_action 05:09:02 INFO - ##### 05:09:02 INFO - ##### Finished populate-webroot step (success) 05:09:02 INFO - ##### 05:09:02 INFO - ##### 05:09:02 INFO - ##### Running create-virtualenv step. 05:09:02 INFO - ##### 05:09:02 INFO - Running pre-action listener: _resource_record_pre_action 05:09:02 INFO - Running main action method: create_virtualenv 05:09:02 INFO - Creating virtualenv /builds/slave/test/build/venv 05:09:02 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 05:09:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:09:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:09:02 INFO - Using real prefix '/tools/python27' 05:09:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:09:03 INFO - Installing distribute.............................................................................................................................................................................................done. 05:09:06 INFO - Installing pip.................done. 05:09:06 INFO - Return code: 0 05:09:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107588a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10758e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb629c809a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742cd30>, '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 0x107250db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107423b20>, '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.GrratGgryN/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.2cdsy1pN7b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 05:09: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', 'pip>=1.5'] in /builds/slave/test/build 05:09: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 pip>=1.5 05:09:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GrratGgryN/Render', 05:09:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:06 INFO - 'HOME': '/Users/cltbld', 05:09:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:06 INFO - 'LOGNAME': 'cltbld', 05:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:06 INFO - 'MOZ_NO_REMOTE': '1', 05:09:06 INFO - 'NO_EM_RESTART': '1', 05:09:06 INFO - 'PAGER': '/bin/cat', 05:09:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:06 INFO - 'PWD': '/builds/slave/test', 05:09:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:06 INFO - 'SHELL': '/bin/bash', 05:09:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2cdsy1pN7b/Listeners', 05:09:06 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 05:09:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:06 INFO - 'USER': 'cltbld', 05:09:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:06 INFO - 'XPC_FLAGS': '0x0', 05:09:06 INFO - 'XPC_SERVICE_NAME': '0', 05:09:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:06 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 05:09:06 INFO - Cleaning up... 05:09:06 INFO - Return code: 0 05:09:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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', '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 0x107588a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10758e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb629c809a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742cd30>, '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 0x107250db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107423b20>, '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.GrratGgryN/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.2cdsy1pN7b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 05:09: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', 'psutil>=0.7.1'] in /builds/slave/test/build 05:09: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 psutil>=0.7.1 05:09:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GrratGgryN/Render', 05:09:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:06 INFO - 'HOME': '/Users/cltbld', 05:09:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:06 INFO - 'LOGNAME': 'cltbld', 05:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:06 INFO - 'MOZ_NO_REMOTE': '1', 05:09:06 INFO - 'NO_EM_RESTART': '1', 05:09:06 INFO - 'PAGER': '/bin/cat', 05:09:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:06 INFO - 'PWD': '/builds/slave/test', 05:09:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:06 INFO - 'SHELL': '/bin/bash', 05:09:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2cdsy1pN7b/Listeners', 05:09:06 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 05:09:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:06 INFO - 'USER': 'cltbld', 05:09:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:06 INFO - 'XPC_FLAGS': '0x0', 05:09:06 INFO - 'XPC_SERVICE_NAME': '0', 05:09:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:07 INFO - Downloading/unpacking psutil>=0.7.1 05:09:07 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 05:09:07 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 05:09:07 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 05:09:07 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 05:09:07 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 05:09:07 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 05:09:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:09:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:10 INFO - Installing collected packages: psutil 05:09:10 INFO - Running setup.py install for psutil 05:09:10 INFO - building 'psutil._psutil_osx' extension 05:09:10 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 05:09:11 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 05:09:11 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 05:09:11 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 05:09:11 INFO - building 'psutil._psutil_posix' extension 05:09:11 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 05:09:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:09:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:09:11 INFO - ^ 05:09:11 INFO - 1 warning generated. 05:09:11 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 05:09:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:11 INFO - Successfully installed psutil 05:09:11 INFO - Cleaning up... 05:09:11 INFO - Return code: 0 05:09:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:09:11 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')]} 05:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:11 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')]} 05:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:11 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 0x107588a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10758e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb629c809a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742cd30>, '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 0x107250db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107423b20>, '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.GrratGgryN/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.2cdsy1pN7b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 05:09:11 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 05:09:11 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 05:09:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GrratGgryN/Render', 05:09:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:11 INFO - 'HOME': '/Users/cltbld', 05:09:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:11 INFO - 'LOGNAME': 'cltbld', 05:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:11 INFO - 'MOZ_NO_REMOTE': '1', 05:09:11 INFO - 'NO_EM_RESTART': '1', 05:09:11 INFO - 'PAGER': '/bin/cat', 05:09:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:11 INFO - 'PWD': '/builds/slave/test', 05:09:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:11 INFO - 'SHELL': '/bin/bash', 05:09:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2cdsy1pN7b/Listeners', 05:09:11 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 05:09:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:11 INFO - 'USER': 'cltbld', 05:09:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:11 INFO - 'XPC_FLAGS': '0x0', 05:09:11 INFO - 'XPC_SERVICE_NAME': '0', 05:09:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:12 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:09:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:09:14 INFO - Installing collected packages: mozsystemmonitor 05:09:14 INFO - Running setup.py install for mozsystemmonitor 05:09:14 INFO - Successfully installed mozsystemmonitor 05:09:14 INFO - Cleaning up... 05:09:14 INFO - Return code: 0 05:09:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:14 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 0x107588a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10758e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb629c809a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742cd30>, '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 0x107250db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107423b20>, '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.GrratGgryN/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.2cdsy1pN7b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 05:09:14 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 05:09:14 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 05:09:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GrratGgryN/Render', 05:09:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:14 INFO - 'HOME': '/Users/cltbld', 05:09:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:14 INFO - 'LOGNAME': 'cltbld', 05:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:14 INFO - 'MOZ_NO_REMOTE': '1', 05:09:14 INFO - 'NO_EM_RESTART': '1', 05:09:14 INFO - 'PAGER': '/bin/cat', 05:09:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:14 INFO - 'PWD': '/builds/slave/test', 05:09:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:14 INFO - 'SHELL': '/bin/bash', 05:09:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2cdsy1pN7b/Listeners', 05:09:14 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 05:09:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:14 INFO - 'USER': 'cltbld', 05:09:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:14 INFO - 'XPC_FLAGS': '0x0', 05:09:14 INFO - 'XPC_SERVICE_NAME': '0', 05:09:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:14 INFO - Downloading/unpacking blobuploader==1.2.4 05:09:14 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 05:09:14 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 05:09:14 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 05:09:14 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 05:09:14 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 05:09:14 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 05:09:17 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:09:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09:18 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 05:09:18 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 05:09:18 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 05:09:18 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 05:09:18 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 05:09:18 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 05:09:18 INFO - Downloading docopt-0.6.1.tar.gz 05:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:18 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:18 INFO - Running setup.py install for blobuploader 05:09:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:09:18 INFO - Running setup.py install for requests 05:09:19 INFO - Running setup.py install for docopt 05:09:19 INFO - Successfully installed blobuploader requests docopt 05:09:19 INFO - Cleaning up... 05:09:19 INFO - Return code: 0 05:09:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:19 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 0x107588a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10758e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb629c809a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742cd30>, '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 0x107250db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107423b20>, '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.GrratGgryN/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.2cdsy1pN7b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 05:09:19 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 05:09:19 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 05:09:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GrratGgryN/Render', 05:09:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:19 INFO - 'HOME': '/Users/cltbld', 05:09:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:19 INFO - 'LOGNAME': 'cltbld', 05:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:19 INFO - 'MOZ_NO_REMOTE': '1', 05:09:19 INFO - 'NO_EM_RESTART': '1', 05:09:19 INFO - 'PAGER': '/bin/cat', 05:09:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:19 INFO - 'PWD': '/builds/slave/test', 05:09:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:19 INFO - 'SHELL': '/bin/bash', 05:09:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2cdsy1pN7b/Listeners', 05:09:19 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 05:09:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:19 INFO - 'USER': 'cltbld', 05:09:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:19 INFO - 'XPC_FLAGS': '0x0', 05:09:19 INFO - 'XPC_SERVICE_NAME': '0', 05:09:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:19 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-V7l73D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:19 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-7RMy4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:19 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-9q72Iw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:20 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-l3kp_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:20 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-fgvOyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:20 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-CIP1sI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:20 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-JHzOvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:20 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-JBhlVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:20 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-qMgCxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:20 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-FPsQcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:20 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-UPjm7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:20 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-EW1YOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-yPjyjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-Spb133-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-L0wzab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-Nd_b7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:21 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-4lH2TV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:21 INFO - Running setup.py install for manifestparser 05:09:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:09:21 INFO - Running setup.py install for mozcrash 05:09:21 INFO - Running setup.py install for mozdebug 05:09:21 INFO - Running setup.py install for mozdevice 05:09:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:09:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:09:22 INFO - Running setup.py install for mozfile 05:09:22 INFO - Running setup.py install for mozhttpd 05:09:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:09:22 INFO - Running setup.py install for mozinfo 05:09:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:09:22 INFO - Running setup.py install for mozInstall 05:09:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:09:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:09:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:09:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:09:22 INFO - Running setup.py install for mozleak 05:09:22 INFO - Running setup.py install for mozlog 05:09:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Running setup.py install for moznetwork 05:09:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Running setup.py install for mozprocess 05:09:23 INFO - Running setup.py install for mozprofile 05:09:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Running setup.py install for mozrunner 05:09:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Running setup.py install for mozscreenshot 05:09:23 INFO - Running setup.py install for moztest 05:09:23 INFO - Running setup.py install for mozversion 05:09:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:09:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:09:24 INFO - Cleaning up... 05:09:24 INFO - Return code: 0 05:09:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:24 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 0x107588a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10758e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb629c809a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742cd30>, '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 0x107250db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107423b20>, '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.GrratGgryN/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.2cdsy1pN7b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 05:09:24 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 05:09:24 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 05:09:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GrratGgryN/Render', 05:09:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:24 INFO - 'HOME': '/Users/cltbld', 05:09:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:24 INFO - 'LOGNAME': 'cltbld', 05:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:24 INFO - 'MOZ_NO_REMOTE': '1', 05:09:24 INFO - 'NO_EM_RESTART': '1', 05:09:24 INFO - 'PAGER': '/bin/cat', 05:09:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:24 INFO - 'PWD': '/builds/slave/test', 05:09:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:24 INFO - 'SHELL': '/bin/bash', 05:09:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2cdsy1pN7b/Listeners', 05:09:24 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 05:09:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:24 INFO - 'USER': 'cltbld', 05:09:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:24 INFO - 'XPC_FLAGS': '0x0', 05:09:24 INFO - 'XPC_SERVICE_NAME': '0', 05:09:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:24 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-0Iaqwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:24 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)) 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:24 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-rvaWll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:24 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)) 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:24 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-dP59yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:24 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)) 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:24 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-_1HNtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:24 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)) 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:24 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-lpp3mX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:24 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)) 05:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:24 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-duEdyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-FLwN0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-tJjCQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-Sc_IWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-p1qzSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-gStjAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-UPUTwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-3ztkdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-UGVPgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-AmJdJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:25 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-Uf9qBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:26 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)) 05:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:26 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-cvipIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:26 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 05:09:26 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 05:09:26 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 05:09:26 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 05:09:26 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 05:09:26 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 05:09:28 INFO - Downloading blessings-1.6.tar.gz 05:09:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:09:28 INFO - Installing collected packages: blessings 05:09:28 INFO - Running setup.py install for blessings 05:09:28 INFO - Successfully installed blessings 05:09:28 INFO - Cleaning up... 05:09:28 INFO - Return code: 0 05:09:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:09:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:29 INFO - Reading from file tmpfile_stdout 05:09:29 INFO - Current package versions: 05:09:29 INFO - blessings == 1.6 05:09:29 INFO - blobuploader == 1.2.4 05:09:29 INFO - docopt == 0.6.1 05:09:29 INFO - manifestparser == 1.1 05:09:29 INFO - mozInstall == 1.12 05:09:29 INFO - mozcrash == 0.17 05:09:29 INFO - mozdebug == 0.1 05:09:29 INFO - mozdevice == 0.48 05:09:29 INFO - mozfile == 1.2 05:09:29 INFO - mozhttpd == 0.7 05:09:29 INFO - mozinfo == 0.9 05:09:29 INFO - mozleak == 0.1 05:09:29 INFO - mozlog == 3.1 05:09:29 INFO - moznetwork == 0.27 05:09:29 INFO - mozprocess == 0.22 05:09:29 INFO - mozprofile == 0.28 05:09:29 INFO - mozrunner == 6.11 05:09:29 INFO - mozscreenshot == 0.1 05:09:29 INFO - mozsystemmonitor == 0.0 05:09:29 INFO - moztest == 0.7 05:09:29 INFO - mozversion == 1.4 05:09:29 INFO - psutil == 3.1.1 05:09:29 INFO - requests == 1.2.3 05:09:29 INFO - wsgiref == 0.1.2 05:09:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:29 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 0x107588a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10758e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb629c809a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742cd30>, '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 0x107250db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107423b20>, '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.GrratGgryN/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.2cdsy1pN7b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 05:09:29 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 05:09:29 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 05:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GrratGgryN/Render', 05:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:29 INFO - 'HOME': '/Users/cltbld', 05:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:29 INFO - 'LOGNAME': 'cltbld', 05:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:29 INFO - 'MOZ_NO_REMOTE': '1', 05:09:29 INFO - 'NO_EM_RESTART': '1', 05:09:29 INFO - 'PAGER': '/bin/cat', 05:09:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:29 INFO - 'PWD': '/builds/slave/test', 05:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:29 INFO - 'SHELL': '/bin/bash', 05:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2cdsy1pN7b/Listeners', 05:09:29 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 05:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:29 INFO - 'USER': 'cltbld', 05:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:29 INFO - 'XPC_FLAGS': '0x0', 05:09:29 INFO - 'XPC_SERVICE_NAME': '0', 05:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:29 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)) 05:09:29 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)) 05:09:29 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)) 05:09:29 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)) 05:09:29 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)) 05:09:29 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)) 05:09:29 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)) 05:09:29 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)) 05:09:29 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)) 05:09:29 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)) 05:09:29 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)) 05:09:29 INFO - Cleaning up... 05:09:29 INFO - Return code: 0 05:09:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:29 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 0x107588a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10758e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb629c809a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742cd30>, '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 0x107250db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107423b20>, '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.GrratGgryN/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.2cdsy1pN7b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 05:09:29 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 05:09:29 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 05:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GrratGgryN/Render', 05:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:29 INFO - 'HOME': '/Users/cltbld', 05:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:29 INFO - 'LOGNAME': 'cltbld', 05:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:29 INFO - 'MOZ_NO_REMOTE': '1', 05:09:29 INFO - 'NO_EM_RESTART': '1', 05:09:29 INFO - 'PAGER': '/bin/cat', 05:09:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:29 INFO - 'PWD': '/builds/slave/test', 05:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:29 INFO - 'SHELL': '/bin/bash', 05:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2cdsy1pN7b/Listeners', 05:09:29 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 05:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:29 INFO - 'USER': 'cltbld', 05:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:29 INFO - 'XPC_FLAGS': '0x0', 05:09:29 INFO - 'XPC_SERVICE_NAME': '0', 05:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:29 INFO - Downloading/unpacking jsonschema 05:09:29 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 05:09:29 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 05:09:29 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 05:09:29 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 05:09:29 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 05:09:29 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 05:09:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:09:32 INFO - Downloading/unpacking functools32 (from jsonschema) 05:09:32 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 05:09:32 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 05:09:32 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 05:09:32 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 05:09:32 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 05:09:32 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 05:09:32 INFO - Downloading functools32-3.2.3-2.tar.gz 05:09:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:09:32 INFO - warning: no files found matching '*.txt' 05:09:32 INFO - no previously-included directories found matching 'build' 05:09:32 INFO - no previously-included directories found matching 'dist' 05:09:32 INFO - no previously-included directories found matching '.git*' 05:09:32 INFO - Installing collected packages: jsonschema, functools32 05:09:32 INFO - Running setup.py install for functools32 05:09:32 INFO - warning: no files found matching '*.txt' 05:09:32 INFO - no previously-included directories found matching 'build' 05:09:32 INFO - no previously-included directories found matching 'dist' 05:09:32 INFO - no previously-included directories found matching '.git*' 05:09:32 INFO - Successfully installed jsonschema functools32 05:09:32 INFO - Cleaning up... 05:09:32 INFO - Return code: 0 05:09:32 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:09:32 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')]} 05:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:32 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')]} 05:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:32 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 0x107588a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10758e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb629c809a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742cd30>, '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 0x107250db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107423b20>, '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.GrratGgryN/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.2cdsy1pN7b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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 05:09:32 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 05:09:32 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 05:09:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GrratGgryN/Render', 05:09:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:32 INFO - 'HOME': '/Users/cltbld', 05:09:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:32 INFO - 'LOGNAME': 'cltbld', 05:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:32 INFO - 'MOZ_NO_REMOTE': '1', 05:09:32 INFO - 'NO_EM_RESTART': '1', 05:09:32 INFO - 'PAGER': '/bin/cat', 05:09:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:32 INFO - 'PWD': '/builds/slave/test', 05:09:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:32 INFO - 'SHELL': '/bin/bash', 05:09:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2cdsy1pN7b/Listeners', 05:09:32 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 05:09:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:32 INFO - 'USER': 'cltbld', 05:09:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:32 INFO - 'XPC_FLAGS': '0x0', 05:09:32 INFO - 'XPC_SERVICE_NAME': '0', 05:09:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:33 INFO - Downloading/unpacking flake8 05:09:33 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 05:09:33 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 05:09:33 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 05:09:33 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 05:09:33 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 05:09:33 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 05:09:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:09:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:09:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:09:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:36 INFO - no previously-included directories found matching 'docs/_build' 05:09:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:09:36 INFO - Running setup.py install for pep8 05:09:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:36 INFO - no previously-included directories found matching 'docs/_build' 05:09:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:09:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:09:37 INFO - Cleaning up... 05:09:37 INFO - Return code: 0 05:09:37 INFO - Running post-action listener: _resource_record_post_action 05:09:37 INFO - Running post-action listener: _start_resource_monitoring 05:09:37 INFO - Starting resource monitoring. 05:09:37 INFO - ##### 05:09:37 INFO - ##### Finished create-virtualenv step (success) 05:09:37 INFO - ##### 05:09:37 INFO - ##### 05:09:37 INFO - ##### Running install step. 05:09:37 INFO - ##### 05:09:37 INFO - Running pre-action listener: _resource_record_pre_action 05:09:37 INFO - Running main action method: install 05:09:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:37 INFO - Reading from file tmpfile_stdout 05:09:37 INFO - Detecting whether we're running mozinstall >=1.0... 05:09:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:09:37 INFO - Reading from file tmpfile_stdout 05:09:37 INFO - Output received: 05:09:37 INFO - Usage: mozinstall [options] installer 05:09:37 INFO - Options: 05:09:37 INFO - -h, --help show this help message and exit 05:09:37 INFO - -d DEST, --destination=DEST 05:09:37 INFO - Directory to install application into. [default: 05:09:37 INFO - "/builds/slave/test"] 05:09:37 INFO - --app=APP Application being installed. [default: firefox] 05:09:37 INFO - mkdir: /builds/slave/test/build/application 05:09:37 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'] 05:09:37 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 05:10:10 INFO - Reading from file tmpfile_stdout 05:10:10 INFO - Output received: 05:10:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:10:10 INFO - Running post-action listener: _resource_record_post_action 05:10:10 INFO - ##### 05:10:10 INFO - ##### Finished install step (success) 05:10:10 INFO - ##### 05:10:10 INFO - ##### 05:10:10 INFO - ##### Running run-tests step. 05:10:10 INFO - ##### 05:10:10 INFO - Running pre-action listener: _resource_record_pre_action 05:10:10 INFO - Running main action method: run_tests 05:10:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:10:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:10:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:10:10 INFO - 2016-05-12 05:10:10.760 screenresolution[1650:8471] starting screenresolution argv=screenresolution get 05:10:10 INFO - 2016-05-12 05:10:10.763 screenresolution[1650:8471] Display 0: 1024x768x32@60 05:10:10 INFO - 2016-05-12 05:10:10.769 screenresolution[1651:8473] starting screenresolution argv=screenresolution list 05:10:10 INFO - Available Modes on Display 0 05:10:10 INFO - 1024x768x32@60 800x600x32@75 800x600x32@60 05:10:11 INFO - 640x480x32@75 640x480x32@60 Graphics/Displays: 05:10:11 INFO - Intel Iris: 05:10:11 INFO - Chipset Model: Intel Iris 05:10:11 INFO - Type: GPU 05:10:11 INFO - Bus: Built-In 05:10:11 INFO - VRAM (Dynamic, Max): 1536 MB 05:10:11 INFO - Vendor: Intel (0x8086) 05:10:11 INFO - Device ID: 0x0a2e 05:10:11 INFO - Revision ID: 0x0009 05:10:11 INFO - Displays: 05:10:11 INFO - Display: 05:10:11 INFO - Resolution: 1024 x 768 @ 60 Hz 05:10:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:10:11 INFO - Display Serial Number: 1024x768 60 05:10:11 INFO - Main Display: Yes 05:10:11 INFO - Mirror: Off 05:10:11 INFO - Online: Yes 05:10:11 INFO - Rotation: Supported 05:10:11 INFO - Return code: 0 05:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:10:11 INFO - Python 2.7.3 05:10:11 INFO - Return code: 0 05:10:11 INFO - grabbing minidump binary from tooltool 05:10:11 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')]} 05:10:11 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742cd30>, '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 0x107250db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107423b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:10:11 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 05:10:11 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 05:10:11 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:10:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:10:11 INFO - Return code: 0 05:10:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:10:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:10:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:10:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:10:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:10:12 INFO - Return code: 0 05:10:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0326', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:10:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0326 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:10:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GrratGgryN/Render', 05:10:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:12 INFO - 'HOME': '/Users/cltbld', 05:10:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:12 INFO - 'LOGNAME': 'cltbld', 05:10:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:10:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:12 INFO - 'MOZ_NO_REMOTE': '1', 05:10:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:10:12 INFO - 'NO_EM_RESTART': '1', 05:10:12 INFO - 'PAGER': '/bin/cat', 05:10:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:12 INFO - 'PWD': '/builds/slave/test', 05:10:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:10:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:12 INFO - 'SHELL': '/bin/bash', 05:10:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2cdsy1pN7b/Listeners', 05:10:12 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 05:10:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:12 INFO - 'USER': 'cltbld', 05:10:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:12 INFO - 'XPC_FLAGS': '0x0', 05:10:12 INFO - 'XPC_SERVICE_NAME': '0', 05:10:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0326', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:10:12 INFO - mozversion application_buildid: 20160512025312 05:10:12 INFO - mozversion application_changeset: 70b53120eb1820043a1739afb2e1574645b92ac3 05:10:12 INFO - mozversion application_display_name: Nightly 05:10:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:10:12 INFO - mozversion application_name: Firefox 05:10:12 INFO - mozversion application_remotingname: firefox 05:10:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:10:12 INFO - mozversion application_vendor: Mozilla 05:10:12 INFO - mozversion application_version: 49.0a1 05:10:12 INFO - mozversion platform_buildid: 20160512025312 05:10:12 INFO - mozversion platform_changeset: 70b53120eb1820043a1739afb2e1574645b92ac3 05:10:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:10:12 INFO - mozversion platform_version: 49.0a1 05:10:12 INFO - using testdate: 1463055012 05:10:12 INFO - actual date: 1463055012 05:10:12 INFO - starting webserver on 'localhost:49277' 05:10:12 INFO - SUITE-START | Running 2 tests 05:10:12 INFO - TEST-START | dromaeo_css 05:10:12 INFO - operating with platform_type : mac_ 05:10:12 INFO - Initialising browser for dromaeo_css test... 05:10:12 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmpRWazGH/profile http://localhost:49277/getInfo.html) 05:10:15 INFO - PROCESS | 1669 | __metrics Screen width/height:1024/768 05:10:15 INFO - PROCESS | 1669 | colorDepth:24 05:10:15 INFO - PROCESS | 1669 | Browser inner width/height: 1024/605 05:10:15 INFO - PROCESS | 1669 | __metrics 05:10:15 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:10:16 INFO - TEST-INFO | 1669: exit 0 05:10:16 INFO - Browser initialized. 05:10:16 INFO - Running cycle 1/1 for dromaeo_css test... 05:10:16 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmpRWazGH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:10:28 INFO - PROCESS | 1673 | RSS: Main: 248676352 05:10:28 INFO - PROCESS | 1673 | 05:11:18 INFO - PROCESS | 1673 | 1463055078060 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:13:29 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 05:13:30 INFO - PROCESS | 1673 | RSS: Main: 259543040 05:13:30 INFO - PROCESS | 1673 | 05:16:33 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 05:16:33 INFO - PROCESS | 1673 | RSS: Main: 269262848 05:16:33 INFO - PROCESS | 1673 | 05:19:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 05:19:36 INFO - PROCESS | 1673 | RSS: Main: 271413248 05:19:36 INFO - PROCESS | 1673 | 05:22:38 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 05:22:38 INFO - PROCESS | 1673 | RSS: Main: 273252352 05:22:38 INFO - PROCESS | 1673 | 05:25:41 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 05:25:42 INFO - PROCESS | 1673 | RSS: Main: 276090880 05:25:42 INFO - PROCESS | 1673 | 05:28:44 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 05:28:45 INFO - PROCESS | 1673 | RSS: Main: 276795392 05:28:45 INFO - PROCESS | 1673 | 05:28:45 INFO - PROCESS | 1673 | __start_tp_report 05:28:45 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 05:28:45 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 05:28:45 INFO - PROCESS | 1673 | |i|pagename|runs| 05:28:45 INFO - PROCESS | 1673 | |0;dojo.html;2990.820550766954;3508.771929824563;3509.1051781795636;3526.0655926179566;3544.4151714967;4609.377734005917;5785.681787501686;5883.705814709264;5921.911171332428;5967.1049342598635;4720.787564559594;5053.797848086081;5874.530037596993;5880.794172203974;5921.615095018831;5185.144451165553;5195.688258704471;5227.294315267428;5249.028929648009;5339.679619222839;4818.090144012939;5358.410574836765;5374.408815030363;5394.8921021579545;5447.482489163543;5176.6117541184185;5206.869828254286;5231.319928409321;5289.8942021159555;5294.867628309304;5259.421463638997;5268.868278293074;5271.472852714736;5298.549623282026;5349.331333583302;5216.765245563959;5253.579713622901;5269.683818970836;5297.17893726473;5310.734463276859;5458.4814442627885;5660.575456840712;5739.540836733051;5744.569157313176;5764.193012978187;6240.251169859601;6247.531435142382;6254.655993920333;6292.244930608311;6310.274318453391;6327.335629758901;6333.873322533524;6365.522585806037;6368.203974503187;6374.808755737289;4974.577160941324;5936.287645482526;5987.461128498456;6075.665838378844;6115.021596544531;6410.0705397718;6495.512836537184;6503.414692677682;6504.3495650435525;6515.641639709766;5098.031374038921;6136.478399336017;6285.811425657238;6321.525885558602;6335.936640633535;6143.204841796972;6209.130721698965;6256;6300.936990630035;6317;6142.897836592492;6198.566100373021;6212.9068063979075;6225.470834978987;6267.247930248308;6124.930239974474;6250.343713910065;6251.437370636664;6268.216472940882;6269.059641053787;5053.974730126326;5081.644284900095;5105.5149760772665;5122.564582010571;5140.306058682105;3963.900902477461;4431.867043988621;4474.440694913136;4485.708428952108;4497.685162038626;5178.792848286026;5307.575393968474;5884.9705751470965;6026.457618814327;6082.75668973245;6201.6899155042065;6311.810645680637;6341.55609107358;6341.841453963688;6345.524085693592;6047.820674968339;6063.211782468251;6067.423594758491;6077.301110372276;6082.148499210114;5450.727463626882;5568.860778480571;5572.804951826666;5635.915461268084;5652.74562644682;6626.602403855784;6641.800745977628;6643.833904152435;6648.0360347750275;6667.8666426670225;6378.9362106380195;6393.1369265148605;6410.935890641033;6412;6433.324500927335;6322.0516922462;6451.129097571956;6452.612843229421;6494.318096599789;6582.407583317525;6290.433860952537;6294.339094395205;6297.779577714758;6298.748050077946;6312.495000400048;6275.309715931336;6342.936570634419;6354.459870911024;6355.936440635719;6367.140436041261;6159.076138579249;6162.753489860355;6190.7214175363115;6193.597416167935;6214.098955651315;5985.521158307412;6250.624962502265;6271.216097987752;6281.371862813682;6310.747570097328;4725.432948046257;6056.545759068;6167.722452489738;6181.81454556364;6196.597221180609;5254.579633629377;6145.402195429131;6214.720337584729;6216.378362163748;6233.438990490878;6182.381761823782;6202.038684003986;6206.06908963659;6207.720652570735;6233.283172435228 05:28:45 INFO - PROCESS | 1673 | |1;ext.html;509.89547142835715;511.7211119939631;519.7349351830566;523.2230138244709;540.1600511205077;38467.23065538691;40501.79749101258;40576.79711601445;40651.59348406519;40788.38817417741;32724.345513089782;41832.79083604578;41959;41997;42100.78949605255;8483.15168483153;9535.046495350478;9736.707898763048;9738.02619738024;9741.805163896717;9407.059294070606;9495.050494950485;9503;9509.714708558755;9547.331686781929;9503.287253455985;9538.380005299634;9541.666041688542;9561.952190239039;9600.471974041426;9447.244220462346;9509.286803489696;9525.666601668909;9528.809423811554;9530.52347382628;9566.186874115709;9671.564779584865;9680.031996800333;9690.612375504972;9694.612215511372;37959.4306085409;38249.617503824884;38304.233915321536;38378.616213837784;38416;2956.4530561846127;3163.383140287645;3171.349873275973;3335.5663763710804;3358.383870283275;27766.3058423539;27950.86024569864;28304.292392690142;28507.429851403063;29000.32351989656;2691.9192424227303;2706.0258307009462;2707.7021527631946;2739.342557786117;2806.578476939583;435428;441072;441927;442100;443369;34015.829920850236;49299.50700493021;59386.40613593809;59443.81112377815;60040.39959600348;30163;36210.637893620726;36825.07937301535;36838.263234735154;36934;15191;15302.158381288913;15341.386344546094;15348.232588370536;15384.61538461525;12861.971042316593;12892.097553171376;12910.09629325957;12917.095803293867;12935.805962910565;8113.432059755761;8128.512289262663;8132.837343253102;8161.5919204039565;8198.057223419264;3016.457037733185;3345.397828390913;3353.6311005789344;3379.121428428626;3426.588809342845;968.6851773173606;976.4190306767462;989.7674046599044;1010.6664800615779;1053.8103141434462;25707.61438578423;28190.71809281922;29175.708242917724;29849;29958.95143669961;990.8166989107038;1088.4285749981323;1135.3755434510986;1140.8745038045809;1241.509603706531;15167.620809479631;15174.544763657108;15252.313645885963;15260;15339;631219;634214;637840;638271;639080;56178.719106404205;57531.712341438026;58375;58392.124118138265;58533.829323413294;1442.7186698593882;1461.9400934322532;1534.225216265801;1607.1723062622993;1713.2718594597247;24709.629355559322;28349.291267718472;29077.563836542045;29318.560221596268;29735.405291893607;50442;50464;50509.242361363875;50594.74702626463;50910.74544627253;523958;525584.3720781371;525854;526095;528693;26128.216153515426;26360.736392635827;26638.600420993313;26969.595456067782;27305.726942730318;45083.098338032396;45835;45980.080398392514;46170.76914615405;46259;28756.568651469825;36204.09489762777;36399.45400818937;37026.444603330434;37560.43659345167;36805.079873002316;36886.446703298934;36918;37367.81316093402;37586;32163.51754723728;36965;38515.807420962716;39276.803615981735;39328.80335598304;32155.196120097185;33194.336113278085;33470.832645836614;33493.827716029824;33594.49608255927;24929.750702492744;24995.375115621526;25197.740112994645;25205.87397063003;25415.87292063528 05:28:45 INFO - PROCESS | 1673 | |2;jquery.html;391.66903966148595;392.47800425434195;392.628965627482;399.51059951559324;417.6011908627261;14394.568162955127;19926.501837454052;20921.47696307593;21263.68104478434;21301.680474792873;19437.028148592584;20791.68812467814;20997.265095721654;21560.676589851166;21569.5195052569;5859.853503662411;5953.613015154001;6041.093835924603;6157.138000679908;6166.969165154168;5853.502452291539;6109.694515274241;6124.356942521038;6127.142200091991;6180.72186751595;5946.643201407907;5954.4938680212235;5978.790742323998;5989.85025374367;6003.609765365259;5817.301923769132;5919.952895856932;5976.641401515923;5996.16053752475;6059.48494377974;5261.815836445706;5285.603579731535;5287.629865909388;5297.629165958385;5306.230596563515;20610;23175.609463432247;25266.621000685005;26526.734732652618;26531.20406387792;2061.6082944240547;2144.5818065477083;2163.5889181055704;2167.4473009382664;2617.0971014999795;12572.371381430892;14755.336009879582;14945.850541494663;15270.541883743506;15383.230838458145;1583.3429126912317;1757.947261582142;1768.5755418699548;1776.7601373814628;1814.936477223304;112404.28182210693;114932;115587.42206289;115657.4217128909;116598.41700791527;9825.950870245602;11328.603498877499;11785.998190153932;11838.28970261787;11867.228630139014;14982.325795339018;15003.774943375858;15028.323725432381;15085;15101.697966040618;8715.128487151234;8736.388452808242;8764.956175219084;8778.648854045767;8859.468431894107;8663.306935445149;8666.56667166639;8677.219050285505;8758.91241087581;8777.868331975025;4635.235186194308;4676.392069031072;4685.601723853442;4690.835820746326;4735.786889589976;1627.2595968834153;1751.9737203941952;1779.9555011124567;1797.9212472516647;1985.5234743661417;863.4430792139035;900.2842740021698;967.5984466446346;973.6300205921705;979.7850665175274;18970.43088707341;19797.30709425163;20450.977451127383;20672.276470323766;21177.364679059654;819.5861090149436;906.5150144672568;946.2335508238349;957.8697137377961;1039.1167507618463;9243.445393276425;10528.36829790215;10567.735806604896;10848.349099054081;11030.393328366856;591990;596572.0342796516;597247.7383264903;601040;605943;68561.2859678502;127141;127554;132183;135300.32349838188;936.2556767369957;950.6245033211843;964.8022155458254;969.1568335548118;991.1476130527597;17831.395174433845;18170.364037258634;18342.309547608787;18357.449276521726;18668.533286667942;41616.79191604022;43137.78431107824;52010;52595.73702131465;55361.61595959972;70767.23081922992;73790;76379;78085.60957195178;78514;6872.484563657846;7318.817029574303;7353.7058517660835;7620.428467865043;8174.141715119827;11217.766045735127;11684.532618695157;12095.637130886087;12363.814542782045;12541.811872822098;5228.398734145622;9051.27589792828;9538.761530961781;10500.852599436961;10683.305585137252;9799.951000245239;10143.7464063399;10469.162467002774;10758.946205268923;10795.73010674708;9138.725838224864;10384.740381490523;10471.581136754445;11166.274192177483;11226.045786107945;5958.880822383615;7323.8535229296185;7344.669489873076;7541.622918854145;7658.6170691464085;9855.556499957662;10810.945945270223;10873.782524349626;11181.440927953407;11185.832212516656 05:28:45 INFO - PROCESS | 1673 | |3;mootools.html;1433.4051368681999;1530.5484881959824;1574.315172899788;1936.7675878894536;1966.950826229345;8007;8581.742547723563;8620.439671421369;9113.772155696111;9126;7527.3601743851705;8778.341624378181;8943.776405589848;8969.506677132755;9007.054259302777;2438.536678031173;2471.1351027140445;2473.542394656994;2474.8391354561895;2476.5913624251975;2413.505231427553;2471.864047477388;2473.7649923257263;2478.045952308365;2483.1681386735377;2439.829211955164;2454.0919859651954;2470.851748895063;2472.950540989179;2485.6644353012293;2422.527607116613;2431.161249368965;2468.1114798672465;2476.0095961615316;2484.1057219401;2408.3136306152837;2450.1179575352858;2452.889619967106;2469.8888550015295;2475.4430253192886;8858.468491890508;8949.239314658262;8959.283257339463;8966.955165224132;8990.775230619221;5358.490943360374;5504.614676972614;5536.778528858841;5540.750666220029;5563.36021357541;3566.696830769388;3605.855765769366;3612.7651702639246;3646.690031347339;3665.3402387570227;1021.193257326711;1046.3355769086609;1058.164050400183;1058.7353161709573;1076.3541874875011;10972.286801357966;12081.214721043105;12104.455299511455;12109.970652494549;12151.210171338835;4846.345743324606;4862.222044472867;4937.530934561211;4982.626303027215;5003.574696150795;10192.592296308212;10195.490225488697;10233;10261.640842570474;10334.689959301233;5409.594280428983;5449.482299181572;5485.424030476823;5486.204500347428;5499.7250137492965;4411.713238639478;4795.832145874878;4957.256411538298;4959.2809042688605;4979.253112033224;4088.4276201331836;4134.150595505034;4216.388623649554;4257.042165512735;4265.317549192115;1344.636948024028;1371.5953793630904;1378.9931050344885;1387.8334599848083;1390.1381143690774;821.8726097454904;838.2539539809575;905.8777065096259;940.7789169545151;966.5264020629841;5870.031444811559;5949.196858424144;5964.970175149097;6074.969625151846;6135.294441139237;449.9100179963955;454.98407555736054;464.4937018649688;467.28272102322774;469.68061718031385;3613.0967258185456;3924.4505769192338;3950.762954222756;4015.0966032642423;4081.836726530965;10569.471526423647;10890.891091088988;11069.833952490719;11101.278416902875;11136.053435458076;10898.346099234072;10941.28881622692;10944.835827462595;11029.83455248172;11046.944765276123;7895.526268423913;8285.751427457186;8403.411761176782;8452.8732069019;8460.957695211608;6086.482648974887;6145.692715364304;6150.661713605795;6225.564210505221;6230.190075290183;9907;10202.18382529381;10221.131203847755;10261.846072308772;10444.321119127537;10122.59509619607;10292.742681433025;10385.688429347132;10441.791164176522;10463.267571270242;7059.399951004017;7502.662380193014;7748.612569371396;7799.025121859768;7917.2874441300555;8608.78478038054;8647.264982476308;8686.348523860863;8686.956565217133;8696.78258043554;6220.7511699533325;7487.438442116754;7532.058492688414;7610.1628820828555;7861;7437.628118594157;7471.8879216812875;7477.66350514239;7543.962280188564;7552.131504877009;6731.831704207434;6743.35938085881;7152.749653762094;7165.534240274574;7253.419726421768;4805;4873.951260487492;4962.5533701967;5058.747062646927;5126.077306084941;5000.274960130833;5077.796888124433;5097.209932460474;5153.201253805666;5307.071262529119 05:28:45 INFO - PROCESS | 1673 | |4;prototype.html;276.3588474738606;302.41784564712924;317.52054397859234;321.5546468141623;325.0346470981185;4210;5262.578993680506;5441.591880608956;5521.260802847105;5522.779088836447;4878.609711223103;5051.419086805018;5338.813141540038;5384.811531596395;5388.91916621251;4116.814743336542;4139.9443141998745;4152.771597562133;4160.771157586331;4186.74879507229;3587.0673624857463;4140.358244472097;4147.668186545069;4151.398047283157;4161.146964872209;4066.593340665939;4074.5314288856866;4091.5908409158997;4100.503316289548;4157.152350639806;3792;4018.4374187613757;4133.813978370966;4153.356229784388;4173.833046678129;3371.4322839879574;3487.302539492112;3617.963820361815;3633.055405594538;3636.945445818315;4740.265258884879;5192.766325515361;5204.34945631796;5229.973850130725;5281.207818827168;3725.690072155281;3923.7057220708275;4541.2733962565835;4585.151746926796;4666.789994450258;4874.22090936809;5460.945390546083;5712.171735098387;5722.599418040739;5751.22358481608;3475.165960169541;3485.250671105699;3901.902452438712;3911.256861196392;3931.960680393188;5134.7946082156295;5166.870828229286;6019.879602407927;6053.667048312341;6091.390860913917;3398.76208665396;3946.8421263149726;3952.505936757905;3953.785879907261;3979.880603581897;3708.4066549352515;3733.439984002367;3811.945324592801;4029.979850100731;4030.3551431770775;3962.881113566598;4046.8988275292763;4184.2468355696265;4437.844675436344;4676.321933319649;5190.091733946544;5413.188021796762;5798.739056742456;5805.796797112165;5881.000229960917;1257.7861763500227;1281.8974482041415;1316.6774140335679;1335.572616762646;1366.1734650536282;782.62042909189;802.7551596763043;810.8337790752881;843.2705709561185;844.5270648436772;4353.608175264241;4543.568361005699;4588.95411045892;4603.976980115078;4608.962983328791;4134.91730165395;4261.870604289866;4497.775111244425;4813.278008298783;5092.337996060563;4414.448193975753;4481.7983190756495;4567.269236922077;4582.221022426195;4597.862064138081;2414.541237164968;2563.4488584954324;2632.8551929643672;2670.4258584404447;2683.2889284339667;4811.879703007453;4830.608848619885;5114.4629813869005;5270.841874743764;5292.417834038252;4064.3758657841922;4385.51759306476;5119.616028797781;5132.717700526507;5160.277561141444;4452.510223875371;4659.976700116477;4875.098106850244;4913.361263035854;5191.925447226754;4387.49543802454;4485.6860019798305;4647.488776234544;4707.976460117815;4807.029118061578;4397.097915375836;4539.774260949591;4920.5571498565305;4935.852903986777;4993.402111324342;4212.178625168062;4350.760033390468;4526.773661316855;4908.263760435963;5046.697198168122;2960.3339248668876;2985.268609190762;3074.7540196784653;3406.9949364936906;3467.9826600866836;4320.811776761416;4989.62785234049;5018.899622007612;5027.245913113063;5609.691466969356;5531.419200983839;5746.310442746897;5752.395998420273;5779.075347944308;5796.304443466811;4942.332785073969;5179.404368497671;5228.797376603478;5755.942440575708;5814.447627475383;4949.604031677385;5207.713575753219;5587.860303492283;5626.8361412553595;5635.718214089197;4629.444466663887;5411.643626880399;5493.478119578634;5660.490555849993;5764.971175144097;4544.363789069467;4951.529604687549;5600.35595906476;5628.437156284339;5647.915281270702 05:28:45 INFO - PROCESS | 1673 | |5;yui.html;596.3112604941292;637.866048129893;641.252940324522;656.865342604766;722.8590424867147;14756.926215368921;16390.934589251705;16914.32342706292;16935.068571228578;17165.82834171658;12942.935285323583;13270.336483175852;16044.278007489662;16257.83742162578;16278.75581866273;700.5866538742141;878.0560897035712;913.8172365526888;919.3105171121658;924.16824857628;905.0496978172926;906.7687739626386;913.8583519554479;913.9908600913973;914.063085337531;958.3866325551653;959.644931375392;959.740869965109;963.8313295173351;963.9855402168973;937.2548823685153;948.1703509429284;951.9476428796413;952.3048174832411;952.6618050592044;702.3257672634277;703.1070540413646;703.707961196103;703.8134894253027;704.989425158623;14980.700385992219;14989.925050374788;15008.324625391884;15010.774838377432;15031;12113.273203607812;12141.635750927486;12175.147739658227;12184.57353992606;12410.379481025913;9701.90298097024;9754.02459754026;9822.26333025026;10003.649872254437;10166.288359814816;3417.4668313478405;3475.300155351189;3497.0732755819727;3498.912527186815;3601.5858176309534;58048.70975645137;77429.61285193538;77572.83640745393;78957;79088.60455697743;8787.34094942869;9316.254699624014;9350.60530030573;9537.475438850792;9615.759606009906;14796.260186990607;20585.69121463179;20729.89635051815;20793.960301985142;20812.791872081085;6845.383915447534;7060.505764596429;7308.30571095745;7650.770476885702;7774.65014074368;4138.6482149017065;4170.282399028103;4178.561251068655;4184.623383895464;4190.601892820177;3385.272166484218;3494.73789465786;3498.265364273531;3513.4905438711244;3520.8415621297104;547.819219657512;620.7237779188218;635.640862912453;637.7704026550438;640.9903851442232;467.99532004680196;482.10569393774745;502.8240115959385;570.7260514952845;601.744258690055;6331;6811.591304521745;7294.678284449601;7800.648970796327;8261.17388261181;469.84495116611436;527.6438404077232;547.0863657691665;581.6335708503616;592.3365830270039;3676.28312479064;3699.3341198583976;3910.4720862683744;3913.2369188008065;3973.6622387097423;83291.5835420819;83804;87290.12709872947;87467.56266218628;87567.56216218878;61809;72009;72697.81906542812;76079;76131.61934190293;67979;68738.9689154663;68825.31174688289;69224.6538767303;69577;7617.923820761722;7655.88516172269;7814.960925195338;7913.406494512819;7934.404919631166;37203.62796372001;38954.4156837642;39266;39325.80337098296;41174;58813;60212.69893650504;60655;60656;60871.69564152151;10363.06732394088;10866;11043.337399756041;11106.777864442503;11226.21416500837;11752.823707644222;11904.16670833068;11991.520339186334;11995.940020299842;12010.939945300566;11439.256448330832;11462.4268786562;11536.653900383002;11656.76686466249;11674.70813229676;11007.61473348429;11269.323840569592;11341.149413794163;11449.370284634426;12449.315287659265;9801.75495612587;11061.944690276498;11329.716757080809;11416.257943233662;11501.884981150082;9170.288305669657;9241.306901982243;9351.298652601217;9449.905500945177;9455.952720236355;8317.251447369767;8459.111793261618;8562.914370856211;9384.624615015324;9595.23238140961 05:28:45 INFO - PROCESS | 1673 | __end_tp_report 05:28:45 INFO - PROCESS | 1673 | __start_cc_report 05:28:45 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,414 05:28:45 INFO - PROCESS | 1673 | __end_cc_report 05:28:45 INFO - PROCESS | 1673 | __startTimestamp1463056125065__endTimestamp 05:28:45 INFO - PROCESS | 1673 | 05:28:45 INFO - PROCESS | 1673 | ------- Summary: start ------- 05:28:45 INFO - PROCESS | 1673 | Number of tests: 6 05:28:45 INFO - PROCESS | 1673 | 05:28:45 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5816.31 Median:6142.90 stddev:701.21 (11.4%) stddev-sans-first:667.41 05:28:45 INFO - PROCESS | 1673 | Values: 2990.8 3508.8 3509.1 3526.1 3544.4 4609.4 5785.7 5883.7 5921.9 5967.1 4720.8 5053.8 5874.5 5880.8 5921.6 5185.1 5195.7 5227.3 5249.0 5339.7 4818.1 5358.4 5374.4 5394.9 5447.5 5176.6 5206.9 5231.3 5289.9 5294.9 5259.4 5268.9 5271.5 5298.5 5349.3 5216.8 5253.6 5269.7 5297.2 5310.7 5458.5 5660.6 5739.5 5744.6 5764.2 6240.3 6247.5 6254.7 6292.2 6310.3 6327.3 6333.9 6365.5 6368.2 6374.8 4974.6 5936.3 5987.5 6075.7 6115.0 6410.1 6495.5 6503.4 6504.3 6515.6 5098.0 6136.5 6285.8 6321.5 6335.9 6143.2 6209.1 6256.0 6300.9 6317.0 6142.9 6198.6 6212.9 6225.5 6267.2 6124.9 6250.3 6251.4 6268.2 6269.1 5054.0 5081.6 5105.5 5122.6 5140.3 3963.9 4431.9 4474.4 4485.7 4497.7 5178.8 5307.6 5885.0 6026.5 6082.8 6201.7 6311.8 6341.6 6341.8 6345.5 6047.8 6063.2 6067.4 6077.3 6082.1 5450.7 5568.9 5572.8 5635.9 5652.7 6626.6 6641.8 6643.8 6648.0 6667.9 6378.9 6393.1 6410.9 6412.0 6433.3 6322.1 6451.1 6452.6 6494.3 6582.4 6290.4 6294.3 6297.8 6298.7 6312.5 6275.3 6342.9 6354.5 6355.9 6367.1 6159.1 6162.8 6190.7 6193.6 6214.1 5985.5 6250.6 6271.2 6281.4 6310.7 4725.4 6056.5 6167.7 6181.8 6196.6 5254.6 6145.4 6214.7 6216.4 6233.4 6182.4 6202.0 6206.1 6207.7 6233.3 05:28:45 INFO - PROCESS | 1673 | 05:28:45 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:65443.69 Median:38104.52 stddev:144584.33 (379.4%) stddev-sans-first:144907.73 05:28:45 INFO - PROCESS | 1673 | Values: 509.9 511.7 519.7 523.2 540.2 38467.2 40501.8 40576.8 40651.6 40788.4 32724.3 41832.8 41959.0 41997.0 42100.8 8483.2 9535.0 9736.7 9738.0 9741.8 9407.1 9495.1 9503.0 9509.7 9547.3 9503.3 9538.4 9541.7 9562.0 9600.5 9447.2 9509.3 9525.7 9528.8 9530.5 9566.2 9671.6 9680.0 9690.6 9694.6 37959.4 38249.6 38304.2 38378.6 38416.0 2956.5 3163.4 3171.3 3335.6 3358.4 27766.3 27950.9 28304.3 28507.4 29000.3 2691.9 2706.0 2707.7 2739.3 2806.6 435428.0 441072.0 441927.0 442100.0 443369.0 34015.8 49299.5 59386.4 59443.8 60040.4 30163.0 36210.6 36825.1 36838.3 36934.0 15191.0 15302.2 15341.4 15348.2 15384.6 12862.0 12892.1 12910.1 12917.1 12935.8 8113.4 8128.5 8132.8 8161.6 8198.1 3016.5 3345.4 3353.6 3379.1 3426.6 968.7 976.4 989.8 1010.7 1053.8 25707.6 28190.7 29175.7 29849.0 29959.0 990.8 1088.4 1135.4 1140.9 1241.5 15167.6 15174.5 15252.3 15260.0 15339.0 631219.0 634214.0 637840.0 638271.0 639080.0 56178.7 57531.7 58375.0 58392.1 58533.8 1442.7 1461.9 1534.2 1607.2 1713.3 24709.6 28349.3 29077.6 29318.6 29735.4 50442.0 50464.0 50509.2 50594.7 50910.7 523958.0 525584.4 525854.0 526095.0 528693.0 26128.2 26360.7 26638.6 26969.6 27305.7 45083.1 45835.0 45980.1 46170.8 46259.0 28756.6 36204.1 36399.5 37026.4 37560.4 36805.1 36886.4 36918.0 37367.8 37586.0 32163.5 36965.0 38515.8 39276.8 39328.8 32155.2 33194.3 33470.8 33493.8 33594.5 24929.8 24995.4 25197.7 25205.9 25415.9 05:28:45 INFO - PROCESS | 1673 | 05:28:45 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:34408.04 Median:21008.15 stddev:99651.44 (474.3%) stddev-sans-first:99898.26 05:28:45 INFO - PROCESS | 1673 | Values: 391.7 392.5 392.6 399.5 417.6 14394.6 19926.5 20921.5 21263.7 21301.7 19437.0 20791.7 20997.3 21560.7 21569.5 5859.9 5953.6 6041.1 6157.1 6167.0 5853.5 6109.7 6124.4 6127.1 6180.7 5946.6 5954.5 5978.8 5989.9 6003.6 5817.3 5920.0 5976.6 5996.2 6059.5 5261.8 5285.6 5287.6 5297.6 5306.2 20610.0 23175.6 25266.6 26526.7 26531.2 2061.6 2144.6 2163.6 2167.4 2617.1 12572.4 14755.3 14945.9 15270.5 15383.2 1583.3 1757.9 1768.6 1776.8 1814.9 112404.3 114932.0 115587.4 115657.4 116598.4 9826.0 11328.6 11786.0 11838.3 11867.2 14982.3 15003.8 15028.3 15085.0 15101.7 8715.1 8736.4 8765.0 8778.6 8859.5 8663.3 8666.6 8677.2 8758.9 8777.9 4635.2 4676.4 4685.6 4690.8 4735.8 1627.3 1752.0 1780.0 1797.9 1985.5 863.4 900.3 967.6 973.6 979.8 18970.4 19797.3 20451.0 20672.3 21177.4 819.6 906.5 946.2 957.9 1039.1 9243.4 10528.4 10567.7 10848.3 11030.4 591990.0 596572.0 597247.7 601040.0 605943.0 68561.3 127141.0 127554.0 132183.0 135300.3 936.3 950.6 964.8 969.2 991.1 17831.4 18170.4 18342.3 18357.4 18668.5 41616.8 43137.8 52010.0 52595.7 55361.6 70767.2 73790.0 76379.0 78085.6 78514.0 6872.5 7318.8 7353.7 7620.4 8174.1 11217.8 11684.5 12095.6 12363.8 12541.8 5228.4 9051.3 9538.8 10500.9 10683.3 9800.0 10143.7 10469.2 10758.9 10795.7 9138.7 10384.7 10471.6 11166.3 11226.0 5958.9 7323.9 7344.7 7541.6 7658.6 9855.6 10810.9 10873.8 11181.4 11185.8 05:28:45 INFO - PROCESS | 1673 | 05:28:45 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5827.12 Median:4800.42 stddev:3274.43 (68.2%) stddev-sans-first:3266.96 05:28:45 INFO - PROCESS | 1673 | Values: 1433.4 1530.5 1574.3 1936.8 1967.0 8007.0 8581.7 8620.4 9113.8 9126.0 7527.4 8778.3 8943.8 8969.5 9007.1 2438.5 2471.1 2473.5 2474.8 2476.6 2413.5 2471.9 2473.8 2478.0 2483.2 2439.8 2454.1 2470.9 2473.0 2485.7 2422.5 2431.2 2468.1 2476.0 2484.1 2408.3 2450.1 2452.9 2469.9 2475.4 8858.5 8949.2 8959.3 8967.0 8990.8 5358.5 5504.6 5536.8 5540.8 5563.4 3566.7 3605.9 3612.8 3646.7 3665.3 1021.2 1046.3 1058.2 1058.7 1076.4 10972.3 12081.2 12104.5 12110.0 12151.2 4846.3 4862.2 4937.5 4982.6 5003.6 10192.6 10195.5 10233.0 10261.6 10334.7 5409.6 5449.5 5485.4 5486.2 5499.7 4411.7 4795.8 4957.3 4959.3 4979.3 4088.4 4134.2 4216.4 4257.0 4265.3 1344.6 1371.6 1379.0 1387.8 1390.1 821.9 838.3 905.9 940.8 966.5 5870.0 5949.2 5965.0 6075.0 6135.3 449.9 455.0 464.5 467.3 469.7 3613.1 3924.5 3950.8 4015.1 4081.8 10569.5 10890.9 11069.8 11101.3 11136.1 10898.3 10941.3 10944.8 11029.8 11046.9 7895.5 8285.8 8403.4 8452.9 8461.0 6086.5 6145.7 6150.7 6225.6 6230.2 9907.0 10202.2 10221.1 10261.8 10444.3 10122.6 10292.7 10385.7 10441.8 10463.3 7059.4 7502.7 7748.6 7799.0 7917.3 8608.8 8647.3 8686.3 8687.0 8696.8 6220.8 7487.4 7532.1 7610.2 7861.0 7437.6 7471.9 7477.7 7544.0 7552.1 6731.8 6743.4 7152.7 7165.5 7253.4 4805.0 4874.0 4962.6 5058.7 5126.1 5000.3 5077.8 5097.2 5153.2 5307.1 05:28:45 INFO - PROCESS | 1673 | 05:28:45 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:4301.92 Median:4593.41 stddev:1308.62 (28.5%) stddev-sans-first:1276.94 05:28:45 INFO - PROCESS | 1673 | Values: 276.4 302.4 317.5 321.6 325.0 4210.0 5262.6 5441.6 5521.3 5522.8 4878.6 5051.4 5338.8 5384.8 5388.9 4116.8 4139.9 4152.8 4160.8 4186.7 3587.1 4140.4 4147.7 4151.4 4161.1 4066.6 4074.5 4091.6 4100.5 4157.2 3792.0 4018.4 4133.8 4153.4 4173.8 3371.4 3487.3 3618.0 3633.1 3636.9 4740.3 5192.8 5204.3 5230.0 5281.2 3725.7 3923.7 4541.3 4585.2 4666.8 4874.2 5460.9 5712.2 5722.6 5751.2 3475.2 3485.3 3901.9 3911.3 3932.0 5134.8 5166.9 6019.9 6053.7 6091.4 3398.8 3946.8 3952.5 3953.8 3979.9 3708.4 3733.4 3811.9 4030.0 4030.4 3962.9 4046.9 4184.2 4437.8 4676.3 5190.1 5413.2 5798.7 5805.8 5881.0 1257.8 1281.9 1316.7 1335.6 1366.2 782.6 802.8 810.8 843.3 844.5 4353.6 4543.6 4589.0 4604.0 4609.0 4134.9 4261.9 4497.8 4813.3 5092.3 4414.4 4481.8 4567.3 4582.2 4597.9 2414.5 2563.4 2632.9 2670.4 2683.3 4811.9 4830.6 5114.5 5270.8 5292.4 4064.4 4385.5 5119.6 5132.7 5160.3 4452.5 4660.0 4875.1 4913.4 5191.9 4387.5 4485.7 4647.5 4708.0 4807.0 4397.1 4539.8 4920.6 4935.9 4993.4 4212.2 4350.8 4526.8 4908.3 5046.7 2960.3 2985.3 3074.8 3407.0 3468.0 4320.8 4989.6 5018.9 5027.2 5609.7 5531.4 5746.3 5752.4 5779.1 5796.3 4942.3 5179.4 5228.8 5755.9 5814.4 4949.6 5207.7 5587.9 5626.8 5635.7 4629.4 5411.6 5493.5 5660.5 5765.0 4544.4 4951.5 5600.4 5628.4 5647.9 05:28:45 INFO - PROCESS | 1673 | 05:28:45 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:17132.20 Median:60433.85 stddev:23613.23 (39.1%) stddev-sans-first:23646.83 05:28:45 INFO - PROCESS | 1673 | Values: 596.3 637.9 641.3 656.9 722.9 14756.9 16390.9 16914.3 16935.1 17165.8 12942.9 13270.3 16044.3 16257.8 16278.8 700.6 878.1 913.8 919.3 924.2 905.0 906.8 913.9 914.0 914.1 958.4 959.6 959.7 963.8 964.0 937.3 948.2 951.9 952.3 952.7 702.3 703.1 703.7 703.8 705.0 14980.7 14989.9 15008.3 15010.8 15031.0 12113.3 12141.6 12175.1 12184.6 12410.4 9701.9 9754.0 9822.3 10003.6 10166.3 3417.5 3475.3 3497.1 3498.9 3601.6 58048.7 77429.6 77572.8 78957.0 79088.6 8787.3 9316.3 9350.6 9537.5 9615.8 14796.3 20585.7 20729.9 20794.0 20812.8 6845.4 7060.5 7308.3 7650.8 7774.7 4138.6 4170.3 4178.6 4184.6 4190.6 3385.3 3494.7 3498.3 3513.5 3520.8 547.8 620.7 635.6 637.8 641.0 468.0 482.1 502.8 570.7 601.7 6331.0 6811.6 7294.7 7800.6 8261.2 469.8 527.6 547.1 581.6 592.3 3676.3 3699.3 3910.5 3913.2 3973.7 83291.6 83804.0 87290.1 87467.6 87567.6 61809.0 72009.0 72697.8 76079.0 76131.6 67979.0 68739.0 68825.3 69224.7 69577.0 7617.9 7655.9 7815.0 7913.4 7934.4 37203.6 38954.4 39266.0 39325.8 41174.0 58813.0 60212.7 60655.0 60656.0 60871.7 10363.1 10866.0 11043.3 11106.8 11226.2 11752.8 11904.2 11991.5 11995.9 12010.9 11439.3 11462.4 11536.7 11656.8 11674.7 11007.6 11269.3 11341.1 11449.4 12449.3 9801.8 11061.9 11329.7 11416.3 11501.9 9170.3 9241.3 9351.3 9449.9 9456.0 8317.3 8459.1 8562.9 9384.6 9595.2 05:28:45 INFO - PROCESS | 1673 | -------- Summary: end -------- 05:28:45 INFO - PROCESS | 1673 | 05:28:45 INFO - TEST-INFO | 1673: exit 0 05:28:45 INFO - TEST-OK | dromaeo_css | took 1113178ms 05:28:45 INFO - TEST-START | kraken 05:28:45 INFO - operating with platform_type : mac_ 05:28:45 INFO - Initialising browser for kraken test... 05:28:45 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmp6VcBtu/profile http://localhost:49277/getInfo.html) 05:28:48 INFO - PROCESS | 1683 | __metrics Screen width/height:1024/768 05:28:48 INFO - PROCESS | 1683 | colorDepth:24 05:28:48 INFO - PROCESS | 1683 | Browser inner width/height: 1024/605 05:28:48 INFO - PROCESS | 1683 | __metrics 05:28:48 ERROR - PROCESS | 1683 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:28:49 INFO - TEST-INFO | 1683: exit 0 05:28:49 INFO - Browser initialized. 05:28:49 INFO - Running cycle 1/1 for kraken test... 05:28:49 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmp6VcBtu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:29:01 INFO - PROCESS | 1686 | RSS: Main: 248721408 05:29:01 INFO - PROCESS | 1686 | 05:29:04 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 05:29:05 INFO - PROCESS | 1686 | RSS: Main: 258752512 05:29:05 INFO - PROCESS | 1686 | 05:29:08 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 05:29:08 INFO - PROCESS | 1686 | RSS: Main: 262553600 05:29:08 INFO - PROCESS | 1686 | 05:29:12 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 05:29:12 INFO - PROCESS | 1686 | RSS: Main: 263118848 05:29:12 INFO - PROCESS | 1686 | 05:29:14 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 05:29:15 INFO - PROCESS | 1686 | RSS: Main: 263467008 05:29:15 INFO - PROCESS | 1686 | 05:29:17 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:29:18 INFO - PROCESS | 1686 | RSS: Main: 264216576 05:29:18 INFO - PROCESS | 1686 | 05:29:21 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 05:29:21 INFO - PROCESS | 1686 | RSS: Main: 264175616 05:29:21 INFO - PROCESS | 1686 | 05:29:25 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 05:29:25 INFO - PROCESS | 1686 | RSS: Main: 264552448 05:29:25 INFO - PROCESS | 1686 | 05:29:28 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 05:29:29 INFO - PROCESS | 1686 | RSS: Main: 264380416 05:29:29 INFO - PROCESS | 1686 | 05:29:31 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:29:31 INFO - PROCESS | 1686 | RSS: Main: 263905280 05:29:31 INFO - PROCESS | 1686 | 05:29:33 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:29:34 INFO - PROCESS | 1686 | RSS: Main: 263946240 05:29:34 INFO - PROCESS | 1686 | 05:29:36 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:29:37 INFO - PROCESS | 1686 | RSS: Main: 264290304 05:29:37 INFO - PROCESS | 1686 | 05:29:40 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:29:41 INFO - PROCESS | 1686 | RSS: Main: 264368128 05:29:41 INFO - PROCESS | 1686 | 05:29:44 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:44 INFO - PROCESS | 1686 | RSS: Main: 264339456 05:29:44 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 05:29:46 INFO - PROCESS | 1686 | RSS: Main: 266829824 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | __start_tp_report 05:29:46 INFO - PROCESS | 1686 | _x_x_mozilla_page_load 05:29:46 INFO - PROCESS | 1686 | _x_x_mozilla_page_load_details 05:29:46 INFO - PROCESS | 1686 | |i|pagename|runs| 05:29:46 INFO - PROCESS | 1686 | |0;ai-astar;115;119;139;98;169;135;167;117;146;169 05:29:46 INFO - PROCESS | 1686 | |1;audio-beat-detection;181;150;154;192;121;135;137;123;169;122 05:29:46 INFO - PROCESS | 1686 | |2;audio-dft;201;208;259;280;216;200;200;200;203;200 05:29:46 INFO - PROCESS | 1686 | |3;audio-fft;72;85;76;181;59;59;64;72;129;62 05:29:46 INFO - PROCESS | 1686 | |4;audio-oscillator;82;85;147;81;81;83;121;85;82;123 05:29:46 INFO - PROCESS | 1686 | |5;imaging-gaussian-blur;109;86;90;86;89;88;90;86;89;86 05:29:46 INFO - PROCESS | 1686 | |6;imaging-darkroom;108;141;107;112;148;107;96;96;96;96 05:29:46 INFO - PROCESS | 1686 | |7;imaging-desaturate;90;91;130;148;91;90;95;90;123;87 05:29:46 INFO - PROCESS | 1686 | |8;json-parse-financial;60;64;63;59;59;62;61;122;61;60 05:29:46 INFO - PROCESS | 1686 | |9;json-stringify-tinderbox;64;63;70;74;74;70;73;66;68;67 05:29:46 INFO - PROCESS | 1686 | |10;stanford-crypto-aes;84;86;89;86;89;76;87;105;110;89 05:29:46 INFO - PROCESS | 1686 | |11;stanford-crypto-ccm;130;161;155;151;151;137;148;155;254;158 05:29:46 INFO - PROCESS | 1686 | |12;stanford-crypto-pbkdf2;176;171;153;226;235;179;169;171;171;170 05:29:46 INFO - PROCESS | 1686 | |13;stanford-crypto-sha256-iterative;67;67;69;74;67;68;68;67;82;80 05:29:46 INFO - PROCESS | 1686 | __end_tp_report 05:29:46 INFO - PROCESS | 1686 | __start_cc_report 05:29:46 INFO - PROCESS | 1686 | _x_x_mozilla_cycle_collect,1075 05:29:46 INFO - PROCESS | 1686 | __end_cc_report 05:29:46 INFO - PROCESS | 1686 | __startTimestamp1463056186878__endTimestamp 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | ------- Summary: start ------- 05:29:46 INFO - PROCESS | 1686 | Number of tests: 14 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#0] ai-astar Cycles:10 Average:137.40 Median:156.50 stddev:25.31 (16.2%) stddev-sans-first:25.51 05:29:46 INFO - PROCESS | 1686 | Values: 115.0 119.0 139.0 98.0 169.0 135.0 167.0 117.0 146.0 169.0 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#1] audio-beat-detection Cycles:10 Average:148.40 Median:152.00 stddev:25.44 (16.7%) stddev-sans-first:24.09 05:29:46 INFO - PROCESS | 1686 | Values: 181.0 150.0 154.0 192.0 121.0 135.0 137.0 123.0 169.0 122.0 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#2] audio-dft Cycles:10 Average:216.70 Median:205.50 stddev:28.72 (14.0%) stddev-sans-first:29.89 05:29:46 INFO - PROCESS | 1686 | Values: 201.0 208.0 259.0 280.0 216.0 200.0 200.0 200.0 203.0 200.0 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#3] audio-fft Cycles:10 Average:85.90 Median:68.00 stddev:39.28 (57.8%) stddev-sans-first:41.34 05:29:46 INFO - PROCESS | 1686 | Values: 72.0 85.0 76.0 181.0 59.0 59.0 64.0 72.0 129.0 62.0 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:82.00 stddev:24.03 (29.3%) stddev-sans-first:24.87 05:29:46 INFO - PROCESS | 1686 | Values: 82.0 85.0 147.0 81.0 81.0 83.0 121.0 85.0 82.0 123.0 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:88.50 stddev:6.92 (7.8%) stddev-sans-first:1.79 05:29:46 INFO - PROCESS | 1686 | Values: 109.0 86.0 90.0 86.0 89.0 88.0 90.0 86.0 89.0 86.0 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#6] imaging-darkroom Cycles:10 Average:110.70 Median:122.00 stddev:18.89 (15.5%) stddev-sans-first:20.01 05:29:46 INFO - PROCESS | 1686 | Values: 108.0 141.0 107.0 112.0 148.0 107.0 96.0 96.0 96.0 96.0 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#7] imaging-desaturate Cycles:10 Average:103.50 Median:90.00 stddev:21.77 (24.2%) stddev-sans-first:22.54 05:29:46 INFO - PROCESS | 1686 | Values: 90.0 91.0 130.0 148.0 91.0 90.0 95.0 90.0 123.0 87.0 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:61.00 stddev:19.36 (31.7%) stddev-sans-first:20.36 05:29:46 INFO - PROCESS | 1686 | Values: 60.0 64.0 63.0 59.0 59.0 62.0 61.0 122.0 61.0 60.0 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:70.00 stddev:3.98 (5.7%) stddev-sans-first:3.81 05:29:46 INFO - PROCESS | 1686 | Values: 64.0 63.0 70.0 74.0 74.0 70.0 73.0 66.0 68.0 67.0 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:86.50 stddev:10.00 (11.6%) stddev-sans-first:10.37 05:29:46 INFO - PROCESS | 1686 | Values: 84.0 86.0 89.0 86.0 89.0 76.0 87.0 105.0 110.0 89.0 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#11] stanford-crypto-ccm Cycles:10 Average:160.00 Median:155.00 stddev:34.36 (22.2%) stddev-sans-first:34.69 05:29:46 INFO - PROCESS | 1686 | Values: 130.0 161.0 155.0 151.0 151.0 137.0 148.0 155.0 254.0 158.0 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.10 Median:173.50 stddev:26.47 (15.3%) stddev-sans-first:27.99 05:29:46 INFO - PROCESS | 1686 | Values: 176.0 171.0 153.0 226.0 235.0 179.0 169.0 171.0 171.0 170.0 05:29:46 INFO - PROCESS | 1686 | 05:29:46 INFO - PROCESS | 1686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.90 Median:68.50 stddev:5.74 (8.4%) stddev-sans-first:5.92 05:29:46 INFO - PROCESS | 1686 | Values: 67.0 67.0 69.0 74.0 67.0 68.0 68.0 67.0 82.0 80.0 05:29:46 INFO - PROCESS | 1686 | -------- Summary: end -------- 05:29:46 INFO - PROCESS | 1686 | 05:29:47 INFO - TEST-INFO | 1686: exit 0 05:29:47 INFO - TEST-OK | kraken | took 62075ms 05:29:47 INFO - SUITE-END | took 1175s 05:29:48 INFO - Completed test suite (00:19:35) 05:29:48 INFO - javascript benchmark 05:29:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2990.820550766954, 3508.771929824563, 3509.1051781795636, 3526.0655926179566, 3544.4151714967, 4609.377734005917, 5785.681787501686, 5883.705814709264, 5921.911171332428, 5967.1049342598635, 4720.787564559594, 5053.797848086081, 5874.530037596993, 5880.794172203974, 5921.615095018831, 5185.144451165553, 5195.688258704471, 5227.294315267428, 5249.028929648009, 5339.679619222839, 4818.090144012939, 5358.410574836765, 5374.408815030363, 5394.8921021579545, 5447.482489163543, 5176.6117541184185, 5206.869828254286, 5231.319928409321, 5289.8942021159555, 5294.867628309304, 5259.421463638997, 5268.868278293074, 5271.472852714736, 5298.549623282026, 5349.331333583302, 5216.765245563959, 5253.579713622901, 5269.683818970836, 5297.17893726473, 5310.734463276859, 5458.4814442627885, 5660.575456840712, 5739.540836733051, 5744.569157313176, 5764.193012978187, 6240.251169859601, 6247.531435142382, 6254.655993920333, 6292.244930608311, 6310.274318453391, 6327.335629758901, 6333.873322533524, 6365.522585806037, 6368.203974503187, 6374.808755737289, 4974.577160941324, 5936.287645482526, 5987.461128498456, 6075.665838378844, 6115.021596544531, 6410.0705397718, 6495.512836537184, 6503.414692677682, 6504.3495650435525, 6515.641639709766, 5098.031374038921, 6136.478399336017, 6285.811425657238, 6321.525885558602, 6335.936640633535, 6143.204841796972, 6209.130721698965, 6256.0, 6300.936990630035, 6317.0, 6142.897836592492, 6198.566100373021, 6212.9068063979075, 6225.470834978987, 6267.247930248308, 6124.930239974474, 6250.343713910065, 6251.437370636664, 6268.216472940882, 6269.059641053787, 5053.974730126326, 5081.644284900095, 5105.5149760772665, 5122.564582010571, 5140.306058682105, 3963.900902477461, 4431.867043988621, 4474.440694913136, 4485.708428952108, 4497.685162038626, 5178.792848286026, 5307.575393968474, 5884.9705751470965, 6026.457618814327, 6082.75668973245, 6201.6899155042065, 6311.810645680637, 6341.55609107358, 6341.841453963688, 6345.524085693592, 6047.820674968339, 6063.211782468251, 6067.423594758491, 6077.301110372276, 6082.148499210114, 5450.727463626882, 5568.860778480571, 5572.804951826666, 5635.915461268084, 5652.74562644682, 6626.602403855784, 6641.800745977628, 6643.833904152435, 6648.0360347750275, 6667.8666426670225, 6378.9362106380195, 6393.1369265148605, 6410.935890641033, 6412.0, 6433.324500927335, 6322.0516922462, 6451.129097571956, 6452.612843229421, 6494.318096599789, 6582.407583317525, 6290.433860952537, 6294.339094395205, 6297.779577714758, 6298.748050077946, 6312.495000400048, 6275.309715931336, 6342.936570634419, 6354.459870911024, 6355.936440635719, 6367.140436041261, 6159.076138579249, 6162.753489860355, 6190.7214175363115, 6193.597416167935, 6214.098955651315, 5985.521158307412, 6250.624962502265, 6271.216097987752, 6281.371862813682, 6310.747570097328, 4725.432948046257, 6056.545759068, 6167.722452489738, 6181.81454556364, 6196.597221180609, 5254.579633629377, 6145.402195429131, 6214.720337584729, 6216.378362163748, 6233.438990490878, 6182.381761823782, 6202.038684003986, 6206.06908963659, 6207.720652570735, 6233.283172435228], "value": 5772.102531341735, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.89547142835715, 511.7211119939631, 519.7349351830566, 523.2230138244709, 540.1600511205077, 38467.23065538691, 40501.79749101258, 40576.79711601445, 40651.59348406519, 40788.38817417741, 32724.345513089782, 41832.79083604578, 41959.0, 41997.0, 42100.78949605255, 8483.15168483153, 9535.046495350478, 9736.707898763048, 9738.02619738024, 9741.805163896717, 9407.059294070606, 9495.050494950485, 9503.0, 9509.714708558755, 9547.331686781929, 9503.287253455985, 9538.380005299634, 9541.666041688542, 9561.952190239039, 9600.471974041426, 9447.244220462346, 9509.286803489696, 9525.666601668909, 9528.809423811554, 9530.52347382628, 9566.186874115709, 9671.564779584865, 9680.031996800333, 9690.612375504972, 9694.612215511372, 37959.4306085409, 38249.617503824884, 38304.233915321536, 38378.616213837784, 38416.0, 2956.4530561846127, 3163.383140287645, 3171.349873275973, 3335.5663763710804, 3358.383870283275, 27766.3058423539, 27950.86024569864, 28304.292392690142, 28507.429851403063, 29000.32351989656, 2691.9192424227303, 2706.0258307009462, 2707.7021527631946, 2739.342557786117, 2806.578476939583, 435428.0, 441072.0, 441927.0, 442100.0, 443369.0, 34015.829920850236, 49299.50700493021, 59386.40613593809, 59443.81112377815, 60040.39959600348, 30163.0, 36210.637893620726, 36825.07937301535, 36838.263234735154, 36934.0, 15191.0, 15302.158381288913, 15341.386344546094, 15348.232588370536, 15384.61538461525, 12861.971042316593, 12892.097553171376, 12910.09629325957, 12917.095803293867, 12935.805962910565, 8113.432059755761, 8128.512289262663, 8132.837343253102, 8161.5919204039565, 8198.057223419264, 3016.457037733185, 3345.397828390913, 3353.6311005789344, 3379.121428428626, 3426.588809342845, 968.6851773173606, 976.4190306767462, 989.7674046599044, 1010.6664800615779, 1053.8103141434462, 25707.61438578423, 28190.71809281922, 29175.708242917724, 29849.0, 29958.95143669961, 990.8166989107038, 1088.4285749981323, 1135.3755434510986, 1140.8745038045809, 1241.509603706531, 15167.620809479631, 15174.544763657108, 15252.313645885963, 15260.0, 15339.0, 631219.0, 634214.0, 637840.0, 638271.0, 639080.0, 56178.719106404205, 57531.712341438026, 58375.0, 58392.124118138265, 58533.829323413294, 1442.7186698593882, 1461.9400934322532, 1534.225216265801, 1607.1723062622993, 1713.2718594597247, 24709.629355559322, 28349.291267718472, 29077.563836542045, 29318.560221596268, 29735.405291893607, 50442.0, 50464.0, 50509.242361363875, 50594.74702626463, 50910.74544627253, 523958.0, 525584.3720781371, 525854.0, 526095.0, 528693.0, 26128.216153515426, 26360.736392635827, 26638.600420993313, 26969.595456067782, 27305.726942730318, 45083.098338032396, 45835.0, 45980.080398392514, 46170.76914615405, 46259.0, 28756.568651469825, 36204.09489762777, 36399.45400818937, 37026.444603330434, 37560.43659345167, 36805.079873002316, 36886.446703298934, 36918.0, 37367.81316093402, 37586.0, 32163.51754723728, 36965.0, 38515.807420962716, 39276.803615981735, 39328.80335598304, 32155.196120097185, 33194.336113278085, 33470.832645836614, 33493.827716029824, 33594.49608255927, 24929.750702492744, 24995.375115621526, 25197.740112994645, 25205.87397063003, 25415.87292063528], "value": 18305.21472102268, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.66903966148595, 392.47800425434195, 392.628965627482, 399.51059951559324, 417.6011908627261, 14394.568162955127, 19926.501837454052, 20921.47696307593, 21263.68104478434, 21301.680474792873, 19437.028148592584, 20791.68812467814, 20997.265095721654, 21560.676589851166, 21569.5195052569, 5859.853503662411, 5953.613015154001, 6041.093835924603, 6157.138000679908, 6166.969165154168, 5853.502452291539, 6109.694515274241, 6124.356942521038, 6127.142200091991, 6180.72186751595, 5946.643201407907, 5954.4938680212235, 5978.790742323998, 5989.85025374367, 6003.609765365259, 5817.301923769132, 5919.952895856932, 5976.641401515923, 5996.16053752475, 6059.48494377974, 5261.815836445706, 5285.603579731535, 5287.629865909388, 5297.629165958385, 5306.230596563515, 20610.0, 23175.609463432247, 25266.621000685005, 26526.734732652618, 26531.20406387792, 2061.6082944240547, 2144.5818065477083, 2163.5889181055704, 2167.4473009382664, 2617.0971014999795, 12572.371381430892, 14755.336009879582, 14945.850541494663, 15270.541883743506, 15383.230838458145, 1583.3429126912317, 1757.947261582142, 1768.5755418699548, 1776.7601373814628, 1814.936477223304, 112404.28182210693, 114932.0, 115587.42206289, 115657.4217128909, 116598.41700791527, 9825.950870245602, 11328.603498877499, 11785.998190153932, 11838.28970261787, 11867.228630139014, 14982.325795339018, 15003.774943375858, 15028.323725432381, 15085.0, 15101.697966040618, 8715.128487151234, 8736.388452808242, 8764.956175219084, 8778.648854045767, 8859.468431894107, 8663.306935445149, 8666.56667166639, 8677.219050285505, 8758.91241087581, 8777.868331975025, 4635.235186194308, 4676.392069031072, 4685.601723853442, 4690.835820746326, 4735.786889589976, 1627.2595968834153, 1751.9737203941952, 1779.9555011124567, 1797.9212472516647, 1985.5234743661417, 863.4430792139035, 900.2842740021698, 967.5984466446346, 973.6300205921705, 979.7850665175274, 18970.43088707341, 19797.30709425163, 20450.977451127383, 20672.276470323766, 21177.364679059654, 819.5861090149436, 906.5150144672568, 946.2335508238349, 957.8697137377961, 1039.1167507618463, 9243.445393276425, 10528.36829790215, 10567.735806604896, 10848.349099054081, 11030.393328366856, 591990.0, 596572.0342796516, 597247.7383264903, 601040.0, 605943.0, 68561.2859678502, 127141.0, 127554.0, 132183.0, 135300.32349838188, 936.2556767369957, 950.6245033211843, 964.8022155458254, 969.1568335548118, 991.1476130527597, 17831.395174433845, 18170.364037258634, 18342.309547608787, 18357.449276521726, 18668.533286667942, 41616.79191604022, 43137.78431107824, 52010.0, 52595.73702131465, 55361.61595959972, 70767.23081922992, 73790.0, 76379.0, 78085.60957195178, 78514.0, 6872.484563657846, 7318.817029574303, 7353.7058517660835, 7620.428467865043, 8174.141715119827, 11217.766045735127, 11684.532618695157, 12095.637130886087, 12363.814542782045, 12541.811872822098, 5228.398734145622, 9051.27589792828, 9538.761530961781, 10500.852599436961, 10683.305585137252, 9799.951000245239, 10143.7464063399, 10469.162467002774, 10758.946205268923, 10795.73010674708, 9138.725838224864, 10384.740381490523, 10471.581136754445, 11166.274192177483, 11226.045786107945, 5958.880822383615, 7323.8535229296185, 7344.669489873076, 7541.622918854145, 7658.6170691464085, 9855.556499957662, 10810.945945270223, 10873.782524349626, 11181.440927953407, 11185.832212516656], "value": 9411.893730911988, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1433.4051368681999, 1530.5484881959824, 1574.315172899788, 1936.7675878894536, 1966.950826229345, 8007.0, 8581.742547723563, 8620.439671421369, 9113.772155696111, 9126.0, 7527.3601743851705, 8778.341624378181, 8943.776405589848, 8969.506677132755, 9007.054259302777, 2438.536678031173, 2471.1351027140445, 2473.542394656994, 2474.8391354561895, 2476.5913624251975, 2413.505231427553, 2471.864047477388, 2473.7649923257263, 2478.045952308365, 2483.1681386735377, 2439.829211955164, 2454.0919859651954, 2470.851748895063, 2472.950540989179, 2485.6644353012293, 2422.527607116613, 2431.161249368965, 2468.1114798672465, 2476.0095961615316, 2484.1057219401, 2408.3136306152837, 2450.1179575352858, 2452.889619967106, 2469.8888550015295, 2475.4430253192886, 8858.468491890508, 8949.239314658262, 8959.283257339463, 8966.955165224132, 8990.775230619221, 5358.490943360374, 5504.614676972614, 5536.778528858841, 5540.750666220029, 5563.36021357541, 3566.696830769388, 3605.855765769366, 3612.7651702639246, 3646.690031347339, 3665.3402387570227, 1021.193257326711, 1046.3355769086609, 1058.164050400183, 1058.7353161709573, 1076.3541874875011, 10972.286801357966, 12081.214721043105, 12104.455299511455, 12109.970652494549, 12151.210171338835, 4846.345743324606, 4862.222044472867, 4937.530934561211, 4982.626303027215, 5003.574696150795, 10192.592296308212, 10195.490225488697, 10233.0, 10261.640842570474, 10334.689959301233, 5409.594280428983, 5449.482299181572, 5485.424030476823, 5486.204500347428, 5499.7250137492965, 4411.713238639478, 4795.832145874878, 4957.256411538298, 4959.2809042688605, 4979.253112033224, 4088.4276201331836, 4134.150595505034, 4216.388623649554, 4257.042165512735, 4265.317549192115, 1344.636948024028, 1371.5953793630904, 1378.9931050344885, 1387.8334599848083, 1390.1381143690774, 821.8726097454904, 838.2539539809575, 905.8777065096259, 940.7789169545151, 966.5264020629841, 5870.031444811559, 5949.196858424144, 5964.970175149097, 6074.969625151846, 6135.294441139237, 449.9100179963955, 454.98407555736054, 464.4937018649688, 467.28272102322774, 469.68061718031385, 3613.0967258185456, 3924.4505769192338, 3950.762954222756, 4015.0966032642423, 4081.836726530965, 10569.471526423647, 10890.891091088988, 11069.833952490719, 11101.278416902875, 11136.053435458076, 10898.346099234072, 10941.28881622692, 10944.835827462595, 11029.83455248172, 11046.944765276123, 7895.526268423913, 8285.751427457186, 8403.411761176782, 8452.8732069019, 8460.957695211608, 6086.482648974887, 6145.692715364304, 6150.661713605795, 6225.564210505221, 6230.190075290183, 9907.0, 10202.18382529381, 10221.131203847755, 10261.846072308772, 10444.321119127537, 10122.59509619607, 10292.742681433025, 10385.688429347132, 10441.791164176522, 10463.267571270242, 7059.399951004017, 7502.662380193014, 7748.612569371396, 7799.025121859768, 7917.2874441300555, 8608.78478038054, 8647.264982476308, 8686.348523860863, 8686.956565217133, 8696.78258043554, 6220.7511699533325, 7487.438442116754, 7532.058492688414, 7610.1628820828555, 7861.0, 7437.628118594157, 7471.8879216812875, 7477.66350514239, 7543.962280188564, 7552.131504877009, 6731.831704207434, 6743.35938085881, 7152.749653762094, 7165.534240274574, 7253.419726421768, 4805.0, 4873.951260487492, 4962.5533701967, 5058.747062646927, 5126.077306084941, 5000.274960130833, 5077.796888124433, 5097.209932460474, 5153.201253805666, 5307.071262529119], "value": 4612.731511493354, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.3588474738606, 302.41784564712924, 317.52054397859234, 321.5546468141623, 325.0346470981185, 4210.0, 5262.578993680506, 5441.591880608956, 5521.260802847105, 5522.779088836447, 4878.609711223103, 5051.419086805018, 5338.813141540038, 5384.811531596395, 5388.91916621251, 4116.814743336542, 4139.9443141998745, 4152.771597562133, 4160.771157586331, 4186.74879507229, 3587.0673624857463, 4140.358244472097, 4147.668186545069, 4151.398047283157, 4161.146964872209, 4066.593340665939, 4074.5314288856866, 4091.5908409158997, 4100.503316289548, 4157.152350639806, 3792.0, 4018.4374187613757, 4133.813978370966, 4153.356229784388, 4173.833046678129, 3371.4322839879574, 3487.302539492112, 3617.963820361815, 3633.055405594538, 3636.945445818315, 4740.265258884879, 5192.766325515361, 5204.34945631796, 5229.973850130725, 5281.207818827168, 3725.690072155281, 3923.7057220708275, 4541.2733962565835, 4585.151746926796, 4666.789994450258, 4874.22090936809, 5460.945390546083, 5712.171735098387, 5722.599418040739, 5751.22358481608, 3475.165960169541, 3485.250671105699, 3901.902452438712, 3911.256861196392, 3931.960680393188, 5134.7946082156295, 5166.870828229286, 6019.879602407927, 6053.667048312341, 6091.390860913917, 3398.76208665396, 3946.8421263149726, 3952.505936757905, 3953.785879907261, 3979.880603581897, 3708.4066549352515, 3733.439984002367, 3811.945324592801, 4029.979850100731, 4030.3551431770775, 3962.881113566598, 4046.8988275292763, 4184.2468355696265, 4437.844675436344, 4676.321933319649, 5190.091733946544, 5413.188021796762, 5798.739056742456, 5805.796797112165, 5881.000229960917, 1257.7861763500227, 1281.8974482041415, 1316.6774140335679, 1335.572616762646, 1366.1734650536282, 782.62042909189, 802.7551596763043, 810.8337790752881, 843.2705709561185, 844.5270648436772, 4353.608175264241, 4543.568361005699, 4588.95411045892, 4603.976980115078, 4608.962983328791, 4134.91730165395, 4261.870604289866, 4497.775111244425, 4813.278008298783, 5092.337996060563, 4414.448193975753, 4481.7983190756495, 4567.269236922077, 4582.221022426195, 4597.862064138081, 2414.541237164968, 2563.4488584954324, 2632.8551929643672, 2670.4258584404447, 2683.2889284339667, 4811.879703007453, 4830.608848619885, 5114.4629813869005, 5270.841874743764, 5292.417834038252, 4064.3758657841922, 4385.51759306476, 5119.616028797781, 5132.717700526507, 5160.277561141444, 4452.510223875371, 4659.976700116477, 4875.098106850244, 4913.361263035854, 5191.925447226754, 4387.49543802454, 4485.6860019798305, 4647.488776234544, 4707.976460117815, 4807.029118061578, 4397.097915375836, 4539.774260949591, 4920.5571498565305, 4935.852903986777, 4993.402111324342, 4212.178625168062, 4350.760033390468, 4526.773661316855, 4908.263760435963, 5046.697198168122, 2960.3339248668876, 2985.268609190762, 3074.7540196784653, 3406.9949364936906, 3467.9826600866836, 4320.811776761416, 4989.62785234049, 5018.899622007612, 5027.245913113063, 5609.691466969356, 5531.419200983839, 5746.310442746897, 5752.395998420273, 5779.075347944308, 5796.304443466811, 4942.332785073969, 5179.404368497671, 5228.797376603478, 5755.942440575708, 5814.447627475383, 4949.604031677385, 5207.713575753219, 5587.860303492283, 5626.8361412553595, 5635.718214089197, 4629.444466663887, 5411.643626880399, 5493.478119578634, 5660.490555849993, 5764.971175144097, 4544.363789069467, 4951.529604687549, 5600.35595906476, 5628.437156284339, 5647.915281270702], "value": 3891.031336906316, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.3112604941292, 637.866048129893, 641.252940324522, 656.865342604766, 722.8590424867147, 14756.926215368921, 16390.934589251705, 16914.32342706292, 16935.068571228578, 17165.82834171658, 12942.935285323583, 13270.336483175852, 16044.278007489662, 16257.83742162578, 16278.75581866273, 700.5866538742141, 878.0560897035712, 913.8172365526888, 919.3105171121658, 924.16824857628, 905.0496978172926, 906.7687739626386, 913.8583519554479, 913.9908600913973, 914.063085337531, 958.3866325551653, 959.644931375392, 959.740869965109, 963.8313295173351, 963.9855402168973, 937.2548823685153, 948.1703509429284, 951.9476428796413, 952.3048174832411, 952.6618050592044, 702.3257672634277, 703.1070540413646, 703.707961196103, 703.8134894253027, 704.989425158623, 14980.700385992219, 14989.925050374788, 15008.324625391884, 15010.774838377432, 15031.0, 12113.273203607812, 12141.635750927486, 12175.147739658227, 12184.57353992606, 12410.379481025913, 9701.90298097024, 9754.02459754026, 9822.26333025026, 10003.649872254437, 10166.288359814816, 3417.4668313478405, 3475.300155351189, 3497.0732755819727, 3498.912527186815, 3601.5858176309534, 58048.70975645137, 77429.61285193538, 77572.83640745393, 78957.0, 79088.60455697743, 8787.34094942869, 9316.254699624014, 9350.60530030573, 9537.475438850792, 9615.759606009906, 14796.260186990607, 20585.69121463179, 20729.89635051815, 20793.960301985142, 20812.791872081085, 6845.383915447534, 7060.505764596429, 7308.30571095745, 7650.770476885702, 7774.65014074368, 4138.6482149017065, 4170.282399028103, 4178.561251068655, 4184.623383895464, 4190.601892820177, 3385.272166484218, 3494.73789465786, 3498.265364273531, 3513.4905438711244, 3520.8415621297104, 547.819219657512, 620.7237779188218, 635.640862912453, 637.7704026550438, 640.9903851442232, 467.99532004680196, 482.10569393774745, 502.8240115959385, 570.7260514952845, 601.744258690055, 6331.0, 6811.591304521745, 7294.678284449601, 7800.648970796327, 8261.17388261181, 469.84495116611436, 527.6438404077232, 547.0863657691665, 581.6335708503616, 592.3365830270039, 3676.28312479064, 3699.3341198583976, 3910.4720862683744, 3913.2369188008065, 3973.6622387097423, 83291.5835420819, 83804.0, 87290.12709872947, 87467.56266218628, 87567.56216218878, 61809.0, 72009.0, 72697.81906542812, 76079.0, 76131.61934190293, 67979.0, 68738.9689154663, 68825.31174688289, 69224.6538767303, 69577.0, 7617.923820761722, 7655.88516172269, 7814.960925195338, 7913.406494512819, 7934.404919631166, 37203.62796372001, 38954.4156837642, 39266.0, 39325.80337098296, 41174.0, 58813.0, 60212.69893650504, 60655.0, 60656.0, 60871.69564152151, 10363.06732394088, 10866.0, 11043.337399756041, 11106.777864442503, 11226.21416500837, 11752.823707644222, 11904.16670833068, 11991.520339186334, 11995.940020299842, 12010.939945300566, 11439.256448330832, 11462.4268786562, 11536.653900383002, 11656.76686466249, 11674.70813229676, 11007.61473348429, 11269.323840569592, 11341.149413794163, 11449.370284634426, 12449.315287659265, 9801.75495612587, 11061.944690276498, 11329.716757080809, 11416.257943233662, 11501.884981150082, 9170.288305669657, 9241.306901982243, 9351.298652601217, 9449.905500945177, 9455.952720236355, 8317.251447369767, 8459.111793261618, 8562.914370856211, 9384.624615015324, 9595.23238140961], "value": 6664.84102564215, "unit": "score"}], "name": "dromaeo_css", "value": 7013.053854288689, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 119.0, 139.0, 98.0, 169.0, 135.0, 167.0, 117.0, 146.0, 169.0], "value": 137.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 150.0, 154.0, 192.0, 121.0, 135.0, 137.0, 123.0, 169.0, 122.0], "value": 148.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 208.0, 259.0, 280.0, 216.0, 200.0, 200.0, 200.0, 203.0, 200.0], "value": 216.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 85.0, 76.0, 181.0, 59.0, 59.0, 64.0, 72.0, 129.0, 62.0], "value": 85.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 147.0, 81.0, 81.0, 83.0, 121.0, 85.0, 82.0, 123.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 86.0, 90.0, 86.0, 89.0, 88.0, 90.0, 86.0, 89.0, 86.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 141.0, 107.0, 112.0, 148.0, 107.0, 96.0, 96.0, 96.0, 96.0], "value": 110.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 130.0, 148.0, 91.0, 90.0, 95.0, 90.0, 123.0, 87.0], "value": 103.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 63.0, 59.0, 59.0, 62.0, 61.0, 122.0, 61.0, 60.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 70.0, 74.0, 74.0, 70.0, 73.0, 66.0, 68.0, 67.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 89.0, 86.0, 89.0, 76.0, 87.0, 105.0, 110.0, 89.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 161.0, 155.0, 151.0, 151.0, 137.0, 148.0, 155.0, 254.0, 158.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 171.0, 153.0, 226.0, 235.0, 179.0, 169.0, 171.0, 171.0, 170.0], "value": 182.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 74.0, 67.0, 68.0, 68.0, 67.0, 82.0, 80.0], "value": 70.9, "unit": "score"}], "name": "kraken", "value": 1628.6, "alertThreshold": 2.0}]} 05:29:48 INFO - Return code: 0 05:29:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:29:48 ERROR - # TBPL SUCCESS # 05:29:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:29:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:29:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:29:48 INFO - 2016-05-12 05:29:48.170 screenresolution[1691:11207] starting screenresolution argv=screenresolution get 05:29:48 INFO - 2016-05-12 05:29:48.173 screenresolution[1691:11207] Display 0: 1024x768x32@60 05:29:48 INFO - 2016-05-12 05:29:48.179 screenresolution[1692:11210] starting screenresolution argv=screenresolution list 05:29:48 INFO - Available Modes on Display 0 05:29:48 INFO - 1024x768x32@60 800x600x32@75 800x600x32@60 05:29:48 INFO - 640x480x32@75 640x480x32@60 Graphics/Displays: 05:29:48 INFO - Intel Iris: 05:29:48 INFO - Chipset Model: Intel Iris 05:29:48 INFO - Type: GPU 05:29:48 INFO - Bus: Built-In 05:29:48 INFO - VRAM (Dynamic, Max): 1536 MB 05:29:48 INFO - Vendor: Intel (0x8086) 05:29:48 INFO - Device ID: 0x0a2e 05:29:48 INFO - Revision ID: 0x0009 05:29:48 INFO - Displays: 05:29:48 INFO - Display: 05:29:48 INFO - Resolution: 1024 x 768 @ 60 Hz 05:29:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:29:48 INFO - Display Serial Number: 1024x768 60 05:29:48 INFO - Main Display: Yes 05:29:48 INFO - Mirror: Off 05:29:48 INFO - Online: Yes 05:29:48 INFO - Rotation: Supported 05:29:48 INFO - Return code: 0 05:29:48 INFO - Running post-action listener: _resource_record_post_action 05:29:48 INFO - ##### 05:29:48 INFO - ##### Finished run-tests step (success) 05:29:48 INFO - ##### 05:29:48 INFO - Running post-run listener: _resource_record_post_run 05:29:49 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 31444480; Write bytes: 696262144; Read time: 197; Write time: 1919 05:29:49 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 383980032; Write bytes: 378652160; Read time: 28384; Write time: 764 05:29:49 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 29679104; Write bytes: 298219520; Read time: 182; Write time: 1108 05:29:49 INFO - Running post-run listener: _upload_blobber_files 05:29:49 INFO - Blob upload gear active. 05:29:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:29:49 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:29:49 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:29:49 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:29:49 INFO - (blobuploader) - INFO - Open directory for files ... 05:29:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:29:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:29:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:50 INFO - (blobuploader) - INFO - Done attempting. 05:29:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:29:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:29:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:51 INFO - (blobuploader) - INFO - Done attempting. 05:29:51 INFO - (blobuploader) - INFO - Iteration through files over. 05:29:51 INFO - Return code: 0 05:29:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:29:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:29:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0332d4403b61c8de945e806f31c4f55111adebabc3fb83f6c3f2485937583dfcefe6cd9f985b28411154ce15037cdd40fa26b9fa111ab4253b16dca3be2162f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7934952ba7ccecab16cad0f800f7ce8727a2e365f3faaac0cbb73ae8279fea7089097b1ec77e12967b0545ed2f886cff37ed406ebae5b4ea1b5d2029973a49cd"} 05:29:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:29:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:29:51 INFO - Contents: 05:29:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0332d4403b61c8de945e806f31c4f55111adebabc3fb83f6c3f2485937583dfcefe6cd9f985b28411154ce15037cdd40fa26b9fa111ab4253b16dca3be2162f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7934952ba7ccecab16cad0f800f7ce8727a2e365f3faaac0cbb73ae8279fea7089097b1ec77e12967b0545ed2f886cff37ed406ebae5b4ea1b5d2029973a49cd"} 05:29:51 INFO - Running post-run listener: copy_logs_to_upload_dir 05:29:51 INFO - Copying logs to upload dir... 05:29:51 INFO - mkdir: /builds/slave/test/build/upload/logs 05:29:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.596916 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2016-05-12 05:29:51.718870) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 05:29:51.721944) ========= 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.GrratGgryN/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.2cdsy1pN7b/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0332d4403b61c8de945e806f31c4f55111adebabc3fb83f6c3f2485937583dfcefe6cd9f985b28411154ce15037cdd40fa26b9fa111ab4253b16dca3be2162f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7934952ba7ccecab16cad0f800f7ce8727a2e365f3faaac0cbb73ae8279fea7089097b1ec77e12967b0545ed2f886cff37ed406ebae5b4ea1b5d2029973a49cd"} build_url:https://queue.taskcluster.net/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011494 build_url: 'https://queue.taskcluster.net/v1/task/aMF_ObgkROqfKzisD8wqOg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0332d4403b61c8de945e806f31c4f55111adebabc3fb83f6c3f2485937583dfcefe6cd9f985b28411154ce15037cdd40fa26b9fa111ab4253b16dca3be2162f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7934952ba7ccecab16cad0f800f7ce8727a2e365f3faaac0cbb73ae8279fea7089097b1ec77e12967b0545ed2f886cff37ed406ebae5b4ea1b5d2029973a49cd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 05:29:51.749813) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:29:51.750123) ========= 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.GrratGgryN/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.2cdsy1pN7b/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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.005250 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:29:51.799146) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:29:51.799703) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:29:51.800297) ========= ========= Total master_lag: 0.20 =========