builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0036 starttime: 1460703635.89 results: success (0) buildid: 20160414215914 builduid: df1f749045324d89b1c656357ec84319 revision: 5a88d4e4b4e0a7906202a157dd1c30c3dfcd6816 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 00:00:35.895100) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 00:00:35.895585) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 00:00:35.895891) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 00:00:35.913862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 00:00:35.914256) ========= 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.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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-04-15 00:00:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.1M=0.001s 2016-04-15 00:00:36 (10.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094202 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 00:00:36.025448) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 00:00:36.025787) ========= 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.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.020527 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 00:00:36.074845) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 00:00:36.075205) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5a88d4e4b4e0a7906202a157dd1c30c3dfcd6816 --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 5a88d4e4b4e0a7906202a157dd1c30c3dfcd6816 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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-04-15 00:00:36,141 truncating revision to first 12 chars 2016-04-15 00:00:36,141 Setting DEBUG logging. 2016-04-15 00:00:36,141 attempt 1/10 2016-04-15 00:00:36,141 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5a88d4e4b4e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-15 00:00:37,333 unpacking tar archive at: fx-team-5a88d4e4b4e0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.431803 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 00:00:37.524191) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 00:00:37.524519) ========= script_repo_revision: 5a88d4e4b4e0a7906202a157dd1c30c3dfcd6816 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 00:00:37.524947) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 00:00:37.525253) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 00:00:37.539238) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-15 00:00:37.539662) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 00:00:37 INFO - MultiFileLogger online at 20160415 00:00:37 in /builds/slave/test 00:00:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 00:00:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:00:37 INFO - {'append_to_log': False, 00:00:37 INFO - 'base_work_dir': '/builds/slave/test', 00:00:37 INFO - 'blob_upload_branch': 'Fx-Team', 00:00:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:00:37 INFO - 'branch': 'Fx-Team', 00:00:37 INFO - 'buildbot_json_path': 'buildprops.json', 00:00:37 INFO - 'config_files': ('talos/mac_config.py',), 00:00:37 INFO - 'default_actions': ('clobber', 00:00:37 INFO - 'read-buildbot-config', 00:00:37 INFO - 'download-and-extract', 00:00:37 INFO - 'populate-webroot', 00:00:37 INFO - 'create-virtualenv', 00:00:37 INFO - 'install', 00:00:37 INFO - 'run-tests'), 00:00:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:00:37 INFO - 'download_minidump_stackwalk': True, 00:00:37 INFO - 'download_symbols': 'ondemand', 00:00:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:00:37 INFO - 'tooltool.py': '/tools/tooltool.py', 00:00:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:00:37 INFO - '/tools/misc-python/virtualenv.py')}, 00:00:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:00:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:00:37 INFO - 'installer_path': 'installer.exe', 00:00:37 INFO - 'log_level': 'info', 00:00:37 INFO - 'log_name': 'talos', 00:00:37 INFO - 'log_to_console': True, 00:00:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:00:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:00:37 INFO - 'opt_config_files': (), 00:00:37 INFO - 'pip_index': False, 00:00:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:00:37 INFO - 'cmd': ('bash', 00:00:37 INFO - '-c', 00:00:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:00:37 INFO - 'enabled': True, 00:00:37 INFO - 'halt_on_failure': False, 00:00:37 INFO - 'name': 'check_screen_resolution'},), 00:00:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:00:37 INFO - 'cmd': ('bash', 00:00:37 INFO - '-c', 00:00:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:00:37 INFO - 'enabled': True, 00:00:37 INFO - 'halt_on_failure': False, 00:00:37 INFO - 'name': 'check_screen_resolution'},), 00:00:37 INFO - 'run_cmd_checks_enabled': True, 00:00:37 INFO - 'sps_profile': False, 00:00:37 INFO - 'sps_profile_interval': 0, 00:00:37 INFO - 'suite': 'dromaeojs', 00:00:37 INFO - 'system_bits': '32', 00:00:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:00:37 INFO - 'title': 't-yosemite-r7-0036', 00:00:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:00:37 INFO - 'use_talos_json': True, 00:00:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:00:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:00:37 INFO - 'work_dir': 'build'} 00:00:37 INFO - ##### 00:00:37 INFO - ##### Running clobber step. 00:00:37 INFO - ##### 00:00:37 INFO - Running pre-action listener: _resource_record_pre_action 00:00:37 INFO - Running main action method: clobber 00:00:37 INFO - rmtree: /builds/slave/test/build 00:00:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:00:39 INFO - Running post-action listener: _resource_record_post_action 00:00:39 INFO - ##### 00:00:39 INFO - ##### Running read-buildbot-config step. 00:00:39 INFO - ##### 00:00:39 INFO - Running pre-action listener: _resource_record_pre_action 00:00:39 INFO - Running main action method: read_buildbot_config 00:00:39 INFO - Using buildbot properties: 00:00:39 INFO - { 00:00:39 INFO - "project": "", 00:00:39 INFO - "product": "firefox", 00:00:39 INFO - "script_repo_revision": "production", 00:00:39 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 00:00:39 INFO - "repository": "", 00:00:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 00:00:39 INFO - "buildid": "20160414215914", 00:00:39 INFO - "pgo_build": "False", 00:00:39 INFO - "basedir": "/builds/slave/test", 00:00:39 INFO - "buildnumber": 660, 00:00:39 INFO - "slavename": "t-yosemite-r7-0036", 00:00:39 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 00:00:39 INFO - "platform": "yosemite_r7", 00:00:39 INFO - "scheduler": "tests-fx-team-macosx64-talos", 00:00:39 INFO - "branch": "fx-team", 00:00:39 INFO - "repo_path": "integration/fx-team", 00:00:39 INFO - "revision": "5a88d4e4b4e0a7906202a157dd1c30c3dfcd6816", 00:00:39 INFO - "stage_platform": "macosx64", 00:00:39 INFO - "builduid": "df1f749045324d89b1c656357ec84319", 00:00:39 INFO - "slavebuilddir": "test" 00:00:39 INFO - } 00:00:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 00:00:39 INFO - Running post-action listener: _resource_record_post_action 00:00:39 INFO - ##### 00:00:39 INFO - ##### Running download-and-extract step. 00:00:39 INFO - ##### 00:00:39 INFO - Running pre-action listener: _resource_record_pre_action 00:00:39 INFO - Running main action method: download_and_extract 00:00:39 INFO - mkdir: /builds/slave/test/build/tests 00:00:39 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')]} 00:00:39 INFO - https://queue.taskcluster.net/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 00:00:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 00:00:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 00:00:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 00:00:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 00:00:40 INFO - Downloaded 1199 bytes. 00:00:40 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 00:00:40 INFO - Using the following test package requirements: 00:00:40 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 00:00:40 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 00:00:40 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 00:00:40 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 00:00:40 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 00:00:40 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 00:00:40 INFO - u'jsshell-mac.zip'], 00:00:40 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 00:00:40 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 00:00:40 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 00:00:40 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 00:00:40 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 00:00:40 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 00:00:40 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 00:00:40 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 00:00:40 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 00:00:40 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 00:00:40 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 00:00:40 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 00:00:40 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')]} 00:00:40 INFO - https://queue.taskcluster.net/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:00:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 00:00:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 00:00:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 00:00:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:00:41 INFO - Downloaded 18031252 bytes. 00:00:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 00:00:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 00:00:44 INFO - Return code: 0 00:00:44 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 00:00:44 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')]} 00:00:44 INFO - https://queue.taskcluster.net/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:00:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 00:00:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 00:00:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 00:00:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:00:45 INFO - Downloaded 18031252 bytes. 00:00:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 00:00:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 00:00:49 INFO - Return code: 0 00:00: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')]} 00:00:49 INFO - https://queue.taskcluster.net/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 00:00:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 00:00:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 00:00:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 00:00:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 00:00:50 INFO - Downloaded 10841076 bytes. 00:00:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 00:00:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 00:00:50 INFO - Return code: 0 00:00:50 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')]} 00:00:50 INFO - https://queue.taskcluster.net/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 00:00:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 00:00:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 00:00:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 00:00:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 00:00:53 INFO - Downloaded 109836095 bytes. 00:00:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 00:00:53 INFO - mkdir: /builds/slave/test/properties 00:00:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:00:53 INFO - Writing to file /builds/slave/test/properties/build_url 00:00:53 INFO - Contents: 00:00:53 INFO - build_url:https://queue.taskcluster.net/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 00:00:54 INFO - Running post-action listener: _resource_record_post_action 00:00:54 INFO - Running post-action listener: set_extra_try_arguments 00:00:54 INFO - ##### 00:00:54 INFO - ##### Running populate-webroot step. 00:00:54 INFO - ##### 00:00:54 INFO - Running pre-action listener: _resource_record_pre_action 00:00:54 INFO - Running main action method: populate_webroot 00:00:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:00:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:00:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:00:54 INFO - '--tppagecycles', 00:00:54 INFO - '7'], 00:00:54 INFO - 'tests': ['tsvgm']}}, 00:00:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:00:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:00:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:00:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:00:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:00:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:00:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:00:54 INFO - 'talos_options': ['--disable-e10s'], 00:00:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:00:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:00:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:00:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:00:54 INFO - 'talos_options': ['--disable-e10s'], 00:00:54 INFO - 'tests': ['damp', 'tps']}, 00:00:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:00:54 INFO - 'tests': ['damp', 'tps']}, 00:00:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:00:54 INFO - 'tests': ['dromaeo_dom']}, 00:00:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:00:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:00:54 INFO - 'tests': ['a11yr', 00:00:54 INFO - 'ts_paint', 00:00:54 INFO - 'tpaint', 00:00:54 INFO - 'sessionrestore', 00:00:54 INFO - 'sessionrestore_no_auto_restore', 00:00:54 INFO - 'tabpaint']}, 00:00:54 INFO - 'other-e10s': {'tests': ['a11yr', 00:00:54 INFO - 'ts_paint', 00:00:54 INFO - 'tpaint', 00:00:54 INFO - 'sessionrestore', 00:00:54 INFO - 'sessionrestore_no_auto_restore', 00:00:54 INFO - 'tabpaint']}, 00:00:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:00:54 INFO - 'ts_paint', 00:00:54 INFO - 'tpaint', 00:00:54 INFO - 'sessionrestore', 00:00:54 INFO - 'sessionrestore_no_auto_restore', 00:00:54 INFO - 'tabpaint']}, 00:00:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:00:54 INFO - 'ts_paint', 00:00:54 INFO - 'tpaint', 00:00:54 INFO - 'sessionrestore', 00:00:54 INFO - 'sessionrestore_no_auto_restore', 00:00:54 INFO - 'tabpaint']}, 00:00:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:00:54 INFO - 'tests': ['a11yr', 00:00:54 INFO - 'ts_paint', 00:00:54 INFO - 'tpaint', 00:00:54 INFO - 'sessionrestore', 00:00:54 INFO - 'sessionrestore_no_auto_restore', 00:00:54 INFO - 'tabpaint']}, 00:00:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:00:54 INFO - 'tests': ['a11yr', 00:00:54 INFO - 'ts_paint', 00:00:54 INFO - 'tpaint', 00:00:54 INFO - 'sessionrestore', 00:00:54 INFO - 'sessionrestore_no_auto_restore', 00:00:54 INFO - 'tabpaint']}, 00:00:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:00:54 INFO - 'tests': ['tsvgx', 00:00:54 INFO - 'tsvgr_opacity', 00:00:54 INFO - 'tart', 00:00:54 INFO - 'tscrollx', 00:00:54 INFO - 'cart']}, 00:00:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:00:54 INFO - 'tsvgr_opacity', 00:00:54 INFO - 'tart', 00:00:54 INFO - 'tscrollx', 00:00:54 INFO - 'cart']}, 00:00:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:00:54 INFO - 'talos_options': ['--disable-e10s'], 00:00:54 INFO - 'tests': ['tp5o']}, 00:00:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:00:54 INFO - 'tests': ['tp5o']}, 00:00:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:00:54 INFO - 'talos_options': ['--disable-e10s', 00:00:54 INFO - '--xperf_path', 00:00:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:00:54 INFO - 'tests': ['tp5n']}, 00:00:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:00:54 INFO - 'talos_options': ['--xperf_path', 00:00:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:00:54 INFO - 'tests': ['tp5n']}}, 00:00:54 INFO - 'talos.zip': {'path': '', 00:00:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:00:54 INFO - Running post-action listener: _resource_record_post_action 00:00:54 INFO - ##### 00:00:54 INFO - ##### Running create-virtualenv step. 00:00:54 INFO - ##### 00:00:54 INFO - Running pre-action listener: _resource_record_pre_action 00:00:54 INFO - Running main action method: create_virtualenv 00:00:54 INFO - Creating virtualenv /builds/slave/test/build/venv 00:00:54 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 00:00:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:00:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:00:54 INFO - Using real prefix '/tools/python27' 00:00:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:00:55 INFO - Installing distribute.............................................................................................................................................................................................done. 00:00:58 INFO - Installing pip.................done. 00:00:58 INFO - Return code: 0 00:00:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:00:58 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')]} 00:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:00:58 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')]} 00:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:00:58 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 0x10feb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e29d8f5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd51e00>, '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 0x10fb7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd4cb20>, '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.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 00:00:58 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 00:00:58 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 00:00:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5ti9k2Rmf/Render', 00:00:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:00:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:00:58 INFO - 'HOME': '/Users/cltbld', 00:00:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:00:58 INFO - 'LOGNAME': 'cltbld', 00:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:00:58 INFO - 'MOZ_NO_REMOTE': '1', 00:00:58 INFO - 'NO_EM_RESTART': '1', 00:00:58 INFO - 'PAGER': '/bin/cat', 00:00:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:00:58 INFO - 'PWD': '/builds/slave/test', 00:00:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:00:58 INFO - 'SHELL': '/bin/bash', 00:00:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.57nMEgkZRZ/Listeners', 00:00:58 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 00:00:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:00:58 INFO - 'USER': 'cltbld', 00:00:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:00:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:00:58 INFO - 'XPC_FLAGS': '0x0', 00:00:58 INFO - 'XPC_SERVICE_NAME': '0', 00:00:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:00:58 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 00:00:58 INFO - Cleaning up... 00:00:58 INFO - Return code: 0 00:00:58 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 00:00:58 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')]} 00:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:00:58 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')]} 00:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:00:58 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 0x10feb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e29d8f5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd51e00>, '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 0x10fb7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd4cb20>, '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.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 00:00:58 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 00:00:58 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 00:00:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5ti9k2Rmf/Render', 00:00:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:00:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:00:58 INFO - 'HOME': '/Users/cltbld', 00:00:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:00:58 INFO - 'LOGNAME': 'cltbld', 00:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:00:58 INFO - 'MOZ_NO_REMOTE': '1', 00:00:58 INFO - 'NO_EM_RESTART': '1', 00:00:58 INFO - 'PAGER': '/bin/cat', 00:00:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:00:58 INFO - 'PWD': '/builds/slave/test', 00:00:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:00:58 INFO - 'SHELL': '/bin/bash', 00:00:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.57nMEgkZRZ/Listeners', 00:00:58 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 00:00:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:00:58 INFO - 'USER': 'cltbld', 00:00:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:00:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:00:58 INFO - 'XPC_FLAGS': '0x0', 00:00:58 INFO - 'XPC_SERVICE_NAME': '0', 00:00:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:00:59 INFO - Downloading/unpacking psutil>=0.7.1 00:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:00:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:00:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:01:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:01:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:01:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:01:01 INFO - Installing collected packages: psutil 00:01:01 INFO - Running setup.py install for psutil 00:01:02 INFO - building 'psutil._psutil_osx' extension 00:01:02 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 00:01:02 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 00:01:02 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 00:01:02 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 00:01:02 INFO - building 'psutil._psutil_posix' extension 00:01:02 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 00:01:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:01:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:01:02 INFO - ^ 00:01:02 INFO - 1 warning generated. 00:01:02 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 00:01:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:01:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:01:02 INFO - Successfully installed psutil 00:01:02 INFO - Cleaning up... 00:01:02 INFO - Return code: 0 00:01:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 00:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10feb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e29d8f5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd51e00>, '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 0x10fb7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd4cb20>, '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.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 00:01:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 00:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 00:01:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5ti9k2Rmf/Render', 00:01:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:01:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:01:02 INFO - 'HOME': '/Users/cltbld', 00:01:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:01:02 INFO - 'LOGNAME': 'cltbld', 00:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:01:02 INFO - 'MOZ_NO_REMOTE': '1', 00:01:02 INFO - 'NO_EM_RESTART': '1', 00:01:02 INFO - 'PAGER': '/bin/cat', 00:01:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:01:02 INFO - 'PWD': '/builds/slave/test', 00:01:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:01:02 INFO - 'SHELL': '/bin/bash', 00:01:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.57nMEgkZRZ/Listeners', 00:01:02 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 00:01:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:01:02 INFO - 'USER': 'cltbld', 00:01:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:01:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:01:02 INFO - 'XPC_FLAGS': '0x0', 00:01:02 INFO - 'XPC_SERVICE_NAME': '0', 00:01:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:01:03 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 00:01:03 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 00:01:03 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 00:01:03 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 00:01:03 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 00:01:03 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 00:01:03 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 00:01:05 INFO - Downloading mozsystemmonitor-0.0.tar.gz 00:01:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 00:01:05 INFO - Installing collected packages: mozsystemmonitor 00:01:05 INFO - Running setup.py install for mozsystemmonitor 00:01:05 INFO - Successfully installed mozsystemmonitor 00:01:05 INFO - Cleaning up... 00:01:05 INFO - Return code: 0 00:01:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10feb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e29d8f5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd51e00>, '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 0x10fb7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd4cb20>, '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.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 00:01:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 00:01:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5ti9k2Rmf/Render', 00:01:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:01:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:01:05 INFO - 'HOME': '/Users/cltbld', 00:01:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:01:05 INFO - 'LOGNAME': 'cltbld', 00:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:01:05 INFO - 'MOZ_NO_REMOTE': '1', 00:01:05 INFO - 'NO_EM_RESTART': '1', 00:01:05 INFO - 'PAGER': '/bin/cat', 00:01:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:01:05 INFO - 'PWD': '/builds/slave/test', 00:01:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:01:05 INFO - 'SHELL': '/bin/bash', 00:01:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.57nMEgkZRZ/Listeners', 00:01:05 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 00:01:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:01:05 INFO - 'USER': 'cltbld', 00:01:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:01:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:01:05 INFO - 'XPC_FLAGS': '0x0', 00:01:05 INFO - 'XPC_SERVICE_NAME': '0', 00:01:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:01:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:01:06 INFO - Downloading/unpacking blobuploader==1.2.4 00:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:01:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:01:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:01:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:01:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:01:08 INFO - Downloading blobuploader-1.2.4.tar.gz 00:01:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:01:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:01: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 00:01: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 00:01: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 00:01: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 00:01: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 00:01: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 00:01:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:01:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:01:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:01:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:01:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:01:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:01:09 INFO - Downloading docopt-0.6.1.tar.gz 00:01:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:01:09 INFO - Installing collected packages: blobuploader, requests, docopt 00:01:09 INFO - Running setup.py install for blobuploader 00:01:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:01:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:01:09 INFO - Running setup.py install for requests 00:01:10 INFO - Running setup.py install for docopt 00:01:10 INFO - Successfully installed blobuploader requests docopt 00:01:10 INFO - Cleaning up... 00:01:10 INFO - Return code: 0 00:01:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:10 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 0x10feb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e29d8f5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd51e00>, '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 0x10fb7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd4cb20>, '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.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 00:01:10 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 00:01:10 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 00:01:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5ti9k2Rmf/Render', 00:01:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:01:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:01:10 INFO - 'HOME': '/Users/cltbld', 00:01:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:01:10 INFO - 'LOGNAME': 'cltbld', 00:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:01:10 INFO - 'MOZ_NO_REMOTE': '1', 00:01:10 INFO - 'NO_EM_RESTART': '1', 00:01:10 INFO - 'PAGER': '/bin/cat', 00:01:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:01:10 INFO - 'PWD': '/builds/slave/test', 00:01:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:01:10 INFO - 'SHELL': '/bin/bash', 00:01:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.57nMEgkZRZ/Listeners', 00:01:10 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 00:01:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:01:10 INFO - 'USER': 'cltbld', 00:01:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:01:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:01:10 INFO - 'XPC_FLAGS': '0x0', 00:01:10 INFO - 'XPC_SERVICE_NAME': '0', 00:01:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:01:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:01:10 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-pqt6Cz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:01:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-y2xXsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:01:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-quvwjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:01:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-lCxCwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:01:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-4PuCWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:01:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-crdekl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:01:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-JDblPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:01:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-MUew8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:01:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-AHopmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:01:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-hXsPL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:01:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-6quuca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:01:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-aU3Kcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:01:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Eer66L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:01:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-4tkiaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:01:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-MRuv_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:01:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-E4jZEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:01:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-UZZnSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:01:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:01:12 INFO - Running setup.py install for manifestparser 00:01:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:01:12 INFO - Running setup.py install for mozcrash 00:01:13 INFO - Running setup.py install for mozdebug 00:01:13 INFO - Running setup.py install for mozdevice 00:01:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:01:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:01:13 INFO - Running setup.py install for mozfile 00:01:13 INFO - Running setup.py install for mozhttpd 00:01:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:01:13 INFO - Running setup.py install for mozinfo 00:01:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:01:13 INFO - Running setup.py install for mozInstall 00:01:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:01:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:01:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:01:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:01:13 INFO - Running setup.py install for mozleak 00:01:14 INFO - Running setup.py install for mozlog 00:01:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:01:14 INFO - Running setup.py install for moznetwork 00:01:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:01:14 INFO - Running setup.py install for mozprocess 00:01:14 INFO - Running setup.py install for mozprofile 00:01:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:01:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:01:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:01:14 INFO - Running setup.py install for mozrunner 00:01:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:01:14 INFO - Running setup.py install for mozscreenshot 00:01:14 INFO - Running setup.py install for moztest 00:01:15 INFO - Running setup.py install for mozversion 00:01:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:01:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:01:15 INFO - Cleaning up... 00:01:15 INFO - Return code: 0 00:01:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:01:15 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')]} 00:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:01:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:15 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')]} 00:01:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:01:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:15 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 0x10feb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e29d8f5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd51e00>, '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 0x10fb7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd4cb20>, '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.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 00:01:15 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 00:01:15 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 00:01:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5ti9k2Rmf/Render', 00:01:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:01:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:01:15 INFO - 'HOME': '/Users/cltbld', 00:01:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:01:15 INFO - 'LOGNAME': 'cltbld', 00:01:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:01:15 INFO - 'MOZ_NO_REMOTE': '1', 00:01:15 INFO - 'NO_EM_RESTART': '1', 00:01:15 INFO - 'PAGER': '/bin/cat', 00:01:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:01:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:01:15 INFO - 'PWD': '/builds/slave/test', 00:01:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:01:15 INFO - 'SHELL': '/bin/bash', 00:01:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.57nMEgkZRZ/Listeners', 00:01:15 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 00:01:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:01:15 INFO - 'USER': 'cltbld', 00:01:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:01:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:01:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:01:15 INFO - 'XPC_FLAGS': '0x0', 00:01:15 INFO - 'XPC_SERVICE_NAME': '0', 00:01:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:01:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:01:15 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-qdbLp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:01:15 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)) 00:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:01:15 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-RsEpVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:01:15 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)) 00:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:01:15 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-hxi500-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:01:15 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)) 00:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:01:15 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-MO3G6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:01:15 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)) 00:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:01:15 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-2DX_2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:01:16 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)) 00:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:01:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-mCJFMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:01:16 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)) 00:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:01:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Yq6FEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:01:16 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)) 00:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:01:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-IK8ntN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:01:16 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)) 00:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:01:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-fFFolM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:01:16 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)) 00:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:01:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-O8HvvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:01:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:01:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-KTeMjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:01:16 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)) 00:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:01:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-9eBPmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:01:16 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)) 00:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:01:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-M4dwUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:01:16 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)) 00:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:01:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-8crxYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:01:17 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)) 00:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:01:17 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-FeOeed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:01:17 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)) 00:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:01:17 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-FCnCj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:01:17 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)) 00:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:01:17 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Zj1KJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:01:17 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)) 00:01:17 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)) 00:01:17 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)) 00:01:17 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)) 00:01:17 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)) 00:01:17 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)) 00:01:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:01:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:01:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:01:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:01:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:01:19 INFO - Downloading blessings-1.6.tar.gz 00:01:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:01:19 INFO - Installing collected packages: blessings 00:01:19 INFO - Running setup.py install for blessings 00:01:20 INFO - Successfully installed blessings 00:01:20 INFO - Cleaning up... 00:01:20 INFO - Return code: 0 00:01:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:01:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:01:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:01:20 INFO - Reading from file tmpfile_stdout 00:01:20 INFO - Current package versions: 00:01:20 INFO - blessings == 1.6 00:01:20 INFO - blobuploader == 1.2.4 00:01:20 INFO - docopt == 0.6.1 00:01:20 INFO - manifestparser == 1.1 00:01:20 INFO - mozInstall == 1.12 00:01:20 INFO - mozcrash == 0.17 00:01:20 INFO - mozdebug == 0.1 00:01:20 INFO - mozdevice == 0.48 00:01:20 INFO - mozfile == 1.2 00:01:20 INFO - mozhttpd == 0.7 00:01:20 INFO - mozinfo == 0.9 00:01:20 INFO - mozleak == 0.1 00:01:20 INFO - mozlog == 3.1 00:01:20 INFO - moznetwork == 0.27 00:01:20 INFO - mozprocess == 0.22 00:01:20 INFO - mozprofile == 0.28 00:01:20 INFO - mozrunner == 6.11 00:01:20 INFO - mozscreenshot == 0.1 00:01:20 INFO - mozsystemmonitor == 0.0 00:01:20 INFO - moztest == 0.7 00:01:20 INFO - mozversion == 1.4 00:01:20 INFO - psutil == 3.1.1 00:01:20 INFO - requests == 1.2.3 00:01:20 INFO - wsgiref == 0.1.2 00:01:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:01:20 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')]} 00:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:01:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:20 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')]} 00:01:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:01:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:20 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 0x10feb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e29d8f5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd51e00>, '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 0x10fb7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd4cb20>, '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.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 00:01:20 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 00:01:20 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 00:01:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5ti9k2Rmf/Render', 00:01:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:01:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:01:20 INFO - 'HOME': '/Users/cltbld', 00:01:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:01:20 INFO - 'LOGNAME': 'cltbld', 00:01:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:01:20 INFO - 'MOZ_NO_REMOTE': '1', 00:01:20 INFO - 'NO_EM_RESTART': '1', 00:01:20 INFO - 'PAGER': '/bin/cat', 00:01:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:01:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:01:20 INFO - 'PWD': '/builds/slave/test', 00:01:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:01:20 INFO - 'SHELL': '/bin/bash', 00:01:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.57nMEgkZRZ/Listeners', 00:01:20 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 00:01:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:01:20 INFO - 'USER': 'cltbld', 00:01:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:01:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:01:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:01:20 INFO - 'XPC_FLAGS': '0x0', 00:01:20 INFO - 'XPC_SERVICE_NAME': '0', 00:01:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:01:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:01:20 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)) 00:01:20 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)) 00:01:20 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)) 00:01:20 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)) 00:01:20 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)) 00:01:20 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)) 00:01:20 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)) 00:01:20 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)) 00:01:20 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)) 00:01:20 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)) 00:01:20 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)) 00:01:20 INFO - Cleaning up... 00:01:20 INFO - Return code: 0 00:01:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:01:20 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')]} 00:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:01:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:20 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')]} 00:01:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:01:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:20 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 0x10feb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e29d8f5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd51e00>, '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 0x10fb7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd4cb20>, '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.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 00:01:20 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 00:01:20 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 00:01:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5ti9k2Rmf/Render', 00:01:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:01:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:01:20 INFO - 'HOME': '/Users/cltbld', 00:01:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:01:20 INFO - 'LOGNAME': 'cltbld', 00:01:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:01:20 INFO - 'MOZ_NO_REMOTE': '1', 00:01:20 INFO - 'NO_EM_RESTART': '1', 00:01:20 INFO - 'PAGER': '/bin/cat', 00:01:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:01:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:01:20 INFO - 'PWD': '/builds/slave/test', 00:01:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:01:20 INFO - 'SHELL': '/bin/bash', 00:01:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.57nMEgkZRZ/Listeners', 00:01:20 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 00:01:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:01:20 INFO - 'USER': 'cltbld', 00:01:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:01:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:01:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:01:20 INFO - 'XPC_FLAGS': '0x0', 00:01:20 INFO - 'XPC_SERVICE_NAME': '0', 00:01:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:01:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:01:20 INFO - Downloading/unpacking jsonschema 00:01:20 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 00:01:20 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 00:01:20 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 00:01:20 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 00:01:20 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 00:01:20 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 00:01:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:01:23 INFO - Downloading/unpacking functools32 (from jsonschema) 00:01: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 00:01: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 00:01: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 00:01: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 00:01: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 00:01: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 00:01:23 INFO - Downloading functools32-3.2.3-2.tar.gz 00:01:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:01:23 INFO - warning: no files found matching '*.txt' 00:01:23 INFO - no previously-included directories found matching 'build' 00:01:23 INFO - no previously-included directories found matching 'dist' 00:01:23 INFO - no previously-included directories found matching '.git*' 00:01:23 INFO - Installing collected packages: jsonschema, functools32 00:01:23 INFO - Running setup.py install for functools32 00:01:23 INFO - warning: no files found matching '*.txt' 00:01:23 INFO - no previously-included directories found matching 'build' 00:01:23 INFO - no previously-included directories found matching 'dist' 00:01:23 INFO - no previously-included directories found matching '.git*' 00:01:24 INFO - Successfully installed jsonschema functools32 00:01:24 INFO - Cleaning up... 00:01:24 INFO - Return code: 0 00:01:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 00:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:01:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:01:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:01:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:01:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fec2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e29d8f5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd51e00>, '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 0x10fb7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd4cb20>, '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.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 00:01:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 00:01:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 00:01:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5ti9k2Rmf/Render', 00:01:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:01:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:01:24 INFO - 'HOME': '/Users/cltbld', 00:01:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:01:24 INFO - 'LOGNAME': 'cltbld', 00:01:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:01:24 INFO - 'MOZ_NO_REMOTE': '1', 00:01:24 INFO - 'NO_EM_RESTART': '1', 00:01:24 INFO - 'PAGER': '/bin/cat', 00:01:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:01:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:01:24 INFO - 'PWD': '/builds/slave/test', 00:01:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:01:24 INFO - 'SHELL': '/bin/bash', 00:01:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.57nMEgkZRZ/Listeners', 00:01:24 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 00:01:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:01:24 INFO - 'USER': 'cltbld', 00:01:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:01:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:01:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:01:24 INFO - 'XPC_FLAGS': '0x0', 00:01:24 INFO - 'XPC_SERVICE_NAME': '0', 00:01:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:01:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:01:24 INFO - Downloading/unpacking flake8 00:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:01:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:01:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:01:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:01:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:01:26 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 00:01:26 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 00:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:01:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:01:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:01:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 00:01:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 00:01: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 00:01: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 00:01: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 00:01: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 00:01: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 00:01: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 00:01:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 00:01: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 00:01: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 00:01: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 00:01: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 00:01: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 00:01: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 00:01:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 00:01:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:01:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:01:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:01:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:01:27 INFO - no previously-included directories found matching 'docs/_build' 00:01:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 00:01:28 INFO - Running setup.py install for pep8 00:01:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:01:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:01:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:01:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:01:28 INFO - no previously-included directories found matching 'docs/_build' 00:01:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 00:01:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 00:01:28 INFO - Cleaning up... 00:01:28 INFO - Return code: 0 00:01:28 INFO - Running post-action listener: _resource_record_post_action 00:01:28 INFO - Running post-action listener: _start_resource_monitoring 00:01:28 INFO - Starting resource monitoring. 00:01:28 INFO - ##### 00:01:28 INFO - ##### Running install step. 00:01:28 INFO - ##### 00:01:28 INFO - Running pre-action listener: _resource_record_pre_action 00:01:28 INFO - Running main action method: install 00:01:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:01:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:01:28 INFO - Reading from file tmpfile_stdout 00:01:28 INFO - Detecting whether we're running mozinstall >=1.0... 00:01:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:01:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:01:28 INFO - Reading from file tmpfile_stdout 00:01:28 INFO - Output received: 00:01:28 INFO - Usage: mozinstall [options] installer 00:01:28 INFO - Options: 00:01:28 INFO - -h, --help show this help message and exit 00:01:28 INFO - -d DEST, --destination=DEST 00:01:28 INFO - Directory to install application into. [default: 00:01:28 INFO - "/builds/slave/test"] 00:01:28 INFO - --app=APP Application being installed. [default: firefox] 00:01:28 INFO - mkdir: /builds/slave/test/build/application 00:01:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 00:01:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 00:02:02 INFO - Reading from file tmpfile_stdout 00:02:02 INFO - Output received: 00:02:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 00:02:02 INFO - Running post-action listener: _resource_record_post_action 00:02:02 INFO - ##### 00:02:02 INFO - ##### Running run-tests step. 00:02:02 INFO - ##### 00:02:02 INFO - Running pre-action listener: _resource_record_pre_action 00:02:02 INFO - Running main action method: run_tests 00:02:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:02:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:02:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:02:02 INFO - 2016-04-15 00:02:02.073 screenresolution[2201:16898] starting screenresolution argv=screenresolution get 00:02:02 INFO - 2016-04-15 00:02:02.076 screenresolution[2201:16898] Display 0: 1600x1200x32@60 00:02:02 INFO - 2016-04-15 00:02:02.082 screenresolution[2202:16900] starting screenresolution argv=screenresolution list 00:02:02 INFO - Available Modes on Display 0 00:02:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:02:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:02:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:02:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:02:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:02:02 INFO - Intel Iris: 00:02:02 INFO - Chipset Model: Intel Iris 00:02:02 INFO - Type: GPU 00:02:02 INFO - Bus: Built-In 00:02:02 INFO - VRAM (Dynamic, Max): 1536 MB 00:02:02 INFO - Vendor: Intel (0x8086) 00:02:02 INFO - Device ID: 0x0a2e 00:02:02 INFO - Revision ID: 0x0009 00:02:02 INFO - Displays: 00:02:02 INFO - Display: 00:02:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:02:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:02:02 INFO - Display Serial Number: 1600x1200 60 00:02:02 INFO - Main Display: Yes 00:02:02 INFO - Mirror: Off 00:02:02 INFO - Online: Yes 00:02:02 INFO - Rotation: Supported 00:02:02 INFO - Return code: 0 00:02:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:02:02 INFO - Python 2.7.3 00:02:02 INFO - Return code: 0 00:02:02 INFO - grabbing minidump binary from tooltool 00:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:02:02 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd51e00>, '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 0x10fb7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd4cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:02:02 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 00:02:02 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 00:02:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:02:02 INFO - Return code: 0 00:02:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:02:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:02:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:02:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:02:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:02:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 00:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 00:02:03 INFO - Return code: 0 00:02:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 00:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0036 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 00:02:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5ti9k2Rmf/Render', 00:02:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:02:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:02:03 INFO - 'HOME': '/Users/cltbld', 00:02:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:02:03 INFO - 'LOGNAME': 'cltbld', 00:02:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:02:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:02:03 INFO - 'MOZ_NO_REMOTE': '1', 00:02:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:02:03 INFO - 'NO_EM_RESTART': '1', 00:02:03 INFO - 'PAGER': '/bin/cat', 00:02:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:02:03 INFO - 'PWD': '/builds/slave/test', 00:02:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:02:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:02:03 INFO - 'SHELL': '/bin/bash', 00:02:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.57nMEgkZRZ/Listeners', 00:02:03 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 00:02:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:02:03 INFO - 'USER': 'cltbld', 00:02:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:02:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:02:03 INFO - 'XPC_FLAGS': '0x0', 00:02:03 INFO - 'XPC_SERVICE_NAME': '0', 00:02:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:02:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 00:02:03 INFO - mozversion application_buildid: 20160414215914 00:02:03 INFO - mozversion application_changeset: 5a88d4e4b4e0a7906202a157dd1c30c3dfcd6816 00:02:03 INFO - mozversion application_display_name: Nightly 00:02:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:02:03 INFO - mozversion application_name: Firefox 00:02:03 INFO - mozversion application_remotingname: firefox 00:02:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 00:02:03 INFO - mozversion application_vendor: Mozilla 00:02:03 INFO - mozversion application_version: 48.0a1 00:02:03 INFO - mozversion platform_buildid: 20160414215914 00:02:03 INFO - mozversion platform_changeset: 5a88d4e4b4e0a7906202a157dd1c30c3dfcd6816 00:02:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 00:02:03 INFO - mozversion platform_version: 48.0a1 00:02:03 INFO - using testdate: 1460703723 00:02:03 INFO - actual date: 1460703723 00:02:03 INFO - starting webserver on 'localhost:53520' 00:02:03 INFO - SUITE-START | Running 2 tests 00:02:03 INFO - TEST-START | dromaeo_css 00:02:03 INFO - operating with platform_type : mac_ 00:02:03 INFO - Initialising browser for dromaeo_css test... 00:02:03 INFO - TEST-INFO | started process 2220 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpsXQp5N/profile http://localhost:53520/getInfo.html) 00:02:06 INFO - PROCESS | 2220 | __metrics Screen width/height:1600/1200 00:02:06 INFO - PROCESS | 2220 | colorDepth:24 00:02:06 INFO - PROCESS | 2220 | Browser inner width/height: 1024/689 00:02:06 INFO - PROCESS | 2220 | __metrics 00:02:06 INFO - PROCESS | 2220 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:02:06 ERROR - PROCESS | 2220 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 00:02:06 INFO - TEST-INFO | 2220: exit 0 00:02:06 INFO - Browser initialized. 00:02:06 INFO - Running cycle 1/1 for dromaeo_css test... 00:02:06 INFO - TEST-INFO | started process 2222 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpsXQp5N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:02:18 INFO - PROCESS | 2222 | RSS: Main: 238071808 00:02:18 INFO - PROCESS | 2222 | 00:03:10 INFO - PROCESS | 2222 | 1460703790021 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] 00:05:19 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:53520/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53520/tests/dromaeo/cssquery-ext.html) 00:05:21 INFO - PROCESS | 2222 | RSS: Main: 406405120 00:05:21 INFO - PROCESS | 2222 | 00:08:24 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:53520/tests/dromaeo/cssquery-ext.html (next: http://localhost:53520/tests/dromaeo/cssquery-jquery.html) 00:08:25 INFO - PROCESS | 2222 | RSS: Main: 370868224 00:08:25 INFO - PROCESS | 2222 | 00:11:27 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:53520/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53520/tests/dromaeo/cssquery-mootools.html) 00:11:27 INFO - PROCESS | 2222 | RSS: Main: 377122816 00:11:27 INFO - PROCESS | 2222 | 00:14:29 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:53520/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53520/tests/dromaeo/cssquery-prototype.html) 00:14:30 INFO - PROCESS | 2222 | RSS: Main: 377298944 00:14:30 INFO - PROCESS | 2222 | 00:17:33 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:53520/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53520/tests/dromaeo/cssquery-yui.html) 00:17:34 INFO - PROCESS | 2222 | RSS: Main: 346935296 00:17:34 INFO - PROCESS | 2222 | 00:20:36 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:53520/tests/dromaeo/cssquery-yui.html (next: http://localhost:53520/tests/dromaeo/cssquery-dojo.html) 00:20:36 INFO - PROCESS | 2222 | RSS: Main: 421085184 00:20:36 INFO - PROCESS | 2222 | 00:20:36 INFO - PROCESS | 2222 | __start_tp_report 00:20:36 INFO - PROCESS | 2222 | _x_x_mozilla_page_load 00:20:36 INFO - PROCESS | 2222 | _x_x_mozilla_page_load_details 00:20:36 INFO - PROCESS | 2222 | |i|pagename|runs| 00:20:36 INFO - PROCESS | 2222 | |0;dojo.html;2785.90249341273;3312.850921708523;3322.717569006635;3345.8996230113107;3367.8652853885847;5456.61803673743;5461.18082287657;5465.371482279534;5468.507834294917;5559.861003474915;5524.088525393316;5557.305336832896;5583.4974852263285;5674.120511320742;5678.290213723285;5010.6993580385115;5012.122878496267;5024.773885175176;5046.84859454217;5228.189630607243;5140.074786538422;5156.355455568054;5166.638335316529;5179.4561571035065;5185.222216667511;5025.497450254945;5040.546350828425;5080.085584594772;5112.437631860511;5146.845594632167;5054.519820617035;5085.720285384303;5101.668391554537;5108.335916330853;5127.102757017545;5026.974865125651;5062.670926389773;5068.138416469173;5077.289179514871;5086.77109530072;5390.757415916293;5468.234447177399;5525.557955363562;5538.252335934678;5545.584081193886;6092.238470191226;6092.573519853612;6104.298005729309;6105.389461053904;6109.908351374733;5910.822675319747;6173.567850250484;6194.9070763938125;6197.349278325801;6217.937820621826;5520.033994051025;5559.722013899329;5624.3813180550105;5628.986782379211;5668.829935101953;6218.720157592919;6227.283862355796;6246.656433896179;6254.405831446005;6262.405071518198;5885.205497257901;5904.852378690482;5907.9070371981325;5908.468237858528;5927.792527261525;5999.940000600025;6014.278286605635;6051.788187413419;6099.054646529795;6127.938720612737;5544.251526044013;5569.248151499496;6091.725872335755;6093.146959425684;6105;6057.363976782374;6070.969645151746;6075.848103797352;6081.695915204222;6142.109394137914;4743.71919581711;4774.618030557547;4783.880402989953;4810.6632535722165;4812.903741925142;3820.522434695663;4343.804528796211;4390.078083602415;4392.209402307615;4394.362817391524;5493.725313734298;5508.256385387921;5510.752016159283;5512.3385193777;5512.889742205213;6088.330283668793;6099.128199784669;6105.793350785129;6117.2184870185665;6121.392004143319;5547.389787123413;5556.138798486241;5566.2207290979295;5568.359638641527;5568.916466253009;5230.34620672416;5294.814681486129;5310.495502927216;5324.707141107198;5329.760160792696;6238.469730072995;6251.9687401562705;6261.311255761953;6265.498760099273;6267.874642507216;6157.353477884758;6170.784022559189;6175.783847565313;6185.690715464299;6188.381161883775;6160.784372546939;6162.568620196544;6198.411150940653;6251.062340648939;6263.530235232284;6033.758649653964;6037.78867739627;6047.395260474093;6085.847853803691;6100.176476175837;5134.383873935151;5435.537979271806;6114.6636934969;6160.075988601746;6175.042868355412;5798.652080875161;5932.822015339547;5933.970330148321;5937.554683398849;5946.405359464019;5756.136579512939;5844.977129002493;5848.356680765198;5849.736761845642;5872.442117998783;5323.866903327293;5720.685362305113;5730.598858079894;5734.2258795062135;5742.483176514133;5829.883402332014;5873.765049397976;5879.323877754113;5935.287765467999;6038.818835434944;5649.672327003069;5671.319441667027;5685.289338832646;5687.20379146928;5696.686682232517 00:20:36 INFO - PROCESS | 2222 | |1;ext.html;460.5118574311229;483.78471580146834;491.15276148643596;523.6360729293139;525.4062908912925;33840.32319353611;36683.266334673324;37348.439773403356;37950;38236.61763382362;34555.654443455554;43430.78284608573;43569;43571.692849214574;43620.78189609048;8558.828823423528;8784.736457906272;9640.80718385632;9915.652952146676;9915.702528924145;9606.27952903535;11930.224535405214;11930.52277908887;11965.940170299136;11966.581169659064;11663.766724665544;11723.5896743614;11826.349550774705;11843;11843.170978031541;8605.225529702326;8876.423032502931;8892.244159246471;11552;11605.187636865423;11538.942305288503;11700.2979821211;11706.239094458833;11714.765704685944;11722.296662200295;38414.423783643266;39123;39196;39199;39249.41125883114;3205.118592387089;3280.2455435249785;3287.539744435757;3441.7590768646205;3624.1664417184197;28294.575581366295;28489.85755071232;28521.429571408662;28546;29183;2743.492453896036;2751.119641714652;2753.5869619557025;2799.412123454077;2853.7146285371296;478550;483592;484456;484526;486128.5693571545;57789.8442031157;58827.70586147042;58828.70585647044;58917.70541147267;59077;62998.68500657468;64789.35210647927;64839;64891.35108648853;65009.674951625886;16453.259603317878;16669.666606667797;16715.832841671672;16721.996680199227;16724.83275167257;11902.166848320707;12205.75588488243;12773.361331933553;13104.475820967245;13126.540571079966;18755.718664220047;18756;18757.968311742985;18762.343317983807;18830.152643131092;7178.856422871513;7946;8125.146859579659;8205.384596155312;8302.54336011513;926.5552534783342;957.674390707149;1050.2438244463974;1073.4954571351452;1097.2429023973432;28006.87972481078;28319.71680283212;28998.27504312409;29225.97709080172;29631.962881299052;1091.2515509225834;1100.8661079088552;1145.4444594371723;1203.4524291447547;1290.9419076141596;14988.775168372265;15302.617434564003;15357.692846143014;15415.768763468333;15453;628608;631184;637535;637712;638182;58077.419225808044;58909.41090589039;59066;59077.818443631746;59179.40820591739;1354.8780609744972;1479.3860547872728;1496.4537943650707;1532.4636377267866;1542.9382824687111;28603.713962860937;29297.121086367442;29413.97051103201;30148.24629384283;30498.542521861746;46948.06103877971;47349;47613.85737641745;47699.5230047709;47719.76140119416;411634;415021;415440;416098;417147;26090.86954565215;40242;42349.78825105855;42461;42492.57507424886;43176.13647726965;44516;44594.777026114665;44942.550574493835;45722.08555828931;30614.84692576523;31479;36931.63068369282;37090.44364334483;37381.6261837389;29101.563476548294;36407.817960910026;36525.63474365223;37321.066973325884;37482.62517374791;36309.63690363063;36760.448593271656;36934.445983309735;37300.44049339208;37486.6251337494;30548.236294092825;32290.35419291554;32342;32381.676183238807;32428.675713242566;24289.878550607133;24338.87830560836;24369.63445548283;25193.87403062973;25236.74763252344 00:20:36 INFO - PROCESS | 2222 | |2;jquery.html;378.2454004261498;378.387013038877;382.47409811509175;387.5717332347756;396.3856023164095;19145.904270478644;20248.59502809943;21040.263590774324;21061.473463163427;21138.57722845544;20193.19227230909;20781.272655457025;21210.681839772376;21277.82971936543;21281.042353094108;5276.683398996053;5807.30312362515;5987.161797348374;6006.549508786837;6014.8797024059495;5899.262592175978;5924.822255332326;5939.316978547479;5967.522598192156;5980.252468441445;5658.009848276556;5864.23764910561;5867.354590994987;5870.794522191724;5876.030454974924;5589.273394458694;5836.562257830658;5858.853528661775;5876.11858221266;5897.085951677497;5133.204353325241;5168.819091331786;5170.482951704837;5173.611979101544;5176.301199338079;12120.27278363301;12790.104692671517;12795;12801.103922725413;25101.62347564788;2050.5181282398626;2143.2820005298245;2145.313499680103;2278.020451205981;2290.2900100968586;7846.450748447611;8939.569668853017;9427.717168484956;10006.299559030798;14514.564563063124;1723.7500562418504;1725.9741103883453;1740.6605711886186;1766.1963806468027;1773.326136068286;106806.46596767045;107769;110155.34766978581;111039.44480277627;111992;11109.444527773741;11324.32054076758;11325.433728313552;11326.943365283121;11441.542338306375;14361.64095897611;14452.277386130652;14463.710725785426;14474.131552106906;14477.611194153058;8389.748307550783;8462.534560599102;8479.95760021196;8550.230479256898;8593.613287402082;8338;8381.539015354214;8384.664613415516;8544.31645468361;8567.914320856837;4346.239408103633;4445.82216711328;4453.532379100147;4495.112052937774;4519.796609152596;1444.241773069126;1821.3807305516189;1821.7631707878156;1885.708863730574;1897.3738666240108;917.7980844214262;920.9907900921039;930.6835675870236;1024.4263212600968;1062.4740753327153;20129.396118116187;20232.39302820918;20257.189712411633;20666.793332066787;20720.689189662167;807.3258828877836;985.6796541124163;996.8137915880079;998.6404894238068;1009.9798004039878;10548.736281593021;10925.508352124014;10940.398278094623;10957.452127393599;10957.780844383067;345260;572154;602629;607785;612172;123613.38193309156;125427;125658.80167022803;127202;129260.3536982309;1018.592562974816;1021.2085633633993;1038.5334829779274;1044.0812085364983;1048.130052056791;14543.92728036353;17897.37359192422;18181.54546136357;18247.361342352953;18725.71911421357;50246.2463063047;50433.74783126061;50762.74618626883;51114.23328649999;52116.47410999263;73926;75414.4917101644;77592.61203693945;79487.80768288596;79723.60138199272;7431.182569917197;7794.259545343183;7833.373330133461;7898.644560994654;8286.254237118446;10654.89345106539;11670.358130302575;12420.254784712946;12488.25070495773;12858.807117893051;6136.969315153396;7183.676734546853;10621.252481717425;10905.56377744913;11083.168762342695;10586.258961872594;10758.677239682822;11017.283876548;11184.328940263611;11511.712207194887;11258.380789056353;11476.368799716096;11750.764984700429;11769.941150294193;11861.05111591088;6952.165740111017;7976.1226265109635;7992.040955085427;8171.305439037748;8357.164283571594;9441.339106262496;10898.346099234072;11082.944585277022;11088.445577721242;11402.543898244308 00:20:36 INFO - PROCESS | 2222 | |3;mootools.html;1421.211227768588;1564.4211641692575;1585.4609432792847;1855.2300795170006;2003.5291706448982;7793.337566306864;8377.664893404264;8613.784655383619;8625.22372986431;8669.003064647568;8334.458260213081;8503;8511.106333834936;8517.276031537327;8527.78680532987;2038.7859274776158;2383.7020372453444;2383.8331316807785;2409.313345696477;2409.6987876515436;2355.740868504463;2384.2251268337773;2398.352444839892;2400.327908185711;2401.627747699101;2358.929232123039;2381;2389.6893403857475;2399.9760002399926;2405.4707964247837;2346.5541547105995;2374.3470545599926;2376.370261880603;2384.4873352229265;2393.892274847636;2120.6109997951366;2202.801747842686;2340.8829558522007;2352.541254455382;2373.9406514837096;8437.83124337516;8447.788805279855;8603.483790972561;8607.354448416394;8625.956870215672;5117.2068329409;5299.708516031616;5302.151655735102;5305.893882122375;5328.040952628526;3385;3422.7775194612273;3452.2750222453315;3485.9302813943577;3494.4234201356744;968.3657204531044;978.3738407419257;981.7643765496301;988.17981075707;1014.1075853249167;10899.964503372246;11130.443477826077;11146.60986865464;11156.553737850476;11167.944160279229;4337.327714204303;4372.846950356722;4425.336199570026;4457.420535330386;4507.121111383314;8741.125887411208;8747.781305467286;8752.21230089295;8758.737237882873;8777;5098.949010509921;5106.004329155779;5120.462351453119;5123.231515272664;5141.151709967889;4323.546027667113;4490.079533695584;4669.813207471731;4817.108834865561;4838.491958344393;3617.330793803102;3650.379435495972;3735.402335626287;3746.2882052409955;3779.3197224499304;1135.5117299561102;1146.3179408252076;1149.6493569461227;1157.2188772578645;1160.9593664221711;630.0926665601611;813.4509206285734;901.4861528928448;933.8793447862456;965.1362030982372;5601.851620417805;5729.7708091676695;5755.9424405756245;5840.883182336414;5851.093080572518;960.5869476125193;964.1997142371955;985;996.890342062387;1014.9391036537831;3752.399616061417;3944.2900277950243;3979.383195604686;4019.41718450823;4059.594040595976;10117.190624750003;10489.527971241312;10567.313124646873;10651.30766500175;10652.680419587425;10617.416042117758;10620.628278010232;10625.203109766799;10641.201909856793;10644.68065958007;8061.959690201512;8070.636821343053;8104;8136.267735903797;8136.552489613128;5800.4489573490455;5819.912701309399;5840.766369345179;6027.36712645166;6069.514438844793;9446.527673616429;9579.425234485952;9730.659426920023;9768.511574421393;9818.45998470091;9885.95057024739;9971;10009.899901000897;10014.998500150217;10018.59925602997;6391.3289104643345;7155.356017958408;7178.102737157856;7341.449391295568;7342.6695798688115;7855.764327070197;7868.567228802471;7945.324647405036;7954.244346787046;7965.522068675898;5980.372060933713;7045.929540704732;7061.964690176516;7277.490575659653;7322.048133742579;7035.155781306072;7036.085308909809;7066.611336376549;7095.077639906779;7143;6824.863502729819;6915;6918.757843475454;6922.10012698346;6931.44548436134;4726.1492931272705;4813.350197723403;4846.224604063333;4926.531979461945;5068.873278168076;3974.4634474345594;4889.46215916256;4912.1894887344615;5045.344105266292;5178.352705911761 00:20:36 INFO - PROCESS | 2222 | |4;prototype.html;306.3658227469139;307.4542686731052;314.7120384847864;316.7624281788657;322.759544139616;5801.129830525423;5889.381614930435;5909.349971503137;5921.289445266574;5937.346891841894;5303.337082864642;5695.291412576231;5766.769329226837;5838.357780644125;5857.033589457735;3710.4063349864027;4131.442255295534;4271.252530807112;4380.693351465383;4445.777711114448;4324.918770307423;4391.736495810262;4398.34024896265;4402.141582391435;4403.823847046115;4375.190589740892;4379.649628029766;4390.956090439103;4394.758288294142;4403.295472724365;4078.89802754932;4115.8765237042935;4350.608445239944;4373.234683930299;4376.934345984812;3747.831347589338;3750.4374343848367;3769.509963704701;3771.4908487354132;3779.4519794629623;4253.617174454283;4444.911101777979;5552.444755524456;5590.692511911843;5607.915881261784;4611.700239484423;4788.712677239377;5051.646384753069;5053.906335867959;5098.719570423626;5479.890402191933;5944.7027648617395;6110.73075904389;6139.877202455926;6144.324124346318;4191.287481128215;4232.597903199191;4234.17433600448;4234.894127646803;4241.427407300006;5437.401885792559;5584.799268157366;5985.760569577212;6123.846903827395;6585.341465853424;3654.0134163775633;3669.688076513473;4001.459802926626;4226.852060177879;4288.785560721952;4331.913361732748;4333.898059441169;4350.608445239944;4369.628581570539;4378.452693413324;4247.745135291831;4800.543948324904;5079.644424890223;5103.030424219386;5130.1535246684625;6204.472619827275;6214.875702486015;6215.595986260879;6231.781887634002;6237.937620623827;1491.7762335649738;1498.5654160293611;1512.6898985707903;1512.9621759456102;1516.9381432996947;843.325339728215;932.165711688049;936.2743873498093;955.1499165742495;995.581855620641;4401.757903315349;5854.853628659318;5865.20819689345;5886.499647529922;5899.233099697012;5757.88484230313;6324.367563243639;6364.49084073273;6581;6608.570442921195;5591.232339991649;5867;5875.265591801025;5876.6180198286975;5888.970555147197;3390.9152271193416;3403.3193361327835;3428.8628454861528;3429.5027221052806;3442.5008373785663;5699.971500142473;6793.422559082434;6869.347411995853;6900.585964842126;6964.686589103502;6053.606515576562;6124.11200375943;6659.134312539339;6720;6742.224644165885;5303.4696530346655;6825.419839313615;6857.759978400732;6861.176658800976;6877.552959057645;5124.8206312778875;5459.181122831448;6308.400701933309;6542.877986868242;6566.146400967844;6094.542909281733;6447.9032814508755;6449.129367535323;6456.418922297016;6470.22357317125;6522.934770652233;6626.801195964129;6631.502637302318;6816.250212476524;6857.451403887577;3573.714102871816;4096.56986016464;4142.984968682692;4155.3766934959995;4165.91668166641;6233.844153896189;6518.804435866742;6520.250171230369;6523.869522609616;6558;6200.937990620036;6272.435480806749;6309.526785491014;6313.2424109107205;6315.905261421174;5955.493783028491;6299.275583307978;6313.116163736989;6335.556511044183;6340.397662222082;5457.863553411196;5874;6072.969635151796;6158.04550294705;6189.876202475835;5005.849824505271;6103.298120716175;6239.407256310643;6255.906161407674;6322.146510221245;6061.181740464981;6185.721642526187;6195.504359651307;6207.782727604693;6232.532560057924 00:20:36 INFO - PROCESS | 2222 | |5;yui.html;668.969896354664;671.7715976567968;733.6515155301232;762.2986852096071;796.5738329993451;14858.25708714564;15215;15237.238138093078;15241.618959526017;15260.084394936312;13866.861331386683;13967.650808729786;14043.859561404382;14090;14107.435702571911;911.7538264668535;919.4529255093208;922.0871337376017;923.1737594852602;926.8053708721177;898.1108702384657;907.773056735816;912.6349460215934;914.0356923445762;915.844306467902;821.2649678537705;960.1790469148882;960.7982323712029;964.1274646444979;966.5602151021303;953.4660590069549;954.19370631816;956.1920177450079;956.9903751542121;957.1529196660936;712.5368510468237;713.3437237741291;713.8286811165335;714.3999040805696;715.309726114297;13165.802512962313;13201.339933003408;13222.20666759988;13254.933725331312;13258.66853328665;11203.069334064143;11335.206535542515;11355.375454350007;11381.943090284496;11585.6524304271;9412.105849944244;9443;9466.763330916714;9632.325737198398;10153.543090560943;3455.188030812757;3462.9653703462895;3475.4265546184847;3499.930001399983;3566.3188749694573;77677.6116119427;78702.60648696776;78731.60634196793;78815;78825.60587197027;8274.552902387899;8343.45767525109;8432.620532076071;8476.957615211884;8713.3464990126;18881.81118188828;18897.90551047236;18905.24379024851;18912.621747564972;18920.81079189218;7496.7750967471065;7551.773446796605;7578.4316176287;7635;7701.537907725555;4035.4552135461336;4095.1809638072505;4112.403701463331;4158.979205103955;4159.521655009652;2844.260492271983;2933.985330073336;2936.6329208848892;2938.8971386001813;2941.2940894185667;577.7140315543749;615.9384061593805;631.3812463785516;634.9904751428639;642.9260635026939;496.55558275343367;563.0541481950614;585.5257241634205;591.8905002574537;608.5314307982828;7756.767296981099;7841.764747057598;7863.410244231705;8049.6377663005305;8299.501131069082;471.2342443529265;499.86503644015914;554.5092593055175;596.5227817745786;602.4607975861667;3676.889693309205;3746.6253374662315;3761.887143385703;3853.9807300963316;3885.6502914737807;83599.16400836036;84198;85161.72257416144;85543;85590.57204713937;65208.673956629915;66520.66739666367;67802.32197678059;70190.64904675444;74693.25306746972;51068;51090.744546277034;52866.678333041215;60456;63519;7030.4024157945105;8079.909212256505;8161.2261822688215;8349.83300334002;8432.675493379966;31319.06042818628;42578.361324579535;42870.78564607157;43268.56731432645;44821.32768008417;54302.728486358894;57854;58137;60235.49411264578;62816.97464202944;10650.946745266225;10765.623203188165;10850.891491085304;11145.49845256949;11725;11957.820632690691;12039.398030098286;12057;12233.204841685618;12298.446569904554;11123.388213648326;11359.148063895076;11614.763265813346;11837.289762614271;11863.406829658656;11193.888061119285;11281.379524125923;11613.535458581562;11815.59930226763;12417.19288246261;11057.557697692324;11118.332900026024;11249.100071994384;11363.772724545628;12327.568535101229;9345.112214339626;9350.25197984146;9542.618295268388;9655.806883862424;9698.224142068477;8484.06675265708;9057.139571740674;9610.375325604093;9653.420794752057;9698.612055517982 00:20:36 INFO - PROCESS | 2222 | __end_tp_report 00:20:36 INFO - PROCESS | 2222 | __start_cc_report 00:20:36 INFO - PROCESS | 2222 | _x_x_mozilla_cycle_collect,1974 00:20:36 INFO - PROCESS | 2222 | __end_cc_report 00:20:36 INFO - PROCESS | 2222 | __startTimestamp1460704836910__endTimestamp 00:20:36 INFO - PROCESS | 2222 | 00:20:36 INFO - PROCESS | 2222 | ------- Summary: start ------- 00:20:36 INFO - PROCESS | 2222 | Number of tests: 6 00:20:36 INFO - PROCESS | 2222 | 00:20:36 INFO - PROCESS | 2222 | [#0] dojo.html Cycles:165 Average:5585.06 Median:5685.29 stddev:638.79 (11.2%) stddev-sans-first:601.83 00:20:36 INFO - PROCESS | 2222 | Values: 2785.9 3312.9 3322.7 3345.9 3367.9 5456.6 5461.2 5465.4 5468.5 5559.9 5524.1 5557.3 5583.5 5674.1 5678.3 5010.7 5012.1 5024.8 5046.8 5228.2 5140.1 5156.4 5166.6 5179.5 5185.2 5025.5 5040.5 5080.1 5112.4 5146.8 5054.5 5085.7 5101.7 5108.3 5127.1 5027.0 5062.7 5068.1 5077.3 5086.8 5390.8 5468.2 5525.6 5538.3 5545.6 6092.2 6092.6 6104.3 6105.4 6109.9 5910.8 6173.6 6194.9 6197.3 6217.9 5520.0 5559.7 5624.4 5629.0 5668.8 6218.7 6227.3 6246.7 6254.4 6262.4 5885.2 5904.9 5907.9 5908.5 5927.8 5999.9 6014.3 6051.8 6099.1 6127.9 5544.3 5569.2 6091.7 6093.1 6105.0 6057.4 6071.0 6075.8 6081.7 6142.1 4743.7 4774.6 4783.9 4810.7 4812.9 3820.5 4343.8 4390.1 4392.2 4394.4 5493.7 5508.3 5510.8 5512.3 5512.9 6088.3 6099.1 6105.8 6117.2 6121.4 5547.4 5556.1 5566.2 5568.4 5568.9 5230.3 5294.8 5310.5 5324.7 5329.8 6238.5 6252.0 6261.3 6265.5 6267.9 6157.4 6170.8 6175.8 6185.7 6188.4 6160.8 6162.6 6198.4 6251.1 6263.5 6033.8 6037.8 6047.4 6085.8 6100.2 5134.4 5435.5 6114.7 6160.1 6175.0 5798.7 5932.8 5934.0 5937.6 5946.4 5756.1 5845.0 5848.4 5849.7 5872.4 5323.9 5720.7 5730.6 5734.2 5742.5 5829.9 5873.8 5879.3 5935.3 6038.8 5649.7 5671.3 5685.3 5687.2 5696.7 00:20:36 INFO - PROCESS | 2222 | 00:20:36 INFO - PROCESS | 2222 | [#1] ext.html Cycles:180 Average:65282.55 Median:36358.73 stddev:138485.23 (380.9%) stddev-sans-first:138788.19 00:20:36 INFO - PROCESS | 2222 | Values: 460.5 483.8 491.2 523.6 525.4 33840.3 36683.3 37348.4 37950.0 38236.6 34555.7 43430.8 43569.0 43571.7 43620.8 8558.8 8784.7 9640.8 9915.7 9915.7 9606.3 11930.2 11930.5 11965.9 11966.6 11663.8 11723.6 11826.3 11843.0 11843.2 8605.2 8876.4 8892.2 11552.0 11605.2 11538.9 11700.3 11706.2 11714.8 11722.3 38414.4 39123.0 39196.0 39199.0 39249.4 3205.1 3280.2 3287.5 3441.8 3624.2 28294.6 28489.9 28521.4 28546.0 29183.0 2743.5 2751.1 2753.6 2799.4 2853.7 478550.0 483592.0 484456.0 484526.0 486128.6 57789.8 58827.7 58828.7 58917.7 59077.0 62998.7 64789.4 64839.0 64891.4 65009.7 16453.3 16669.7 16715.8 16722.0 16724.8 11902.2 12205.8 12773.4 13104.5 13126.5 18755.7 18756.0 18758.0 18762.3 18830.2 7178.9 7946.0 8125.1 8205.4 8302.5 926.6 957.7 1050.2 1073.5 1097.2 28006.9 28319.7 28998.3 29226.0 29632.0 1091.3 1100.9 1145.4 1203.5 1290.9 14988.8 15302.6 15357.7 15415.8 15453.0 628608.0 631184.0 637535.0 637712.0 638182.0 58077.4 58909.4 59066.0 59077.8 59179.4 1354.9 1479.4 1496.5 1532.5 1542.9 28603.7 29297.1 29414.0 30148.2 30498.5 46948.1 47349.0 47613.9 47699.5 47719.8 411634.0 415021.0 415440.0 416098.0 417147.0 26090.9 40242.0 42349.8 42461.0 42492.6 43176.1 44516.0 44594.8 44942.6 45722.1 30614.8 31479.0 36931.6 37090.4 37381.6 29101.6 36407.8 36525.6 37321.1 37482.6 36309.6 36760.4 36934.4 37300.4 37486.6 30548.2 32290.4 32342.0 32381.7 32428.7 24289.9 24338.9 24369.6 25193.9 25236.7 00:20:36 INFO - PROCESS | 2222 | 00:20:36 INFO - PROCESS | 2222 | [#2] jquery.html Cycles:180 Average:32849.92 Median:21279.44 stddev:93341.40 (438.6%) stddev-sans-first:93571.40 00:20:36 INFO - PROCESS | 2222 | Values: 378.2 378.4 382.5 387.6 396.4 19145.9 20248.6 21040.3 21061.5 21138.6 20193.2 20781.3 21210.7 21277.8 21281.0 5276.7 5807.3 5987.2 6006.5 6014.9 5899.3 5924.8 5939.3 5967.5 5980.3 5658.0 5864.2 5867.4 5870.8 5876.0 5589.3 5836.6 5858.9 5876.1 5897.1 5133.2 5168.8 5170.5 5173.6 5176.3 12120.3 12790.1 12795.0 12801.1 25101.6 2050.5 2143.3 2145.3 2278.0 2290.3 7846.5 8939.6 9427.7 10006.3 14514.6 1723.8 1726.0 1740.7 1766.2 1773.3 106806.5 107769.0 110155.3 111039.4 111992.0 11109.4 11324.3 11325.4 11326.9 11441.5 14361.6 14452.3 14463.7 14474.1 14477.6 8389.7 8462.5 8480.0 8550.2 8593.6 8338.0 8381.5 8384.7 8544.3 8567.9 4346.2 4445.8 4453.5 4495.1 4519.8 1444.2 1821.4 1821.8 1885.7 1897.4 917.8 921.0 930.7 1024.4 1062.5 20129.4 20232.4 20257.2 20666.8 20720.7 807.3 985.7 996.8 998.6 1010.0 10548.7 10925.5 10940.4 10957.5 10957.8 345260.0 572154.0 602629.0 607785.0 612172.0 123613.4 125427.0 125658.8 127202.0 129260.4 1018.6 1021.2 1038.5 1044.1 1048.1 14543.9 17897.4 18181.5 18247.4 18725.7 50246.2 50433.7 50762.7 51114.2 52116.5 73926.0 75414.5 77592.6 79487.8 79723.6 7431.2 7794.3 7833.4 7898.6 8286.3 10654.9 11670.4 12420.3 12488.3 12858.8 6137.0 7183.7 10621.3 10905.6 11083.2 10586.3 10758.7 11017.3 11184.3 11511.7 11258.4 11476.4 11750.8 11769.9 11861.1 6952.2 7976.1 7992.0 8171.3 8357.2 9441.3 10898.3 11082.9 11088.4 11402.5 00:20:36 INFO - PROCESS | 2222 | 00:20:36 INFO - PROCESS | 2222 | [#3] mootools.html Cycles:180 Average:5538.31 Median:5111.61 stddev:3074.40 (60.1%) stddev-sans-first:3067.45 00:20:36 INFO - PROCESS | 2222 | Values: 1421.2 1564.4 1585.5 1855.2 2003.5 7793.3 8377.7 8613.8 8625.2 8669.0 8334.5 8503.0 8511.1 8517.3 8527.8 2038.8 2383.7 2383.8 2409.3 2409.7 2355.7 2384.2 2398.4 2400.3 2401.6 2358.9 2381.0 2389.7 2400.0 2405.5 2346.6 2374.3 2376.4 2384.5 2393.9 2120.6 2202.8 2340.9 2352.5 2373.9 8437.8 8447.8 8603.5 8607.4 8626.0 5117.2 5299.7 5302.2 5305.9 5328.0 3385.0 3422.8 3452.3 3485.9 3494.4 968.4 978.4 981.8 988.2 1014.1 10900.0 11130.4 11146.6 11156.6 11167.9 4337.3 4372.8 4425.3 4457.4 4507.1 8741.1 8747.8 8752.2 8758.7 8777.0 5098.9 5106.0 5120.5 5123.2 5141.2 4323.5 4490.1 4669.8 4817.1 4838.5 3617.3 3650.4 3735.4 3746.3 3779.3 1135.5 1146.3 1149.6 1157.2 1161.0 630.1 813.5 901.5 933.9 965.1 5601.9 5729.8 5755.9 5840.9 5851.1 960.6 964.2 985.0 996.9 1014.9 3752.4 3944.3 3979.4 4019.4 4059.6 10117.2 10489.5 10567.3 10651.3 10652.7 10617.4 10620.6 10625.2 10641.2 10644.7 8062.0 8070.6 8104.0 8136.3 8136.6 5800.4 5819.9 5840.8 6027.4 6069.5 9446.5 9579.4 9730.7 9768.5 9818.5 9886.0 9971.0 10009.9 10015.0 10018.6 6391.3 7155.4 7178.1 7341.4 7342.7 7855.8 7868.6 7945.3 7954.2 7965.5 5980.4 7045.9 7062.0 7277.5 7322.0 7035.2 7036.1 7066.6 7095.1 7143.0 6824.9 6915.0 6918.8 6922.1 6931.4 4726.1 4813.4 4846.2 4926.5 5068.9 3974.5 4889.5 4912.2 5045.3 5178.4 00:20:36 INFO - PROCESS | 2222 | 00:20:36 INFO - PROCESS | 2222 | [#4] prototype.html Cycles:180 Average:5052.61 Median:5819.74 stddev:1599.21 (27.5%) stddev-sans-first:1563.51 00:20:36 INFO - PROCESS | 2222 | Values: 306.4 307.5 314.7 316.8 322.8 5801.1 5889.4 5909.3 5921.3 5937.3 5303.3 5695.3 5766.8 5838.4 5857.0 3710.4 4131.4 4271.3 4380.7 4445.8 4324.9 4391.7 4398.3 4402.1 4403.8 4375.2 4379.6 4391.0 4394.8 4403.3 4078.9 4115.9 4350.6 4373.2 4376.9 3747.8 3750.4 3769.5 3771.5 3779.5 4253.6 4444.9 5552.4 5590.7 5607.9 4611.7 4788.7 5051.6 5053.9 5098.7 5479.9 5944.7 6110.7 6139.9 6144.3 4191.3 4232.6 4234.2 4234.9 4241.4 5437.4 5584.8 5985.8 6123.8 6585.3 3654.0 3669.7 4001.5 4226.9 4288.8 4331.9 4333.9 4350.6 4369.6 4378.5 4247.7 4800.5 5079.6 5103.0 5130.2 6204.5 6214.9 6215.6 6231.8 6237.9 1491.8 1498.6 1512.7 1513.0 1516.9 843.3 932.2 936.3 955.1 995.6 4401.8 5854.9 5865.2 5886.5 5899.2 5757.9 6324.4 6364.5 6581.0 6608.6 5591.2 5867.0 5875.3 5876.6 5889.0 3390.9 3403.3 3428.9 3429.5 3442.5 5700.0 6793.4 6869.3 6900.6 6964.7 6053.6 6124.1 6659.1 6720.0 6742.2 5303.5 6825.4 6857.8 6861.2 6877.6 5124.8 5459.2 6308.4 6542.9 6566.1 6094.5 6447.9 6449.1 6456.4 6470.2 6522.9 6626.8 6631.5 6816.3 6857.5 3573.7 4096.6 4143.0 4155.4 4165.9 6233.8 6518.8 6520.3 6523.9 6558.0 6200.9 6272.4 6309.5 6313.2 6315.9 5955.5 6299.3 6313.1 6335.6 6340.4 5457.9 5874.0 6073.0 6158.0 6189.9 5005.8 6103.3 6239.4 6255.9 6322.1 6061.2 6185.7 6195.5 6207.8 6232.5 00:20:36 INFO - PROCESS | 2222 | 00:20:36 INFO - PROCESS | 2222 | [#5] yui.html Cycles:180 Average:16661.60 Median:599.49 stddev:22892.67 (3818.7%) stddev-sans-first:22925.40 00:20:36 INFO - PROCESS | 2222 | Values: 669.0 671.8 733.7 762.3 796.6 14858.3 15215.0 15237.2 15241.6 15260.1 13866.9 13967.7 14043.9 14090.0 14107.4 911.8 919.5 922.1 923.2 926.8 898.1 907.8 912.6 914.0 915.8 821.3 960.2 960.8 964.1 966.6 953.5 954.2 956.2 957.0 957.2 712.5 713.3 713.8 714.4 715.3 13165.8 13201.3 13222.2 13254.9 13258.7 11203.1 11335.2 11355.4 11381.9 11585.7 9412.1 9443.0 9466.8 9632.3 10153.5 3455.2 3463.0 3475.4 3499.9 3566.3 77677.6 78702.6 78731.6 78815.0 78825.6 8274.6 8343.5 8432.6 8477.0 8713.3 18881.8 18897.9 18905.2 18912.6 18920.8 7496.8 7551.8 7578.4 7635.0 7701.5 4035.5 4095.2 4112.4 4159.0 4159.5 2844.3 2934.0 2936.6 2938.9 2941.3 577.7 615.9 631.4 635.0 642.9 496.6 563.1 585.5 591.9 608.5 7756.8 7841.8 7863.4 8049.6 8299.5 471.2 499.9 554.5 596.5 602.5 3676.9 3746.6 3761.9 3854.0 3885.7 83599.2 84198.0 85161.7 85543.0 85590.6 65208.7 66520.7 67802.3 70190.6 74693.3 51068.0 51090.7 52866.7 60456.0 63519.0 7030.4 8079.9 8161.2 8349.8 8432.7 31319.1 42578.4 42870.8 43268.6 44821.3 54302.7 57854.0 58137.0 60235.5 62817.0 10650.9 10765.6 10850.9 11145.5 11725.0 11957.8 12039.4 12057.0 12233.2 12298.4 11123.4 11359.1 11614.8 11837.3 11863.4 11193.9 11281.4 11613.5 11815.6 12417.2 11057.6 11118.3 11249.1 11363.8 12327.6 9345.1 9350.3 9542.6 9655.8 9698.2 8484.1 9057.1 9610.4 9653.4 9698.6 00:20:36 INFO - PROCESS | 2222 | -------- Summary: end -------- 00:20:36 INFO - PROCESS | 2222 | 00:20:37 INFO - TEST-INFO | 2222: exit 0 00:20:37 INFO - TEST-OK | dromaeo_css | took 1113668ms 00:20:37 INFO - TEST-START | kraken 00:20:37 INFO - operating with platform_type : mac_ 00:20:37 INFO - Initialising browser for kraken test... 00:20:37 INFO - TEST-INFO | started process 2226 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpqGlxFB/profile http://localhost:53520/getInfo.html) 00:20:39 INFO - PROCESS | 2226 | __metrics Screen width/height:1600/1200 00:20:39 INFO - PROCESS | 2226 | colorDepth:24 00:20:39 INFO - PROCESS | 2226 | Browser inner width/height: 1024/689 00:20:39 INFO - PROCESS | 2226 | __metrics 00:20:39 INFO - PROCESS | 2226 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:20:39 ERROR - PROCESS | 2226 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 00:20:40 INFO - TEST-INFO | 2226: exit 0 00:20:40 INFO - Browser initialized. 00:20:40 INFO - Running cycle 1/1 for kraken test... 00:20:40 INFO - TEST-INFO | started process 2228 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpqGlxFB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:20:52 INFO - PROCESS | 2228 | RSS: Main: 237842432 00:20:52 INFO - PROCESS | 2228 | 00:20:55 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53520/tests/kraken/driver.html?testName=audio-beat-detection) 00:20:55 INFO - PROCESS | 2228 | RSS: Main: 286334976 00:20:55 INFO - PROCESS | 2228 | 00:20:58 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53520/tests/kraken/driver.html?testName=audio-dft) 00:20:59 INFO - PROCESS | 2228 | RSS: Main: 299659264 00:20:59 INFO - PROCESS | 2228 | 00:21:02 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53520/tests/kraken/driver.html?testName=audio-fft) 00:21:03 INFO - PROCESS | 2228 | RSS: Main: 386367488 00:21:03 INFO - PROCESS | 2228 | 00:21:05 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53520/tests/kraken/driver.html?testName=audio-oscillator) 00:21:06 INFO - PROCESS | 2228 | RSS: Main: 286932992 00:21:06 INFO - PROCESS | 2228 | 00:21:08 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53520/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:21:08 INFO - PROCESS | 2228 | RSS: Main: 290795520 00:21:08 INFO - PROCESS | 2228 | 00:21:11 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53520/tests/kraken/driver.html?testName=imaging-darkroom) 00:21:12 INFO - PROCESS | 2228 | RSS: Main: 331165696 00:21:12 INFO - PROCESS | 2228 | 00:21:15 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53520/tests/kraken/driver.html?testName=imaging-desaturate) 00:21:15 INFO - PROCESS | 2228 | RSS: Main: 331112448 00:21:15 INFO - PROCESS | 2228 | 00:21:18 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53520/tests/kraken/driver.html?testName=json-parse-financial) 00:21:19 INFO - PROCESS | 2228 | RSS: Main: 330539008 00:21:19 INFO - PROCESS | 2228 | 00:21:20 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53520/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:21:21 INFO - PROCESS | 2228 | RSS: Main: 320323584 00:21:21 INFO - PROCESS | 2228 | 00:21:23 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53520/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:21:23 INFO - PROCESS | 2228 | RSS: Main: 327643136 00:21:23 INFO - PROCESS | 2228 | 00:21:25 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53520/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:21:26 INFO - PROCESS | 2228 | RSS: Main: 330215424 00:21:26 INFO - PROCESS | 2228 | 00:21:29 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53520/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:21:30 INFO - PROCESS | 2228 | RSS: Main: 334958592 00:21:30 INFO - PROCESS | 2228 | 00:21:33 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53520/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:21:33 INFO - PROCESS | 2228 | RSS: Main: 332451840 00:21:33 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | Cycle 1(1): loaded http://localhost:53520/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53520/tests/kraken/driver.html?testName=ai-astar) 00:21:35 INFO - PROCESS | 2228 | RSS: Main: 396132352 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | __start_tp_report 00:21:35 INFO - PROCESS | 2228 | _x_x_mozilla_page_load 00:21:35 INFO - PROCESS | 2228 | _x_x_mozilla_page_load_details 00:21:35 INFO - PROCESS | 2228 | |i|pagename|runs| 00:21:35 INFO - PROCESS | 2228 | |0;ai-astar;113;109;113;116;160;164;118;113;113;112 00:21:35 INFO - PROCESS | 2228 | |1;audio-beat-detection;167;117;146;168;120;145;161;118;172;121 00:21:35 INFO - PROCESS | 2228 | |2;audio-dft;187;186;186;187;222;264;181;183;180;189 00:21:35 INFO - PROCESS | 2228 | |3;audio-fft;76;73;89;91;56;60;58;80;95;77 00:21:35 INFO - PROCESS | 2228 | |4;audio-oscillator;95;99;137;79;79;80;124;78;76;115 00:21:35 INFO - PROCESS | 2228 | |5;imaging-gaussian-blur;88;87;89;88;133;85;89;85;88;84 00:21:35 INFO - PROCESS | 2228 | |6;imaging-darkroom;93;97;94;95;94;104;149;94;93;101 00:21:35 INFO - PROCESS | 2228 | |7;imaging-desaturate;85;89;88;90;86;86;85;84;85;86 00:21:35 INFO - PROCESS | 2228 | |8;json-parse-financial;59;58;58;58;57;57;60;73;59;57 00:21:35 INFO - PROCESS | 2228 | |9;json-stringify-tinderbox;65;63;73;81;72;71;70;68;68;68 00:21:35 INFO - PROCESS | 2228 | |10;stanford-crypto-aes;86;87;85;85;86;84;85;84;86;86 00:21:35 INFO - PROCESS | 2228 | |11;stanford-crypto-ccm;160;161;158;145;142;143;143;141;186;144 00:21:35 INFO - PROCESS | 2228 | |12;stanford-crypto-pbkdf2;173;170;166;199;226;225;181;172;170;170 00:21:35 INFO - PROCESS | 2228 | |13;stanford-crypto-sha256-iterative;61;94;98;70;68;68;68;70;71;67 00:21:35 INFO - PROCESS | 2228 | __end_tp_report 00:21:35 INFO - PROCESS | 2228 | __start_cc_report 00:21:35 INFO - PROCESS | 2228 | _x_x_mozilla_cycle_collect,1906 00:21:35 INFO - PROCESS | 2228 | __end_cc_report 00:21:35 INFO - PROCESS | 2228 | __startTimestamp1460704895817__endTimestamp 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | ------- Summary: start ------- 00:21:35 INFO - PROCESS | 2228 | Number of tests: 14 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#0] ai-astar Cycles:10 Average:123.10 Median:114.50 stddev:20.66 (18.0%) stddev-sans-first:21.59 00:21:35 INFO - PROCESS | 2228 | Values: 113.0 109.0 113.0 116.0 160.0 164.0 118.0 113.0 113.0 112.0 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:153.50 stddev:22.83 (14.9%) stddev-sans-first:22.57 00:21:35 INFO - PROCESS | 2228 | Values: 167.0 117.0 146.0 168.0 120.0 145.0 161.0 118.0 172.0 121.0 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#2] audio-dft Cycles:10 Average:196.50 Median:187.00 stddev:26.58 (14.2%) stddev-sans-first:27.97 00:21:35 INFO - PROCESS | 2228 | Values: 187.0 186.0 186.0 187.0 222.0 264.0 181.0 183.0 180.0 189.0 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#3] audio-fft Cycles:10 Average:75.50 Median:78.50 stddev:13.98 (17.8%) stddev-sans-first:14.82 00:21:35 INFO - PROCESS | 2228 | Values: 76.0 73.0 89.0 91.0 56.0 60.0 58.0 80.0 95.0 77.0 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:79.00 stddev:22.09 (28.0%) stddev-sans-first:23.43 00:21:35 INFO - PROCESS | 2228 | Values: 95.0 99.0 137.0 79.0 79.0 80.0 124.0 78.0 76.0 115.0 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#5] imaging-gaussian-blur Cycles:10 Average:91.60 Median:88.00 stddev:14.65 (16.7%) stddev-sans-first:15.48 00:21:35 INFO - PROCESS | 2228 | Values: 88.0 87.0 89.0 88.0 133.0 85.0 89.0 85.0 88.0 84.0 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:94.00 stddev:17.12 (18.2%) stddev-sans-first:17.89 00:21:35 INFO - PROCESS | 2228 | Values: 93.0 97.0 94.0 95.0 94.0 104.0 149.0 94.0 93.0 101.0 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#7] imaging-desaturate Cycles:10 Average:86.40 Median:86.00 stddev:1.96 (2.3%) stddev-sans-first:2.01 00:21:35 INFO - PROCESS | 2228 | Values: 85.0 89.0 88.0 90.0 86.0 86.0 85.0 84.0 85.0 86.0 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:58.50 stddev:4.81 (8.2%) stddev-sans-first:5.10 00:21:35 INFO - PROCESS | 2228 | Values: 59.0 58.0 58.0 58.0 57.0 57.0 60.0 73.0 59.0 57.0 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.50 stddev:4.95 (7.0%) stddev-sans-first:4.93 00:21:35 INFO - PROCESS | 2228 | Values: 65.0 63.0 73.0 81.0 72.0 71.0 70.0 68.0 68.0 68.0 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:86.00 stddev:0.97 (1.1%) stddev-sans-first:1.00 00:21:35 INFO - PROCESS | 2228 | Values: 86.0 87.0 85.0 85.0 86.0 84.0 85.0 84.0 86.0 86.0 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#11] stanford-crypto-ccm Cycles:10 Average:152.30 Median:151.50 stddev:14.27 (9.4%) stddev-sans-first:14.86 00:21:35 INFO - PROCESS | 2228 | Values: 160.0 161.0 158.0 145.0 142.0 143.0 143.0 141.0 186.0 144.0 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.20 Median:177.00 stddev:23.19 (13.1%) stddev-sans-first:24.18 00:21:35 INFO - PROCESS | 2228 | Values: 173.0 170.0 166.0 199.0 226.0 225.0 181.0 172.0 170.0 170.0 00:21:35 INFO - PROCESS | 2228 | 00:21:35 INFO - PROCESS | 2228 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:70.00 stddev:12.20 (17.4%) stddev-sans-first:12.08 00:21:35 INFO - PROCESS | 2228 | Values: 61.0 94.0 98.0 70.0 68.0 68.0 68.0 70.0 71.0 67.0 00:21:35 INFO - PROCESS | 2228 | -------- Summary: end -------- 00:21:35 INFO - PROCESS | 2228 | 00:21:36 INFO - TEST-INFO | 2228: exit 0 00:21:36 INFO - TEST-OK | kraken | took 59100ms 00:21:36 INFO - SUITE-END | took 1172s 00:21:36 INFO - Completed test suite (00:19:33) 00:21:36 INFO - javascript benchmark 00:21:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2785.90249341273, 3312.850921708523, 3322.717569006635, 3345.8996230113107, 3367.8652853885847, 5456.61803673743, 5461.18082287657, 5465.371482279534, 5468.507834294917, 5559.861003474915, 5524.088525393316, 5557.305336832896, 5583.4974852263285, 5674.120511320742, 5678.290213723285, 5010.6993580385115, 5012.122878496267, 5024.773885175176, 5046.84859454217, 5228.189630607243, 5140.074786538422, 5156.355455568054, 5166.638335316529, 5179.4561571035065, 5185.222216667511, 5025.497450254945, 5040.546350828425, 5080.085584594772, 5112.437631860511, 5146.845594632167, 5054.519820617035, 5085.720285384303, 5101.668391554537, 5108.335916330853, 5127.102757017545, 5026.974865125651, 5062.670926389773, 5068.138416469173, 5077.289179514871, 5086.77109530072, 5390.757415916293, 5468.234447177399, 5525.557955363562, 5538.252335934678, 5545.584081193886, 6092.238470191226, 6092.573519853612, 6104.298005729309, 6105.389461053904, 6109.908351374733, 5910.822675319747, 6173.567850250484, 6194.9070763938125, 6197.349278325801, 6217.937820621826, 5520.033994051025, 5559.722013899329, 5624.3813180550105, 5628.986782379211, 5668.829935101953, 6218.720157592919, 6227.283862355796, 6246.656433896179, 6254.405831446005, 6262.405071518198, 5885.205497257901, 5904.852378690482, 5907.9070371981325, 5908.468237858528, 5927.792527261525, 5999.940000600025, 6014.278286605635, 6051.788187413419, 6099.054646529795, 6127.938720612737, 5544.251526044013, 5569.248151499496, 6091.725872335755, 6093.146959425684, 6105.0, 6057.363976782374, 6070.969645151746, 6075.848103797352, 6081.695915204222, 6142.109394137914, 4743.71919581711, 4774.618030557547, 4783.880402989953, 4810.6632535722165, 4812.903741925142, 3820.522434695663, 4343.804528796211, 4390.078083602415, 4392.209402307615, 4394.362817391524, 5493.725313734298, 5508.256385387921, 5510.752016159283, 5512.3385193777, 5512.889742205213, 6088.330283668793, 6099.128199784669, 6105.793350785129, 6117.2184870185665, 6121.392004143319, 5547.389787123413, 5556.138798486241, 5566.2207290979295, 5568.359638641527, 5568.916466253009, 5230.34620672416, 5294.814681486129, 5310.495502927216, 5324.707141107198, 5329.760160792696, 6238.469730072995, 6251.9687401562705, 6261.311255761953, 6265.498760099273, 6267.874642507216, 6157.353477884758, 6170.784022559189, 6175.783847565313, 6185.690715464299, 6188.381161883775, 6160.784372546939, 6162.568620196544, 6198.411150940653, 6251.062340648939, 6263.530235232284, 6033.758649653964, 6037.78867739627, 6047.395260474093, 6085.847853803691, 6100.176476175837, 5134.383873935151, 5435.537979271806, 6114.6636934969, 6160.075988601746, 6175.042868355412, 5798.652080875161, 5932.822015339547, 5933.970330148321, 5937.554683398849, 5946.405359464019, 5756.136579512939, 5844.977129002493, 5848.356680765198, 5849.736761845642, 5872.442117998783, 5323.866903327293, 5720.685362305113, 5730.598858079894, 5734.2258795062135, 5742.483176514133, 5829.883402332014, 5873.765049397976, 5879.323877754113, 5935.287765467999, 6038.818835434944, 5649.672327003069, 5671.319441667027, 5685.289338832646, 5687.20379146928, 5696.686682232517], "value": 5543.102320719781, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.5118574311229, 483.78471580146834, 491.15276148643596, 523.6360729293139, 525.4062908912925, 33840.32319353611, 36683.266334673324, 37348.439773403356, 37950.0, 38236.61763382362, 34555.654443455554, 43430.78284608573, 43569.0, 43571.692849214574, 43620.78189609048, 8558.828823423528, 8784.736457906272, 9640.80718385632, 9915.652952146676, 9915.702528924145, 9606.27952903535, 11930.224535405214, 11930.52277908887, 11965.940170299136, 11966.581169659064, 11663.766724665544, 11723.5896743614, 11826.349550774705, 11843.0, 11843.170978031541, 8605.225529702326, 8876.423032502931, 8892.244159246471, 11552.0, 11605.187636865423, 11538.942305288503, 11700.2979821211, 11706.239094458833, 11714.765704685944, 11722.296662200295, 38414.423783643266, 39123.0, 39196.0, 39199.0, 39249.41125883114, 3205.118592387089, 3280.2455435249785, 3287.539744435757, 3441.7590768646205, 3624.1664417184197, 28294.575581366295, 28489.85755071232, 28521.429571408662, 28546.0, 29183.0, 2743.492453896036, 2751.119641714652, 2753.5869619557025, 2799.412123454077, 2853.7146285371296, 478550.0, 483592.0, 484456.0, 484526.0, 486128.5693571545, 57789.8442031157, 58827.70586147042, 58828.70585647044, 58917.70541147267, 59077.0, 62998.68500657468, 64789.35210647927, 64839.0, 64891.35108648853, 65009.674951625886, 16453.259603317878, 16669.666606667797, 16715.832841671672, 16721.996680199227, 16724.83275167257, 11902.166848320707, 12205.75588488243, 12773.361331933553, 13104.475820967245, 13126.540571079966, 18755.718664220047, 18756.0, 18757.968311742985, 18762.343317983807, 18830.152643131092, 7178.856422871513, 7946.0, 8125.146859579659, 8205.384596155312, 8302.54336011513, 926.5552534783342, 957.674390707149, 1050.2438244463974, 1073.4954571351452, 1097.2429023973432, 28006.87972481078, 28319.71680283212, 28998.27504312409, 29225.97709080172, 29631.962881299052, 1091.2515509225834, 1100.8661079088552, 1145.4444594371723, 1203.4524291447547, 1290.9419076141596, 14988.775168372265, 15302.617434564003, 15357.692846143014, 15415.768763468333, 15453.0, 628608.0, 631184.0, 637535.0, 637712.0, 638182.0, 58077.419225808044, 58909.41090589039, 59066.0, 59077.818443631746, 59179.40820591739, 1354.8780609744972, 1479.3860547872728, 1496.4537943650707, 1532.4636377267866, 1542.9382824687111, 28603.713962860937, 29297.121086367442, 29413.97051103201, 30148.24629384283, 30498.542521861746, 46948.06103877971, 47349.0, 47613.85737641745, 47699.5230047709, 47719.76140119416, 411634.0, 415021.0, 415440.0, 416098.0, 417147.0, 26090.86954565215, 40242.0, 42349.78825105855, 42461.0, 42492.57507424886, 43176.13647726965, 44516.0, 44594.777026114665, 44942.550574493835, 45722.08555828931, 30614.84692576523, 31479.0, 36931.63068369282, 37090.44364334483, 37381.6261837389, 29101.563476548294, 36407.817960910026, 36525.63474365223, 37321.066973325884, 37482.62517374791, 36309.63690363063, 36760.448593271656, 36934.445983309735, 37300.44049339208, 37486.6251337494, 30548.236294092825, 32290.35419291554, 32342.0, 32381.676183238807, 32428.675713242566, 24289.878550607133, 24338.87830560836, 24369.63445548283, 25193.87403062973, 25236.74763252344], "value": 20016.560212844724, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.2454004261498, 378.387013038877, 382.47409811509175, 387.5717332347756, 396.3856023164095, 19145.904270478644, 20248.59502809943, 21040.263590774324, 21061.473463163427, 21138.57722845544, 20193.19227230909, 20781.272655457025, 21210.681839772376, 21277.82971936543, 21281.042353094108, 5276.683398996053, 5807.30312362515, 5987.161797348374, 6006.549508786837, 6014.8797024059495, 5899.262592175978, 5924.822255332326, 5939.316978547479, 5967.522598192156, 5980.252468441445, 5658.009848276556, 5864.23764910561, 5867.354590994987, 5870.794522191724, 5876.030454974924, 5589.273394458694, 5836.562257830658, 5858.853528661775, 5876.11858221266, 5897.085951677497, 5133.204353325241, 5168.819091331786, 5170.482951704837, 5173.611979101544, 5176.301199338079, 12120.27278363301, 12790.104692671517, 12795.0, 12801.103922725413, 25101.62347564788, 2050.5181282398626, 2143.2820005298245, 2145.313499680103, 2278.020451205981, 2290.2900100968586, 7846.450748447611, 8939.569668853017, 9427.717168484956, 10006.299559030798, 14514.564563063124, 1723.7500562418504, 1725.9741103883453, 1740.6605711886186, 1766.1963806468027, 1773.326136068286, 106806.46596767045, 107769.0, 110155.34766978581, 111039.44480277627, 111992.0, 11109.444527773741, 11324.32054076758, 11325.433728313552, 11326.943365283121, 11441.542338306375, 14361.64095897611, 14452.277386130652, 14463.710725785426, 14474.131552106906, 14477.611194153058, 8389.748307550783, 8462.534560599102, 8479.95760021196, 8550.230479256898, 8593.613287402082, 8338.0, 8381.539015354214, 8384.664613415516, 8544.31645468361, 8567.914320856837, 4346.239408103633, 4445.82216711328, 4453.532379100147, 4495.112052937774, 4519.796609152596, 1444.241773069126, 1821.3807305516189, 1821.7631707878156, 1885.708863730574, 1897.3738666240108, 917.7980844214262, 920.9907900921039, 930.6835675870236, 1024.4263212600968, 1062.4740753327153, 20129.396118116187, 20232.39302820918, 20257.189712411633, 20666.793332066787, 20720.689189662167, 807.3258828877836, 985.6796541124163, 996.8137915880079, 998.6404894238068, 1009.9798004039878, 10548.736281593021, 10925.508352124014, 10940.398278094623, 10957.452127393599, 10957.780844383067, 345260.0, 572154.0, 602629.0, 607785.0, 612172.0, 123613.38193309156, 125427.0, 125658.80167022803, 127202.0, 129260.3536982309, 1018.592562974816, 1021.2085633633993, 1038.5334829779274, 1044.0812085364983, 1048.130052056791, 14543.92728036353, 17897.37359192422, 18181.54546136357, 18247.361342352953, 18725.71911421357, 50246.2463063047, 50433.74783126061, 50762.74618626883, 51114.23328649999, 52116.47410999263, 73926.0, 75414.4917101644, 77592.61203693945, 79487.80768288596, 79723.60138199272, 7431.182569917197, 7794.259545343183, 7833.373330133461, 7898.644560994654, 8286.254237118446, 10654.89345106539, 11670.358130302575, 12420.254784712946, 12488.25070495773, 12858.807117893051, 6136.969315153396, 7183.676734546853, 10621.252481717425, 10905.56377744913, 11083.168762342695, 10586.258961872594, 10758.677239682822, 11017.283876548, 11184.328940263611, 11511.712207194887, 11258.380789056353, 11476.368799716096, 11750.764984700429, 11769.941150294193, 11861.05111591088, 6952.165740111017, 7976.1226265109635, 7992.040955085427, 8171.305439037748, 8357.164283571594, 9441.339106262496, 10898.346099234072, 11082.944585277022, 11088.445577721242, 11402.543898244308], "value": 9236.386583334814, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.211227768588, 1564.4211641692575, 1585.4609432792847, 1855.2300795170006, 2003.5291706448982, 7793.337566306864, 8377.664893404264, 8613.784655383619, 8625.22372986431, 8669.003064647568, 8334.458260213081, 8503.0, 8511.106333834936, 8517.276031537327, 8527.78680532987, 2038.7859274776158, 2383.7020372453444, 2383.8331316807785, 2409.313345696477, 2409.6987876515436, 2355.740868504463, 2384.2251268337773, 2398.352444839892, 2400.327908185711, 2401.627747699101, 2358.929232123039, 2381.0, 2389.6893403857475, 2399.9760002399926, 2405.4707964247837, 2346.5541547105995, 2374.3470545599926, 2376.370261880603, 2384.4873352229265, 2393.892274847636, 2120.6109997951366, 2202.801747842686, 2340.8829558522007, 2352.541254455382, 2373.9406514837096, 8437.83124337516, 8447.788805279855, 8603.483790972561, 8607.354448416394, 8625.956870215672, 5117.2068329409, 5299.708516031616, 5302.151655735102, 5305.893882122375, 5328.040952628526, 3385.0, 3422.7775194612273, 3452.2750222453315, 3485.9302813943577, 3494.4234201356744, 968.3657204531044, 978.3738407419257, 981.7643765496301, 988.17981075707, 1014.1075853249167, 10899.964503372246, 11130.443477826077, 11146.60986865464, 11156.553737850476, 11167.944160279229, 4337.327714204303, 4372.846950356722, 4425.336199570026, 4457.420535330386, 4507.121111383314, 8741.125887411208, 8747.781305467286, 8752.21230089295, 8758.737237882873, 8777.0, 5098.949010509921, 5106.004329155779, 5120.462351453119, 5123.231515272664, 5141.151709967889, 4323.546027667113, 4490.079533695584, 4669.813207471731, 4817.108834865561, 4838.491958344393, 3617.330793803102, 3650.379435495972, 3735.402335626287, 3746.2882052409955, 3779.3197224499304, 1135.5117299561102, 1146.3179408252076, 1149.6493569461227, 1157.2188772578645, 1160.9593664221711, 630.0926665601611, 813.4509206285734, 901.4861528928448, 933.8793447862456, 965.1362030982372, 5601.851620417805, 5729.7708091676695, 5755.9424405756245, 5840.883182336414, 5851.093080572518, 960.5869476125193, 964.1997142371955, 985.0, 996.890342062387, 1014.9391036537831, 3752.399616061417, 3944.2900277950243, 3979.383195604686, 4019.41718450823, 4059.594040595976, 10117.190624750003, 10489.527971241312, 10567.313124646873, 10651.30766500175, 10652.680419587425, 10617.416042117758, 10620.628278010232, 10625.203109766799, 10641.201909856793, 10644.68065958007, 8061.959690201512, 8070.636821343053, 8104.0, 8136.267735903797, 8136.552489613128, 5800.4489573490455, 5819.912701309399, 5840.766369345179, 6027.36712645166, 6069.514438844793, 9446.527673616429, 9579.425234485952, 9730.659426920023, 9768.511574421393, 9818.45998470091, 9885.95057024739, 9971.0, 10009.899901000897, 10014.998500150217, 10018.59925602997, 6391.3289104643345, 7155.356017958408, 7178.102737157856, 7341.449391295568, 7342.6695798688115, 7855.764327070197, 7868.567228802471, 7945.324647405036, 7954.244346787046, 7965.522068675898, 5980.372060933713, 7045.929540704732, 7061.964690176516, 7277.490575659653, 7322.048133742579, 7035.155781306072, 7036.085308909809, 7066.611336376549, 7095.077639906779, 7143.0, 6824.863502729819, 6915.0, 6918.757843475454, 6922.10012698346, 6931.44548436134, 4726.1492931272705, 4813.350197723403, 4846.224604063333, 4926.531979461945, 5068.873278168076, 3974.4634474345594, 4889.46215916256, 4912.1894887344615, 5045.344105266292, 5178.352705911761], "value": 4466.37429054882, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.3658227469139, 307.4542686731052, 314.7120384847864, 316.7624281788657, 322.759544139616, 5801.129830525423, 5889.381614930435, 5909.349971503137, 5921.289445266574, 5937.346891841894, 5303.337082864642, 5695.291412576231, 5766.769329226837, 5838.357780644125, 5857.033589457735, 3710.4063349864027, 4131.442255295534, 4271.252530807112, 4380.693351465383, 4445.777711114448, 4324.918770307423, 4391.736495810262, 4398.34024896265, 4402.141582391435, 4403.823847046115, 4375.190589740892, 4379.649628029766, 4390.956090439103, 4394.758288294142, 4403.295472724365, 4078.89802754932, 4115.8765237042935, 4350.608445239944, 4373.234683930299, 4376.934345984812, 3747.831347589338, 3750.4374343848367, 3769.509963704701, 3771.4908487354132, 3779.4519794629623, 4253.617174454283, 4444.911101777979, 5552.444755524456, 5590.692511911843, 5607.915881261784, 4611.700239484423, 4788.712677239377, 5051.646384753069, 5053.906335867959, 5098.719570423626, 5479.890402191933, 5944.7027648617395, 6110.73075904389, 6139.877202455926, 6144.324124346318, 4191.287481128215, 4232.597903199191, 4234.17433600448, 4234.894127646803, 4241.427407300006, 5437.401885792559, 5584.799268157366, 5985.760569577212, 6123.846903827395, 6585.341465853424, 3654.0134163775633, 3669.688076513473, 4001.459802926626, 4226.852060177879, 4288.785560721952, 4331.913361732748, 4333.898059441169, 4350.608445239944, 4369.628581570539, 4378.452693413324, 4247.745135291831, 4800.543948324904, 5079.644424890223, 5103.030424219386, 5130.1535246684625, 6204.472619827275, 6214.875702486015, 6215.595986260879, 6231.781887634002, 6237.937620623827, 1491.7762335649738, 1498.5654160293611, 1512.6898985707903, 1512.9621759456102, 1516.9381432996947, 843.325339728215, 932.165711688049, 936.2743873498093, 955.1499165742495, 995.581855620641, 4401.757903315349, 5854.853628659318, 5865.20819689345, 5886.499647529922, 5899.233099697012, 5757.88484230313, 6324.367563243639, 6364.49084073273, 6581.0, 6608.570442921195, 5591.232339991649, 5867.0, 5875.265591801025, 5876.6180198286975, 5888.970555147197, 3390.9152271193416, 3403.3193361327835, 3428.8628454861528, 3429.5027221052806, 3442.5008373785663, 5699.971500142473, 6793.422559082434, 6869.347411995853, 6900.585964842126, 6964.686589103502, 6053.606515576562, 6124.11200375943, 6659.134312539339, 6720.0, 6742.224644165885, 5303.4696530346655, 6825.419839313615, 6857.759978400732, 6861.176658800976, 6877.552959057645, 5124.8206312778875, 5459.181122831448, 6308.400701933309, 6542.877986868242, 6566.146400967844, 6094.542909281733, 6447.9032814508755, 6449.129367535323, 6456.418922297016, 6470.22357317125, 6522.934770652233, 6626.801195964129, 6631.502637302318, 6816.250212476524, 6857.451403887577, 3573.714102871816, 4096.56986016464, 4142.984968682692, 4155.3766934959995, 4165.91668166641, 6233.844153896189, 6518.804435866742, 6520.250171230369, 6523.869522609616, 6558.0, 6200.937990620036, 6272.435480806749, 6309.526785491014, 6313.2424109107205, 6315.905261421174, 5955.493783028491, 6299.275583307978, 6313.116163736989, 6335.556511044183, 6340.397662222082, 5457.863553411196, 5874.0, 6072.969635151796, 6158.04550294705, 6189.876202475835, 5005.849824505271, 6103.298120716175, 6239.407256310643, 6255.906161407674, 6322.146510221245, 6061.181740464981, 6185.721642526187, 6195.504359651307, 6207.782727604693, 6232.532560057924], "value": 4533.576984091418, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.969896354664, 671.7715976567968, 733.6515155301232, 762.2986852096071, 796.5738329993451, 14858.25708714564, 15215.0, 15237.238138093078, 15241.618959526017, 15260.084394936312, 13866.861331386683, 13967.650808729786, 14043.859561404382, 14090.0, 14107.435702571911, 911.7538264668535, 919.4529255093208, 922.0871337376017, 923.1737594852602, 926.8053708721177, 898.1108702384657, 907.773056735816, 912.6349460215934, 914.0356923445762, 915.844306467902, 821.2649678537705, 960.1790469148882, 960.7982323712029, 964.1274646444979, 966.5602151021303, 953.4660590069549, 954.19370631816, 956.1920177450079, 956.9903751542121, 957.1529196660936, 712.5368510468237, 713.3437237741291, 713.8286811165335, 714.3999040805696, 715.309726114297, 13165.802512962313, 13201.339933003408, 13222.20666759988, 13254.933725331312, 13258.66853328665, 11203.069334064143, 11335.206535542515, 11355.375454350007, 11381.943090284496, 11585.6524304271, 9412.105849944244, 9443.0, 9466.763330916714, 9632.325737198398, 10153.543090560943, 3455.188030812757, 3462.9653703462895, 3475.4265546184847, 3499.930001399983, 3566.3188749694573, 77677.6116119427, 78702.60648696776, 78731.60634196793, 78815.0, 78825.60587197027, 8274.552902387899, 8343.45767525109, 8432.620532076071, 8476.957615211884, 8713.3464990126, 18881.81118188828, 18897.90551047236, 18905.24379024851, 18912.621747564972, 18920.81079189218, 7496.7750967471065, 7551.773446796605, 7578.4316176287, 7635.0, 7701.537907725555, 4035.4552135461336, 4095.1809638072505, 4112.403701463331, 4158.979205103955, 4159.521655009652, 2844.260492271983, 2933.985330073336, 2936.6329208848892, 2938.8971386001813, 2941.2940894185667, 577.7140315543749, 615.9384061593805, 631.3812463785516, 634.9904751428639, 642.9260635026939, 496.55558275343367, 563.0541481950614, 585.5257241634205, 591.8905002574537, 608.5314307982828, 7756.767296981099, 7841.764747057598, 7863.410244231705, 8049.6377663005305, 8299.501131069082, 471.2342443529265, 499.86503644015914, 554.5092593055175, 596.5227817745786, 602.4607975861667, 3676.889693309205, 3746.6253374662315, 3761.887143385703, 3853.9807300963316, 3885.6502914737807, 83599.16400836036, 84198.0, 85161.72257416144, 85543.0, 85590.57204713937, 65208.673956629915, 66520.66739666367, 67802.32197678059, 70190.64904675444, 74693.25306746972, 51068.0, 51090.744546277034, 52866.678333041215, 60456.0, 63519.0, 7030.4024157945105, 8079.909212256505, 8161.2261822688215, 8349.83300334002, 8432.675493379966, 31319.06042818628, 42578.361324579535, 42870.78564607157, 43268.56731432645, 44821.32768008417, 54302.728486358894, 57854.0, 58137.0, 60235.49411264578, 62816.97464202944, 10650.946745266225, 10765.623203188165, 10850.891491085304, 11145.49845256949, 11725.0, 11957.820632690691, 12039.398030098286, 12057.0, 12233.204841685618, 12298.446569904554, 11123.388213648326, 11359.148063895076, 11614.763265813346, 11837.289762614271, 11863.406829658656, 11193.888061119285, 11281.379524125923, 11613.535458581562, 11815.59930226763, 12417.19288246261, 11057.557697692324, 11118.332900026024, 11249.100071994384, 11363.772724545628, 12327.568535101229, 9345.112214339626, 9350.25197984146, 9542.618295268388, 9655.806883862424, 9698.224142068477, 8484.06675265708, 9057.139571740674, 9610.375325604093, 9653.420794752057, 9698.612055517982], "value": 6600.085110413086, "unit": "score"}], "name": "dromaeo_css", "value": 7179.686923298123, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 113.0, 116.0, 160.0, 164.0, 118.0, 113.0, 113.0, 112.0], "value": 123.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 117.0, 146.0, 168.0, 120.0, 145.0, 161.0, 118.0, 172.0, 121.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 186.0, 187.0, 222.0, 264.0, 181.0, 183.0, 180.0, 189.0], "value": 196.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 89.0, 91.0, 56.0, 60.0, 58.0, 80.0, 95.0, 77.0], "value": 75.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 99.0, 137.0, 79.0, 79.0, 80.0, 124.0, 78.0, 76.0, 115.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 88.0, 133.0, 85.0, 89.0, 85.0, 88.0, 84.0], "value": 91.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 97.0, 94.0, 95.0, 94.0, 104.0, 149.0, 94.0, 93.0, 101.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 88.0, 90.0, 86.0, 86.0, 85.0, 84.0, 85.0, 86.0], "value": 86.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 58.0, 58.0, 57.0, 57.0, 60.0, 73.0, 59.0, 57.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 73.0, 81.0, 72.0, 71.0, 70.0, 68.0, 68.0, 68.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 85.0, 85.0, 86.0, 84.0, 85.0, 84.0, 86.0, 86.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 158.0, 145.0, 142.0, 143.0, 143.0, 141.0, 186.0, 144.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 170.0, 166.0, 199.0, 226.0, 225.0, 181.0, 172.0, 170.0, 170.0], "value": 185.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 94.0, 98.0, 70.0, 68.0, 68.0, 68.0, 70.0, 71.0, 67.0], "value": 73.5, "unit": "score"}], "name": "kraken", "value": 1540.1000000000001, "alertThreshold": 2.0}]} 00:21:37 INFO - Return code: 0 00:21:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 00:21:37 ERROR - # TBPL SUCCESS # 00:21:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:21:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:21:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:21:37 INFO - 2016-04-15 00:21:37.148 screenresolution[2232:18760] starting screenresolution argv=screenresolution get 00:21:37 INFO - 2016-04-15 00:21:37.150 screenresolution[2232:18760] Display 0: 1600x1200x32@60 00:21:37 INFO - 2016-04-15 00:21:37.156 screenresolution[2233:18764] starting screenresolution argv=screenresolution list 00:21:37 INFO - Available Modes on Display 0 00:21:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:21:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:21:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:21:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:21:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:21:37 INFO - Intel Iris: 00:21:37 INFO - Chipset Model: Intel Iris 00:21:37 INFO - Type: GPU 00:21:37 INFO - Bus: Built-In 00:21:37 INFO - VRAM (Dynamic, Max): 1536 MB 00:21:37 INFO - Vendor: Intel (0x8086) 00:21:37 INFO - Device ID: 0x0a2e 00:21:37 INFO - Revision ID: 0x0009 00:21:37 INFO - Displays: 00:21:37 INFO - Display: 00:21:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:21:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:21:37 INFO - Display Serial Number: 1600x1200 60 00:21:37 INFO - Main Display: Yes 00:21:37 INFO - Mirror: Off 00:21:37 INFO - Online: Yes 00:21:37 INFO - Rotation: Supported 00:21:37 INFO - Return code: 0 00:21:37 INFO - Running post-action listener: _resource_record_post_action 00:21:37 INFO - Running post-run listener: _resource_record_post_run 00:21:38 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 507904; Write bytes: 691847680; Read time: 7; Write time: 2095 00:21:38 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 381356544; Write bytes: 376104448; Read time: 28265; Write time: 680 00:21:38 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 507904; Write bytes: 293952512; Read time: 7; Write time: 1369 00:21:38 INFO - Running post-run listener: _upload_blobber_files 00:21:38 INFO - Blob upload gear active. 00:21:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:21:38 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 00:21:38 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'] 00:21:38 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 00:21:38 INFO - (blobuploader) - INFO - Open directory for files ... 00:21:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:21:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:21:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:21:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:21:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:21:39 INFO - (blobuploader) - INFO - Done attempting. 00:21:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:21:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:21:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:21:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:21:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:21:40 INFO - (blobuploader) - INFO - Done attempting. 00:21:40 INFO - (blobuploader) - INFO - Iteration through files over. 00:21:40 INFO - Return code: 0 00:21:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:21:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:21:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b29bbeb6401bc1d5b2fc90f368c3dcaeededd8b1a782126346a8d0b8db605aae0c748de96f57bfb3db6822e4b2411aeda074b8e2cf898c0ae9f19e8336dfd376", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4c953e5acbd45b15097a2e55bd45dbdd8a247f433265db875812ad338d0d4e1338a4f025059f78f9af3ea2a85e9fcc70c7649ce4a3686221dd0b85f0a9af982d"} 00:21:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:21:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:21:40 INFO - Contents: 00:21:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b29bbeb6401bc1d5b2fc90f368c3dcaeededd8b1a782126346a8d0b8db605aae0c748de96f57bfb3db6822e4b2411aeda074b8e2cf898c0ae9f19e8336dfd376", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4c953e5acbd45b15097a2e55bd45dbdd8a247f433265db875812ad338d0d4e1338a4f025059f78f9af3ea2a85e9fcc70c7649ce4a3686221dd0b85f0a9af982d"} 00:21:40 INFO - Running post-run listener: copy_logs_to_upload_dir 00:21:40 INFO - Copying logs to upload dir... 00:21:40 INFO - mkdir: /builds/slave/test/build/upload/logs 00:21:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.216114 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-15 00:21:40.800934) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-15 00:21:40.804014) ========= 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.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b29bbeb6401bc1d5b2fc90f368c3dcaeededd8b1a782126346a8d0b8db605aae0c748de96f57bfb3db6822e4b2411aeda074b8e2cf898c0ae9f19e8336dfd376", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4c953e5acbd45b15097a2e55bd45dbdd8a247f433265db875812ad338d0d4e1338a4f025059f78f9af3ea2a85e9fcc70c7649ce4a3686221dd0b85f0a9af982d"} build_url:https://queue.taskcluster.net/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011065 build_url: 'https://queue.taskcluster.net/v1/task/EaDFXm0VRQSa-lzMGt2fTg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b29bbeb6401bc1d5b2fc90f368c3dcaeededd8b1a782126346a8d0b8db605aae0c748de96f57bfb3db6822e4b2411aeda074b8e2cf898c0ae9f19e8336dfd376", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4c953e5acbd45b15097a2e55bd45dbdd8a247f433265db875812ad338d0d4e1338a4f025059f78f9af3ea2a85e9fcc70c7649ce4a3686221dd0b85f0a9af982d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-15 00:21:40.831254) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 00:21:40.831536) ========= 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.X5ti9k2Rmf/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.57nMEgkZRZ/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.005249 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 00:21:40.880359) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 00:21:40.880665) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 00:21:40.881023) ========= ========= Total master_lag: 0.17 =========