builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0281 starttime: 1463056452.42 results: success (0) buildid: 20160512030854 builduid: 15569e7007164e49bb20a959a2aae493 revision: 092d7ffc5eeed5c0096117fff18638bbda0ebf6e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:12.426630) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:12.427034) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:12.427303) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:12.474884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:12.475146) ========= 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.NrCsm7KkWm/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.YoseALi28j/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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:34:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 140M=0s 2016-05-12 05:34:13 (140 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.635242 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:13.136323) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:13.136606) ========= 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.NrCsm7KkWm/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.YoseALi28j/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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.019421 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:13.189129) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 05:34:13.189441) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 092d7ffc5eeed5c0096117fff18638bbda0ebf6e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 092d7ffc5eeed5c0096117fff18638bbda0ebf6e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NrCsm7KkWm/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.YoseALi28j/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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:34:13,204 truncating revision to first 12 chars 2016-05-12 05:34:13,204 Setting DEBUG logging. 2016-05-12 05:34:13,204 attempt 1/10 2016-05-12 05:34:13,204 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/092d7ffc5eee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 05:34:14,027 unpacking tar archive at: fx-team-092d7ffc5eee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.081939 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 05:34:14.288837) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:14.289124) ========= script_repo_revision: 092d7ffc5eeed5c0096117fff18638bbda0ebf6e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:14.289490) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:14.289771) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 05:34:14.307992) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-05-12 05:34:14.308268) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NrCsm7KkWm/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.YoseALi28j/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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:34:14 INFO - MultiFileLogger online at 20160512 05:34:14 in /builds/slave/test 05:34:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:34:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:34:14 INFO - {'append_to_log': False, 05:34:14 INFO - 'base_work_dir': '/builds/slave/test', 05:34:14 INFO - 'blob_upload_branch': 'Fx-Team', 05:34:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:34:14 INFO - 'branch': 'Fx-Team', 05:34:14 INFO - 'buildbot_json_path': 'buildprops.json', 05:34:14 INFO - 'config_files': ('talos/mac_config.py',), 05:34:14 INFO - 'default_actions': ('clobber', 05:34:14 INFO - 'read-buildbot-config', 05:34:14 INFO - 'download-and-extract', 05:34:14 INFO - 'populate-webroot', 05:34:14 INFO - 'create-virtualenv', 05:34:14 INFO - 'install', 05:34:14 INFO - 'run-tests'), 05:34:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:34:14 INFO - 'download_minidump_stackwalk': True, 05:34:14 INFO - 'download_symbols': 'ondemand', 05:34:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:34:14 INFO - 'tooltool.py': '/tools/tooltool.py', 05:34:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:34:14 INFO - '/tools/misc-python/virtualenv.py')}, 05:34:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:34:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:34:14 INFO - 'installer_path': 'installer.exe', 05:34:14 INFO - 'log_level': 'info', 05:34:14 INFO - 'log_name': 'talos', 05:34:14 INFO - 'log_to_console': True, 05:34:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:34:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:34:14 INFO - 'opt_config_files': (), 05:34:14 INFO - 'pip_index': False, 05:34:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:34:14 INFO - 'cmd': ('bash', 05:34:14 INFO - '-c', 05:34:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:34:14 INFO - 'enabled': True, 05:34:14 INFO - 'halt_on_failure': False, 05:34:14 INFO - 'name': 'check_screen_resolution'},), 05:34:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:34:14 INFO - 'cmd': ('bash', 05:34:14 INFO - '-c', 05:34:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:34:14 INFO - 'enabled': True, 05:34:14 INFO - 'halt_on_failure': False, 05:34:14 INFO - 'name': 'check_screen_resolution'},), 05:34:14 INFO - 'run_cmd_checks_enabled': True, 05:34:14 INFO - 'sps_profile': False, 05:34:14 INFO - 'sps_profile_interval': 0, 05:34:14 INFO - 'suite': 'dromaeojs-e10s', 05:34:14 INFO - 'system_bits': '32', 05:34:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:34:14 INFO - 'title': 't-yosemite-r7-0281', 05:34:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:34:14 INFO - 'use_talos_json': True, 05:34:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:34:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:34:14 INFO - 'work_dir': 'build'} 05:34:14 INFO - ##### 05:34:14 INFO - ##### Running clobber step. 05:34:14 INFO - ##### 05:34:14 INFO - Running pre-action listener: _resource_record_pre_action 05:34:14 INFO - Running main action method: clobber 05:34:14 INFO - rmtree: /builds/slave/test/build 05:34:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:34:16 INFO - Running post-action listener: _resource_record_post_action 05:34:16 INFO - ##### 05:34:16 INFO - ##### Finished clobber step (success) 05:34:16 INFO - ##### 05:34:16 INFO - ##### 05:34:16 INFO - ##### Running read-buildbot-config step. 05:34:16 INFO - ##### 05:34:16 INFO - Running pre-action listener: _resource_record_pre_action 05:34:16 INFO - Running main action method: read_buildbot_config 05:34:16 INFO - Using buildbot properties: 05:34:16 INFO - { 05:34:16 INFO - "project": "", 05:34:16 INFO - "product": "firefox", 05:34:16 INFO - "script_repo_revision": "production", 05:34:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:34:16 INFO - "repository": "", 05:34:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:34:16 INFO - "buildid": "20160512030854", 05:34:16 INFO - "pgo_build": "False", 05:34:16 INFO - "basedir": "/builds/slave/test", 05:34:16 INFO - "buildnumber": 673, 05:34:16 INFO - "slavename": "t-yosemite-r7-0281", 05:34:16 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:34:16 INFO - "platform": "yosemite_r7", 05:34:16 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:34:16 INFO - "branch": "fx-team", 05:34:16 INFO - "repo_path": "integration/fx-team", 05:34:16 INFO - "revision": "092d7ffc5eeed5c0096117fff18638bbda0ebf6e", 05:34:16 INFO - "stage_platform": "macosx64", 05:34:16 INFO - "builduid": "15569e7007164e49bb20a959a2aae493", 05:34:16 INFO - "slavebuilddir": "test" 05:34:16 INFO - } 05:34:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:34:16 INFO - Running post-action listener: _resource_record_post_action 05:34:16 INFO - ##### 05:34:16 INFO - ##### Finished read-buildbot-config step (success) 05:34:16 INFO - ##### 05:34:16 INFO - ##### 05:34:16 INFO - ##### Running download-and-extract step. 05:34:16 INFO - ##### 05:34:16 INFO - Running pre-action listener: _resource_record_pre_action 05:34:16 INFO - Running main action method: download_and_extract 05:34:16 INFO - mkdir: /builds/slave/test/build/tests 05:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:16 INFO - https://queue.taskcluster.net/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:34:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:34:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:34:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/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:34:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/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:34:29 INFO - Downloaded 1199 bytes. 05:34:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:34:29 INFO - Using the following test package requirements: 05:34:29 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:34:29 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:29 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:34:29 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:29 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:34:29 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:29 INFO - u'jsshell-mac.zip'], 05:34:29 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:29 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:34:29 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:34:29 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:29 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:34:29 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:29 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:34:29 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:29 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:34:29 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:29 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:34:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:34: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:34:29 INFO - https://queue.taskcluster.net/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:34:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:34:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:34:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/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:34:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/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:34:35 INFO - Downloaded 18151251 bytes. 05:34:35 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:34:35 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:34:38 INFO - Return code: 0 05:34:38 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:34:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:38 INFO - https://queue.taskcluster.net/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:34:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:34:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:34:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/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:34:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/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:34:44 INFO - Downloaded 18151251 bytes. 05:34:44 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:34:44 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:34:48 INFO - Return code: 0 05:34: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:34:48 INFO - https://queue.taskcluster.net/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:34:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:34:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:34:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/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:34:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/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:34:49 INFO - Downloaded 10840239 bytes. 05:34:49 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:34:49 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:34:49 INFO - Return code: 0 05:34:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:49 INFO - https://queue.taskcluster.net/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:34:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:34:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:34:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:34:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/awJQdXjGRt2R3XLvsayZbA/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:34:59 INFO - Downloaded 109824156 bytes. 05:34:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:34:59 INFO - mkdir: /builds/slave/test/properties 05:34:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:34:59 INFO - Writing to file /builds/slave/test/properties/build_url 05:34:59 INFO - Contents: 05:34:59 INFO - build_url:https://queue.taskcluster.net/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:35:00 INFO - Running post-action listener: _resource_record_post_action 05:35:00 INFO - Running post-action listener: set_extra_try_arguments 05:35:00 INFO - ##### 05:35:00 INFO - ##### Finished download-and-extract step (success) 05:35:00 INFO - ##### 05:35:00 INFO - ##### 05:35:00 INFO - ##### Running populate-webroot step. 05:35:00 INFO - ##### 05:35:00 INFO - Running pre-action listener: _resource_record_pre_action 05:35:00 INFO - Running main action method: populate_webroot 05:35:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:35:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:35:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:35:00 INFO - '--tppagecycles', 05:35:00 INFO - '7'], 05:35:00 INFO - 'tests': ['tsvgm']}}, 05:35:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:35:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:35:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:35:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:35:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:35:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:35:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:00 INFO - 'talos_options': ['--disable-e10s'], 05:35:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:35:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:35:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:00 INFO - 'talos_options': ['--disable-e10s'], 05:35:00 INFO - 'tests': ['damp', 'tps']}, 05:35:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:00 INFO - 'tests': ['damp', 'tps']}, 05:35:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:35:00 INFO - 'tests': ['dromaeo_dom']}, 05:35:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:35:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:35:00 INFO - 'tests': ['a11yr', 05:35:00 INFO - 'ts_paint', 05:35:00 INFO - 'tpaint', 05:35:00 INFO - 'sessionrestore', 05:35:00 INFO - 'sessionrestore_no_auto_restore', 05:35:00 INFO - 'tabpaint']}, 05:35:00 INFO - 'other-e10s': {'tests': ['a11yr', 05:35:00 INFO - 'ts_paint', 05:35:00 INFO - 'tpaint', 05:35:00 INFO - 'sessionrestore', 05:35:00 INFO - 'sessionrestore_no_auto_restore', 05:35:00 INFO - 'tabpaint']}, 05:35:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:35:00 INFO - 'ts_paint', 05:35:00 INFO - 'tpaint', 05:35:00 INFO - 'sessionrestore', 05:35:00 INFO - 'sessionrestore_no_auto_restore', 05:35:00 INFO - 'tabpaint']}, 05:35:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:35:00 INFO - 'ts_paint', 05:35:00 INFO - 'tpaint', 05:35:00 INFO - 'sessionrestore', 05:35:00 INFO - 'sessionrestore_no_auto_restore', 05:35:00 INFO - 'tabpaint']}, 05:35:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:35:00 INFO - 'tests': ['a11yr', 05:35:00 INFO - 'ts_paint', 05:35:00 INFO - 'tpaint', 05:35:00 INFO - 'sessionrestore', 05:35:00 INFO - 'sessionrestore_no_auto_restore', 05:35:00 INFO - 'tabpaint']}, 05:35:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:35:00 INFO - 'tests': ['a11yr', 05:35:00 INFO - 'ts_paint', 05:35:00 INFO - 'tpaint', 05:35:00 INFO - 'sessionrestore', 05:35:00 INFO - 'sessionrestore_no_auto_restore', 05:35:00 INFO - 'tabpaint']}, 05:35:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:35:00 INFO - 'tests': ['tsvgx', 05:35:00 INFO - 'tsvgr_opacity', 05:35:00 INFO - 'tart', 05:35:00 INFO - 'tscrollx', 05:35:00 INFO - 'cart']}, 05:35:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:35:00 INFO - 'tsvgr_opacity', 05:35:00 INFO - 'tart', 05:35:00 INFO - 'tscrollx', 05:35:00 INFO - 'cart']}, 05:35:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:00 INFO - 'talos_options': ['--disable-e10s'], 05:35:00 INFO - 'tests': ['tp5o']}, 05:35:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:00 INFO - 'tests': ['tp5o']}, 05:35:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:00 INFO - 'talos_options': ['--disable-e10s', 05:35:00 INFO - '--xperf_path', 05:35:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:35:00 INFO - 'tests': ['tp5n']}, 05:35:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:00 INFO - 'talos_options': ['--xperf_path', 05:35:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:35:00 INFO - 'tests': ['tp5n']}}, 05:35:00 INFO - 'talos.zip': {'path': '', 05:35:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:35:00 INFO - Running post-action listener: _resource_record_post_action 05:35:00 INFO - ##### 05:35:00 INFO - ##### Finished populate-webroot step (success) 05:35:00 INFO - ##### 05:35:00 INFO - ##### 05:35:00 INFO - ##### Running create-virtualenv step. 05:35:00 INFO - ##### 05:35:00 INFO - Running pre-action listener: _resource_record_pre_action 05:35:00 INFO - Running main action method: create_virtualenv 05:35:00 INFO - Creating virtualenv /builds/slave/test/build/venv 05:35:00 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:35:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:35:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:35:00 INFO - Using real prefix '/tools/python27' 05:35:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:35:01 INFO - Installing distribute.............................................................................................................................................................................................done. 05:35:04 INFO - Installing pip.................done. 05:35:04 INFO - Return code: 0 05:35:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:35:04 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:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:04 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:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:04 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 0x105c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6014993e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b18d30>, '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 0x10593cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0fb20>, '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.NrCsm7KkWm/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.YoseALi28j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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:35:04 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:35:04 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:35:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NrCsm7KkWm/Render', 05:35:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:04 INFO - 'HOME': '/Users/cltbld', 05:35:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:04 INFO - 'LOGNAME': 'cltbld', 05:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:04 INFO - 'MOZ_NO_REMOTE': '1', 05:35:04 INFO - 'NO_EM_RESTART': '1', 05:35:04 INFO - 'PAGER': '/bin/cat', 05:35:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:04 INFO - 'PWD': '/builds/slave/test', 05:35:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:04 INFO - 'SHELL': '/bin/bash', 05:35:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoseALi28j/Listeners', 05:35:04 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 05:35:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:04 INFO - 'USER': 'cltbld', 05:35:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:04 INFO - 'XPC_FLAGS': '0x0', 05:35:04 INFO - 'XPC_SERVICE_NAME': '0', 05:35:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:04 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:35:04 INFO - Cleaning up... 05:35:04 INFO - Return code: 0 05:35:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:35:04 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:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:04 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:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:04 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 0x105c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6014993e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b18d30>, '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 0x10593cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0fb20>, '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.NrCsm7KkWm/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.YoseALi28j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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:35:04 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:35:04 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:35:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NrCsm7KkWm/Render', 05:35:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:04 INFO - 'HOME': '/Users/cltbld', 05:35:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:04 INFO - 'LOGNAME': 'cltbld', 05:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:04 INFO - 'MOZ_NO_REMOTE': '1', 05:35:04 INFO - 'NO_EM_RESTART': '1', 05:35:04 INFO - 'PAGER': '/bin/cat', 05:35:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:04 INFO - 'PWD': '/builds/slave/test', 05:35:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:04 INFO - 'SHELL': '/bin/bash', 05:35:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoseALi28j/Listeners', 05:35:04 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 05:35:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:04 INFO - 'USER': 'cltbld', 05:35:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:04 INFO - 'XPC_FLAGS': '0x0', 05:35:04 INFO - 'XPC_SERVICE_NAME': '0', 05:35:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:04 INFO - Downloading/unpacking psutil>=0.7.1 05:35:04 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:35:04 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:35:04 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:35:04 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:35:04 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:35:04 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:35:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:35:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:35:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:35:07 INFO - Installing collected packages: psutil 05:35:07 INFO - Running setup.py install for psutil 05:35:07 INFO - building 'psutil._psutil_osx' extension 05:35:07 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:35:08 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:35:08 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:35:08 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:35:08 INFO - building 'psutil._psutil_posix' extension 05:35:08 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:35:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:35:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:35:08 INFO - ^ 05:35:08 INFO - 1 warning generated. 05:35:08 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:35:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:35:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:35:08 INFO - Successfully installed psutil 05:35:08 INFO - Cleaning up... 05:35:08 INFO - Return code: 0 05:35:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:35:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:08 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 0x105c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6014993e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b18d30>, '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 0x10593cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0fb20>, '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.NrCsm7KkWm/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.YoseALi28j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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:35:08 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:35:08 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:35:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NrCsm7KkWm/Render', 05:35:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:08 INFO - 'HOME': '/Users/cltbld', 05:35:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:08 INFO - 'LOGNAME': 'cltbld', 05:35:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:08 INFO - 'MOZ_NO_REMOTE': '1', 05:35:08 INFO - 'NO_EM_RESTART': '1', 05:35:08 INFO - 'PAGER': '/bin/cat', 05:35:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:08 INFO - 'PWD': '/builds/slave/test', 05:35:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:08 INFO - 'SHELL': '/bin/bash', 05:35:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoseALi28j/Listeners', 05:35:08 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 05:35:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:08 INFO - 'USER': 'cltbld', 05:35:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:08 INFO - 'XPC_FLAGS': '0x0', 05:35:08 INFO - 'XPC_SERVICE_NAME': '0', 05:35:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:35:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:35:11 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:35:11 INFO - Installing collected packages: mozsystemmonitor 05:35:11 INFO - Running setup.py install for mozsystemmonitor 05:35:11 INFO - Successfully installed mozsystemmonitor 05:35:11 INFO - Cleaning up... 05:35:11 INFO - Return code: 0 05:35:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:35: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:35:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35: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:35:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35: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', '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 0x105c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6014993e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b18d30>, '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 0x10593cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0fb20>, '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.NrCsm7KkWm/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.YoseALi28j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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:35: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:35: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 blobuploader==1.2.4 05:35:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NrCsm7KkWm/Render', 05:35:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:11 INFO - 'HOME': '/Users/cltbld', 05:35:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:11 INFO - 'LOGNAME': 'cltbld', 05:35:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:11 INFO - 'MOZ_NO_REMOTE': '1', 05:35:11 INFO - 'NO_EM_RESTART': '1', 05:35:11 INFO - 'PAGER': '/bin/cat', 05:35:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:11 INFO - 'PWD': '/builds/slave/test', 05:35:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:11 INFO - 'SHELL': '/bin/bash', 05:35:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoseALi28j/Listeners', 05:35:11 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 05:35:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:11 INFO - 'USER': 'cltbld', 05:35:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:11 INFO - 'XPC_FLAGS': '0x0', 05:35:11 INFO - 'XPC_SERVICE_NAME': '0', 05:35:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:11 INFO - Downloading/unpacking blobuploader==1.2.4 05:35:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:14 INFO - Downloading blobuploader-1.2.4.tar.gz 05:35:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:35:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:35: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:35: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:35: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:35: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:35: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:35: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:35:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:35:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:35: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:35: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:35: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:35: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:35: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:35: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:35:15 INFO - Downloading docopt-0.6.1.tar.gz 05:35:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:35:15 INFO - Installing collected packages: blobuploader, requests, docopt 05:35:15 INFO - Running setup.py install for blobuploader 05:35:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:35:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:35:15 INFO - Running setup.py install for requests 05:35:16 INFO - Running setup.py install for docopt 05:35:16 INFO - Successfully installed blobuploader requests docopt 05:35:16 INFO - Cleaning up... 05:35:16 INFO - Return code: 0 05:35:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:16 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 0x105c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6014993e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b18d30>, '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 0x10593cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0fb20>, '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.NrCsm7KkWm/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.YoseALi28j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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:35:16 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:35:16 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:35:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NrCsm7KkWm/Render', 05:35:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:16 INFO - 'HOME': '/Users/cltbld', 05:35:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:16 INFO - 'LOGNAME': 'cltbld', 05:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:16 INFO - 'MOZ_NO_REMOTE': '1', 05:35:16 INFO - 'NO_EM_RESTART': '1', 05:35:16 INFO - 'PAGER': '/bin/cat', 05:35:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:16 INFO - 'PWD': '/builds/slave/test', 05:35:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:16 INFO - 'SHELL': '/bin/bash', 05:35:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoseALi28j/Listeners', 05:35:16 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 05:35:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:16 INFO - 'USER': 'cltbld', 05:35:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:16 INFO - 'XPC_FLAGS': '0x0', 05:35:16 INFO - 'XPC_SERVICE_NAME': '0', 05:35:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:35:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Hx0md9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:35:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-gwMWtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:35:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-cM2Z_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:35:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-7MEn_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:35:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-qWniMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:35:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-2hCXwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:35:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-yWRyF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:35:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-EulI0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:35:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-CyZh6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:35:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-55Dgr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:35:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-NzUTtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:35:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Htmngv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:35:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-TKaO1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:35:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-oMtSiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-JY5tCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:35:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-4ASclj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:35:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-oZhAxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:35:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:35:18 INFO - Running setup.py install for manifestparser 05:35:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:35:18 INFO - Running setup.py install for mozcrash 05:35:19 INFO - Running setup.py install for mozdebug 05:35:19 INFO - Running setup.py install for mozdevice 05:35:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:35:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:35:19 INFO - Running setup.py install for mozfile 05:35:19 INFO - Running setup.py install for mozhttpd 05:35:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:35:19 INFO - Running setup.py install for mozinfo 05:35:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:35:19 INFO - Running setup.py install for mozInstall 05:35:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:35:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:35:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:35:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:35:19 INFO - Running setup.py install for mozleak 05:35:20 INFO - Running setup.py install for mozlog 05:35:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:35:20 INFO - Running setup.py install for moznetwork 05:35:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:35:20 INFO - Running setup.py install for mozprocess 05:35:20 INFO - Running setup.py install for mozprofile 05:35:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:35:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:35:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:35:20 INFO - Running setup.py install for mozrunner 05:35:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:35:20 INFO - Running setup.py install for mozscreenshot 05:35:21 INFO - Running setup.py install for moztest 05:35:21 INFO - Running setup.py install for mozversion 05:35:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:35:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:35:21 INFO - Cleaning up... 05:35:21 INFO - Return code: 0 05:35:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:21 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 0x105c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6014993e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b18d30>, '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 0x10593cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0fb20>, '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.NrCsm7KkWm/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.YoseALi28j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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:35:21 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:35:21 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:35:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NrCsm7KkWm/Render', 05:35:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:21 INFO - 'HOME': '/Users/cltbld', 05:35:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:21 INFO - 'LOGNAME': 'cltbld', 05:35:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:21 INFO - 'MOZ_NO_REMOTE': '1', 05:35:21 INFO - 'NO_EM_RESTART': '1', 05:35:21 INFO - 'PAGER': '/bin/cat', 05:35:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:21 INFO - 'PWD': '/builds/slave/test', 05:35:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:21 INFO - 'SHELL': '/bin/bash', 05:35:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoseALi28j/Listeners', 05:35:21 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 05:35:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:21 INFO - 'USER': 'cltbld', 05:35:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:21 INFO - 'XPC_FLAGS': '0x0', 05:35:21 INFO - 'XPC_SERVICE_NAME': '0', 05:35:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:35:21 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-D1VHkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:35:21 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:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:35:21 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Rh3Vql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:35:21 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:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:35:21 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-xS_u7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:35:21 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:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:35:21 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-oQY0_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:35:22 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:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:35:22 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-xgUHjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:35:22 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:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:35:22 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Wb2IUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:35:22 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:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:35:22 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-qWRFYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:35:22 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:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:35:22 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-BGgSwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:35:22 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:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:35:22 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-PVjutj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:35:22 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:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:35:22 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-RfvCmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:35:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:35:22 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-ZuKDWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:35:22 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:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:35:22 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-utTaWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:35:22 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:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:35:22 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-z1a2gh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:35:23 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:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:35:23 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-mp3L2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:35:23 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:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:23 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-PjuRwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:23 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:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:35:23 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-cmKcKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:35:23 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:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:35:23 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Ge9LKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:35:23 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:35:23 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:35:23 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:35:23 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:35:23 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:35:23 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:35:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:35:23 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:35:23 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:35:23 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:35:23 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:35:23 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:35:23 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:35:25 INFO - Downloading blessings-1.6.tar.gz 05:35:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:35:25 INFO - Installing collected packages: blessings 05:35:25 INFO - Running setup.py install for blessings 05:35:26 INFO - Successfully installed blessings 05:35:26 INFO - Cleaning up... 05:35:26 INFO - Return code: 0 05:35:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:35:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:35:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:35:26 INFO - Reading from file tmpfile_stdout 05:35:26 INFO - Current package versions: 05:35:26 INFO - blessings == 1.6 05:35:26 INFO - blobuploader == 1.2.4 05:35:26 INFO - docopt == 0.6.1 05:35:26 INFO - manifestparser == 1.1 05:35:26 INFO - mozInstall == 1.12 05:35:26 INFO - mozcrash == 0.17 05:35:26 INFO - mozdebug == 0.1 05:35:26 INFO - mozdevice == 0.48 05:35:26 INFO - mozfile == 1.2 05:35:26 INFO - mozhttpd == 0.7 05:35:26 INFO - mozinfo == 0.9 05:35:26 INFO - mozleak == 0.1 05:35:26 INFO - mozlog == 3.2 05:35:26 INFO - moznetwork == 0.27 05:35:26 INFO - mozprocess == 0.22 05:35:26 INFO - mozprofile == 0.28 05:35:26 INFO - mozrunner == 6.11 05:35:26 INFO - mozscreenshot == 0.1 05:35:26 INFO - mozsystemmonitor == 0.0 05:35:26 INFO - moztest == 0.7 05:35:26 INFO - mozversion == 1.4 05:35:26 INFO - psutil == 3.1.1 05:35:26 INFO - requests == 1.2.3 05:35:26 INFO - wsgiref == 0.1.2 05:35:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35:26 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:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:26 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:35:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:26 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 0x105c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6014993e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b18d30>, '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 0x10593cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0fb20>, '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.NrCsm7KkWm/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.YoseALi28j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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:35:26 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:35:26 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:35:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NrCsm7KkWm/Render', 05:35:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:26 INFO - 'HOME': '/Users/cltbld', 05:35:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:26 INFO - 'LOGNAME': 'cltbld', 05:35:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:26 INFO - 'MOZ_NO_REMOTE': '1', 05:35:26 INFO - 'NO_EM_RESTART': '1', 05:35:26 INFO - 'PAGER': '/bin/cat', 05:35:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:26 INFO - 'PWD': '/builds/slave/test', 05:35:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:26 INFO - 'SHELL': '/bin/bash', 05:35:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoseALi28j/Listeners', 05:35:26 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 05:35:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:26 INFO - 'USER': 'cltbld', 05:35:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:26 INFO - 'XPC_FLAGS': '0x0', 05:35:26 INFO - 'XPC_SERVICE_NAME': '0', 05:35:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:26 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:35:26 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:35:26 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:35:26 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:35:26 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:35:26 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:35:26 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:35:26 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:35:26 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:35:26 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:35:26 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:35:26 INFO - Cleaning up... 05:35:26 INFO - Return code: 0 05:35:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:35:26 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:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:26 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:35:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:26 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 0x105c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6014993e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b18d30>, '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 0x10593cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0fb20>, '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.NrCsm7KkWm/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.YoseALi28j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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:35:26 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:35:26 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:35:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NrCsm7KkWm/Render', 05:35:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:26 INFO - 'HOME': '/Users/cltbld', 05:35:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:26 INFO - 'LOGNAME': 'cltbld', 05:35:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:26 INFO - 'MOZ_NO_REMOTE': '1', 05:35:26 INFO - 'NO_EM_RESTART': '1', 05:35:26 INFO - 'PAGER': '/bin/cat', 05:35:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:26 INFO - 'PWD': '/builds/slave/test', 05:35:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:26 INFO - 'SHELL': '/bin/bash', 05:35:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoseALi28j/Listeners', 05:35:26 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 05:35:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:26 INFO - 'USER': 'cltbld', 05:35:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:26 INFO - 'XPC_FLAGS': '0x0', 05:35:26 INFO - 'XPC_SERVICE_NAME': '0', 05:35:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:27 INFO - Downloading/unpacking jsonschema 05:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:35:29 INFO - Downloading/unpacking functools32 (from jsonschema) 05:35: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:35: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:35: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:35: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:35: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:35: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:35:29 INFO - Downloading functools32-3.2.3-2.tar.gz 05:35:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:35:29 INFO - warning: no files found matching '*.txt' 05:35:29 INFO - no previously-included directories found matching 'build' 05:35:29 INFO - no previously-included directories found matching 'dist' 05:35:29 INFO - no previously-included directories found matching '.git*' 05:35:29 INFO - Installing collected packages: jsonschema, functools32 05:35:29 INFO - Running setup.py install for functools32 05:35:30 INFO - warning: no files found matching '*.txt' 05:35:30 INFO - no previously-included directories found matching 'build' 05:35:30 INFO - no previously-included directories found matching 'dist' 05:35:30 INFO - no previously-included directories found matching '.git*' 05:35:30 INFO - Successfully installed jsonschema functools32 05:35:30 INFO - Cleaning up... 05:35:30 INFO - Return code: 0 05:35:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:35:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6014993e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b18d30>, '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 0x10593cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0fb20>, '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.NrCsm7KkWm/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.YoseALi28j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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:35:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:35:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:35:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NrCsm7KkWm/Render', 05:35:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:30 INFO - 'HOME': '/Users/cltbld', 05:35:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:30 INFO - 'LOGNAME': 'cltbld', 05:35:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:30 INFO - 'MOZ_NO_REMOTE': '1', 05:35:30 INFO - 'NO_EM_RESTART': '1', 05:35:30 INFO - 'PAGER': '/bin/cat', 05:35:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:30 INFO - 'PWD': '/builds/slave/test', 05:35:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:30 INFO - 'SHELL': '/bin/bash', 05:35:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoseALi28j/Listeners', 05:35:30 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 05:35:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:30 INFO - 'USER': 'cltbld', 05:35:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:30 INFO - 'XPC_FLAGS': '0x0', 05:35:30 INFO - 'XPC_SERVICE_NAME': '0', 05:35:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:30 INFO - Downloading/unpacking flake8 05:35:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:35:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:35: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:35: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:35: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:35: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:35: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:35: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:35:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:35:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:35: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:35: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:35: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:35: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:35: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:35: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:35:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:35: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:35: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:35: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:35: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:35: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:35: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:35:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:35:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:35:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:35:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:35:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:35:34 INFO - no previously-included directories found matching 'docs/_build' 05:35:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:35:34 INFO - Running setup.py install for pep8 05:35:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:35:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:35:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:35:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:35:34 INFO - no previously-included directories found matching 'docs/_build' 05:35:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:35:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:35:34 INFO - Cleaning up... 05:35:34 INFO - Return code: 0 05:35:34 INFO - Running post-action listener: _resource_record_post_action 05:35:34 INFO - Running post-action listener: _start_resource_monitoring 05:35:34 INFO - Starting resource monitoring. 05:35:34 INFO - ##### 05:35:34 INFO - ##### Finished create-virtualenv step (success) 05:35:34 INFO - ##### 05:35:34 INFO - ##### 05:35:34 INFO - ##### Running install step. 05:35:34 INFO - ##### 05:35:34 INFO - Running pre-action listener: _resource_record_pre_action 05:35:34 INFO - Running main action method: install 05:35:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:35:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:35:34 INFO - Reading from file tmpfile_stdout 05:35:34 INFO - Detecting whether we're running mozinstall >=1.0... 05:35:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:35:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:35:34 INFO - Reading from file tmpfile_stdout 05:35:34 INFO - Output received: 05:35:34 INFO - Usage: mozinstall [options] installer 05:35:34 INFO - Options: 05:35:34 INFO - -h, --help show this help message and exit 05:35:34 INFO - -d DEST, --destination=DEST 05:35:34 INFO - Directory to install application into. [default: 05:35:34 INFO - "/builds/slave/test"] 05:35:34 INFO - --app=APP Application being installed. [default: firefox] 05:35:34 INFO - mkdir: /builds/slave/test/build/application 05:35:34 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:35:34 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:36:08 INFO - Reading from file tmpfile_stdout 05:36:08 INFO - Output received: 05:36:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:36:08 INFO - Running post-action listener: _resource_record_post_action 05:36:08 INFO - ##### 05:36:08 INFO - ##### Finished install step (success) 05:36:08 INFO - ##### 05:36:08 INFO - ##### 05:36:08 INFO - ##### Running run-tests step. 05:36:08 INFO - ##### 05:36:08 INFO - Running pre-action listener: _resource_record_pre_action 05:36:08 INFO - Running main action method: run_tests 05:36:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:36:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:36:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:36:08 INFO - 2016-05-12 05:36:08.984 screenresolution[1922:18258] starting screenresolution argv=screenresolution get 05:36:08 INFO - 2016-05-12 05:36:08.986 screenresolution[1922:18258] Display 0: 1024x768x32@60 05:36:08 INFO - 2016-05-12 05:36:08.992 screenresolution[1923:18260] starting screenresolution argv=screenresolution list 05:36:08 INFO - Available Modes on Display 0 05:36:08 INFO - 1024x768x32@60 800x600x32@75 800x600x32@60 05:36:09 INFO - 640x480x32@75 640x480x32@60 Graphics/Displays: 05:36:09 INFO - Intel Iris: 05:36:09 INFO - Chipset Model: Intel Iris 05:36:09 INFO - Type: GPU 05:36:09 INFO - Bus: Built-In 05:36:09 INFO - VRAM (Dynamic, Max): 1536 MB 05:36:09 INFO - Vendor: Intel (0x8086) 05:36:09 INFO - Device ID: 0x0a2e 05:36:09 INFO - Revision ID: 0x0009 05:36:09 INFO - Displays: 05:36:09 INFO - Display: 05:36:09 INFO - Resolution: 1024 x 768 @ 60 Hz 05:36:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:36:09 INFO - Display Serial Number: 1024x768 60 05:36:09 INFO - Main Display: Yes 05:36:09 INFO - Mirror: Off 05:36:09 INFO - Online: Yes 05:36:09 INFO - Rotation: Supported 05:36:09 INFO - Return code: 0 05:36:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:36:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:36:09 INFO - Python 2.7.3 05:36:09 INFO - Return code: 0 05:36:09 INFO - grabbing minidump binary from tooltool 05:36:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:36:09 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 0x105b18d30>, '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 0x10593cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:36:09 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:36:09 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:36:09 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:36:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:36:09 INFO - Return code: 0 05:36:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:36:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:36:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:36:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:36:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:36:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:36:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:36:10 INFO - Return code: 0 05:36:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--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:36:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0281 --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:36:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NrCsm7KkWm/Render', 05:36:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:10 INFO - 'HOME': '/Users/cltbld', 05:36:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:10 INFO - 'LOGNAME': 'cltbld', 05:36:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:36:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:36:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:10 INFO - 'MOZ_NO_REMOTE': '1', 05:36:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:36:10 INFO - 'NO_EM_RESTART': '1', 05:36:10 INFO - 'PAGER': '/bin/cat', 05:36:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:10 INFO - 'PWD': '/builds/slave/test', 05:36:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:36:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:10 INFO - 'SHELL': '/bin/bash', 05:36:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoseALi28j/Listeners', 05:36:10 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 05:36:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:10 INFO - 'USER': 'cltbld', 05:36:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:10 INFO - 'XPC_FLAGS': '0x0', 05:36:10 INFO - 'XPC_SERVICE_NAME': '0', 05:36:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--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:36:10 INFO - mozversion application_buildid: 20160512030854 05:36:10 INFO - mozversion application_changeset: 092d7ffc5eeed5c0096117fff18638bbda0ebf6e 05:36:10 INFO - mozversion application_display_name: Nightly 05:36:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:36:10 INFO - mozversion application_name: Firefox 05:36:10 INFO - mozversion application_remotingname: firefox 05:36:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:36:10 INFO - mozversion application_vendor: Mozilla 05:36:10 INFO - mozversion application_version: 49.0a1 05:36:10 INFO - mozversion platform_buildid: 20160512030854 05:36:10 INFO - mozversion platform_changeset: 092d7ffc5eeed5c0096117fff18638bbda0ebf6e 05:36:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:36:10 INFO - mozversion platform_version: 49.0a1 05:36:10 INFO - using testdate: 1463056570 05:36:10 INFO - actual date: 1463056570 05:36:10 INFO - starting webserver on 'localhost:55239' 05:36:10 INFO - SUITE-START | Running 2 tests 05:36:10 INFO - TEST-START | dromaeo_css 05:36:10 INFO - operating with platform_type : mac_ 05:36:10 INFO - Initialising browser for dromaeo_css test... 05:36:10 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmpJcY5gv/profile http://localhost:55239/getInfo.html) 05:36:14 INFO - PROCESS | 1941 | __metrics Screen width/height:1024/768 05:36:14 INFO - PROCESS | 1941 | colorDepth:24 05:36:14 INFO - PROCESS | 1941 | Browser inner width/height: 1024/605 05:36:14 INFO - PROCESS | 1941 | __metrics 05:36:14 ERROR - PROCESS | 1941 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:36:14 INFO - TEST-INFO | 1941: exit 0 05:36:14 INFO - Browser initialized. 05:36:14 INFO - Running cycle 1/1 for dromaeo_css test... 05:36:14 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmpJcY5gv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:36:26 INFO - PROCESS | 1944 | RSS: Main: 249225216 05:36:26 INFO - PROCESS | 1944 | 05:37:16 INFO - PROCESS | 1944 | 1463056636592 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:39:27 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:55239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55239/tests/dromaeo/cssquery-ext.html) 05:39:28 INFO - PROCESS | 1944 | RSS: Main: 259735552 05:39:28 INFO - PROCESS | 1944 | 05:42:31 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:55239/tests/dromaeo/cssquery-ext.html (next: http://localhost:55239/tests/dromaeo/cssquery-jquery.html) 05:42:31 INFO - PROCESS | 1944 | RSS: Main: 270315520 05:42:31 INFO - PROCESS | 1944 | 05:45:33 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:55239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55239/tests/dromaeo/cssquery-mootools.html) 05:45:34 INFO - PROCESS | 1944 | RSS: Main: 272429056 05:45:34 INFO - PROCESS | 1944 | 05:48:36 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:55239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55239/tests/dromaeo/cssquery-prototype.html) 05:48:36 INFO - PROCESS | 1944 | RSS: Main: 274268160 05:48:36 INFO - PROCESS | 1944 | 05:51:39 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:55239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55239/tests/dromaeo/cssquery-yui.html) 05:51:40 INFO - PROCESS | 1944 | RSS: Main: 277155840 05:51:40 INFO - PROCESS | 1944 | 05:54:42 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:55239/tests/dromaeo/cssquery-yui.html (next: http://localhost:55239/tests/dromaeo/cssquery-dojo.html) 05:54:43 INFO - PROCESS | 1944 | RSS: Main: 278016000 05:54:43 INFO - PROCESS | 1944 | 05:54:43 INFO - PROCESS | 1944 | __start_tp_report 05:54:43 INFO - PROCESS | 1944 | _x_x_mozilla_page_load 05:54:43 INFO - PROCESS | 1944 | _x_x_mozilla_page_load_details 05:54:43 INFO - PROCESS | 1944 | |i|pagename|runs| 05:54:43 INFO - PROCESS | 1944 | |0;dojo.html;2919.927001824954;3385.6445073267323;3404.2170300830817;3422.1615704152487;3444.2422667013266;4357.782110894451;5564.888702225953;5660.830175094743;5724.513416359609;5808.883822323546;5556.16657501375;5635.549156067515;5638.210650508932;5690.004249256374;5761.827145185641;5077.3907131144315;5108.1060814357525;5149.510796474329;5188.50709182628;5211.921821172686;5080.1617733074;5216.165413533835;5220.634555581092;5267.841964741045;5292.947070529303;5113.335266415343;5133.640645154841;5137.7944882204665;5171.896562068757;5182.870428239286;3983.2830090583684;4194.937075943863;4699.765011749399;5051.06555287273;5203.089459344637;5145.536901678829;5149.253358263069;5152.381714194284;5183.844484665466;5217.321748172713;5275.155975043975;5521.116621340566;5589.357223919261;5590;5620.156976453564;6125.142480052797;6184.536159788034;6197.597156184834;6201.16284301618;6228.252609686821;6202.227560602768;6221;6231.532635052389;6241.251049874044;6269.623822570615;5626.184203290541;5725.456081672234;5756.0214763489885;5758.078707406795;5794.710264486843;6259.247857476208;6276.905846412307;6280.528960327993;6290.905636415366;6300.527460440485;5324.627276090714;5841.007028805113;5992.191054207626;6070.635761854214;6091.269047714302;6096.798833414304;6121.605402471149;6174.66039367839;6220.720067596969;6223.595466294676;5987.550933679991;6034.094885767082;6114.601521188728;6124.319907527303;6144;6035.275766907911;6120.883718150473;6178.135061091451;6178.814635560941;6190.102435146969;4812.759362031955;4828.903421931542;4836.322914791932;4840.201366774443;4896.902061958741;4323.156984388077;4338.609525142752;4353.0423306872435;4361.323994780814;4368.36658684489;5577.6653400796085;5710.771569137271;5715.885682286331;5731.226284451629;5758.481736643722;6190.442860142609;6227.1282020517165;6262.060690896402;6271.153394291803;6301.464375528131;5744.770209191585;5756.107803290497;5757.596968212269;5764.279465066867;5782.826515204551;5208.583313334924;5286.101362768339;5289.47105289468;5379.919301210485;5387.757550910218;6310.684465776692;6322.652254126067;6325.873482530324;6331.493480521547;6345.8730825384155;4884.8290309838985;5405.567554595545;6216.285127210248;6249.468795152281;6265.4361107500545;6196.566240363131;6234.345393733593;6264.718087685974;6302.810915672537;6306.180196574416;6071.574989750675;6103.481204097701;6106.3588323227195;6108.827782940854;6137.754489820357;6105.730467933617;6154.415330543591;6199.659018754011;6205.472534834409;6218.315985241529;5789.594728368974;5848.298204215522;5907.734151963086;6045.183900173596;6074.939250607437;5926.585139040226;5930.28836539618;5946.108083787466;5995.250593675791;6007.669578173242;5810.709464526842;5831.475167234969;5842.1820945066875;5868.031774757117;5875.529957603467;5486.835394938158;5884.323302820059;5965.6718880461995;5970.76116955317;5985.581009329305;5854.297484301911;5857.912131318119;5883.235179426647;5884.852878678067;5887.264091988502 05:54:43 INFO - PROCESS | 1944 | |1;ext.html;506.69851438394164;516.9069567477852;530.1570502900389;539.7759929629202;566.365670449097;26558.6016209757;41460.17079658405;42614.57385426145;42756.786216068955;44470.11059778802;42977;43319.566804331946;43324.566754332445;43449.78275108628;43547.56452435475;9539.379940303892;9735.269854760885;9736.026397360243;9741;9748.75628109296;9559.95220023899;9598.280128990318;9608.951955240214;9631.988641192678;9665.516724163763;9659.275554333419;9701.320907536476;9709.271804614647;9709.465979371133;9726.70819875405;9578;9613.279004074688;9644.662436814679;9651.613935442574;9677.419354838661;9480.288978326653;9505.239580833517;9569.856452153153;9601.519924003773;9636.42181469114;32534.6746532534;36081.27837443263;39122.804385978176;39621.20757584861;39716.40425393621;3083.6607973122937;3144.8113113213053;3174.841257937094;3352.7988320700592;3577.678008979204;28735.137945861657;28747.712522874714;29161;29369.559456608167;30259.546106808415;2216.4348091236793;2728.3451971526692;2788.665360156794;2797.132888804457;2850.144956513038;502499;511563;512146;512489;515305.9999999963;34767.82616086904;36066.8196659015;62600.68699656473;63576;63733.68133159305;36761.632383675824;36884.63115368812;36911.63088369136;37101.44347834784;37163.628363716016;15084.472043478425;15105.773413398807;15247.923760381349;15257.923710381378;15267.923660381626;12797.168184068192;12843.743125137446;12880.677983050311;12898.419571119322;12919.418626161845;8145.755627331189;8150.551719655476;8160.102388737234;8209.753707388787;8224.588770561448;3410.522526846194;3429.879954201591;3440.2947395783804;3446.3968805458944;3476.860925562949;869.5087275689216;960.7021823234819;997.7306127345721;1041.2718677066134;1074.2909679611575;24897.875510622693;27900.441991160063;29875.551866722017;29996.85001574978;30029.69970300269;1065.1851333729703;1112.4993752811267;1132.5752842683994;1189.4052973513244;1252.267423557211;15134.545963621109;15279.159646219343;15317.157556334285;15527.611809704847;15548.689026219412;654018;659587;662593;669554;674935;58644.12033819496;58754;59350.70324648436;59363.109553356735;59377.70311148417;1266.9873301266869;1439.172475826404;1444.4294503670992;1470.6029372069481;1471.925494389102;29197.708022919498;29230.123096307147;29263.85368073146;29733.553996689636;29841.403171936872;50206;50513.24230136477;50737.492625073275;50748.49251507438;51039.489605103474;450693;457564;465716;466383;467612;26498.867505662347;26732.465350693266;26830.865845670647;26838;26863.462730745665;44639.33041004323;45187.54812451833;45701.314480282155;45778.54221457743;46073.53926460827;37300;37557.43663845099;37620;38211.235775283785;38399.616003839605;36781.816090919376;37001.629983699815;37314;37753.81123094367;38174.427383588714;26492.205233843015;34890.82554587211;37072.81463592665;37153.07117322089;37619;26173.68134257826;29594;32487;33209;33342.83328583342;24303.75696243015;24504.142355017488;24678.766061696486;24988;25073.749262507143 05:54:43 INFO - PROCESS | 1944 | |2;jquery.html;291.4040786591419;347.53430403259625;382.49892640640763;393.3941729736205;409.6149619357805;15902;19423.32018379357;20316.984150792476;20987.895060524676;20997.47506312341;20679.172833086657;20720.792792072076;21086.156553737834;21101.99999999996;21661.783382166173;4909.803607855682;5941.792037278696;6036.758529658809;6131.9386806131815;6186.783462578811;6007.849803754897;6021.608595441304;6031.4270144336215;6055.424734650202;6088.847778805543;5767.538596912258;5992.34084250732;5995.430434108753;6049.304330002062;6078.149059131725;5955.702214889281;6030.035194368902;6049.848753781147;6050.909236361458;6077.361877002917;4979.651424400294;5244.39689435716;5307;5331.626786124973;5395.568354531628;13127.86872131276;13476.056676032682;13651.317434128254;13719.314034298344;13726.519571814939;2010.5624478497944;2150.537634408594;2298.8735619540917;2319.0491898321607;2697.131677322432;7897.763067107996;9550.140487356102;10008;10197.541110650038;10283.177345812317;1608.1878651281145;1684.3683618643008;1727.0587529796167;1734.5056658852166;1748.9300427982866;110590;128922;130959.03561446586;131530.99999999904;132201;10729.83905241422;10935.234533582572;11199.66401007971;11902.88097119035;11999.700007499883;15018.774718379233;15062.096274223582;15070.02044867102;15079.321430535652;15096.24518774079;8542.060373358925;8588.785280367916;8595.226429621363;8668.176523230282;8699;8403.41176117666;8416.537090460084;8417.663293468193;8500;8510.74467765968;4589.380433641482;4604.723716577016;4647.55848194421;4669.95330046702;4724.834630787906;1664.8085470171086;1676.8742344324226;1937.8062193780509;2004.2183548416128;2007.879527228371;901.9278457723366;935.9812803743887;995.4027583450016;1009.7630402756711;1068.3643232276784;20680.862552559454;20732.963351832346;20830.791692082887;21041.789582103982;21106.261280855095;934.1265916368218;956.8038552096804;995.5918073589794;1022.5091955861228;1024.912882404989;10435.16518678504;10841.728956775985;10956.936139857205;11029.724256893642;11061.668149955514;624475;628215;631631;637069;647092;147260;149257;153518.23240883724;158310;158618;908.6592527802075;1056.4400867540062;1069.3904474449644;1070.8286674132257;1076.3541874875011;16228.756568651715;18177.272909083487;18594.163262652943;18741.718874216625;19036.861177058603;49605.751971239915;49627.50372496229;50679.74660126676;52652.47347526629;53063.46936530585;67800;75112.87330690153;76149.23850761644;76647.61676191584;77870.2212977863;7062.823429414306;8046.235607617267;8064.032316122103;8114.83770324602;8190.7133250335955;9069.455832650063;12399.442025108712;12480.313582753035;12702.555410560586;12903.741925161632;10595.470226488798;10872;11098.112151028059;11214.439278036229;11260.94369528147;9154.221891139061;9867.358621689567;10759.89240107589;11034.400011998376;11036.779264414507;10992.450377481255;11024.338539687644;11058.889411106107;11463.197576169589;11477;8071.192880711882;8148.22591853773;8204.466709663853;8228.136045715353;8314.708985185489;11102.61140860066;11259.211855170059;11292.7741445169;11345.48945297447;11376.94311528437 05:54:43 INFO - PROCESS | 1944 | |3;mootools.html;1574.2522301906592;1611.7743515907775;1746.3538490758422;1828.387490190786;1830.5423644088987;7309.597972111532;8117.431779775403;8764.649414023439;9259.861102083465;9283.860742088875;7696.807579810494;8865.867011994826;8918;9118.270538356932;9124.178823905848;2418.7581241875846;2439.1828737373003;2451.656768052474;2455.5702752018415;2456.705195376557;2423.260905423845;2428.429319110008;2460.507898420314;2464.1375518568448;2472.530219258344;2419.2258477287273;2428.3200703802963;2437.098273638756;2438.5976313908186;2454.202384225125;2422.8061755059553;2438.1953955194745;2444.0956845967016;2444.5477586646525;2457.1522824625476;2437.305367970138;2441.8779061046876;2443.2548072837776;2444.352246654638;2453.4479742058074;9012.594433250453;9169.862452063224;9175.311851611155;9176.31177661678;9177.632894684271;5397.892042159174;5566.552696298951;5573.498385145316;5576.386597474275;5599.244102046212;3551.5915669698056;3558.484019817138;3566.8751593694356;3696.9648498420484;3701.5002974598624;824.8309096635236;989;1029.67050543826;1050.1598721023227;1072.3083611070815;10781.407022613754;12039.699007524794;12085.033197344279;12126.878731212662;12176.26942383451;4801.783919723621;4840.491748366441;4896.902061958812;4913.434954980223;4915;10265.230107741949;10284.382937023802;10298.073173414428;10302.227332950059;10370.533326000348;5399.082156033483;5471.945280547144;5484.808031718871;5548.805791797267;5582.33012038558;4504.189245935763;4841.443234028062;4981.128302547112;5018.824341148043;5074.898502029939;3435.4675025371107;4099;4105.414660777222;4146.523149837747;4182.581741825854;1360.156702844243;1363.9795403068963;1372.7872179811945;1382.7441923244232;1388.5001399496375;815.3029160068174;927.6057675487891;962.6197651927449;964.7877466957258;983.7343917142332;5087.745612719349;6014.609050411709;6130.1724267224245;6140.662263575457;6250.9062364064575;392.7839688171494;463.1131383400817;473.97630118493936;475.3582663404446;482.55845901000896;3951.38753493209;4057.046594050396;4059.5331536873045;4105.055837157471;4163.542010378856;10634.361938283573;10963.83554246687;10995.505202265756;11149.99650031501;11165.83251251232;8866.822663546693;8999.235065019415;10639.680809575726;10975.560977560808;11089.445527723581;7922.326602238874;8240.299574536111;8357.707480238163;8390.496570205807;8438.367122465717;5340.49265319794;6083.178770866053;6109.11417844419;6233.501319894307;6270.843228919313;8979.865302020344;10142.89857101449;10287.125594324267;10418.166546676399;10442.738931526772;10132.898671013196;10324.174066074846;10532.104771094542;10596.099331556905;10634.52144653508;7015.473839461959;7530.924690753022;7692.730754423568;7796.181400952818;7845.6077196141105;7082.468814838805;8656.78358041054;8667.306615470874;8724.956375218337;8738.432001919855;7571.810704732426;7588.734394296173;7675.4243431741725;7683.692652293846;7844.9607751960875;7476.439267054884;7487.663055162422;7543.924560754323;7636.885446718415;7696.653650585625;7153.82115447118;7236.819079523054;7249.673764680497;7263.636818159177;7263.782086537412;4577.7482238477205;5059.418166910852;5133.178691409356;5149.588032957429;5152.8148525838515;4702.965347623517;5029.64792464524;5054;5138.665986710852;5163.173892177234 05:54:43 INFO - PROCESS | 1944 | |4;prototype.html;269.69793830909384;319.5207189216176;321.72974701250945;327.53980657176675;330.0478120622005;4616.907661846761;5384.030874442604;5600.5799565032685;5721.370649228592;5748.971255143723;5057.645964782467;5188.792448302063;5552.86117847053;5612.466815652517;5635.365743934264;3715.8109404990414;4056.553779084306;4083.3262511685534;4118.588141185873;4260.914781704364;3543.7696549724187;4145.689073319498;4156.896077598054;4188.560201178878;4226.640735537483;3978.482797236355;4165.500139983206;4200.978995105035;4201.831926722927;4214.473190851144;3695.8891233263043;4056.5943405659345;4235.195312890556;4239.512456067561;4250.702450828443;3456.4988076728987;3487.860485580574;3563.233904710474;3660.5058317127114;3685.4840322354894;4808.374911261513;5272.362070171607;5275.314209152824;5330.440303768088;5337.332833395825;3959.4456776051375;4023.1752490739327;4898.5591296783105;4908.0429316283335;4916.63125265603;5856.824295271149;5886.1465087562065;5895.174675545427;5900.675462849542;5912.763489460416;3258.283177700902;3614.331348700499;4061.5378463753027;4089.284375234322;4114.7119701620895;5569.637973531708;5669.44090375146;5731.656100633933;5772.624779389368;6241.875162496815;3538.80594031702;3689.6125906779403;3827.4067519534515;4015.4980627421573;4034.1528279061727;3262.2546937388383;3307.1566750478955;3527.765282151278;3897.6881849451975;4116.958830411718;4064.227796718614;4370.38814565961;4646.930296045562;4712.30357071454;4769.213079841857;4686.414198225222;5099.133147364957;5440.836774896759;5609.943900561024;6005.369436209223;1200.8258802473592;1361.5166615851429;1402.2708191740237;1403.7824137258742;1407.2541552976938;717.7129148340706;793.6587267475041;806.9066415007698;843.1652663862732;872.6814712629845;4990.451050384455;5178.275041494194;5333.813316533902;5442.292501974718;5479.780808767605;4794.472608013116;5435.021696094662;6009.609375390585;6037.547183961133;6038.456538911431;4609.769511524478;4816.397950256218;5189.080040385006;5408.891822163535;5616.550675945915;2645.550256456407;2753.435545713124;3217.2761128745847;3220.0500852248642;3228.951565726469;5031.471695471996;6205.913965056097;6251.78118765841;6271.9686401568615;6335.275831392545;5137.640365174477;5484.122540393518;6062.969295219823;6370.490360771128;6377.872442551123;5527.778888844482;6429.903551446732;6472.0843889446205;6488.805335840027;6519.207218014989;5398.946010539844;5845.327787304344;5918.201042859159;6123.540734445023;6150.354212807591;5221.582273418194;5228.45101264377;5393.514583687487;5634.887302254014;5878.2358293421585;5218.452062533379;5778.884422311614;6059.969700151471;6184.289426344158;6382.68086595659;3922.725409221327;4036.688076375131;4047.3119569672635;4072.3484242521054;4072.6741860650486;5040.227874668844;6081.331053584191;6179;6194.721237544411;6209.733447291796;4952.950470495393;5113.775507654183;5148.948510514847;5167;5915.58590898633;5825.79609713658;5896.469317761251;5909.261342332208;5910.261217347832;5931.258592675916;4881.316615673737;5271.499207575274;5804.6807425592;5805.777793324745;5837.562182836219;4603.930941035954;5949.702514874152;5965.642061476326;5995.250593675791;6017.608855424383;5709.400512946081;5768.480836724715;5858.179854820243;5875.764969401347;5890.852728681817 05:54:43 INFO - PROCESS | 1944 | |5;yui.html;659.7789740436954;662.6256165266624;727.6907314391383;742.6435311050694;761.4859969520572;15004.849951500482;15204;15472.149031803261;15494.520876659737;15618.297176627051;11807.232529885552;12207.51169953203;14007.719845603082;14422.639434014154;14471.927640361784;725.3435640745134;891.8038031633063;895.1809094678362;916.1983264643437;928.8885333759958;890.9955450222773;897.1477096758073;923.9122283383068;925.2736601767614;928.3547934185743;948.2793077261297;977.867987821642;978.0659470205936;979.5053497983508;980.1913421427315;937.2267878999818;937.2689302344148;948.7628092976756;968.0658164870863;971.7522031881861;703.43022152057;718.6119495472439;719.3022767915166;719.4783781758235;721.8772808622547;14818.555443336718;14925.552233433014;14971;15014.47449339268;15022.624434389229;10424.947875260576;12432.813507797391;12556.937215313956;12571.874281257162;12757.170783899017;9768.853467197998;9834.655787047419;10047.849282260771;10319.690409287734;10525.84211236832;3013.9246518837203;3437.071990562559;3442.3631628148873;3473.1838018065732;3556.5376501054957;79470;79678.60160699218;79719.60140199262;80577.59711201406;81394;9460.57427415768;9473.242140628734;9544.61821527145;9773.169280611228;9778.119969202806;21757.782422175576;21949.122035118737;21956.341309760734;21987;22118.557628847655;7337.19290878003;7338.41292696583;7484.998682568677;7519.135299440634;7644.847103057908;3938.3895496198134;4009.3585026395635;4010.6791456683395;4013.979930100331;4014.0968282136287;3484.303139372136;3515.296940611837;3578.7315951303235;3580.785152890835;3594.5866225383893;618.5731845026917;625.1997443272528;630.168178005029;638.1512588257606;640.9871802563922;431.7582153993774;499.5332393191241;518.2977066075489;567.0926517571852;601.1073555769678;7765.805854853674;7837.921620783719;8138.104808471064;8257.545834979133;8364.707235246737;507.061935419471;555.5971920357708;566.1620801214144;589.7316720891984;590.3004939147039;3360.277540328841;3729.813509324523;3872.9225415492097;3879.553851307079;3902.2585708715255;78010;78516;79156.60421697855;79244;88752.55623721969;75548;75557;76371;77238.6138069306;81091;70333;70397.94403083957;70672.64663676752;70990.58018839594;71142;6266.279377871603;7650.622887880235;7843.137254901842;7856.37149028065;7864.252895974873;41336;42088.3686744693;42541.7237482876;43209;43968.78015609901;50061.749691252764;56989.43010569841;61556.384436155065;61896.76206475935;61907.071393930026;9714.611415543299;9983.700488985342;10115.79768404613;10479;10531.89468105309;10961.451927403758;10993.285436446606;11208.719782005515;11269.7746045077;11430.48562814692;9447.905520944978;9476.431414115175;10304.742381440525;10601.204909631655;10617.628383006559;8946.821063578562;8954.776130596787;8996.50519221449;10133.949330253301;10153.39079655223;8919.795827563456;10149.441780701836;10301.175905927357;10317.587296508056;10430.53062612169;9075.682351117679;9087.000429952568;9270.582823773082;9351.766205844637;9357.672481463116;8967.865482017905;8974.83327167464;9016.053314402156;9025.954870225607;9060.773480662772 05:54:43 INFO - PROCESS | 1944 | __end_tp_report 05:54:43 INFO - PROCESS | 1944 | __start_cc_report 05:54:43 INFO - PROCESS | 1944 | _x_x_mozilla_cycle_collect,414 05:54:43 INFO - PROCESS | 1944 | __end_cc_report 05:54:43 INFO - PROCESS | 1944 | __startTimestamp1463057683141__endTimestamp 05:54:43 INFO - PROCESS | 1944 | 05:54:43 INFO - PROCESS | 1944 | ------- Summary: start ------- 05:54:43 INFO - PROCESS | 1944 | Number of tests: 6 05:54:43 INFO - PROCESS | 1944 | 05:54:43 INFO - PROCESS | 1944 | [#0] dojo.html Cycles:165 Average:5644.47 Median:5841.01 stddev:671.95 (11.5%) stddev-sans-first:639.12 05:54:43 INFO - PROCESS | 1944 | Values: 2919.9 3385.6 3404.2 3422.2 3444.2 4357.8 5564.9 5660.8 5724.5 5808.9 5556.2 5635.5 5638.2 5690.0 5761.8 5077.4 5108.1 5149.5 5188.5 5211.9 5080.2 5216.2 5220.6 5267.8 5292.9 5113.3 5133.6 5137.8 5171.9 5182.9 3983.3 4194.9 4699.8 5051.1 5203.1 5145.5 5149.3 5152.4 5183.8 5217.3 5275.2 5521.1 5589.4 5590.0 5620.2 6125.1 6184.5 6197.6 6201.2 6228.3 6202.2 6221.0 6231.5 6241.3 6269.6 5626.2 5725.5 5756.0 5758.1 5794.7 6259.2 6276.9 6280.5 6290.9 6300.5 5324.6 5841.0 5992.2 6070.6 6091.3 6096.8 6121.6 6174.7 6220.7 6223.6 5987.6 6034.1 6114.6 6124.3 6144.0 6035.3 6120.9 6178.1 6178.8 6190.1 4812.8 4828.9 4836.3 4840.2 4896.9 4323.2 4338.6 4353.0 4361.3 4368.4 5577.7 5710.8 5715.9 5731.2 5758.5 6190.4 6227.1 6262.1 6271.2 6301.5 5744.8 5756.1 5757.6 5764.3 5782.8 5208.6 5286.1 5289.5 5379.9 5387.8 6310.7 6322.7 6325.9 6331.5 6345.9 4884.8 5405.6 6216.3 6249.5 6265.4 6196.6 6234.3 6264.7 6302.8 6306.2 6071.6 6103.5 6106.4 6108.8 6137.8 6105.7 6154.4 6199.7 6205.5 6218.3 5789.6 5848.3 5907.7 6045.2 6074.9 5926.6 5930.3 5946.1 5995.3 6007.7 5810.7 5831.5 5842.2 5868.0 5875.5 5486.8 5884.3 5965.7 5970.8 5985.6 5854.3 5857.9 5883.2 5884.9 5887.3 05:54:43 INFO - PROCESS | 1944 | 05:54:43 INFO - PROCESS | 1944 | [#1] ext.html Cycles:180 Average:66499.97 Median:37588.22 stddev:147923.51 (393.5%) stddev-sans-first:148255.49 05:54:43 INFO - PROCESS | 1944 | Values: 506.7 516.9 530.2 539.8 566.4 26558.6 41460.2 42614.6 42756.8 44470.1 42977.0 43319.6 43324.6 43449.8 43547.6 9539.4 9735.3 9736.0 9741.0 9748.8 9560.0 9598.3 9609.0 9632.0 9665.5 9659.3 9701.3 9709.3 9709.5 9726.7 9578.0 9613.3 9644.7 9651.6 9677.4 9480.3 9505.2 9569.9 9601.5 9636.4 32534.7 36081.3 39122.8 39621.2 39716.4 3083.7 3144.8 3174.8 3352.8 3577.7 28735.1 28747.7 29161.0 29369.6 30259.5 2216.4 2728.3 2788.7 2797.1 2850.1 502499.0 511563.0 512146.0 512489.0 515306.0 34767.8 36066.8 62600.7 63576.0 63733.7 36761.6 36884.6 36911.6 37101.4 37163.6 15084.5 15105.8 15247.9 15257.9 15267.9 12797.2 12843.7 12880.7 12898.4 12919.4 8145.8 8150.6 8160.1 8209.8 8224.6 3410.5 3429.9 3440.3 3446.4 3476.9 869.5 960.7 997.7 1041.3 1074.3 24897.9 27900.4 29875.6 29996.9 30029.7 1065.2 1112.5 1132.6 1189.4 1252.3 15134.5 15279.2 15317.2 15527.6 15548.7 654018.0 659587.0 662593.0 669554.0 674935.0 58644.1 58754.0 59350.7 59363.1 59377.7 1267.0 1439.2 1444.4 1470.6 1471.9 29197.7 29230.1 29263.9 29733.6 29841.4 50206.0 50513.2 50737.5 50748.5 51039.5 450693.0 457564.0 465716.0 466383.0 467612.0 26498.9 26732.5 26830.9 26838.0 26863.5 44639.3 45187.5 45701.3 45778.5 46073.5 37300.0 37557.4 37620.0 38211.2 38399.6 36781.8 37001.6 37314.0 37753.8 38174.4 26492.2 34890.8 37072.8 37153.1 37619.0 26173.7 29594.0 32487.0 33209.0 33342.8 24303.8 24504.1 24678.8 24988.0 25073.7 05:54:43 INFO - PROCESS | 1944 | 05:54:43 INFO - PROCESS | 1944 | [#2] jquery.html Cycles:180 Average:36437.04 Median:21094.08 stddev:106389.23 (504.4%) stddev-sans-first:106653.06 05:54:43 INFO - PROCESS | 1944 | Values: 291.4 347.5 382.5 393.4 409.6 15902.0 19423.3 20317.0 20987.9 20997.5 20679.2 20720.8 21086.2 21102.0 21661.8 4909.8 5941.8 6036.8 6131.9 6186.8 6007.8 6021.6 6031.4 6055.4 6088.8 5767.5 5992.3 5995.4 6049.3 6078.1 5955.7 6030.0 6049.8 6050.9 6077.4 4979.7 5244.4 5307.0 5331.6 5395.6 13127.9 13476.1 13651.3 13719.3 13726.5 2010.6 2150.5 2298.9 2319.0 2697.1 7897.8 9550.1 10008.0 10197.5 10283.2 1608.2 1684.4 1727.1 1734.5 1748.9 110590.0 128922.0 130959.0 131531.0 132201.0 10729.8 10935.2 11199.7 11902.9 11999.7 15018.8 15062.1 15070.0 15079.3 15096.2 8542.1 8588.8 8595.2 8668.2 8699.0 8403.4 8416.5 8417.7 8500.0 8510.7 4589.4 4604.7 4647.6 4670.0 4724.8 1664.8 1676.9 1937.8 2004.2 2007.9 901.9 936.0 995.4 1009.8 1068.4 20680.9 20733.0 20830.8 21041.8 21106.3 934.1 956.8 995.6 1022.5 1024.9 10435.2 10841.7 10956.9 11029.7 11061.7 624475.0 628215.0 631631.0 637069.0 647092.0 147260.0 149257.0 153518.2 158310.0 158618.0 908.7 1056.4 1069.4 1070.8 1076.4 16228.8 18177.3 18594.2 18741.7 19036.9 49605.8 49627.5 50679.7 52652.5 53063.5 67800.0 75112.9 76149.2 76647.6 77870.2 7062.8 8046.2 8064.0 8114.8 8190.7 9069.5 12399.4 12480.3 12702.6 12903.7 10595.5 10872.0 11098.1 11214.4 11260.9 9154.2 9867.4 10759.9 11034.4 11036.8 10992.5 11024.3 11058.9 11463.2 11477.0 8071.2 8148.2 8204.5 8228.1 8314.7 11102.6 11259.2 11292.8 11345.5 11376.9 05:54:43 INFO - PROCESS | 1944 | 05:54:43 INFO - PROCESS | 1944 | [#3] mootools.html Cycles:180 Average:5805.44 Median:4869.17 stddev:3256.19 (66.9%) stddev-sans-first:3249.80 05:54:43 INFO - PROCESS | 1944 | Values: 1574.3 1611.8 1746.4 1828.4 1830.5 7309.6 8117.4 8764.6 9259.9 9283.9 7696.8 8865.9 8918.0 9118.3 9124.2 2418.8 2439.2 2451.7 2455.6 2456.7 2423.3 2428.4 2460.5 2464.1 2472.5 2419.2 2428.3 2437.1 2438.6 2454.2 2422.8 2438.2 2444.1 2444.5 2457.2 2437.3 2441.9 2443.3 2444.4 2453.4 9012.6 9169.9 9175.3 9176.3 9177.6 5397.9 5566.6 5573.5 5576.4 5599.2 3551.6 3558.5 3566.9 3697.0 3701.5 824.8 989.0 1029.7 1050.2 1072.3 10781.4 12039.7 12085.0 12126.9 12176.3 4801.8 4840.5 4896.9 4913.4 4915.0 10265.2 10284.4 10298.1 10302.2 10370.5 5399.1 5471.9 5484.8 5548.8 5582.3 4504.2 4841.4 4981.1 5018.8 5074.9 3435.5 4099.0 4105.4 4146.5 4182.6 1360.2 1364.0 1372.8 1382.7 1388.5 815.3 927.6 962.6 964.8 983.7 5087.7 6014.6 6130.2 6140.7 6250.9 392.8 463.1 474.0 475.4 482.6 3951.4 4057.0 4059.5 4105.1 4163.5 10634.4 10963.8 10995.5 11150.0 11165.8 8866.8 8999.2 10639.7 10975.6 11089.4 7922.3 8240.3 8357.7 8390.5 8438.4 5340.5 6083.2 6109.1 6233.5 6270.8 8979.9 10142.9 10287.1 10418.2 10442.7 10132.9 10324.2 10532.1 10596.1 10634.5 7015.5 7530.9 7692.7 7796.2 7845.6 7082.5 8656.8 8667.3 8725.0 8738.4 7571.8 7588.7 7675.4 7683.7 7845.0 7476.4 7487.7 7543.9 7636.9 7696.7 7153.8 7236.8 7249.7 7263.6 7263.8 4577.7 5059.4 5133.2 5149.6 5152.8 4703.0 5029.6 5054.0 5138.7 5163.2 05:54:43 INFO - PROCESS | 1944 | 05:54:43 INFO - PROCESS | 1944 | [#4] prototype.html Cycles:180 Average:4681.71 Median:5203.77 stddev:1485.65 (28.5%) stddev-sans-first:1452.45 05:54:43 INFO - PROCESS | 1944 | Values: 269.7 319.5 321.7 327.5 330.0 4616.9 5384.0 5600.6 5721.4 5749.0 5057.6 5188.8 5552.9 5612.5 5635.4 3715.8 4056.6 4083.3 4118.6 4260.9 3543.8 4145.7 4156.9 4188.6 4226.6 3978.5 4165.5 4201.0 4201.8 4214.5 3695.9 4056.6 4235.2 4239.5 4250.7 3456.5 3487.9 3563.2 3660.5 3685.5 4808.4 5272.4 5275.3 5330.4 5337.3 3959.4 4023.2 4898.6 4908.0 4916.6 5856.8 5886.1 5895.2 5900.7 5912.8 3258.3 3614.3 4061.5 4089.3 4114.7 5569.6 5669.4 5731.7 5772.6 6241.9 3538.8 3689.6 3827.4 4015.5 4034.2 3262.3 3307.2 3527.8 3897.7 4117.0 4064.2 4370.4 4646.9 4712.3 4769.2 4686.4 5099.1 5440.8 5609.9 6005.4 1200.8 1361.5 1402.3 1403.8 1407.3 717.7 793.7 806.9 843.2 872.7 4990.5 5178.3 5333.8 5442.3 5479.8 4794.5 5435.0 6009.6 6037.5 6038.5 4609.8 4816.4 5189.1 5408.9 5616.6 2645.6 2753.4 3217.3 3220.1 3229.0 5031.5 6205.9 6251.8 6272.0 6335.3 5137.6 5484.1 6063.0 6370.5 6377.9 5527.8 6429.9 6472.1 6488.8 6519.2 5398.9 5845.3 5918.2 6123.5 6150.4 5221.6 5228.5 5393.5 5634.9 5878.2 5218.5 5778.9 6060.0 6184.3 6382.7 3922.7 4036.7 4047.3 4072.3 4072.7 5040.2 6081.3 6179.0 6194.7 6209.7 4953.0 5113.8 5148.9 5167.0 5915.6 5825.8 5896.5 5909.3 5910.3 5931.3 4881.3 5271.5 5804.7 5805.8 5837.6 4603.9 5949.7 5965.6 5995.3 6017.6 5709.4 5768.5 5858.2 5875.8 5890.9 05:54:43 INFO - PROCESS | 1944 | 05:54:43 INFO - PROCESS | 1944 | [#5] yui.html Cycles:180 Average:17252.17 Median:650.38 stddev:24134.62 (3710.8%) stddev-sans-first:24170.16 05:54:43 INFO - PROCESS | 1944 | Values: 659.8 662.6 727.7 742.6 761.5 15004.8 15204.0 15472.1 15494.5 15618.3 11807.2 12207.5 14007.7 14422.6 14471.9 725.3 891.8 895.2 916.2 928.9 891.0 897.1 923.9 925.3 928.4 948.3 977.9 978.1 979.5 980.2 937.2 937.3 948.8 968.1 971.8 703.4 718.6 719.3 719.5 721.9 14818.6 14925.6 14971.0 15014.5 15022.6 10424.9 12432.8 12556.9 12571.9 12757.2 9768.9 9834.7 10047.8 10319.7 10525.8 3013.9 3437.1 3442.4 3473.2 3556.5 79470.0 79678.6 79719.6 80577.6 81394.0 9460.6 9473.2 9544.6 9773.2 9778.1 21757.8 21949.1 21956.3 21987.0 22118.6 7337.2 7338.4 7485.0 7519.1 7644.8 3938.4 4009.4 4010.7 4014.0 4014.1 3484.3 3515.3 3578.7 3580.8 3594.6 618.6 625.2 630.2 638.2 641.0 431.8 499.5 518.3 567.1 601.1 7765.8 7837.9 8138.1 8257.5 8364.7 507.1 555.6 566.2 589.7 590.3 3360.3 3729.8 3872.9 3879.6 3902.3 78010.0 78516.0 79156.6 79244.0 88752.6 75548.0 75557.0 76371.0 77238.6 81091.0 70333.0 70397.9 70672.6 70990.6 71142.0 6266.3 7650.6 7843.1 7856.4 7864.3 41336.0 42088.4 42541.7 43209.0 43968.8 50061.7 56989.4 61556.4 61896.8 61907.1 9714.6 9983.7 10115.8 10479.0 10531.9 10961.5 10993.3 11208.7 11269.8 11430.5 9447.9 9476.4 10304.7 10601.2 10617.6 8946.8 8954.8 8996.5 10133.9 10153.4 8919.8 10149.4 10301.2 10317.6 10430.5 9075.7 9087.0 9270.6 9351.8 9357.7 8967.9 8974.8 9016.1 9026.0 9060.8 05:54:43 INFO - PROCESS | 1944 | -------- Summary: end -------- 05:54:43 INFO - PROCESS | 1944 | 05:54:43 INFO - TEST-INFO | 1944: exit 0 05:54:43 INFO - TEST-OK | dromaeo_css | took 1113029ms 05:54:43 INFO - TEST-START | kraken 05:54:43 INFO - operating with platform_type : mac_ 05:54:43 INFO - Initialising browser for kraken test... 05:54:43 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp9v3g1Z/profile http://localhost:55239/getInfo.html) 05:54:46 INFO - PROCESS | 1949 | __metrics Screen width/height:1024/768 05:54:46 INFO - PROCESS | 1949 | colorDepth:24 05:54:46 INFO - PROCESS | 1949 | Browser inner width/height: 1024/605 05:54:46 INFO - PROCESS | 1949 | __metrics 05:54:46 ERROR - PROCESS | 1949 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:54:47 INFO - TEST-INFO | 1949: exit 0 05:54:47 INFO - Browser initialized. 05:54:47 INFO - Running cycle 1/1 for kraken test... 05:54:47 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp9v3g1Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:54:59 INFO - PROCESS | 1952 | RSS: Main: 249319424 05:54:59 INFO - PROCESS | 1952 | 05:55:02 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55239/tests/kraken/driver.html?testName=audio-beat-detection) 05:55:02 INFO - PROCESS | 1952 | RSS: Main: 259366912 05:55:02 INFO - PROCESS | 1952 | 05:55:05 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55239/tests/kraken/driver.html?testName=audio-dft) 05:55:06 INFO - PROCESS | 1952 | RSS: Main: 263208960 05:55:06 INFO - PROCESS | 1952 | 05:55:10 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55239/tests/kraken/driver.html?testName=audio-fft) 05:55:10 INFO - PROCESS | 1952 | RSS: Main: 264126464 05:55:10 INFO - PROCESS | 1952 | 05:55:12 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55239/tests/kraken/driver.html?testName=audio-oscillator) 05:55:13 INFO - PROCESS | 1952 | RSS: Main: 264077312 05:55:13 INFO - PROCESS | 1952 | 05:55:15 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:55:16 INFO - PROCESS | 1952 | RSS: Main: 264396800 05:55:16 INFO - PROCESS | 1952 | 05:55:19 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55239/tests/kraken/driver.html?testName=imaging-darkroom) 05:55:19 INFO - PROCESS | 1952 | RSS: Main: 264253440 05:55:19 INFO - PROCESS | 1952 | 05:55:23 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55239/tests/kraken/driver.html?testName=imaging-desaturate) 05:55:23 INFO - PROCESS | 1952 | RSS: Main: 264499200 05:55:23 INFO - PROCESS | 1952 | 05:55:26 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55239/tests/kraken/driver.html?testName=json-parse-financial) 05:55:27 INFO - PROCESS | 1952 | RSS: Main: 265871360 05:55:27 INFO - PROCESS | 1952 | 05:55:29 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:55:29 INFO - PROCESS | 1952 | RSS: Main: 266317824 05:55:29 INFO - PROCESS | 1952 | 05:55:31 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55239/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:55:32 INFO - PROCESS | 1952 | RSS: Main: 266391552 05:55:32 INFO - PROCESS | 1952 | 05:55:34 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:55:35 INFO - PROCESS | 1952 | RSS: Main: 266682368 05:55:35 INFO - PROCESS | 1952 | 05:55:38 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:55:39 INFO - PROCESS | 1952 | RSS: Main: 266833920 05:55:39 INFO - PROCESS | 1952 | 05:55:42 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:55:42 INFO - PROCESS | 1952 | RSS: Main: 266780672 05:55:42 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:55239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55239/tests/kraken/driver.html?testName=ai-astar) 05:55:44 INFO - PROCESS | 1952 | RSS: Main: 269504512 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | __start_tp_report 05:55:44 INFO - PROCESS | 1952 | _x_x_mozilla_page_load 05:55:44 INFO - PROCESS | 1952 | _x_x_mozilla_page_load_details 05:55:44 INFO - PROCESS | 1952 | |i|pagename|runs| 05:55:44 INFO - PROCESS | 1952 | |0;ai-astar;116;139;107;116;121;106;134;163;171;172 05:55:44 INFO - PROCESS | 1952 | |1;audio-beat-detection;201;153;133;191;137;172;158;152;205;127 05:55:44 INFO - PROCESS | 1952 | |2;audio-dft;204;246;286;225;195;230;199;204;206;196 05:55:44 INFO - PROCESS | 1952 | |3;audio-fft;72;82;76;180;49;54;63;101;155;86 05:55:44 INFO - PROCESS | 1952 | |4;audio-oscillator;79;88;154;77;80;81;120;81;81;121 05:55:44 INFO - PROCESS | 1952 | |5;imaging-gaussian-blur;88;88;90;87;89;88;90;87;90;86 05:55:44 INFO - PROCESS | 1952 | |6;imaging-darkroom;94;97;92;95;89;133;151;106;153;149 05:55:44 INFO - PROCESS | 1952 | |7;imaging-desaturate;98;96;98;115;116;98;99;127;146;92 05:55:44 INFO - PROCESS | 1952 | |8;json-parse-financial;60;65;63;60;62;60;60;124;64;61 05:55:44 INFO - PROCESS | 1952 | |9;json-stringify-tinderbox;64;99;94;69;82;75;69;67;66;70 05:55:44 INFO - PROCESS | 1952 | |10;stanford-crypto-aes;85;82;85;110;85;85;74;98;111;107 05:55:44 INFO - PROCESS | 1952 | |11;stanford-crypto-ccm;137;140;136;136;138;136;137;139;208;138 05:55:44 INFO - PROCESS | 1952 | |12;stanford-crypto-pbkdf2;186;227;229;174;172;177;204;232;177;208 05:55:44 INFO - PROCESS | 1952 | |13;stanford-crypto-sha256-iterative;65;67;64;67;67;65;65;65;69;65 05:55:44 INFO - PROCESS | 1952 | __end_tp_report 05:55:44 INFO - PROCESS | 1952 | __start_cc_report 05:55:44 INFO - PROCESS | 1952 | _x_x_mozilla_cycle_collect,1087 05:55:44 INFO - PROCESS | 1952 | __end_cc_report 05:55:44 INFO - PROCESS | 1952 | __startTimestamp1463057744857__endTimestamp 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | ------- Summary: start ------- 05:55:44 INFO - PROCESS | 1952 | Number of tests: 14 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#0] ai-astar Cycles:10 Average:134.50 Median:136.50 stddev:25.83 (18.9%) stddev-sans-first:26.52 05:55:44 INFO - PROCESS | 1952 | Values: 116.0 139.0 107.0 116.0 121.0 106.0 134.0 163.0 171.0 172.0 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#1] audio-beat-detection Cycles:10 Average:162.90 Median:165.00 stddev:28.27 (17.1%) stddev-sans-first:26.41 05:55:44 INFO - PROCESS | 1952 | Values: 201.0 153.0 133.0 191.0 137.0 172.0 158.0 152.0 205.0 127.0 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#2] audio-dft Cycles:10 Average:219.10 Median:215.50 stddev:28.87 (13.4%) stddev-sans-first:30.09 05:55:44 INFO - PROCESS | 1952 | Values: 204.0 246.0 286.0 225.0 195.0 230.0 199.0 204.0 206.0 196.0 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#3] audio-fft Cycles:10 Average:91.80 Median:67.50 stddev:43.10 (63.9%) stddev-sans-first:45.12 05:55:44 INFO - PROCESS | 1952 | Values: 72.0 82.0 76.0 180.0 49.0 54.0 63.0 101.0 155.0 86.0 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:80.50 stddev:26.27 (32.6%) stddev-sans-first:27.11 05:55:44 INFO - PROCESS | 1952 | Values: 79.0 88.0 154.0 77.0 80.0 81.0 120.0 81.0 81.0 121.0 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:88.50 stddev:1.42 (1.6%) stddev-sans-first:1.50 05:55:44 INFO - PROCESS | 1952 | Values: 88.0 88.0 90.0 87.0 89.0 88.0 90.0 87.0 90.0 86.0 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#6] imaging-darkroom Cycles:10 Average:115.90 Median:90.50 stddev:27.21 (30.1%) stddev-sans-first:27.68 05:55:44 INFO - PROCESS | 1952 | Values: 94.0 97.0 92.0 95.0 89.0 133.0 151.0 106.0 153.0 149.0 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#7] imaging-desaturate Cycles:10 Average:108.50 Median:97.00 stddev:17.31 (17.8%) stddev-sans-first:17.94 05:55:44 INFO - PROCESS | 1952 | Values: 98.0 96.0 98.0 115.0 116.0 98.0 99.0 127.0 146.0 92.0 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#8] json-parse-financial Cycles:10 Average:67.90 Median:61.50 stddev:19.80 (32.2%) stddev-sans-first:20.79 05:55:44 INFO - PROCESS | 1952 | Values: 60.0 65.0 63.0 60.0 62.0 60.0 60.0 124.0 64.0 61.0 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#9] json-stringify-tinderbox Cycles:10 Average:75.50 Median:72.50 stddev:12.23 (16.9%) stddev-sans-first:12.25 05:55:44 INFO - PROCESS | 1952 | Values: 64.0 99.0 94.0 69.0 82.0 75.0 69.0 67.0 66.0 70.0 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:85.00 stddev:13.19 (15.5%) stddev-sans-first:13.73 05:55:44 INFO - PROCESS | 1952 | Values: 85.0 82.0 85.0 110.0 85.0 85.0 74.0 98.0 111.0 107.0 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#11] stanford-crypto-ccm Cycles:10 Average:144.50 Median:138.00 stddev:22.35 (16.2%) stddev-sans-first:23.54 05:55:44 INFO - PROCESS | 1952 | Values: 137.0 140.0 136.0 136.0 138.0 136.0 137.0 139.0 208.0 138.0 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.60 Median:206.00 stddev:24.42 (11.9%) stddev-sans-first:25.48 05:55:44 INFO - PROCESS | 1952 | Values: 186.0 227.0 229.0 174.0 172.0 177.0 204.0 232.0 177.0 208.0 05:55:44 INFO - PROCESS | 1952 | 05:55:44 INFO - PROCESS | 1952 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:1.52 (2.3%) stddev-sans-first:1.58 05:55:44 INFO - PROCESS | 1952 | Values: 65.0 67.0 64.0 67.0 67.0 65.0 65.0 65.0 69.0 65.0 05:55:44 INFO - PROCESS | 1952 | -------- Summary: end -------- 05:55:44 INFO - PROCESS | 1952 | 05:55:45 INFO - TEST-INFO | 1952: exit 0 05:55:45 INFO - TEST-OK | kraken | took 61912ms 05:55:45 INFO - SUITE-END | took 1174s 05:55:46 INFO - Completed test suite (00:19:35) 05:55:46 INFO - javascript benchmark 05:55:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7064.935154149713, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2919.927001824954, 3385.6445073267323, 3404.2170300830817, 3422.1615704152487, 3444.2422667013266, 4357.782110894451, 5564.888702225953, 5660.830175094743, 5724.513416359609, 5808.883822323546, 5556.16657501375, 5635.549156067515, 5638.210650508932, 5690.004249256374, 5761.827145185641, 5077.3907131144315, 5108.1060814357525, 5149.510796474329, 5188.50709182628, 5211.921821172686, 5080.1617733074, 5216.165413533835, 5220.634555581092, 5267.841964741045, 5292.947070529303, 5113.335266415343, 5133.640645154841, 5137.7944882204665, 5171.896562068757, 5182.870428239286, 3983.2830090583684, 4194.937075943863, 4699.765011749399, 5051.06555287273, 5203.089459344637, 5145.536901678829, 5149.253358263069, 5152.381714194284, 5183.844484665466, 5217.321748172713, 5275.155975043975, 5521.116621340566, 5589.357223919261, 5590.0, 5620.156976453564, 6125.142480052797, 6184.536159788034, 6197.597156184834, 6201.16284301618, 6228.252609686821, 6202.227560602768, 6221.0, 6231.532635052389, 6241.251049874044, 6269.623822570615, 5626.184203290541, 5725.456081672234, 5756.0214763489885, 5758.078707406795, 5794.710264486843, 6259.247857476208, 6276.905846412307, 6280.528960327993, 6290.905636415366, 6300.527460440485, 5324.627276090714, 5841.007028805113, 5992.191054207626, 6070.635761854214, 6091.269047714302, 6096.798833414304, 6121.605402471149, 6174.66039367839, 6220.720067596969, 6223.595466294676, 5987.550933679991, 6034.094885767082, 6114.601521188728, 6124.319907527303, 6144.0, 6035.275766907911, 6120.883718150473, 6178.135061091451, 6178.814635560941, 6190.102435146969, 4812.759362031955, 4828.903421931542, 4836.322914791932, 4840.201366774443, 4896.902061958741, 4323.156984388077, 4338.609525142752, 4353.0423306872435, 4361.323994780814, 4368.36658684489, 5577.6653400796085, 5710.771569137271, 5715.885682286331, 5731.226284451629, 5758.481736643722, 6190.442860142609, 6227.1282020517165, 6262.060690896402, 6271.153394291803, 6301.464375528131, 5744.770209191585, 5756.107803290497, 5757.596968212269, 5764.279465066867, 5782.826515204551, 5208.583313334924, 5286.101362768339, 5289.47105289468, 5379.919301210485, 5387.757550910218, 6310.684465776692, 6322.652254126067, 6325.873482530324, 6331.493480521547, 6345.8730825384155, 4884.8290309838985, 5405.567554595545, 6216.285127210248, 6249.468795152281, 6265.4361107500545, 6196.566240363131, 6234.345393733593, 6264.718087685974, 6302.810915672537, 6306.180196574416, 6071.574989750675, 6103.481204097701, 6106.3588323227195, 6108.827782940854, 6137.754489820357, 6105.730467933617, 6154.415330543591, 6199.659018754011, 6205.472534834409, 6218.315985241529, 5789.594728368974, 5848.298204215522, 5907.734151963086, 6045.183900173596, 6074.939250607437, 5926.585139040226, 5930.28836539618, 5946.108083787466, 5995.250593675791, 6007.669578173242, 5810.709464526842, 5831.475167234969, 5842.1820945066875, 5868.031774757117, 5875.529957603467, 5486.835394938158, 5884.323302820059, 5965.6718880461995, 5970.76116955317, 5985.581009329305, 5854.297484301911, 5857.912131318119, 5883.235179426647, 5884.852878678067, 5887.264091988502], "value": 5600.916615388442, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.69851438394164, 516.9069567477852, 530.1570502900389, 539.7759929629202, 566.365670449097, 26558.6016209757, 41460.17079658405, 42614.57385426145, 42756.786216068955, 44470.11059778802, 42977.0, 43319.566804331946, 43324.566754332445, 43449.78275108628, 43547.56452435475, 9539.379940303892, 9735.269854760885, 9736.026397360243, 9741.0, 9748.75628109296, 9559.95220023899, 9598.280128990318, 9608.951955240214, 9631.988641192678, 9665.516724163763, 9659.275554333419, 9701.320907536476, 9709.271804614647, 9709.465979371133, 9726.70819875405, 9578.0, 9613.279004074688, 9644.662436814679, 9651.613935442574, 9677.419354838661, 9480.288978326653, 9505.239580833517, 9569.856452153153, 9601.519924003773, 9636.42181469114, 32534.6746532534, 36081.27837443263, 39122.804385978176, 39621.20757584861, 39716.40425393621, 3083.6607973122937, 3144.8113113213053, 3174.841257937094, 3352.7988320700592, 3577.678008979204, 28735.137945861657, 28747.712522874714, 29161.0, 29369.559456608167, 30259.546106808415, 2216.4348091236793, 2728.3451971526692, 2788.665360156794, 2797.132888804457, 2850.144956513038, 502499.0, 511563.0, 512146.0, 512489.0, 515305.9999999963, 34767.82616086904, 36066.8196659015, 62600.68699656473, 63576.0, 63733.68133159305, 36761.632383675824, 36884.63115368812, 36911.63088369136, 37101.44347834784, 37163.628363716016, 15084.472043478425, 15105.773413398807, 15247.923760381349, 15257.923710381378, 15267.923660381626, 12797.168184068192, 12843.743125137446, 12880.677983050311, 12898.419571119322, 12919.418626161845, 8145.755627331189, 8150.551719655476, 8160.102388737234, 8209.753707388787, 8224.588770561448, 3410.522526846194, 3429.879954201591, 3440.2947395783804, 3446.3968805458944, 3476.860925562949, 869.5087275689216, 960.7021823234819, 997.7306127345721, 1041.2718677066134, 1074.2909679611575, 24897.875510622693, 27900.441991160063, 29875.551866722017, 29996.85001574978, 30029.69970300269, 1065.1851333729703, 1112.4993752811267, 1132.5752842683994, 1189.4052973513244, 1252.267423557211, 15134.545963621109, 15279.159646219343, 15317.157556334285, 15527.611809704847, 15548.689026219412, 654018.0, 659587.0, 662593.0, 669554.0, 674935.0, 58644.12033819496, 58754.0, 59350.70324648436, 59363.109553356735, 59377.70311148417, 1266.9873301266869, 1439.172475826404, 1444.4294503670992, 1470.6029372069481, 1471.925494389102, 29197.708022919498, 29230.123096307147, 29263.85368073146, 29733.553996689636, 29841.403171936872, 50206.0, 50513.24230136477, 50737.492625073275, 50748.49251507438, 51039.489605103474, 450693.0, 457564.0, 465716.0, 466383.0, 467612.0, 26498.867505662347, 26732.465350693266, 26830.865845670647, 26838.0, 26863.462730745665, 44639.33041004323, 45187.54812451833, 45701.314480282155, 45778.54221457743, 46073.53926460827, 37300.0, 37557.43663845099, 37620.0, 38211.235775283785, 38399.616003839605, 36781.816090919376, 37001.629983699815, 37314.0, 37753.81123094367, 38174.427383588714, 26492.205233843015, 34890.82554587211, 37072.81463592665, 37153.07117322089, 37619.0, 26173.68134257826, 29594.0, 32487.0, 33209.0, 33342.83328583342, 24303.75696243015, 24504.142355017488, 24678.766061696486, 24988.0, 25073.749262507143], "value": 18405.240579818277, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.4040786591419, 347.53430403259625, 382.49892640640763, 393.3941729736205, 409.6149619357805, 15902.0, 19423.32018379357, 20316.984150792476, 20987.895060524676, 20997.47506312341, 20679.172833086657, 20720.792792072076, 21086.156553737834, 21101.99999999996, 21661.783382166173, 4909.803607855682, 5941.792037278696, 6036.758529658809, 6131.9386806131815, 6186.783462578811, 6007.849803754897, 6021.608595441304, 6031.4270144336215, 6055.424734650202, 6088.847778805543, 5767.538596912258, 5992.34084250732, 5995.430434108753, 6049.304330002062, 6078.149059131725, 5955.702214889281, 6030.035194368902, 6049.848753781147, 6050.909236361458, 6077.361877002917, 4979.651424400294, 5244.39689435716, 5307.0, 5331.626786124973, 5395.568354531628, 13127.86872131276, 13476.056676032682, 13651.317434128254, 13719.314034298344, 13726.519571814939, 2010.5624478497944, 2150.537634408594, 2298.8735619540917, 2319.0491898321607, 2697.131677322432, 7897.763067107996, 9550.140487356102, 10008.0, 10197.541110650038, 10283.177345812317, 1608.1878651281145, 1684.3683618643008, 1727.0587529796167, 1734.5056658852166, 1748.9300427982866, 110590.0, 128922.0, 130959.03561446586, 131530.99999999904, 132201.0, 10729.83905241422, 10935.234533582572, 11199.66401007971, 11902.88097119035, 11999.700007499883, 15018.774718379233, 15062.096274223582, 15070.02044867102, 15079.321430535652, 15096.24518774079, 8542.060373358925, 8588.785280367916, 8595.226429621363, 8668.176523230282, 8699.0, 8403.41176117666, 8416.537090460084, 8417.663293468193, 8500.0, 8510.74467765968, 4589.380433641482, 4604.723716577016, 4647.55848194421, 4669.95330046702, 4724.834630787906, 1664.8085470171086, 1676.8742344324226, 1937.8062193780509, 2004.2183548416128, 2007.879527228371, 901.9278457723366, 935.9812803743887, 995.4027583450016, 1009.7630402756711, 1068.3643232276784, 20680.862552559454, 20732.963351832346, 20830.791692082887, 21041.789582103982, 21106.261280855095, 934.1265916368218, 956.8038552096804, 995.5918073589794, 1022.5091955861228, 1024.912882404989, 10435.16518678504, 10841.728956775985, 10956.936139857205, 11029.724256893642, 11061.668149955514, 624475.0, 628215.0, 631631.0, 637069.0, 647092.0, 147260.0, 149257.0, 153518.23240883724, 158310.0, 158618.0, 908.6592527802075, 1056.4400867540062, 1069.3904474449644, 1070.8286674132257, 1076.3541874875011, 16228.756568651715, 18177.272909083487, 18594.163262652943, 18741.718874216625, 19036.861177058603, 49605.751971239915, 49627.50372496229, 50679.74660126676, 52652.47347526629, 53063.46936530585, 67800.0, 75112.87330690153, 76149.23850761644, 76647.61676191584, 77870.2212977863, 7062.823429414306, 8046.235607617267, 8064.032316122103, 8114.83770324602, 8190.7133250335955, 9069.455832650063, 12399.442025108712, 12480.313582753035, 12702.555410560586, 12903.741925161632, 10595.470226488798, 10872.0, 11098.112151028059, 11214.439278036229, 11260.94369528147, 9154.221891139061, 9867.358621689567, 10759.89240107589, 11034.400011998376, 11036.779264414507, 10992.450377481255, 11024.338539687644, 11058.889411106107, 11463.197576169589, 11477.0, 8071.192880711882, 8148.22591853773, 8204.466709663853, 8228.136045715353, 8314.708985185489, 11102.61140860066, 11259.211855170059, 11292.7741445169, 11345.48945297447, 11376.94311528437], "value": 9425.852545779306, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1574.2522301906592, 1611.7743515907775, 1746.3538490758422, 1828.387490190786, 1830.5423644088987, 7309.597972111532, 8117.431779775403, 8764.649414023439, 9259.861102083465, 9283.860742088875, 7696.807579810494, 8865.867011994826, 8918.0, 9118.270538356932, 9124.178823905848, 2418.7581241875846, 2439.1828737373003, 2451.656768052474, 2455.5702752018415, 2456.705195376557, 2423.260905423845, 2428.429319110008, 2460.507898420314, 2464.1375518568448, 2472.530219258344, 2419.2258477287273, 2428.3200703802963, 2437.098273638756, 2438.5976313908186, 2454.202384225125, 2422.8061755059553, 2438.1953955194745, 2444.0956845967016, 2444.5477586646525, 2457.1522824625476, 2437.305367970138, 2441.8779061046876, 2443.2548072837776, 2444.352246654638, 2453.4479742058074, 9012.594433250453, 9169.862452063224, 9175.311851611155, 9176.31177661678, 9177.632894684271, 5397.892042159174, 5566.552696298951, 5573.498385145316, 5576.386597474275, 5599.244102046212, 3551.5915669698056, 3558.484019817138, 3566.8751593694356, 3696.9648498420484, 3701.5002974598624, 824.8309096635236, 989.0, 1029.67050543826, 1050.1598721023227, 1072.3083611070815, 10781.407022613754, 12039.699007524794, 12085.033197344279, 12126.878731212662, 12176.26942383451, 4801.783919723621, 4840.491748366441, 4896.902061958812, 4913.434954980223, 4915.0, 10265.230107741949, 10284.382937023802, 10298.073173414428, 10302.227332950059, 10370.533326000348, 5399.082156033483, 5471.945280547144, 5484.808031718871, 5548.805791797267, 5582.33012038558, 4504.189245935763, 4841.443234028062, 4981.128302547112, 5018.824341148043, 5074.898502029939, 3435.4675025371107, 4099.0, 4105.414660777222, 4146.523149837747, 4182.581741825854, 1360.156702844243, 1363.9795403068963, 1372.7872179811945, 1382.7441923244232, 1388.5001399496375, 815.3029160068174, 927.6057675487891, 962.6197651927449, 964.7877466957258, 983.7343917142332, 5087.745612719349, 6014.609050411709, 6130.1724267224245, 6140.662263575457, 6250.9062364064575, 392.7839688171494, 463.1131383400817, 473.97630118493936, 475.3582663404446, 482.55845901000896, 3951.38753493209, 4057.046594050396, 4059.5331536873045, 4105.055837157471, 4163.542010378856, 10634.361938283573, 10963.83554246687, 10995.505202265756, 11149.99650031501, 11165.83251251232, 8866.822663546693, 8999.235065019415, 10639.680809575726, 10975.560977560808, 11089.445527723581, 7922.326602238874, 8240.299574536111, 8357.707480238163, 8390.496570205807, 8438.367122465717, 5340.49265319794, 6083.178770866053, 6109.11417844419, 6233.501319894307, 6270.843228919313, 8979.865302020344, 10142.89857101449, 10287.125594324267, 10418.166546676399, 10442.738931526772, 10132.898671013196, 10324.174066074846, 10532.104771094542, 10596.099331556905, 10634.52144653508, 7015.473839461959, 7530.924690753022, 7692.730754423568, 7796.181400952818, 7845.6077196141105, 7082.468814838805, 8656.78358041054, 8667.306615470874, 8724.956375218337, 8738.432001919855, 7571.810704732426, 7588.734394296173, 7675.4243431741725, 7683.692652293846, 7844.9607751960875, 7476.439267054884, 7487.663055162422, 7543.924560754323, 7636.885446718415, 7696.653650585625, 7153.82115447118, 7236.819079523054, 7249.673764680497, 7263.636818159177, 7263.782086537412, 4577.7482238477205, 5059.418166910852, 5133.178691409356, 5149.588032957429, 5152.8148525838515, 4702.965347623517, 5029.64792464524, 5054.0, 5138.665986710852, 5163.173892177234], "value": 4598.159296798287, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.69793830909384, 319.5207189216176, 321.72974701250945, 327.53980657176675, 330.0478120622005, 4616.907661846761, 5384.030874442604, 5600.5799565032685, 5721.370649228592, 5748.971255143723, 5057.645964782467, 5188.792448302063, 5552.86117847053, 5612.466815652517, 5635.365743934264, 3715.8109404990414, 4056.553779084306, 4083.3262511685534, 4118.588141185873, 4260.914781704364, 3543.7696549724187, 4145.689073319498, 4156.896077598054, 4188.560201178878, 4226.640735537483, 3978.482797236355, 4165.500139983206, 4200.978995105035, 4201.831926722927, 4214.473190851144, 3695.8891233263043, 4056.5943405659345, 4235.195312890556, 4239.512456067561, 4250.702450828443, 3456.4988076728987, 3487.860485580574, 3563.233904710474, 3660.5058317127114, 3685.4840322354894, 4808.374911261513, 5272.362070171607, 5275.314209152824, 5330.440303768088, 5337.332833395825, 3959.4456776051375, 4023.1752490739327, 4898.5591296783105, 4908.0429316283335, 4916.63125265603, 5856.824295271149, 5886.1465087562065, 5895.174675545427, 5900.675462849542, 5912.763489460416, 3258.283177700902, 3614.331348700499, 4061.5378463753027, 4089.284375234322, 4114.7119701620895, 5569.637973531708, 5669.44090375146, 5731.656100633933, 5772.624779389368, 6241.875162496815, 3538.80594031702, 3689.6125906779403, 3827.4067519534515, 4015.4980627421573, 4034.1528279061727, 3262.2546937388383, 3307.1566750478955, 3527.765282151278, 3897.6881849451975, 4116.958830411718, 4064.227796718614, 4370.38814565961, 4646.930296045562, 4712.30357071454, 4769.213079841857, 4686.414198225222, 5099.133147364957, 5440.836774896759, 5609.943900561024, 6005.369436209223, 1200.8258802473592, 1361.5166615851429, 1402.2708191740237, 1403.7824137258742, 1407.2541552976938, 717.7129148340706, 793.6587267475041, 806.9066415007698, 843.1652663862732, 872.6814712629845, 4990.451050384455, 5178.275041494194, 5333.813316533902, 5442.292501974718, 5479.780808767605, 4794.472608013116, 5435.021696094662, 6009.609375390585, 6037.547183961133, 6038.456538911431, 4609.769511524478, 4816.397950256218, 5189.080040385006, 5408.891822163535, 5616.550675945915, 2645.550256456407, 2753.435545713124, 3217.2761128745847, 3220.0500852248642, 3228.951565726469, 5031.471695471996, 6205.913965056097, 6251.78118765841, 6271.9686401568615, 6335.275831392545, 5137.640365174477, 5484.122540393518, 6062.969295219823, 6370.490360771128, 6377.872442551123, 5527.778888844482, 6429.903551446732, 6472.0843889446205, 6488.805335840027, 6519.207218014989, 5398.946010539844, 5845.327787304344, 5918.201042859159, 6123.540734445023, 6150.354212807591, 5221.582273418194, 5228.45101264377, 5393.514583687487, 5634.887302254014, 5878.2358293421585, 5218.452062533379, 5778.884422311614, 6059.969700151471, 6184.289426344158, 6382.68086595659, 3922.725409221327, 4036.688076375131, 4047.3119569672635, 4072.3484242521054, 4072.6741860650486, 5040.227874668844, 6081.331053584191, 6179.0, 6194.721237544411, 6209.733447291796, 4952.950470495393, 5113.775507654183, 5148.948510514847, 5167.0, 5915.58590898633, 5825.79609713658, 5896.469317761251, 5909.261342332208, 5910.261217347832, 5931.258592675916, 4881.316615673737, 5271.499207575274, 5804.6807425592, 5805.777793324745, 5837.562182836219, 4603.930941035954, 5949.702514874152, 5965.642061476326, 5995.250593675791, 6017.608855424383, 5709.400512946081, 5768.480836724715, 5858.179854820243, 5875.764969401347, 5890.852728681817], "value": 4202.014266677249, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.7789740436954, 662.6256165266624, 727.6907314391383, 742.6435311050694, 761.4859969520572, 15004.849951500482, 15204.0, 15472.149031803261, 15494.520876659737, 15618.297176627051, 11807.232529885552, 12207.51169953203, 14007.719845603082, 14422.639434014154, 14471.927640361784, 725.3435640745134, 891.8038031633063, 895.1809094678362, 916.1983264643437, 928.8885333759958, 890.9955450222773, 897.1477096758073, 923.9122283383068, 925.2736601767614, 928.3547934185743, 948.2793077261297, 977.867987821642, 978.0659470205936, 979.5053497983508, 980.1913421427315, 937.2267878999818, 937.2689302344148, 948.7628092976756, 968.0658164870863, 971.7522031881861, 703.43022152057, 718.6119495472439, 719.3022767915166, 719.4783781758235, 721.8772808622547, 14818.555443336718, 14925.552233433014, 14971.0, 15014.47449339268, 15022.624434389229, 10424.947875260576, 12432.813507797391, 12556.937215313956, 12571.874281257162, 12757.170783899017, 9768.853467197998, 9834.655787047419, 10047.849282260771, 10319.690409287734, 10525.84211236832, 3013.9246518837203, 3437.071990562559, 3442.3631628148873, 3473.1838018065732, 3556.5376501054957, 79470.0, 79678.60160699218, 79719.60140199262, 80577.59711201406, 81394.0, 9460.57427415768, 9473.242140628734, 9544.61821527145, 9773.169280611228, 9778.119969202806, 21757.782422175576, 21949.122035118737, 21956.341309760734, 21987.0, 22118.557628847655, 7337.19290878003, 7338.41292696583, 7484.998682568677, 7519.135299440634, 7644.847103057908, 3938.3895496198134, 4009.3585026395635, 4010.6791456683395, 4013.979930100331, 4014.0968282136287, 3484.303139372136, 3515.296940611837, 3578.7315951303235, 3580.785152890835, 3594.5866225383893, 618.5731845026917, 625.1997443272528, 630.168178005029, 638.1512588257606, 640.9871802563922, 431.7582153993774, 499.5332393191241, 518.2977066075489, 567.0926517571852, 601.1073555769678, 7765.805854853674, 7837.921620783719, 8138.104808471064, 8257.545834979133, 8364.707235246737, 507.061935419471, 555.5971920357708, 566.1620801214144, 589.7316720891984, 590.3004939147039, 3360.277540328841, 3729.813509324523, 3872.9225415492097, 3879.553851307079, 3902.2585708715255, 78010.0, 78516.0, 79156.60421697855, 79244.0, 88752.55623721969, 75548.0, 75557.0, 76371.0, 77238.6138069306, 81091.0, 70333.0, 70397.94403083957, 70672.64663676752, 70990.58018839594, 71142.0, 6266.279377871603, 7650.622887880235, 7843.137254901842, 7856.37149028065, 7864.252895974873, 41336.0, 42088.3686744693, 42541.7237482876, 43209.0, 43968.78015609901, 50061.749691252764, 56989.43010569841, 61556.384436155065, 61896.76206475935, 61907.071393930026, 9714.611415543299, 9983.700488985342, 10115.79768404613, 10479.0, 10531.89468105309, 10961.451927403758, 10993.285436446606, 11208.719782005515, 11269.7746045077, 11430.48562814692, 9447.905520944978, 9476.431414115175, 10304.742381440525, 10601.204909631655, 10617.628383006559, 8946.821063578562, 8954.776130596787, 8996.50519221449, 10133.949330253301, 10153.39079655223, 8919.795827563456, 10149.441780701836, 10301.175905927357, 10317.587296508056, 10430.53062612169, 9075.682351117679, 9087.000429952568, 9270.582823773082, 9351.766205844637, 9357.672481463116, 8967.865482017905, 8974.83327167464, 9016.053314402156, 9025.954870225607, 9060.773480662772], "value": 6622.844377924214, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1661.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 139.0, 107.0, 116.0, 121.0, 106.0, 134.0, 163.0, 171.0, 172.0], "value": 134.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 153.0, 133.0, 191.0, 137.0, 172.0, 158.0, 152.0, 205.0, 127.0], "value": 162.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 246.0, 286.0, 225.0, 195.0, 230.0, 199.0, 204.0, 206.0, 196.0], "value": 219.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 82.0, 76.0, 180.0, 49.0, 54.0, 63.0, 101.0, 155.0, 86.0], "value": 91.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 88.0, 154.0, 77.0, 80.0, 81.0, 120.0, 81.0, 81.0, 121.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 90.0, 87.0, 89.0, 88.0, 90.0, 87.0, 90.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 92.0, 95.0, 89.0, 133.0, 151.0, 106.0, 153.0, 149.0], "value": 115.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 98.0, 115.0, 116.0, 98.0, 99.0, 127.0, 146.0, 92.0], "value": 108.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 65.0, 63.0, 60.0, 62.0, 60.0, 60.0, 124.0, 64.0, 61.0], "value": 67.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 99.0, 94.0, 69.0, 82.0, 75.0, 69.0, 67.0, 66.0, 70.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 85.0, 110.0, 85.0, 85.0, 74.0, 98.0, 111.0, 107.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 136.0, 136.0, 138.0, 136.0, 137.0, 139.0, 208.0, 138.0], "value": 144.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 227.0, 229.0, 174.0, 172.0, 177.0, 204.0, 232.0, 177.0, 208.0], "value": 198.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 64.0, 67.0, 67.0, 65.0, 65.0, 65.0, 69.0, 65.0], "value": 65.9, "unit": "score"}]}]} 05:55:46 INFO - Return code: 0 05:55:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:55:46 ERROR - # TBPL SUCCESS # 05:55:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:55:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:55:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:55:46 INFO - 2016-05-12 05:55:46.164 screenresolution[1957:20936] starting screenresolution argv=screenresolution get 05:55:46 INFO - 2016-05-12 05:55:46.167 screenresolution[1957:20936] Display 0: 1024x768x32@60 05:55:46 INFO - 2016-05-12 05:55:46.173 screenresolution[1958:20939] starting screenresolution argv=screenresolution list 05:55:46 INFO - Available Modes on Display 0 05:55:46 INFO - 1024x768x32@60 800x600x32@75 800x600x32@60 05:55:46 INFO - 640x480x32@75 640x480x32@60 Graphics/Displays: 05:55:46 INFO - Intel Iris: 05:55:46 INFO - Chipset Model: Intel Iris 05:55:46 INFO - Type: GPU 05:55:46 INFO - Bus: Built-In 05:55:46 INFO - VRAM (Dynamic, Max): 1536 MB 05:55:46 INFO - Vendor: Intel (0x8086) 05:55:46 INFO - Device ID: 0x0a2e 05:55:46 INFO - Revision ID: 0x0009 05:55:46 INFO - Displays: 05:55:46 INFO - Display: 05:55:46 INFO - Resolution: 1024 x 768 @ 60 Hz 05:55:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:55:46 INFO - Display Serial Number: 1024x768 60 05:55:46 INFO - Main Display: Yes 05:55:46 INFO - Mirror: Off 05:55:46 INFO - Online: Yes 05:55:46 INFO - Rotation: Supported 05:55:46 INFO - Return code: 0 05:55:46 INFO - Running post-action listener: _resource_record_post_action 05:55:46 INFO - ##### 05:55:46 INFO - ##### Finished run-tests step (success) 05:55:46 INFO - ##### 05:55:46 INFO - Running post-run listener: _resource_record_post_run 05:55:47 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 622592; Write bytes: 696343552; Read time: 5; Write time: 1908 05:55:47 INFO - TinderboxPrint: CPU usage
54.3% 05:55:47 INFO - TinderboxPrint: I/O read bytes / time
622,592 / 5 05:55:47 INFO - TinderboxPrint: I/O write bytes / time
696,343,552 / 1,908 05:55:47 INFO - TinderboxPrint: CPU idle
2,214.8 (45.7%) 05:55:47 INFO - TinderboxPrint: CPU system
975.9 (20.1%) 05:55:47 INFO - TinderboxPrint: CPU user
1,655.1 (34.2%) 05:55:47 INFO - TinderboxPrint: Swap in / out
394,526,720 / 0 05:55:47 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 387992064; Write bytes: 382289408; Read time: 29044; Write time: 766 05:55:47 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 622592; Write bytes: 298853888; Read time: 5; Write time: 1098 05:55:47 INFO - Running post-run listener: _upload_blobber_files 05:55:47 INFO - Blob upload gear active. 05:55:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:55:47 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:55:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:55:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:55:47 INFO - (blobuploader) - INFO - Open directory for files ... 05:55:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:55:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:55:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:55:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:55:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:55:48 INFO - (blobuploader) - INFO - Done attempting. 05:55:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:55:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:55:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:55:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:55:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:55:50 INFO - (blobuploader) - INFO - Done attempting. 05:55:50 INFO - (blobuploader) - INFO - Iteration through files over. 05:55:50 INFO - Return code: 0 05:55:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:55:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:55:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b23da18ca4f711a3ec6dae95fefda264dad15fecca94367e0a6f4b43ad090292da0673e48a327795b6ed860473a9c3cb1ecc039fedd11db80720e9174cfb6fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59d90a16e9354a6686b1e1805c27ac761cff0ed769b3545e8fa4a936eef1a6faa2a3888db586c21b4796674eac5e8963afc57c58827ab678a80b00a7b7165b08"} 05:55:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:55:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:55:50 INFO - Contents: 05:55:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b23da18ca4f711a3ec6dae95fefda264dad15fecca94367e0a6f4b43ad090292da0673e48a327795b6ed860473a9c3cb1ecc039fedd11db80720e9174cfb6fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59d90a16e9354a6686b1e1805c27ac761cff0ed769b3545e8fa4a936eef1a6faa2a3888db586c21b4796674eac5e8963afc57c58827ab678a80b00a7b7165b08"} 05:55:50 INFO - Running post-run listener: copy_logs_to_upload_dir 05:55:50 INFO - Copying logs to upload dir... 05:55:50 INFO - mkdir: /builds/slave/test/build/upload/logs 05:55:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1295.900477 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-05-12 05:55:50.282683) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 05:55:50.286162) ========= 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.NrCsm7KkWm/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.YoseALi28j/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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/Fx-Team/sha512/4b23da18ca4f711a3ec6dae95fefda264dad15fecca94367e0a6f4b43ad090292da0673e48a327795b6ed860473a9c3cb1ecc039fedd11db80720e9174cfb6fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59d90a16e9354a6686b1e1805c27ac761cff0ed769b3545e8fa4a936eef1a6faa2a3888db586c21b4796674eac5e8963afc57c58827ab678a80b00a7b7165b08"} build_url:https://queue.taskcluster.net/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011263 build_url: 'https://queue.taskcluster.net/v1/task/awJQdXjGRt2R3XLvsayZbA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b23da18ca4f711a3ec6dae95fefda264dad15fecca94367e0a6f4b43ad090292da0673e48a327795b6ed860473a9c3cb1ecc039fedd11db80720e9174cfb6fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59d90a16e9354a6686b1e1805c27ac761cff0ed769b3545e8fa4a936eef1a6faa2a3888db586c21b4796674eac5e8963afc57c58827ab678a80b00a7b7165b08"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 05:55:50.314141) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:55:50.314445) ========= 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.NrCsm7KkWm/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.YoseALi28j/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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.005039 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:55:50.366925) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:55:50.367370) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:55:50.367870) ========= ========= Total master_lag: 0.21 =========