builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0108 starttime: 1462935721.14 results: success (0) buildid: 20160510174715 builduid: b1db30ce115e4403ad2a90eb4ace112c revision: 7fc6b24beea495728bc5070a5b621b31e403b82d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 20:02:01.143923) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 20:02:01.144386) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 20:02:01.144680) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 20:02:01.167825) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:02:01.168115) ========= 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.g15MoE3Zib/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.I6k5RT37Kp/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-10 20:02:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.26M=0.001s 2016-05-10 20:02:01 (8.26 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128817 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:02:01.313169) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:02:01.313527) ========= 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.g15MoE3Zib/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.I6k5RT37Kp/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.020881 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:02:01.362040) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 20:02:01.363152) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7fc6b24beea495728bc5070a5b621b31e403b82d --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 7fc6b24beea495728bc5070a5b621b31e403b82d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g15MoE3Zib/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.I6k5RT37Kp/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-10 20:02:01,433 truncating revision to first 12 chars 2016-05-10 20:02:01,433 Setting DEBUG logging. 2016-05-10 20:02:01,433 attempt 1/10 2016-05-10 20:02:01,433 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7fc6b24beea4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 20:02:02,668 unpacking tar archive at: fx-team-7fc6b24beea4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.481000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 20:02:02.861041) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 20:02:02.861395) ========= script_repo_revision: 7fc6b24beea495728bc5070a5b621b31e403b82d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 20:02:02.861828) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 20:02:02.862097) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 20:02:02.875613) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-10 20:02:02.875915) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g15MoE3Zib/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.I6k5RT37Kp/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 20:02:03 INFO - MultiFileLogger online at 20160510 20:02:03 in /builds/slave/test 20:02:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:02:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:02:03 INFO - {'append_to_log': False, 20:02:03 INFO - 'base_work_dir': '/builds/slave/test', 20:02:03 INFO - 'blob_upload_branch': 'Fx-Team', 20:02:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:02:03 INFO - 'branch': 'Fx-Team', 20:02:03 INFO - 'buildbot_json_path': 'buildprops.json', 20:02:03 INFO - 'config_files': ('talos/mac_config.py',), 20:02:03 INFO - 'default_actions': ('clobber', 20:02:03 INFO - 'read-buildbot-config', 20:02:03 INFO - 'download-and-extract', 20:02:03 INFO - 'populate-webroot', 20:02:03 INFO - 'create-virtualenv', 20:02:03 INFO - 'install', 20:02:03 INFO - 'run-tests'), 20:02:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:02:03 INFO - 'download_minidump_stackwalk': True, 20:02:03 INFO - 'download_symbols': 'ondemand', 20:02:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:02:03 INFO - 'tooltool.py': '/tools/tooltool.py', 20:02:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:02:03 INFO - '/tools/misc-python/virtualenv.py')}, 20:02:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:02:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:02:03 INFO - 'installer_path': 'installer.exe', 20:02:03 INFO - 'log_level': 'info', 20:02:03 INFO - 'log_name': 'talos', 20:02:03 INFO - 'log_to_console': True, 20:02:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:02:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:02:03 INFO - 'opt_config_files': (), 20:02:03 INFO - 'pip_index': False, 20:02:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:02:03 INFO - 'cmd': ('bash', 20:02:03 INFO - '-c', 20:02:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:02:03 INFO - 'enabled': True, 20:02:03 INFO - 'halt_on_failure': False, 20:02:03 INFO - 'name': 'check_screen_resolution'},), 20:02:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:02:03 INFO - 'cmd': ('bash', 20:02:03 INFO - '-c', 20:02:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:02:03 INFO - 'enabled': True, 20:02:03 INFO - 'halt_on_failure': False, 20:02:03 INFO - 'name': 'check_screen_resolution'},), 20:02:03 INFO - 'run_cmd_checks_enabled': True, 20:02:03 INFO - 'sps_profile': False, 20:02:03 INFO - 'sps_profile_interval': 0, 20:02:03 INFO - 'suite': 'dromaeojs-e10s', 20:02:03 INFO - 'system_bits': '32', 20:02:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:02:03 INFO - 'title': 't-yosemite-r7-0108', 20:02:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:02:03 INFO - 'use_talos_json': True, 20:02:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:02:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:02:03 INFO - 'work_dir': 'build'} 20:02:03 INFO - ##### 20:02:03 INFO - ##### Running clobber step. 20:02:03 INFO - ##### 20:02:03 INFO - Running pre-action listener: _resource_record_pre_action 20:02:03 INFO - Running main action method: clobber 20:02:03 INFO - rmtree: /builds/slave/test/build 20:02:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:02:04 INFO - Running post-action listener: _resource_record_post_action 20:02:04 INFO - ##### 20:02:04 INFO - ##### Finished clobber step (success) 20:02:04 INFO - ##### 20:02:04 INFO - ##### 20:02:04 INFO - ##### Running read-buildbot-config step. 20:02:04 INFO - ##### 20:02:04 INFO - Running pre-action listener: _resource_record_pre_action 20:02:04 INFO - Running main action method: read_buildbot_config 20:02:04 INFO - Using buildbot properties: 20:02:04 INFO - { 20:02:04 INFO - "project": "", 20:02:04 INFO - "product": "firefox", 20:02:04 INFO - "script_repo_revision": "production", 20:02:04 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 20:02:04 INFO - "repository": "", 20:02:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 20:02:04 INFO - "buildid": "20160510174715", 20:02:04 INFO - "pgo_build": "False", 20:02:04 INFO - "basedir": "/builds/slave/test", 20:02:04 INFO - "buildnumber": 660, 20:02:04 INFO - "slavename": "t-yosemite-r7-0108", 20:02:04 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 20:02:04 INFO - "platform": "yosemite_r7", 20:02:04 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 20:02:04 INFO - "branch": "fx-team", 20:02:04 INFO - "repo_path": "integration/fx-team", 20:02:04 INFO - "revision": "7fc6b24beea495728bc5070a5b621b31e403b82d", 20:02:04 INFO - "stage_platform": "macosx64", 20:02:04 INFO - "builduid": "b1db30ce115e4403ad2a90eb4ace112c", 20:02:04 INFO - "slavebuilddir": "test" 20:02:04 INFO - } 20:02:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 20:02:04 INFO - Running post-action listener: _resource_record_post_action 20:02:04 INFO - ##### 20:02:04 INFO - ##### Finished read-buildbot-config step (success) 20:02:04 INFO - ##### 20:02:04 INFO - ##### 20:02:04 INFO - ##### Running download-and-extract step. 20:02:04 INFO - ##### 20:02:04 INFO - Running pre-action listener: _resource_record_pre_action 20:02:04 INFO - Running main action method: download_and_extract 20:02:04 INFO - mkdir: /builds/slave/test/build/tests 20:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:04 INFO - https://queue.taskcluster.net/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:02:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:02:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:02:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 20:02:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 20:02:06 INFO - Downloaded 1199 bytes. 20:02:06 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 20:02:06 INFO - Using the following test package requirements: 20:02:06 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:02:06 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:02:06 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 20:02:06 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:02:06 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 20:02:06 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:02:06 INFO - u'jsshell-mac.zip'], 20:02:06 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:02:06 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 20:02:06 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:02:06 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:02:06 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 20:02:06 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:02:06 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 20:02:06 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:02:06 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 20:02:06 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:02:06 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 20:02:06 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:06 INFO - https://queue.taskcluster.net/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:02:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:02:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:02:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:02:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:02:07 INFO - Downloaded 18150487 bytes. 20:02:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:02:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:02:11 INFO - Return code: 0 20:02:11 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:11 INFO - https://queue.taskcluster.net/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:02:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:02:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:02:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:02:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:02:12 INFO - Downloaded 18150487 bytes. 20:02:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:02:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:02:15 INFO - Return code: 0 20:02: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')]} 20:02:15 INFO - https://queue.taskcluster.net/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:02:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:02:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:02:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:02:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:02:16 INFO - Downloaded 10840152 bytes. 20:02:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:02:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 20:02:17 INFO - Return code: 0 20:02:17 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')]} 20:02:17 INFO - https://queue.taskcluster.net/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 20:02:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:02:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:02:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 20:02:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 20:02:19 INFO - Downloaded 109846132 bytes. 20:02:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:02:19 INFO - mkdir: /builds/slave/test/properties 20:02:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:02:19 INFO - Writing to file /builds/slave/test/properties/build_url 20:02:19 INFO - Contents: 20:02:19 INFO - build_url:https://queue.taskcluster.net/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:02:21 INFO - Running post-action listener: _resource_record_post_action 20:02:21 INFO - Running post-action listener: set_extra_try_arguments 20:02:21 INFO - ##### 20:02:21 INFO - ##### Finished download-and-extract step (success) 20:02:21 INFO - ##### 20:02:21 INFO - ##### 20:02:21 INFO - ##### Running populate-webroot step. 20:02:21 INFO - ##### 20:02:21 INFO - Running pre-action listener: _resource_record_pre_action 20:02:21 INFO - Running main action method: populate_webroot 20:02:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:02:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:02:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:02:21 INFO - '--tppagecycles', 20:02:21 INFO - '7'], 20:02:21 INFO - 'tests': ['tsvgm']}}, 20:02:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:02:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:02:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:02:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:02:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:02:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:02:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:21 INFO - 'talos_options': ['--disable-e10s'], 20:02:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:02:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:02:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:21 INFO - 'talos_options': ['--disable-e10s'], 20:02:21 INFO - 'tests': ['damp', 'tps']}, 20:02:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:21 INFO - 'tests': ['damp', 'tps']}, 20:02:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:02:21 INFO - 'tests': ['dromaeo_dom']}, 20:02:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:02:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:02:21 INFO - 'tests': ['a11yr', 20:02:21 INFO - 'ts_paint', 20:02:21 INFO - 'tpaint', 20:02:21 INFO - 'sessionrestore', 20:02:21 INFO - 'sessionrestore_no_auto_restore', 20:02:21 INFO - 'tabpaint']}, 20:02:21 INFO - 'other-e10s': {'tests': ['a11yr', 20:02:21 INFO - 'ts_paint', 20:02:21 INFO - 'tpaint', 20:02:21 INFO - 'sessionrestore', 20:02:21 INFO - 'sessionrestore_no_auto_restore', 20:02:21 INFO - 'tabpaint']}, 20:02:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:02:21 INFO - 'ts_paint', 20:02:21 INFO - 'tpaint', 20:02:21 INFO - 'sessionrestore', 20:02:21 INFO - 'sessionrestore_no_auto_restore', 20:02:21 INFO - 'tabpaint']}, 20:02:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:02:21 INFO - 'ts_paint', 20:02:21 INFO - 'tpaint', 20:02:21 INFO - 'sessionrestore', 20:02:21 INFO - 'sessionrestore_no_auto_restore', 20:02:21 INFO - 'tabpaint']}, 20:02:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:02:21 INFO - 'tests': ['a11yr', 20:02:21 INFO - 'ts_paint', 20:02:21 INFO - 'tpaint', 20:02:21 INFO - 'sessionrestore', 20:02:21 INFO - 'sessionrestore_no_auto_restore', 20:02:21 INFO - 'tabpaint']}, 20:02:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:02:21 INFO - 'tests': ['a11yr', 20:02:21 INFO - 'ts_paint', 20:02:21 INFO - 'tpaint', 20:02:21 INFO - 'sessionrestore', 20:02:21 INFO - 'sessionrestore_no_auto_restore', 20:02:21 INFO - 'tabpaint']}, 20:02:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:02:21 INFO - 'tests': ['tsvgx', 20:02:21 INFO - 'tsvgr_opacity', 20:02:21 INFO - 'tart', 20:02:21 INFO - 'tscrollx', 20:02:21 INFO - 'cart']}, 20:02:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:02:21 INFO - 'tsvgr_opacity', 20:02:21 INFO - 'tart', 20:02:21 INFO - 'tscrollx', 20:02:21 INFO - 'cart']}, 20:02:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:21 INFO - 'talos_options': ['--disable-e10s'], 20:02:21 INFO - 'tests': ['tp5o']}, 20:02:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:21 INFO - 'tests': ['tp5o']}, 20:02:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:21 INFO - 'talos_options': ['--disable-e10s', 20:02:21 INFO - '--xperf_path', 20:02:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:02:21 INFO - 'tests': ['tp5n']}, 20:02:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:21 INFO - 'talos_options': ['--xperf_path', 20:02:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:02:21 INFO - 'tests': ['tp5n']}}, 20:02:21 INFO - 'talos.zip': {'path': '', 20:02:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:02:21 INFO - Running post-action listener: _resource_record_post_action 20:02:21 INFO - ##### 20:02:21 INFO - ##### Finished populate-webroot step (success) 20:02:21 INFO - ##### 20:02:21 INFO - ##### 20:02:21 INFO - ##### Running create-virtualenv step. 20:02:21 INFO - ##### 20:02:21 INFO - Running pre-action listener: _resource_record_pre_action 20:02:21 INFO - Running main action method: create_virtualenv 20:02:21 INFO - Creating virtualenv /builds/slave/test/build/venv 20:02:21 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 20:02:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:02:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:02:21 INFO - Using real prefix '/tools/python27' 20:02:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:02:22 INFO - Installing distribute.............................................................................................................................................................................................done. 20:02:24 INFO - Installing pip.................done. 20:02:24 INFO - Return code: 0 20:02:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:02: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')]} 20:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02: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')]} 20:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb80570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc01049c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1ed30>, '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 0x10f842db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa15b20>, '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.g15MoE3Zib/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.I6k5RT37Kp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 20:02: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', 'pip>=1.5'] in /builds/slave/test/build 20:02: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 pip>=1.5 20:02:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g15MoE3Zib/Render', 20:02:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:24 INFO - 'HOME': '/Users/cltbld', 20:02:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:24 INFO - 'LOGNAME': 'cltbld', 20:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:24 INFO - 'MOZ_NO_REMOTE': '1', 20:02:24 INFO - 'NO_EM_RESTART': '1', 20:02:24 INFO - 'PAGER': '/bin/cat', 20:02:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:24 INFO - 'PWD': '/builds/slave/test', 20:02:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:24 INFO - 'SHELL': '/bin/bash', 20:02:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I6k5RT37Kp/Listeners', 20:02:24 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 20:02:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:24 INFO - 'USER': 'cltbld', 20:02:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:24 INFO - 'XPC_FLAGS': '0x0', 20:02:24 INFO - 'XPC_SERVICE_NAME': '0', 20:02:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:25 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 20:02:25 INFO - Cleaning up... 20:02:25 INFO - Return code: 0 20:02:25 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 20:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:25 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 0x10fb7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb80570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc01049c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1ed30>, '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 0x10f842db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa15b20>, '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.g15MoE3Zib/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.I6k5RT37Kp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 20:02:25 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 20:02:25 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 20:02:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g15MoE3Zib/Render', 20:02:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:25 INFO - 'HOME': '/Users/cltbld', 20:02:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:25 INFO - 'LOGNAME': 'cltbld', 20:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:25 INFO - 'MOZ_NO_REMOTE': '1', 20:02:25 INFO - 'NO_EM_RESTART': '1', 20:02:25 INFO - 'PAGER': '/bin/cat', 20:02:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:25 INFO - 'PWD': '/builds/slave/test', 20:02:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:25 INFO - 'SHELL': '/bin/bash', 20:02:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I6k5RT37Kp/Listeners', 20:02:25 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 20:02:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:25 INFO - 'USER': 'cltbld', 20:02:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:25 INFO - 'XPC_FLAGS': '0x0', 20:02:25 INFO - 'XPC_SERVICE_NAME': '0', 20:02:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:25 INFO - Downloading/unpacking psutil>=0.7.1 20:02:25 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 20:02:25 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 20:02:25 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 20:02:25 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 20:02:25 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 20:02:25 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 20:02:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:02:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:02:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:02:28 INFO - Installing collected packages: psutil 20:02:28 INFO - Running setup.py install for psutil 20:02:28 INFO - building 'psutil._psutil_osx' extension 20:02:28 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 20:02:29 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 20:02:29 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 20:02:29 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 20:02:29 INFO - building 'psutil._psutil_posix' extension 20:02:29 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 20:02:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:02:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:02:29 INFO - ^ 20:02:29 INFO - 1 warning generated. 20:02:29 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 20:02:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:02:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:02:29 INFO - Successfully installed psutil 20:02:29 INFO - Cleaning up... 20:02:29 INFO - Return code: 0 20:02:29 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 20:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fb7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb80570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc01049c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1ed30>, '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 0x10f842db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa15b20>, '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.g15MoE3Zib/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.I6k5RT37Kp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 20:02:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 20:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 20:02:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g15MoE3Zib/Render', 20:02:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:29 INFO - 'HOME': '/Users/cltbld', 20:02:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:29 INFO - 'LOGNAME': 'cltbld', 20:02:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:29 INFO - 'MOZ_NO_REMOTE': '1', 20:02:29 INFO - 'NO_EM_RESTART': '1', 20:02:29 INFO - 'PAGER': '/bin/cat', 20:02:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:29 INFO - 'PWD': '/builds/slave/test', 20:02:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:29 INFO - 'SHELL': '/bin/bash', 20:02:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I6k5RT37Kp/Listeners', 20:02:29 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 20:02:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:29 INFO - 'USER': 'cltbld', 20:02:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:29 INFO - 'XPC_FLAGS': '0x0', 20:02:29 INFO - 'XPC_SERVICE_NAME': '0', 20:02:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:29 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:32 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:02:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:02:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 20:02:32 INFO - Installing collected packages: mozsystemmonitor 20:02:32 INFO - Running setup.py install for mozsystemmonitor 20:02:32 INFO - Successfully installed mozsystemmonitor 20:02:32 INFO - Cleaning up... 20:02:32 INFO - Return code: 0 20:02:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:02:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fb7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb80570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc01049c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1ed30>, '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 0x10f842db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa15b20>, '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.g15MoE3Zib/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.I6k5RT37Kp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 20:02:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:02:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g15MoE3Zib/Render', 20:02:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:32 INFO - 'HOME': '/Users/cltbld', 20:02:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:32 INFO - 'LOGNAME': 'cltbld', 20:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:32 INFO - 'MOZ_NO_REMOTE': '1', 20:02:32 INFO - 'NO_EM_RESTART': '1', 20:02:32 INFO - 'PAGER': '/bin/cat', 20:02:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:32 INFO - 'PWD': '/builds/slave/test', 20:02:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:32 INFO - 'SHELL': '/bin/bash', 20:02:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I6k5RT37Kp/Listeners', 20:02:32 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 20:02:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:32 INFO - 'USER': 'cltbld', 20:02:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:32 INFO - 'XPC_FLAGS': '0x0', 20:02:32 INFO - 'XPC_SERVICE_NAME': '0', 20:02:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:32 INFO - Downloading/unpacking blobuploader==1.2.4 20:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:35 INFO - Downloading blobuploader-1.2.4.tar.gz 20:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:02:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:02:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:36 INFO - Downloading docopt-0.6.1.tar.gz 20:02:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:02:36 INFO - Installing collected packages: blobuploader, requests, docopt 20:02:36 INFO - Running setup.py install for blobuploader 20:02:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:02:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:02:36 INFO - Running setup.py install for requests 20:02:37 INFO - Running setup.py install for docopt 20:02:37 INFO - Successfully installed blobuploader requests docopt 20:02:37 INFO - Cleaning up... 20:02:37 INFO - Return code: 0 20:02:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:02:37 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')]} 20:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:37 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')]} 20:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:37 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 0x10fb7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb80570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc01049c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1ed30>, '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 0x10f842db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa15b20>, '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.g15MoE3Zib/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.I6k5RT37Kp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 20:02:37 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 20:02:37 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 20:02:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g15MoE3Zib/Render', 20:02:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:37 INFO - 'HOME': '/Users/cltbld', 20:02:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:37 INFO - 'LOGNAME': 'cltbld', 20:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:37 INFO - 'MOZ_NO_REMOTE': '1', 20:02:37 INFO - 'NO_EM_RESTART': '1', 20:02:37 INFO - 'PAGER': '/bin/cat', 20:02:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:37 INFO - 'PWD': '/builds/slave/test', 20:02:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:37 INFO - 'SHELL': '/bin/bash', 20:02:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I6k5RT37Kp/Listeners', 20:02:37 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 20:02:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:37 INFO - 'USER': 'cltbld', 20:02:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:37 INFO - 'XPC_FLAGS': '0x0', 20:02:37 INFO - 'XPC_SERVICE_NAME': '0', 20:02:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:02:37 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-05npvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:02:37 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-aZT56q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:02:38 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-CzxUGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:02:38 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-AR3u0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:02:38 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-A6gzvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:02:38 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Z7pjFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:02:38 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-bDbflN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:02:38 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-yixEDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:02:38 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-4DgWL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:02:38 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-MMFtwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:02:38 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-5kx6rA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:02:38 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-SodyCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:02:39 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-YkJy4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:02:39 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-We3t9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:39 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-5JCd_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:02:39 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-p2PJck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:02:39 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-_WO2ZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:02:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:02:39 INFO - Running setup.py install for manifestparser 20:02:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:02:39 INFO - Running setup.py install for mozcrash 20:02:39 INFO - Running setup.py install for mozdebug 20:02:39 INFO - Running setup.py install for mozdevice 20:02:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:02:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:02:40 INFO - Running setup.py install for mozfile 20:02:40 INFO - Running setup.py install for mozhttpd 20:02:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:02:40 INFO - Running setup.py install for mozinfo 20:02:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:02:40 INFO - Running setup.py install for mozInstall 20:02:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:02:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:02:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:02:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:02:40 INFO - Running setup.py install for mozleak 20:02:40 INFO - Running setup.py install for mozlog 20:02:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:02:41 INFO - Running setup.py install for moznetwork 20:02:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:02:41 INFO - Running setup.py install for mozprocess 20:02:41 INFO - Running setup.py install for mozprofile 20:02:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:02:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:02:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:02:41 INFO - Running setup.py install for mozrunner 20:02:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:02:41 INFO - Running setup.py install for mozscreenshot 20:02:41 INFO - Running setup.py install for moztest 20:02:41 INFO - Running setup.py install for mozversion 20:02:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:02:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:02:42 INFO - Cleaning up... 20:02:42 INFO - Return code: 0 20:02:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:02:42 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')]} 20:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:42 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')]} 20:02:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:42 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 0x10fb7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb80570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc01049c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1ed30>, '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 0x10f842db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa15b20>, '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.g15MoE3Zib/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.I6k5RT37Kp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 20:02:42 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 20:02:42 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 20:02:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g15MoE3Zib/Render', 20:02:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:42 INFO - 'HOME': '/Users/cltbld', 20:02:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:42 INFO - 'LOGNAME': 'cltbld', 20:02:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:42 INFO - 'MOZ_NO_REMOTE': '1', 20:02:42 INFO - 'NO_EM_RESTART': '1', 20:02:42 INFO - 'PAGER': '/bin/cat', 20:02:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:42 INFO - 'PWD': '/builds/slave/test', 20:02:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:42 INFO - 'SHELL': '/bin/bash', 20:02:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I6k5RT37Kp/Listeners', 20:02:42 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 20:02:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:42 INFO - 'USER': 'cltbld', 20:02:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:42 INFO - 'XPC_FLAGS': '0x0', 20:02:42 INFO - 'XPC_SERVICE_NAME': '0', 20:02:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:02:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-sLqBei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:02:42 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)) 20:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:02:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-r0XGpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:02:42 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)) 20:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:02:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-x3CcUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:02:42 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)) 20:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:02:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-l2SCh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:02:42 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)) 20:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:02:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-0eauXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:02:42 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)) 20:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:02:42 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Xfky0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:02:43 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)) 20:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:02:43 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-50Hoza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:02:43 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)) 20:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:02:43 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-BJ8gOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:02:43 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)) 20:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:02:43 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-YlG4jX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:02:43 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)) 20:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:02:43 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-2nGbGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:02:43 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)) 20:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:02:43 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ugJfc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:02:43 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)) 20:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:02:43 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-54GuE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:02:43 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)) 20:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:02:43 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-GydR3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:02:43 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)) 20:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:02:43 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-nxqzU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:02:43 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)) 20:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:43 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Zip4kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:44 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)) 20:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:02:44 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-maw6uU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:02:44 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)) 20:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:02:44 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-WkxDZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:02:44 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)) 20:02:44 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)) 20:02:44 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)) 20:02:44 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)) 20:02:44 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)) 20:02:44 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)) 20:02:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:46 INFO - Downloading blessings-1.6.tar.gz 20:02:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:02:46 INFO - Installing collected packages: blessings 20:02:46 INFO - Running setup.py install for blessings 20:02:46 INFO - Successfully installed blessings 20:02:46 INFO - Cleaning up... 20:02:46 INFO - Return code: 0 20:02:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:02:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:02:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:02:47 INFO - Reading from file tmpfile_stdout 20:02:47 INFO - Current package versions: 20:02:47 INFO - blessings == 1.6 20:02:47 INFO - blobuploader == 1.2.4 20:02:47 INFO - docopt == 0.6.1 20:02:47 INFO - manifestparser == 1.1 20:02:47 INFO - mozInstall == 1.12 20:02:47 INFO - mozcrash == 0.17 20:02:47 INFO - mozdebug == 0.1 20:02:47 INFO - mozdevice == 0.48 20:02:47 INFO - mozfile == 1.2 20:02:47 INFO - mozhttpd == 0.7 20:02:47 INFO - mozinfo == 0.9 20:02:47 INFO - mozleak == 0.1 20:02:47 INFO - mozlog == 3.1 20:02:47 INFO - moznetwork == 0.27 20:02:47 INFO - mozprocess == 0.22 20:02:47 INFO - mozprofile == 0.28 20:02:47 INFO - mozrunner == 6.11 20:02:47 INFO - mozscreenshot == 0.1 20:02:47 INFO - mozsystemmonitor == 0.0 20:02:47 INFO - moztest == 0.7 20:02:47 INFO - mozversion == 1.4 20:02:47 INFO - psutil == 3.1.1 20:02:47 INFO - requests == 1.2.3 20:02:47 INFO - wsgiref == 0.1.2 20:02:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:02:47 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')]} 20:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:47 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')]} 20:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:47 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 0x10fb7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb80570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc01049c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1ed30>, '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 0x10f842db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa15b20>, '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.g15MoE3Zib/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.I6k5RT37Kp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 20:02:47 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 20:02:47 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 20:02:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g15MoE3Zib/Render', 20:02:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:47 INFO - 'HOME': '/Users/cltbld', 20:02:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:47 INFO - 'LOGNAME': 'cltbld', 20:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:47 INFO - 'MOZ_NO_REMOTE': '1', 20:02:47 INFO - 'NO_EM_RESTART': '1', 20:02:47 INFO - 'PAGER': '/bin/cat', 20:02:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:47 INFO - 'PWD': '/builds/slave/test', 20:02:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:47 INFO - 'SHELL': '/bin/bash', 20:02:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I6k5RT37Kp/Listeners', 20:02:47 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 20:02:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:47 INFO - 'USER': 'cltbld', 20:02:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:47 INFO - 'XPC_FLAGS': '0x0', 20:02:47 INFO - 'XPC_SERVICE_NAME': '0', 20:02:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:47 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)) 20:02:47 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)) 20:02:47 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)) 20:02:47 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)) 20:02:47 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)) 20:02:47 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)) 20:02:47 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)) 20:02:47 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)) 20:02:47 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)) 20:02:47 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)) 20:02:47 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)) 20:02:47 INFO - Cleaning up... 20:02:47 INFO - Return code: 0 20:02:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:02:47 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')]} 20:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:47 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')]} 20:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:47 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 0x10fb7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb80570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc01049c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1ed30>, '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 0x10f842db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa15b20>, '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.g15MoE3Zib/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.I6k5RT37Kp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 20:02:47 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 20:02:47 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 20:02:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g15MoE3Zib/Render', 20:02:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:47 INFO - 'HOME': '/Users/cltbld', 20:02:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:47 INFO - 'LOGNAME': 'cltbld', 20:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:47 INFO - 'MOZ_NO_REMOTE': '1', 20:02:47 INFO - 'NO_EM_RESTART': '1', 20:02:47 INFO - 'PAGER': '/bin/cat', 20:02:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:47 INFO - 'PWD': '/builds/slave/test', 20:02:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:47 INFO - 'SHELL': '/bin/bash', 20:02:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I6k5RT37Kp/Listeners', 20:02:47 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 20:02:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:47 INFO - 'USER': 'cltbld', 20:02:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:47 INFO - 'XPC_FLAGS': '0x0', 20:02:47 INFO - 'XPC_SERVICE_NAME': '0', 20:02:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:47 INFO - Downloading/unpacking jsonschema 20:02:47 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 20:02:47 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 20:02:47 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 20:02:47 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 20:02:47 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 20:02:47 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 20:02:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:02:50 INFO - Downloading/unpacking functools32 (from jsonschema) 20:02:50 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 20:02:50 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 20:02:50 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 20:02:50 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 20:02:50 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 20:02:50 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 20:02:50 INFO - Downloading functools32-3.2.3-2.tar.gz 20:02:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:02:50 INFO - warning: no files found matching '*.txt' 20:02:50 INFO - no previously-included directories found matching 'build' 20:02:50 INFO - no previously-included directories found matching 'dist' 20:02:50 INFO - no previously-included directories found matching '.git*' 20:02:50 INFO - Installing collected packages: jsonschema, functools32 20:02:50 INFO - Running setup.py install for functools32 20:02:50 INFO - warning: no files found matching '*.txt' 20:02:50 INFO - no previously-included directories found matching 'build' 20:02:50 INFO - no previously-included directories found matching 'dist' 20:02:50 INFO - no previously-included directories found matching '.git*' 20:02:50 INFO - Successfully installed jsonschema functools32 20:02:50 INFO - Cleaning up... 20:02:50 INFO - Return code: 0 20:02:50 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:02: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')]} 20:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02: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')]} 20:02:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:50 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 0x10fb7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb80570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc01049c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1ed30>, '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 0x10f842db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa15b20>, '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.g15MoE3Zib/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.I6k5RT37Kp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 20:02:50 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 20:02:50 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 20:02:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g15MoE3Zib/Render', 20:02:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:50 INFO - 'HOME': '/Users/cltbld', 20:02:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:50 INFO - 'LOGNAME': 'cltbld', 20:02:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:50 INFO - 'MOZ_NO_REMOTE': '1', 20:02:50 INFO - 'NO_EM_RESTART': '1', 20:02:50 INFO - 'PAGER': '/bin/cat', 20:02:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:50 INFO - 'PWD': '/builds/slave/test', 20:02:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:50 INFO - 'SHELL': '/bin/bash', 20:02:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I6k5RT37Kp/Listeners', 20:02:50 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 20:02:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:50 INFO - 'USER': 'cltbld', 20:02:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:50 INFO - 'XPC_FLAGS': '0x0', 20:02:50 INFO - 'XPC_SERVICE_NAME': '0', 20:02:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:51 INFO - Downloading/unpacking flake8 20:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:53 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:02:53 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:53 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:02:53 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:02:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:02:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:02:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:02:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:02:54 INFO - no previously-included directories found matching 'docs/_build' 20:02:54 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:02:54 INFO - Running setup.py install for pep8 20:02:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:02:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:02:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:02:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:02:55 INFO - no previously-included directories found matching 'docs/_build' 20:02:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:02:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:02:55 INFO - Cleaning up... 20:02:55 INFO - Return code: 0 20:02:55 INFO - Running post-action listener: _resource_record_post_action 20:02:55 INFO - Running post-action listener: _start_resource_monitoring 20:02:55 INFO - Starting resource monitoring. 20:02:55 INFO - ##### 20:02:55 INFO - ##### Finished create-virtualenv step (success) 20:02:55 INFO - ##### 20:02:55 INFO - ##### 20:02:55 INFO - ##### Running install step. 20:02:55 INFO - ##### 20:02:55 INFO - Running pre-action listener: _resource_record_pre_action 20:02:55 INFO - Running main action method: install 20:02:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:02:55 INFO - Reading from file tmpfile_stdout 20:02:55 INFO - Detecting whether we're running mozinstall >=1.0... 20:02:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:02:55 INFO - Reading from file tmpfile_stdout 20:02:55 INFO - Output received: 20:02:55 INFO - Usage: mozinstall [options] installer 20:02:55 INFO - Options: 20:02:55 INFO - -h, --help show this help message and exit 20:02:55 INFO - -d DEST, --destination=DEST 20:02:55 INFO - Directory to install application into. [default: 20:02:55 INFO - "/builds/slave/test"] 20:02:55 INFO - --app=APP Application being installed. [default: firefox] 20:02:55 INFO - mkdir: /builds/slave/test/build/application 20:02:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:03:29 INFO - Reading from file tmpfile_stdout 20:03:29 INFO - Output received: 20:03:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:03:29 INFO - Running post-action listener: _resource_record_post_action 20:03:29 INFO - ##### 20:03:29 INFO - ##### Finished install step (success) 20:03:29 INFO - ##### 20:03:29 INFO - ##### 20:03:29 INFO - ##### Running run-tests step. 20:03:29 INFO - ##### 20:03:29 INFO - Running pre-action listener: _resource_record_pre_action 20:03:29 INFO - Running main action method: run_tests 20:03:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:03:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:03:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:03:29 INFO - 2016-05-10 20:03:29.414 screenresolution[2245:42752] starting screenresolution argv=screenresolution get 20:03:29 INFO - 2016-05-10 20:03:29.417 screenresolution[2245:42752] Display 0: 1600x1200x32@60 20:03:29 INFO - 2016-05-10 20:03:29.423 screenresolution[2246:42754] starting screenresolution argv=screenresolution list 20:03:29 INFO - Available Modes on Display 0 20:03:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:03:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:03:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:03:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:03:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:03:29 INFO - Intel Iris: 20:03:29 INFO - Chipset Model: Intel Iris 20:03:29 INFO - Type: GPU 20:03:29 INFO - Bus: Built-In 20:03:29 INFO - VRAM (Dynamic, Max): 1536 MB 20:03:29 INFO - Vendor: Intel (0x8086) 20:03:29 INFO - Device ID: 0x0a2e 20:03:29 INFO - Revision ID: 0x0009 20:03:29 INFO - Displays: 20:03:29 INFO - Display: 20:03:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:03:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:03:29 INFO - Display Serial Number: 1600x1200 60 20:03:29 INFO - Main Display: Yes 20:03:29 INFO - Mirror: Off 20:03:29 INFO - Online: Yes 20:03:29 INFO - Rotation: Supported 20:03:29 INFO - Return code: 0 20:03:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:03:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:03:29 INFO - Python 2.7.3 20:03:29 INFO - Return code: 0 20:03:29 INFO - grabbing minidump binary from tooltool 20:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:03:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1ed30>, '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 0x10f842db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa15b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:03:29 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 20:03:29 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 20:03:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:03:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:03:29 INFO - Return code: 0 20:03:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:03:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:03:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:03:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:03:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:03:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:03:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:03:30 INFO - Return code: 0 20:03:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:03:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0108 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:03:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g15MoE3Zib/Render', 20:03:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:03:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:03:30 INFO - 'HOME': '/Users/cltbld', 20:03:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:03:30 INFO - 'LOGNAME': 'cltbld', 20:03:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:03:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:03:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:03:30 INFO - 'MOZ_NO_REMOTE': '1', 20:03:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:03:30 INFO - 'NO_EM_RESTART': '1', 20:03:30 INFO - 'PAGER': '/bin/cat', 20:03:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:03:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:03:30 INFO - 'PWD': '/builds/slave/test', 20:03:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:03:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:03:30 INFO - 'SHELL': '/bin/bash', 20:03:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I6k5RT37Kp/Listeners', 20:03:30 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 20:03:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:03:30 INFO - 'USER': 'cltbld', 20:03:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:03:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:03:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:03:30 INFO - 'XPC_FLAGS': '0x0', 20:03:30 INFO - 'XPC_SERVICE_NAME': '0', 20:03:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:03:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:03:31 INFO - mozversion application_buildid: 20160510174715 20:03:31 INFO - mozversion application_changeset: 7fc6b24beea495728bc5070a5b621b31e403b82d 20:03:31 INFO - mozversion application_display_name: Nightly 20:03:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:03:31 INFO - mozversion application_name: Firefox 20:03:31 INFO - mozversion application_remotingname: firefox 20:03:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:03:31 INFO - mozversion application_vendor: Mozilla 20:03:31 INFO - mozversion application_version: 49.0a1 20:03:31 INFO - mozversion platform_buildid: 20160510174715 20:03:31 INFO - mozversion platform_changeset: 7fc6b24beea495728bc5070a5b621b31e403b82d 20:03:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:03:31 INFO - mozversion platform_version: 49.0a1 20:03:31 INFO - using testdate: 1462935811 20:03:31 INFO - actual date: 1462935811 20:03:31 INFO - starting webserver on 'localhost:53472' 20:03:31 INFO - SUITE-START | Running 2 tests 20:03:31 INFO - TEST-START | dromaeo_css 20:03:31 INFO - operating with platform_type : mac_ 20:03:31 INFO - Initialising browser for dromaeo_css test... 20:03:31 INFO - TEST-INFO | started process 2264 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmp6ifAw_/profile http://localhost:53472/getInfo.html) 20:03:34 INFO - PROCESS | 2264 | __metrics Screen width/height:1600/1200 20:03:34 INFO - PROCESS | 2264 | colorDepth:24 20:03:34 INFO - PROCESS | 2264 | Browser inner width/height: 1024/689 20:03:34 INFO - PROCESS | 2264 | __metrics 20:03:34 ERROR - PROCESS | 2264 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 20:03:34 INFO - TEST-INFO | 2264: exit 0 20:03:34 INFO - Browser initialized. 20:03:34 INFO - Running cycle 1/1 for dromaeo_css test... 20:03:34 INFO - TEST-INFO | started process 2267 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmp6ifAw_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:03:47 INFO - PROCESS | 2267 | RSS: Main: 249606144 20:03:47 INFO - PROCESS | 2267 | 20:04:36 INFO - PROCESS | 2267 | 1462935876754 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] 20:06:48 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:53472/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53472/tests/dromaeo/cssquery-ext.html) 20:06:49 INFO - PROCESS | 2267 | RSS: Main: 263225344 20:06:49 INFO - PROCESS | 2267 | 20:09:52 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:53472/tests/dromaeo/cssquery-ext.html (next: http://localhost:53472/tests/dromaeo/cssquery-jquery.html) 20:09:52 INFO - PROCESS | 2267 | RSS: Main: 275013632 20:09:52 INFO - PROCESS | 2267 | 20:12:54 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:53472/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53472/tests/dromaeo/cssquery-mootools.html) 20:12:55 INFO - PROCESS | 2267 | RSS: Main: 276979712 20:12:55 INFO - PROCESS | 2267 | 20:15:57 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:53472/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53472/tests/dromaeo/cssquery-prototype.html) 20:15:57 INFO - PROCESS | 2267 | RSS: Main: 278654976 20:15:57 INFO - PROCESS | 2267 | 20:19:00 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:53472/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53472/tests/dromaeo/cssquery-yui.html) 20:19:01 INFO - PROCESS | 2267 | RSS: Main: 281399296 20:19:01 INFO - PROCESS | 2267 | 20:22:03 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:53472/tests/dromaeo/cssquery-yui.html (next: http://localhost:53472/tests/dromaeo/cssquery-dojo.html) 20:22:03 INFO - PROCESS | 2267 | RSS: Main: 282611712 20:22:03 INFO - PROCESS | 2267 | 20:22:03 INFO - PROCESS | 2267 | __start_tp_report 20:22:03 INFO - PROCESS | 2267 | _x_x_mozilla_page_load 20:22:03 INFO - PROCESS | 2267 | _x_x_mozilla_page_load_details 20:22:03 INFO - PROCESS | 2267 | |i|pagename|runs| 20:22:03 INFO - PROCESS | 2267 | |0;dojo.html;2987.7908546401754;3490.668386503282;3502.4396096624546;3554.520139781129;3554.857805687774;5621.016322143619;5827.737751801168;5850.648961062334;6001.459868611835;6007.939920600793;5141;5411.70119171399;5840.0947853082735;5881.147233651118;6012.278526576806;5253.4221235664045;5301;5309.548688361494;5392.460753924595;5439.292891924044;4927.303928893231;5036.6726162799305;5355;5457.154141108134;5460.890782184354;3993.301172294866;5300.92048619271;5346.0109879672;5361.195820626898;5385.461453854583;5004.9749751251375;5330.573554115662;5344.438833922421;5363.195520671891;5363.6245462817615;5241.449647786965;5326.094563924142;5368.248445217672;5369.463053694639;5408.188771684239;4712.293156026589;5509.311336082989;5738.741756620962;5799.536037117063;5844.532437404998;6241.063840423927;6286.654234017127;6297.6536290504;6314.463270622003;6343.048542718583;5704.401037891003;5949.524038076944;6256.998880179195;6260.530460215502;6388.201474815648;6016.75932962681;6083.270007599028;6085.817425477243;6092.45167934888;6134.907976380313;5455.1271796513165;6384.808455746335;6439.3238709934785;6474.741010359627;6501.31736167705;6168.25980882297;6222.564420490522;6240.095186197976;6320.462760665395;6333.493320534346;6323.241211054703;6336.366363363719;6345.524085693592;6361.4592759615025;6376.202974628171;6191.349908259568;6249.281332646713;6266.592671476339;6315.052742088632;6321.209848768904;6227.96886015567;6235.96882015587;6250.531210159165;6251.74993000284;6306.8423289417215;5000.224965130411;5032.295478632995;5043.167877300204;5050.924236136461;5078;4112.013116851994;4143.254214241405;4552.089582083597;4556.817727290938;4581.2440947243995;5811.360750317546;5995.190649262381;6048.879022419615;6079.544034197453;6093.96953015241;6288.119663247149;6353.5234857385885;6353.714082866282;6377.17096777416;6391.680415979183;6063.54523410746;6085.878282434326;6093.390660933871;6098.35967223444;6126.295476020226;4606.493285738566;5412.377576578665;5416.674999500043;5441.29263195782;5459.836204913859;5420.403755586883;6351.428371446592;6426.550141490051;6440.355964403522;6462.935370646233;5200.765965531485;6241.8751624966335;6308.558400912076;6329.367063293634;6334.841628959313;6414.743410263537;6442.549021568445;6497.350264973465;6581.967090164519;6583.835404114744;5232;6219.036049412348;6256.593321434274;6268.1851359323;6304.464120549805;5223.242629818731;6212.565120441525;6356.68216589178;6360.650164241011;6419.967900160469;5323.228131920927;6109.647596799152;6177.660228687286;6232.501399887908;6246.437820596168;6229.626222426669;6264.937350626436;6271.592346497463;6306.558540902093;6345.1434056401795;5946.583739138218;6001.789937352171;6099.352737958104;6230.813075607739;6234.189555357774;6213.5339849512375;6229.2524897012645;6288.025356069817;6315.684215789284;6315.715792789244;6205;6219.968900155471;6223;6224.221972253468;6232.25212974446 20:22:03 INFO - PROCESS | 2267 | |1;ext.html;480.8461292386435;512.4388794270274;516.4448218165473;548.6077454619947;570.8772613888013;38927.22145557087;41318.99999999996;41404.17191656165;41449.37825932606;41472.58527414725;42104.73685789423;42779.572204277945;42921.356179657254;42947;42976.57023429765;9720.07659272368;9842.114209721123;9911.900880991207;9933;9951.253655975795;9790.951045244763;9825.508724563742;9845.950770246138;9854.11312981835;9873.60505579776;9897.90102098977;9911.454869982148;9913.405195688245;9923.354981926152;9932.900670993306;9808.803823923517;9864.852027219598;9872.40765554069;9889.80220395588;9908;9711.854322185172;9816.901830981671;9825.803483930353;9842.9507852461;9849.965753595841;39460.605393946265;39625.40561891574;39643.8017809912;39775.40336894949;39784.80107599472;3245.6592057833823;3271.9018429447156;3421.4354631485776;3425.486177073434;3804.714646401531;28909.988150414843;30096.398072038435;30282.940097096496;30332.69667303321;30911.763529458796;2480.2931164618;2742.314421394651;2774.6392968914115;2774.9306267343277;2919;505514;511291;520086;521227.9999999962;521413;36453.63546364556;68270.65864670645;69730.30269697339;69777.30222697809;70076.64961675159;35856.64143358533;36918.815405922796;37136.25727485435;37334.879953601434;37460;12700.301483418501;15456.92271538635;15631.452899148475;15648.374065037498;15661.451849185452;10644.946775266075;12757.362131893367;13072.934635326763;13108.279044652445;13146.934265328613;8264.917350826414;8342.833143337099;8382;8413.70552030676;8440.282575980988;3465.5841299044278;3501.457274122515;3505.106197919514;3505.910318316741;3528.4354503279865;857.2756021162108;921.7648351209411;1018.0837246478229;1019.5310157327564;1023.4729114506035;29076.418471630448;29196.562051569243;29525.704742952727;30053;30157;1089.8992018061815;1105.8728246251624;1110.3005106782675;1116.9944150279196;1201.843760311154;13673.948907663731;14881.776773348409;15346.693066138614;15478.071315721092;15676.980996235208;431618.84190578846;502700.4864975652;564197;564267;564607;57123.714381427824;58790.11814822781;58922;59190.70404648035;59220.70389648111;1432.9856701433062;1444.5449683349711;1457.7157454296523;1470.4412323317072;1471.3820195517908;29153.85423072871;29265.41469170647;29532.852335739044;29734.810607575455;30104.09687709372;51371;51540.74229628828;51616;51666;51905.480945190066;405257;405912;407502.9249707465;407970;408819;26832.8658356707;27104.864475677496;27185.728142719112;27584.172474825788;27639.58540621852;45238.547614523435;45352;45556.31665524953;45713;46156;36978.260434790616;38136;38521.422178666784;39100.608993910835;40189;38213.549512407524;38248.235035299695;38437.615623843405;38995.41506877342;39192.60807392003;37399.43900841544;38092.619073808906;38266.808665956494;39358.212835743696;39441;33745.88406941253;33817.32365352728;34065.48901766525;34203.657963420046;34280.97157085291;22906.54186916219;23117.88441057784;23206;23312;23439.179628712915 20:22:03 INFO - PROCESS | 2267 | |2;jquery.html;397.33446477150767;400.5293779808724;401.7448919935841;402.6617641181407;419.4484253207033;20130.89934550327;20396.490087747778;21296.254631087915;21297.787022129774;21664.8916755416;12212.206206596587;12549.435275412605;17881.374151904685;21428.142874285048;21487.462813429673;5505.256790333294;5533.253010843525;5679.659220446766;5682.488576028157;5717.685527295997;5589.0219211637805;5597.776088956437;5637.295338082739;5642.097264437691;5654.123610840305;5611.410801865806;5702.515286200678;6064.363241859607;6075.939240607581;6137.785177518787;5274;6101.511879049665;6150.907736383958;6916.723331066752;6948.687309071053;5216.8695782605355;5638.718064096779;5737.8565535861935;6040.969795150996;6165.445109940127;12453.688657783538;14025.64935876601;20251.493712657153;26626.73373266262;26697.332566685793;1995.1819753900832;2128.4040468668795;2250.3670158501113;2387.2957477544164;2624.7925954061157;10050.9497452513;10262.897371026269;10273.640422585248;10323.174146068297;10480.266381353309;1493.3056128900005;1736.305477808874;1773.6807374672553;1813.5755400320193;1816.7819861616558;123862;128800;129174;131823;132399;10258.820235672843;11268.661940141808;11672;11936.701582460508;12123.939380303042;14021.17724695783;14956.878234132266;15465.14941678196;15611.843881561266;15640.92179539095;8766;8907.73276801697;8993.505357205286;9001.279897608176;9078.727638170865;8754.824903501894;8900.465972041698;8929;9013.143751343612;9045.412048216846;3807.2195199983935;4650.813967441332;4654.789754663811;4692.202325604655;4818.10864989978;1967.8622496425112;2019.1115909000287;2023.9898800506198;2027.2397850805949;2072.8652637578507;362.96733294003667;390.3559127439747;425.7525450430269;839.7487743262495;874.4796845876693;11835.467403966843;11884.286942783461;11954.103442241867;12019.87980120205;12267.57063502773;444.6242924728599;445.25197667918206;448.2379954078015;451.4898165073446;459.9701019433726;6079.665618390943;6090.446936031283;6154.969225153935;6221.96889015552;6242.282137554149;586786;612909;638595;639801;644247;102390;133997;144413.27793361177;145298;147127;800.2597597222546;978.3591747405461;998.7702828349375;1003.5233264199593;1025.1235193909101;18848.717269240697;19042.42872713821;19158;19200.903995479934;19228.71156932675;56273.718631406584;57028.71485642545;57137;57217.713911430175;57313;71974.64012679903;73746.26253737394;78920;81161.18838811536;85832.57083714791;5511.972440137773;6482.481401487964;7414.666340014604;8071.434999549975;8366.163383661586;10487.580496780043;12608.243505389706;12623.242605443702;12713.42789574453;12911.741765164456;10886.945565272123;11068.225224234226;11142.665720028412;11287.661370158909;11307.604233851776;10704.732381690521;10788.867168947147;11101.944490277498;11116.666500005012;11230.101591872468;10889;11239.831402528805;11579.478923448296;11605.825912611148;11608.878848008835;8173.386995975206;8287.171282871665;8306.584670766559;8323.292520135614;8437.526410770164;10044.949775251076;10161.796764064826;10346.017128372792;10962.671119866416;11328.546858125912 20:22:03 INFO - PROCESS | 2267 | |3;mootools.html;1347.9056466047377;1419.4889839657724;1694.3476761446848;1868.570228847366;1926.0754837677923;8186.304164146055;8610.827783444336;8699.260562852149;9160.17558419742;9165.220956218722;8819.691310804123;8859.379843410963;8985.2811775058;9032.774180645487;9067.637294508211;2449.1183174057282;2491.252624212738;2496.3010357100043;2497.063601149569;2500.099964012963;2431.732509423962;2479.3181874984434;2489.0168383488513;2491.7383674714165;2495.6880389951257;2447;2453.472503411766;2477;2486.3038349262147;2487.8507289562685;2436.914707985221;2442.645816356627;2449.2284930247006;2462.076721229539;2468.9259322220364;2416.5770990076667;2431.294924471901;2435.3789783605243;2443.1937460637946;2450.03223726627;7340.192578816326;8749.47503149813;8803.77990550235;8932.106789321017;9016.143466370684;5286.709230992294;5387.757550910218;5453.481919217668;5455.481729235717;5475.589330800166;3582.1044738815294;3610.9097272568133;3643.811450697413;3664.9554876859966;3674.3753561894273;937.7608709779031;1045.148204213571;1045.4406892312577;1062.4793851012948;1064.1486810551528;11713.238639488405;11854.585089521825;11861.644150675493;11873.465694043789;11925.642230733005;4775.21209000518;4851.56335929768;4941.678790878582;4949.7772600233075;4952.851414457572;9998.25013124004;10035;10048.246381521416;10051.798964020678;10057.14514266281;4561.2473941799235;5425.728713564306;5472.972635136799;5495.120780675073;5528.806491772769;4126.628603425706;4478.910421791546;4836.153673107192;5020.698758074527;5022.096022715946;4006.1186538961383;4054.412110243998;4133.338665813455;4154.729942553724;4155.252054630196;1366.8974826888023;1366.9726605467836;1371.136184203965;1376.105531404595;1385.7436374270792;907.9409838360485;930.2557953637198;939.178219058324;948.9952550237205;971.8104969531015;5866.442687944638;5990.9101363479585;6026.638401695912;6179.3820617937845;6209.875802483925;853;959.7648576098761;979.7312480337979;1012.4431562640518;1016.0398423489879;3905.882823515299;3981.462502562117;4025.295573274665;4046.2109888571445;4105.404716316118;10417.062464378243;10871.728206794734;10886.40124793144;11119.72200694989;11174.329540227613;10992.505337259843;11045.061169800658;11094.1124710023;11120.944395277971;11136.554537818396;7420.962895185489;8376.329893608498;8428.199321064612;8508.064112947572;8508.65965361391;5803.332616749128;6018.6689732063405;6078.300995385587;6151.815445536641;6180.258368995749;9733.318667693193;10054.597816087275;10234.692959211236;10270.229732769923;10333.328333658288;10351.65428494291;10534.947325263325;10599;10604.939506049333;10632.787344252916;6877.277885821916;7530.962345188458;7563.281488258606;7647.502912310682;7760.534367937942;8438.409311348147;8460.280876125598;8491.15088491146;8538.530380828866;8552.059273480038;7458.291462311072;7559.508631938907;7597.734079307198;7626.923730762622;7657.425693072931;7337.669804858688;7413.147488038729;7462.067241594801;7504.812379690551;7576.24237576238;7099.651066297387;7140.892886606601;7145.21402645719;7178.533395329287;7186.60473673932;4022.939655905105;4805.255185446262;4882.536159064884;5033.723145226902;5132.923006154985;4931.334269873521;4984.277279794482;4998.300237966615;5017.37282839645;5038.899222015612 20:22:03 INFO - PROCESS | 2267 | |4;prototype.html;315.59130925451535;318.3219741949647;319.7697657686465;320.9679032096792;326.9803811771295;4472.709273897194;5451.700156491396;5466.590005749565;5605.5515558755305;5645.520130788888;4614.792334344954;5080.110980578403;5317.032697901772;5392.514673679368;5422.9728851355685;3439.4840773883866;3892.2604705106082;4028.899277518071;4114.465119534456;4211.38934854446;4080.7143499955014;4145.31602285624;4147.315692910666;4162.771047592381;4166.979165104185;4093.5906409359122;4109.835606575733;4112.157007813406;4119.320312148492;4127.236461254663;3563.9109022274392;3581.3374525712698;4069.901126695805;4100.384942258685;4136.883041578757;3510.5611798525183;3519.9968009117533;3520.876769313087;3533.8233088345737;3544.3442963051916;4942.579880710144;5211.374635043781;5213.478652134756;5250.422453530108;5265.656782731686;4103.425520427142;4551;4688.742119183477;4696.154692155411;4705.3647757552635;5001.924971125436;5618.848136132083;5657.321121465409;5680.403557626472;5700.230468886689;3748.7750734955716;3881.165549406891;3888.1336958260194;3903.2974064668347;3935.736951253041;5671.986444812073;5972.6715030673295;5981.5513836462005;6015.849603759941;6041.969790151021;3199.712025917632;3281.917952051218;3360.445526488102;3883.3204189266767;3959.9406008909314;3275.819829909378;3507.982460087734;3861.227754449121;3983.342748446532;4010.839566417369;4241.088166044316;4310.159518893848;4529.909401811945;4695.694779839299;4705.85882423521;4815.903681926342;5061.645684802029;5418.83743487696;5552.805651802249;5896.646201227854;1202.6590351757727;1361.748076605831;1363.3026881532555;1386.646405166676;1398.6083896508994;722.8698834209788;761.2958013837178;803.8070862992956;803.8304267291078;806.5967016491754;4740.3408806918;5313.256144139824;5318.946810531923;5350.608841701186;5408;5056.595472362275;5063.594912407072;5898.734556944948;5915.615484993462;5938.970305148447;3997.8001099944886;4264.616184543344;4678.345031695565;4886.951130488721;5265.789368425297;2609.856457894796;2750.844645249;2784.5127102756937;2904.6514418269944;3034.286942568495;5809.941900581025;6261.467775239155;6270.592411493239;6354.872902541923;6356.4279214870885;4930.112579735682;5559.27729395176;5908.734106965197;5973.402659733992;6245.5628106033055;5960.612560183574;6001.399860013964;6197.5351848611535;6318.968405158037;6321.462675672619;4901.485344038824;5327.547158491571;5709.34342550612;5966.552508561962;6073.939260607514;4942.950570494249;5316.734163291897;5441.932065814965;5655;5936.554758393224;5274.868128296824;6211.378862113753;6269.655168965751;6309.432151106423;6309.716062777278;3767.9058023549633;3914.72596918213;3925.2738243425056;4106.630403263721;4110.691698122593;4925.842427029574;5828.697664422444;5839.8832023358445;5891.406242192345;5961.821145365646;5187.884604809984;5293.841184764463;5373.435789242073;5788.279508630937;5882.558808089325;4806.375171227718;4824.855254342376;4962.39474276008;5571.721413929368;5902.61632993854;4839.322494850794;4979.900401992012;5513.696746678971;5782.335031471434;5794.348610646114;4450.1989641864775;4769.948921963589;5108.514691104339;5551.666899985852;5874.794382196603;5055.620828437955;5607.635503692247;5710.628809127394;5723.570732195185;5762.12730862463 20:22:03 INFO - PROCESS | 2267 | |5;yui.html;665.936736010079;687.1307795638518;742.3244847189055;742.3779883439723;761.6648674583183;14396.208208548525;14672.92663536682;15301.92349038256;15413.537593872188;15434.845651543495;11463.484143213554;14059.297035148253;14112.71774564508;14120.293985300745;14163.29183540824;913.8126684029775;919.2278486071698;931.1991687149047;931.6413180925325;935.0369119806576;592.7925226170848;808.3412019204359;810.5096416667919;898.4743924803964;916.1525588830339;965.7199412170462;967.7048500207434;968.5060619084283;975.8145952269082;980.3284749946309;931.7624005878457;965.9420434773936;968.1286841842362;968.2737946540095;971.6113554578154;688.7899190746821;710.0839916507699;720.6684924934536;723.0780754538006;724.6195747232692;13144.60566183016;13453.730925381535;14542.27288635564;14602;14616.196109213988;12259.325737084457;12378.381080945917;12402.317872517007;12416.689582760413;12661.366931653472;8718.128187181232;9412.905870941271;9655.275854310954;9804.509774511318;9847.16299114576;3422.6235114137426;3471.6354782747953;3478.565179352581;3510.947335789965;3574.535310409656;81646;83166.58416707878;83215;83438;84396.57801711014;9146.86279705805;9171.35800493959;9276.90723092774;9392.483413412192;9480.146786789084;21190.258340958302;21362.252321168686;21372;21400;21511.892440537697;7414.890192874254;7465.141508726566;7678.692852285958;7736.883946740803;7768.495047821873;3982.7012974026484;4055.4322394864744;4067.6339129477897;4091.5908409158847;4095.3856921461424;3449.9482507762405;3475.6871881530783;3565.8038807865814;3566.8394922228563;3569.625189355132;601.3835818286292;640.5772190354344;644.1818890009662;648.8183308673579;667.3360006793272;522.8732082362515;578.7453520450988;581.1457157977766;597.0864577196809;613.046712362272;8052.919470805334;8166.83666326682;8176.264136227768;8179.509229446252;8271.1728827118;513.2557791202771;532.0715351711211;600.3066458240739;601.1853937914063;615.3108518459264;3542.628024057437;3860.2279544091293;3867.941980870289;3894.90262743428;3935.35066713989;84429;87780.68328975071;89091;89183;91034.08278462011;77234;78047.60976195196;78237.60881195671;78478;78857.4228515438;52597.474025260024;70522.64738676274;70629.64685176438;70896;71054.57890842257;8108.270255677017;8184.918150818416;8196.508209507449;8276.462029968034;8285.544295063588;38334;39115.6088439112;40528.3920741195;40672.38991415191;42797.786011069744;49306;55583.72208138934;62866;62869.74260514855;64089.03866441914;10857.022867941923;10967.616133435291;11014.229003969645;11015.118790496581;11384.715382115513;12002.579909703118;12013.939930300292;12049.638510844688;12293.078019148421;12387.19483233587;11229.04553112962;11488.425578720864;11508.539658413905;11544.769104617693;11605.419729013684;11172.273802202832;11266.887331126913;11539.769204615694;11602.303861768321;11642.6507204784;9315.86026209594;10351.585936562453;11470.51394440088;11541.884581154416;12240.938795305967;9156.705452523549;9323.72028839136;9487.288453365887;9740.16909946566;9903.851442228517;8007;8969.14793094655;9342.439453632804;9539.809203816023;9718.979507151647 20:22:03 INFO - PROCESS | 2267 | __end_tp_report 20:22:03 INFO - PROCESS | 2267 | __start_cc_report 20:22:03 INFO - PROCESS | 2267 | _x_x_mozilla_cycle_collect,423 20:22:03 INFO - PROCESS | 2267 | __end_cc_report 20:22:03 INFO - PROCESS | 2267 | __startTimestamp1462936923924__endTimestamp 20:22:03 INFO - PROCESS | 2267 | 20:22:03 INFO - PROCESS | 2267 | ------- Summary: start ------- 20:22:03 INFO - PROCESS | 2267 | Number of tests: 6 20:22:03 INFO - PROCESS | 2267 | 20:22:03 INFO - PROCESS | 2267 | [#0] dojo.html Cycles:165 Average:5807.33 Median:6098.36 stddev:694.68 (11.4%) stddev-sans-first:660.66 20:22:03 INFO - PROCESS | 2267 | Values: 2987.8 3490.7 3502.4 3554.5 3554.9 5621.0 5827.7 5850.6 6001.5 6007.9 5141.0 5411.7 5840.1 5881.1 6012.3 5253.4 5301.0 5309.5 5392.5 5439.3 4927.3 5036.7 5355.0 5457.2 5460.9 3993.3 5300.9 5346.0 5361.2 5385.5 5005.0 5330.6 5344.4 5363.2 5363.6 5241.4 5326.1 5368.2 5369.5 5408.2 4712.3 5509.3 5738.7 5799.5 5844.5 6241.1 6286.7 6297.7 6314.5 6343.0 5704.4 5949.5 6257.0 6260.5 6388.2 6016.8 6083.3 6085.8 6092.5 6134.9 5455.1 6384.8 6439.3 6474.7 6501.3 6168.3 6222.6 6240.1 6320.5 6333.5 6323.2 6336.4 6345.5 6361.5 6376.2 6191.3 6249.3 6266.6 6315.1 6321.2 6228.0 6236.0 6250.5 6251.7 6306.8 5000.2 5032.3 5043.2 5050.9 5078.0 4112.0 4143.3 4552.1 4556.8 4581.2 5811.4 5995.2 6048.9 6079.5 6094.0 6288.1 6353.5 6353.7 6377.2 6391.7 6063.5 6085.9 6093.4 6098.4 6126.3 4606.5 5412.4 5416.7 5441.3 5459.8 5420.4 6351.4 6426.6 6440.4 6462.9 5200.8 6241.9 6308.6 6329.4 6334.8 6414.7 6442.5 6497.4 6582.0 6583.8 5232.0 6219.0 6256.6 6268.2 6304.5 5223.2 6212.6 6356.7 6360.7 6420.0 5323.2 6109.6 6177.7 6232.5 6246.4 6229.6 6264.9 6271.6 6306.6 6345.1 5946.6 6001.8 6099.4 6230.8 6234.2 6213.5 6229.3 6288.0 6315.7 6315.7 6205.0 6220.0 6223.0 6224.2 6232.3 20:22:03 INFO - PROCESS | 2267 | 20:22:03 INFO - PROCESS | 2267 | [#1] ext.html Cycles:180 Average:61970.48 Median:39146.61 stddev:129684.86 (331.3%) stddev-sans-first:129966.49 20:22:03 INFO - PROCESS | 2267 | Values: 480.8 512.4 516.4 548.6 570.9 38927.2 41319.0 41404.2 41449.4 41472.6 42104.7 42779.6 42921.4 42947.0 42976.6 9720.1 9842.1 9911.9 9933.0 9951.3 9791.0 9825.5 9846.0 9854.1 9873.6 9897.9 9911.5 9913.4 9923.4 9932.9 9808.8 9864.9 9872.4 9889.8 9908.0 9711.9 9816.9 9825.8 9843.0 9850.0 39460.6 39625.4 39643.8 39775.4 39784.8 3245.7 3271.9 3421.4 3425.5 3804.7 28910.0 30096.4 30282.9 30332.7 30911.8 2480.3 2742.3 2774.6 2774.9 2919.0 505514.0 511291.0 520086.0 521228.0 521413.0 36453.6 68270.7 69730.3 69777.3 70076.6 35856.6 36918.8 37136.3 37334.9 37460.0 12700.3 15456.9 15631.5 15648.4 15661.5 10644.9 12757.4 13072.9 13108.3 13146.9 8264.9 8342.8 8382.0 8413.7 8440.3 3465.6 3501.5 3505.1 3505.9 3528.4 857.3 921.8 1018.1 1019.5 1023.5 29076.4 29196.6 29525.7 30053.0 30157.0 1089.9 1105.9 1110.3 1117.0 1201.8 13673.9 14881.8 15346.7 15478.1 15677.0 431618.8 502700.5 564197.0 564267.0 564607.0 57123.7 58790.1 58922.0 59190.7 59220.7 1433.0 1444.5 1457.7 1470.4 1471.4 29153.9 29265.4 29532.9 29734.8 30104.1 51371.0 51540.7 51616.0 51666.0 51905.5 405257.0 405912.0 407502.9 407970.0 408819.0 26832.9 27104.9 27185.7 27584.2 27639.6 45238.5 45352.0 45556.3 45713.0 46156.0 36978.3 38136.0 38521.4 39100.6 40189.0 38213.5 38248.2 38437.6 38995.4 39192.6 37399.4 38092.6 38266.8 39358.2 39441.0 33745.9 33817.3 34065.5 34203.7 34281.0 22906.5 23117.9 23206.0 23312.0 23439.2 20:22:03 INFO - PROCESS | 2267 | 20:22:03 INFO - PROCESS | 2267 | [#2] jquery.html Cycles:180 Average:35769.23 Median:26662.03 stddev:104670.81 (392.6%) stddev-sans-first:104930.75 20:22:03 INFO - PROCESS | 2267 | Values: 397.3 400.5 401.7 402.7 419.4 20130.9 20396.5 21296.3 21297.8 21664.9 12212.2 12549.4 17881.4 21428.1 21487.5 5505.3 5533.3 5679.7 5682.5 5717.7 5589.0 5597.8 5637.3 5642.1 5654.1 5611.4 5702.5 6064.4 6075.9 6137.8 5274.0 6101.5 6150.9 6916.7 6948.7 5216.9 5638.7 5737.9 6041.0 6165.4 12453.7 14025.6 20251.5 26626.7 26697.3 1995.2 2128.4 2250.4 2387.3 2624.8 10050.9 10262.9 10273.6 10323.2 10480.3 1493.3 1736.3 1773.7 1813.6 1816.8 123862.0 128800.0 129174.0 131823.0 132399.0 10258.8 11268.7 11672.0 11936.7 12123.9 14021.2 14956.9 15465.1 15611.8 15640.9 8766.0 8907.7 8993.5 9001.3 9078.7 8754.8 8900.5 8929.0 9013.1 9045.4 3807.2 4650.8 4654.8 4692.2 4818.1 1967.9 2019.1 2024.0 2027.2 2072.9 363.0 390.4 425.8 839.7 874.5 11835.5 11884.3 11954.1 12019.9 12267.6 444.6 445.3 448.2 451.5 460.0 6079.7 6090.4 6155.0 6222.0 6242.3 586786.0 612909.0 638595.0 639801.0 644247.0 102390.0 133997.0 144413.3 145298.0 147127.0 800.3 978.4 998.8 1003.5 1025.1 18848.7 19042.4 19158.0 19200.9 19228.7 56273.7 57028.7 57137.0 57217.7 57313.0 71974.6 73746.3 78920.0 81161.2 85832.6 5512.0 6482.5 7414.7 8071.4 8366.2 10487.6 12608.2 12623.2 12713.4 12911.7 10886.9 11068.2 11142.7 11287.7 11307.6 10704.7 10788.9 11101.9 11116.7 11230.1 10889.0 11239.8 11579.5 11605.8 11608.9 8173.4 8287.2 8306.6 8323.3 8437.5 10044.9 10161.8 10346.0 10962.7 11328.5 20:22:03 INFO - PROCESS | 2267 | 20:22:03 INFO - PROCESS | 2267 | [#3] mootools.html Cycles:180 Average:5817.25 Median:4843.86 stddev:3251.29 (67.1%) stddev-sans-first:3243.05 20:22:03 INFO - PROCESS | 2267 | Values: 1347.9 1419.5 1694.3 1868.6 1926.1 8186.3 8610.8 8699.3 9160.2 9165.2 8819.7 8859.4 8985.3 9032.8 9067.6 2449.1 2491.3 2496.3 2497.1 2500.1 2431.7 2479.3 2489.0 2491.7 2495.7 2447.0 2453.5 2477.0 2486.3 2487.9 2436.9 2442.6 2449.2 2462.1 2468.9 2416.6 2431.3 2435.4 2443.2 2450.0 7340.2 8749.5 8803.8 8932.1 9016.1 5286.7 5387.8 5453.5 5455.5 5475.6 3582.1 3610.9 3643.8 3665.0 3674.4 937.8 1045.1 1045.4 1062.5 1064.1 11713.2 11854.6 11861.6 11873.5 11925.6 4775.2 4851.6 4941.7 4949.8 4952.9 9998.3 10035.0 10048.2 10051.8 10057.1 4561.2 5425.7 5473.0 5495.1 5528.8 4126.6 4478.9 4836.2 5020.7 5022.1 4006.1 4054.4 4133.3 4154.7 4155.3 1366.9 1367.0 1371.1 1376.1 1385.7 907.9 930.3 939.2 949.0 971.8 5866.4 5990.9 6026.6 6179.4 6209.9 853.0 959.8 979.7 1012.4 1016.0 3905.9 3981.5 4025.3 4046.2 4105.4 10417.1 10871.7 10886.4 11119.7 11174.3 10992.5 11045.1 11094.1 11120.9 11136.6 7421.0 8376.3 8428.2 8508.1 8508.7 5803.3 6018.7 6078.3 6151.8 6180.3 9733.3 10054.6 10234.7 10270.2 10333.3 10351.7 10534.9 10599.0 10604.9 10632.8 6877.3 7531.0 7563.3 7647.5 7760.5 8438.4 8460.3 8491.2 8538.5 8552.1 7458.3 7559.5 7597.7 7626.9 7657.4 7337.7 7413.1 7462.1 7504.8 7576.2 7099.7 7140.9 7145.2 7178.5 7186.6 4022.9 4805.3 4882.5 5033.7 5132.9 4931.3 4984.3 4998.3 5017.4 5038.9 20:22:03 INFO - PROCESS | 2267 | 20:22:03 INFO - PROCESS | 2267 | [#4] prototype.html Cycles:180 Average:4573.82 Median:5028.77 stddev:1450.39 (28.8%) stddev-sans-first:1418.81 20:22:03 INFO - PROCESS | 2267 | Values: 315.6 318.3 319.8 321.0 327.0 4472.7 5451.7 5466.6 5605.6 5645.5 4614.8 5080.1 5317.0 5392.5 5423.0 3439.5 3892.3 4028.9 4114.5 4211.4 4080.7 4145.3 4147.3 4162.8 4167.0 4093.6 4109.8 4112.2 4119.3 4127.2 3563.9 3581.3 4069.9 4100.4 4136.9 3510.6 3520.0 3520.9 3533.8 3544.3 4942.6 5211.4 5213.5 5250.4 5265.7 4103.4 4551.0 4688.7 4696.2 4705.4 5001.9 5618.8 5657.3 5680.4 5700.2 3748.8 3881.2 3888.1 3903.3 3935.7 5672.0 5972.7 5981.6 6015.8 6042.0 3199.7 3281.9 3360.4 3883.3 3959.9 3275.8 3508.0 3861.2 3983.3 4010.8 4241.1 4310.2 4529.9 4695.7 4705.9 4815.9 5061.6 5418.8 5552.8 5896.6 1202.7 1361.7 1363.3 1386.6 1398.6 722.9 761.3 803.8 803.8 806.6 4740.3 5313.3 5318.9 5350.6 5408.0 5056.6 5063.6 5898.7 5915.6 5939.0 3997.8 4264.6 4678.3 4887.0 5265.8 2609.9 2750.8 2784.5 2904.7 3034.3 5809.9 6261.5 6270.6 6354.9 6356.4 4930.1 5559.3 5908.7 5973.4 6245.6 5960.6 6001.4 6197.5 6319.0 6321.5 4901.5 5327.5 5709.3 5966.6 6073.9 4943.0 5316.7 5441.9 5655.0 5936.6 5274.9 6211.4 6269.7 6309.4 6309.7 3767.9 3914.7 3925.3 4106.6 4110.7 4925.8 5828.7 5839.9 5891.4 5961.8 5187.9 5293.8 5373.4 5788.3 5882.6 4806.4 4824.9 4962.4 5571.7 5902.6 4839.3 4979.9 5513.7 5782.3 5794.3 4450.2 4769.9 5108.5 5551.7 5874.8 5055.6 5607.6 5710.6 5723.6 5762.1 20:22:03 INFO - PROCESS | 2267 | 20:22:03 INFO - PROCESS | 2267 | [#5] yui.html Cycles:180 Average:17577.99 Median:31755.16 stddev:24722.42 (77.9%) stddev-sans-first:24759.16 20:22:03 INFO - PROCESS | 2267 | Values: 665.9 687.1 742.3 742.4 761.7 14396.2 14672.9 15301.9 15413.5 15434.8 11463.5 14059.3 14112.7 14120.3 14163.3 913.8 919.2 931.2 931.6 935.0 592.8 808.3 810.5 898.5 916.2 965.7 967.7 968.5 975.8 980.3 931.8 965.9 968.1 968.3 971.6 688.8 710.1 720.7 723.1 724.6 13144.6 13453.7 14542.3 14602.0 14616.2 12259.3 12378.4 12402.3 12416.7 12661.4 8718.1 9412.9 9655.3 9804.5 9847.2 3422.6 3471.6 3478.6 3510.9 3574.5 81646.0 83166.6 83215.0 83438.0 84396.6 9146.9 9171.4 9276.9 9392.5 9480.1 21190.3 21362.3 21372.0 21400.0 21511.9 7414.9 7465.1 7678.7 7736.9 7768.5 3982.7 4055.4 4067.6 4091.6 4095.4 3449.9 3475.7 3565.8 3566.8 3569.6 601.4 640.6 644.2 648.8 667.3 522.9 578.7 581.1 597.1 613.0 8052.9 8166.8 8176.3 8179.5 8271.2 513.3 532.1 600.3 601.2 615.3 3542.6 3860.2 3867.9 3894.9 3935.4 84429.0 87780.7 89091.0 89183.0 91034.1 77234.0 78047.6 78237.6 78478.0 78857.4 52597.5 70522.6 70629.6 70896.0 71054.6 8108.3 8184.9 8196.5 8276.5 8285.5 38334.0 39115.6 40528.4 40672.4 42797.8 49306.0 55583.7 62866.0 62869.7 64089.0 10857.0 10967.6 11014.2 11015.1 11384.7 12002.6 12013.9 12049.6 12293.1 12387.2 11229.0 11488.4 11508.5 11544.8 11605.4 11172.3 11266.9 11539.8 11602.3 11642.7 9315.9 10351.6 11470.5 11541.9 12240.9 9156.7 9323.7 9487.3 9740.2 9903.9 8007.0 8969.1 9342.4 9539.8 9719.0 20:22:03 INFO - PROCESS | 2267 | -------- Summary: end -------- 20:22:03 INFO - PROCESS | 2267 | 20:22:04 INFO - TEST-INFO | 2267: exit 0 20:22:04 INFO - TEST-OK | dromaeo_css | took 1113383ms 20:22:04 INFO - TEST-START | kraken 20:22:04 INFO - operating with platform_type : mac_ 20:22:04 INFO - Initialising browser for kraken test... 20:22:04 INFO - TEST-INFO | started process 2272 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpSgauLU/profile http://localhost:53472/getInfo.html) 20:22:07 INFO - PROCESS | 2272 | __metrics Screen width/height:1600/1200 20:22:07 INFO - PROCESS | 2272 | colorDepth:24 20:22:07 INFO - PROCESS | 2272 | Browser inner width/height: 1024/689 20:22:07 INFO - PROCESS | 2272 | __metrics 20:22:07 ERROR - PROCESS | 2272 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 20:22:08 INFO - TEST-INFO | 2272: exit 0 20:22:08 INFO - Browser initialized. 20:22:08 INFO - Running cycle 1/1 for kraken test... 20:22:08 INFO - TEST-INFO | started process 2275 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpSgauLU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:22:20 INFO - PROCESS | 2275 | RSS: Main: 249790464 20:22:20 INFO - PROCESS | 2275 | 20:22:23 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53472/tests/kraken/driver.html?testName=audio-beat-detection) 20:22:24 INFO - PROCESS | 2275 | RSS: Main: 261558272 20:22:24 INFO - PROCESS | 2275 | 20:22:26 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53472/tests/kraken/driver.html?testName=audio-dft) 20:22:27 INFO - PROCESS | 2275 | RSS: Main: 266252288 20:22:27 INFO - PROCESS | 2275 | 20:22:31 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53472/tests/kraken/driver.html?testName=audio-fft) 20:22:31 INFO - PROCESS | 2275 | RSS: Main: 267218944 20:22:31 INFO - PROCESS | 2275 | 20:22:34 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53472/tests/kraken/driver.html?testName=audio-oscillator) 20:22:34 INFO - PROCESS | 2275 | RSS: Main: 267333632 20:22:34 INFO - PROCESS | 2275 | 20:22:37 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53472/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:22:37 INFO - PROCESS | 2275 | RSS: Main: 267874304 20:22:37 INFO - PROCESS | 2275 | 20:22:40 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53472/tests/kraken/driver.html?testName=imaging-darkroom) 20:22:41 INFO - PROCESS | 2275 | RSS: Main: 268476416 20:22:41 INFO - PROCESS | 2275 | 20:22:44 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53472/tests/kraken/driver.html?testName=imaging-desaturate) 20:22:45 INFO - PROCESS | 2275 | RSS: Main: 267751424 20:22:45 INFO - PROCESS | 2275 | 20:22:48 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53472/tests/kraken/driver.html?testName=json-parse-financial) 20:22:49 INFO - PROCESS | 2275 | RSS: Main: 268558336 20:22:49 INFO - PROCESS | 2275 | 20:22:51 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53472/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:22:51 INFO - PROCESS | 2275 | RSS: Main: 268939264 20:22:51 INFO - PROCESS | 2275 | 20:22:53 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53472/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:22:54 INFO - PROCESS | 2275 | RSS: Main: 268976128 20:22:54 INFO - PROCESS | 2275 | 20:22:56 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53472/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:22:57 INFO - PROCESS | 2275 | RSS: Main: 269225984 20:22:57 INFO - PROCESS | 2275 | 20:23:00 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53472/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:23:01 INFO - PROCESS | 2275 | RSS: Main: 269197312 20:23:01 INFO - PROCESS | 2275 | 20:23:04 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53472/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:23:04 INFO - PROCESS | 2275 | RSS: Main: 269283328 20:23:04 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53472/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53472/tests/kraken/driver.html?testName=ai-astar) 20:23:06 INFO - PROCESS | 2275 | RSS: Main: 271921152 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | __start_tp_report 20:23:06 INFO - PROCESS | 2275 | _x_x_mozilla_page_load 20:23:06 INFO - PROCESS | 2275 | _x_x_mozilla_page_load_details 20:23:06 INFO - PROCESS | 2275 | |i|pagename|runs| 20:23:06 INFO - PROCESS | 2275 | |0;ai-astar;112;117;123;175;118;115;108;116;116;115 20:23:06 INFO - PROCESS | 2275 | |1;audio-beat-detection;158;126;146;194;156;179;144;120;203;149 20:23:06 INFO - PROCESS | 2275 | |2;audio-dft;197;202;229;249;283;273;194;204;200;199 20:23:06 INFO - PROCESS | 2275 | |3;audio-fft;75;86;71;189;82;83;93;102;129;59 20:23:06 INFO - PROCESS | 2275 | |4;audio-oscillator;88;135;166;96;116;99;108;75;118;164 20:23:06 INFO - PROCESS | 2275 | |5;imaging-gaussian-blur;122;131;88;92;104;84;90;86;89;92 20:23:06 INFO - PROCESS | 2275 | |6;imaging-darkroom;94;118;150;100;107;153;118;100;151;121 20:23:06 INFO - PROCESS | 2275 | |7;imaging-desaturate;91;93;89;125;148;92;113;116;91;91 20:23:06 INFO - PROCESS | 2275 | |8;json-parse-financial;56;51;92;90;92;49;52;161;98;93 20:23:06 INFO - PROCESS | 2275 | |9;json-stringify-tinderbox;65;66;67;79;82;73;71;69;70;59 20:23:06 INFO - PROCESS | 2275 | |10;stanford-crypto-aes;89;89;90;89;88;88;90;93;110;74 20:23:06 INFO - PROCESS | 2275 | |11;stanford-crypto-ccm;156;154;133;137;140;141;147;136;245;165 20:23:06 INFO - PROCESS | 2275 | |12;stanford-crypto-pbkdf2;170;167;172;169;171;170;166;172;170;170 20:23:06 INFO - PROCESS | 2275 | |13;stanford-crypto-sha256-iterative;69;68;68;70;68;66;66;67;68;66 20:23:06 INFO - PROCESS | 2275 | __end_tp_report 20:23:06 INFO - PROCESS | 2275 | __start_cc_report 20:23:06 INFO - PROCESS | 2275 | _x_x_mozilla_cycle_collect,1081 20:23:06 INFO - PROCESS | 2275 | __end_cc_report 20:23:06 INFO - PROCESS | 2275 | __startTimestamp1462936986727__endTimestamp 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | ------- Summary: start ------- 20:23:06 INFO - PROCESS | 2275 | Number of tests: 14 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#0] ai-astar Cycles:10 Average:121.50 Median:116.50 stddev:19.19 (16.5%) stddev-sans-first:20.04 20:23:06 INFO - PROCESS | 2275 | Values: 112.0 117.0 123.0 175.0 118.0 115.0 108.0 116.0 116.0 115.0 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#1] audio-beat-detection Cycles:10 Average:157.50 Median:157.00 stddev:27.19 (17.3%) stddev-sans-first:28.84 20:23:06 INFO - PROCESS | 2275 | Values: 158.0 126.0 146.0 194.0 156.0 179.0 144.0 120.0 203.0 149.0 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#2] audio-dft Cycles:10 Average:223.00 Median:216.50 stddev:33.72 (15.6%) stddev-sans-first:34.43 20:23:06 INFO - PROCESS | 2275 | Values: 197.0 202.0 229.0 249.0 283.0 273.0 194.0 204.0 200.0 199.0 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#3] audio-fft Cycles:10 Average:96.90 Median:78.50 stddev:37.53 (47.8%) stddev-sans-first:38.96 20:23:06 INFO - PROCESS | 2275 | Values: 75.0 86.0 71.0 189.0 82.0 83.0 93.0 102.0 129.0 59.0 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#4] audio-oscillator Cycles:10 Average:116.50 Median:120.50 stddev:30.52 (25.3%) stddev-sans-first:30.58 20:23:06 INFO - PROCESS | 2275 | Values: 88.0 135.0 166.0 96.0 116.0 99.0 108.0 75.0 118.0 164.0 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#5] imaging-gaussian-blur Cycles:10 Average:97.80 Median:88.50 stddev:16.19 (18.3%) stddev-sans-first:14.61 20:23:06 INFO - PROCESS | 2275 | Values: 122.0 131.0 88.0 92.0 104.0 84.0 90.0 86.0 89.0 92.0 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#6] imaging-darkroom Cycles:10 Average:121.20 Median:135.50 stddev:22.58 (16.7%) stddev-sans-first:21.70 20:23:06 INFO - PROCESS | 2275 | Values: 94.0 118.0 150.0 100.0 107.0 153.0 118.0 100.0 151.0 121.0 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#7] imaging-desaturate Cycles:10 Average:104.90 Median:91.00 stddev:19.97 (22.0%) stddev-sans-first:20.54 20:23:06 INFO - PROCESS | 2275 | Values: 91.0 93.0 89.0 125.0 148.0 92.0 113.0 116.0 91.0 91.0 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#8] json-parse-financial Cycles:10 Average:83.40 Median:91.00 stddev:34.14 (37.5%) stddev-sans-first:34.74 20:23:06 INFO - PROCESS | 2275 | Values: 56.0 51.0 92.0 90.0 92.0 49.0 52.0 161.0 98.0 93.0 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.50 stddev:6.72 (9.5%) stddev-sans-first:6.87 20:23:06 INFO - PROCESS | 2275 | Values: 65.0 66.0 67.0 79.0 82.0 73.0 71.0 69.0 70.0 59.0 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:89.00 stddev:8.67 (9.7%) stddev-sans-first:9.18 20:23:06 INFO - PROCESS | 2275 | Values: 89.0 89.0 90.0 89.0 88.0 88.0 90.0 93.0 110.0 74.0 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#11] stanford-crypto-ccm Cycles:10 Average:155.40 Median:150.50 stddev:33.09 (22.0%) stddev-sans-first:35.10 20:23:06 INFO - PROCESS | 2275 | Values: 156.0 154.0 133.0 137.0 140.0 141.0 147.0 136.0 245.0 165.0 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:170.00 stddev:1.95 (1.1%) stddev-sans-first:2.06 20:23:06 INFO - PROCESS | 2275 | Values: 170.0 167.0 172.0 169.0 171.0 170.0 166.0 172.0 170.0 170.0 20:23:06 INFO - PROCESS | 2275 | 20:23:06 INFO - PROCESS | 2275 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:1.35 (2.0%) stddev-sans-first:1.33 20:23:06 INFO - PROCESS | 2275 | Values: 69.0 68.0 68.0 70.0 68.0 66.0 66.0 67.0 68.0 66.0 20:23:06 INFO - PROCESS | 2275 | -------- Summary: end -------- 20:23:06 INFO - PROCESS | 2275 | 20:23:07 INFO - TEST-INFO | 2275: exit 0 20:23:07 INFO - TEST-OK | kraken | took 63125ms 20:23:07 INFO - SUITE-END | took 1176s 20:23:07 INFO - Completed test suite (00:19:36) 20:23:07 INFO - javascript benchmark 20:23:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2987.7908546401754, 3490.668386503282, 3502.4396096624546, 3554.520139781129, 3554.857805687774, 5621.016322143619, 5827.737751801168, 5850.648961062334, 6001.459868611835, 6007.939920600793, 5141.0, 5411.70119171399, 5840.0947853082735, 5881.147233651118, 6012.278526576806, 5253.4221235664045, 5301.0, 5309.548688361494, 5392.460753924595, 5439.292891924044, 4927.303928893231, 5036.6726162799305, 5355.0, 5457.154141108134, 5460.890782184354, 3993.301172294866, 5300.92048619271, 5346.0109879672, 5361.195820626898, 5385.461453854583, 5004.9749751251375, 5330.573554115662, 5344.438833922421, 5363.195520671891, 5363.6245462817615, 5241.449647786965, 5326.094563924142, 5368.248445217672, 5369.463053694639, 5408.188771684239, 4712.293156026589, 5509.311336082989, 5738.741756620962, 5799.536037117063, 5844.532437404998, 6241.063840423927, 6286.654234017127, 6297.6536290504, 6314.463270622003, 6343.048542718583, 5704.401037891003, 5949.524038076944, 6256.998880179195, 6260.530460215502, 6388.201474815648, 6016.75932962681, 6083.270007599028, 6085.817425477243, 6092.45167934888, 6134.907976380313, 5455.1271796513165, 6384.808455746335, 6439.3238709934785, 6474.741010359627, 6501.31736167705, 6168.25980882297, 6222.564420490522, 6240.095186197976, 6320.462760665395, 6333.493320534346, 6323.241211054703, 6336.366363363719, 6345.524085693592, 6361.4592759615025, 6376.202974628171, 6191.349908259568, 6249.281332646713, 6266.592671476339, 6315.052742088632, 6321.209848768904, 6227.96886015567, 6235.96882015587, 6250.531210159165, 6251.74993000284, 6306.8423289417215, 5000.224965130411, 5032.295478632995, 5043.167877300204, 5050.924236136461, 5078.0, 4112.013116851994, 4143.254214241405, 4552.089582083597, 4556.817727290938, 4581.2440947243995, 5811.360750317546, 5995.190649262381, 6048.879022419615, 6079.544034197453, 6093.96953015241, 6288.119663247149, 6353.5234857385885, 6353.714082866282, 6377.17096777416, 6391.680415979183, 6063.54523410746, 6085.878282434326, 6093.390660933871, 6098.35967223444, 6126.295476020226, 4606.493285738566, 5412.377576578665, 5416.674999500043, 5441.29263195782, 5459.836204913859, 5420.403755586883, 6351.428371446592, 6426.550141490051, 6440.355964403522, 6462.935370646233, 5200.765965531485, 6241.8751624966335, 6308.558400912076, 6329.367063293634, 6334.841628959313, 6414.743410263537, 6442.549021568445, 6497.350264973465, 6581.967090164519, 6583.835404114744, 5232.0, 6219.036049412348, 6256.593321434274, 6268.1851359323, 6304.464120549805, 5223.242629818731, 6212.565120441525, 6356.68216589178, 6360.650164241011, 6419.967900160469, 5323.228131920927, 6109.647596799152, 6177.660228687286, 6232.501399887908, 6246.437820596168, 6229.626222426669, 6264.937350626436, 6271.592346497463, 6306.558540902093, 6345.1434056401795, 5946.583739138218, 6001.789937352171, 6099.352737958104, 6230.813075607739, 6234.189555357774, 6213.5339849512375, 6229.2524897012645, 6288.025356069817, 6315.684215789284, 6315.715792789244, 6205.0, 6219.968900155471, 6223.0, 6224.221972253468, 6232.25212974446], "value": 5764.351511677451, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.8461292386435, 512.4388794270274, 516.4448218165473, 548.6077454619947, 570.8772613888013, 38927.22145557087, 41318.99999999996, 41404.17191656165, 41449.37825932606, 41472.58527414725, 42104.73685789423, 42779.572204277945, 42921.356179657254, 42947.0, 42976.57023429765, 9720.07659272368, 9842.114209721123, 9911.900880991207, 9933.0, 9951.253655975795, 9790.951045244763, 9825.508724563742, 9845.950770246138, 9854.11312981835, 9873.60505579776, 9897.90102098977, 9911.454869982148, 9913.405195688245, 9923.354981926152, 9932.900670993306, 9808.803823923517, 9864.852027219598, 9872.40765554069, 9889.80220395588, 9908.0, 9711.854322185172, 9816.901830981671, 9825.803483930353, 9842.9507852461, 9849.965753595841, 39460.605393946265, 39625.40561891574, 39643.8017809912, 39775.40336894949, 39784.80107599472, 3245.6592057833823, 3271.9018429447156, 3421.4354631485776, 3425.486177073434, 3804.714646401531, 28909.988150414843, 30096.398072038435, 30282.940097096496, 30332.69667303321, 30911.763529458796, 2480.2931164618, 2742.314421394651, 2774.6392968914115, 2774.9306267343277, 2919.0, 505514.0, 511291.0, 520086.0, 521227.9999999962, 521413.0, 36453.63546364556, 68270.65864670645, 69730.30269697339, 69777.30222697809, 70076.64961675159, 35856.64143358533, 36918.815405922796, 37136.25727485435, 37334.879953601434, 37460.0, 12700.301483418501, 15456.92271538635, 15631.452899148475, 15648.374065037498, 15661.451849185452, 10644.946775266075, 12757.362131893367, 13072.934635326763, 13108.279044652445, 13146.934265328613, 8264.917350826414, 8342.833143337099, 8382.0, 8413.70552030676, 8440.282575980988, 3465.5841299044278, 3501.457274122515, 3505.106197919514, 3505.910318316741, 3528.4354503279865, 857.2756021162108, 921.7648351209411, 1018.0837246478229, 1019.5310157327564, 1023.4729114506035, 29076.418471630448, 29196.562051569243, 29525.704742952727, 30053.0, 30157.0, 1089.8992018061815, 1105.8728246251624, 1110.3005106782675, 1116.9944150279196, 1201.843760311154, 13673.948907663731, 14881.776773348409, 15346.693066138614, 15478.071315721092, 15676.980996235208, 431618.84190578846, 502700.4864975652, 564197.0, 564267.0, 564607.0, 57123.714381427824, 58790.11814822781, 58922.0, 59190.70404648035, 59220.70389648111, 1432.9856701433062, 1444.5449683349711, 1457.7157454296523, 1470.4412323317072, 1471.3820195517908, 29153.85423072871, 29265.41469170647, 29532.852335739044, 29734.810607575455, 30104.09687709372, 51371.0, 51540.74229628828, 51616.0, 51666.0, 51905.480945190066, 405257.0, 405912.0, 407502.9249707465, 407970.0, 408819.0, 26832.8658356707, 27104.864475677496, 27185.728142719112, 27584.172474825788, 27639.58540621852, 45238.547614523435, 45352.0, 45556.31665524953, 45713.0, 46156.0, 36978.260434790616, 38136.0, 38521.422178666784, 39100.608993910835, 40189.0, 38213.549512407524, 38248.235035299695, 38437.615623843405, 38995.41506877342, 39192.60807392003, 37399.43900841544, 38092.619073808906, 38266.808665956494, 39358.212835743696, 39441.0, 33745.88406941253, 33817.32365352728, 34065.48901766525, 34203.657963420046, 34280.97157085291, 22906.54186916219, 23117.88441057784, 23206.0, 23312.0, 23439.179628712915], "value": 18580.95705129923, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.33446477150767, 400.5293779808724, 401.7448919935841, 402.6617641181407, 419.4484253207033, 20130.89934550327, 20396.490087747778, 21296.254631087915, 21297.787022129774, 21664.8916755416, 12212.206206596587, 12549.435275412605, 17881.374151904685, 21428.142874285048, 21487.462813429673, 5505.256790333294, 5533.253010843525, 5679.659220446766, 5682.488576028157, 5717.685527295997, 5589.0219211637805, 5597.776088956437, 5637.295338082739, 5642.097264437691, 5654.123610840305, 5611.410801865806, 5702.515286200678, 6064.363241859607, 6075.939240607581, 6137.785177518787, 5274.0, 6101.511879049665, 6150.907736383958, 6916.723331066752, 6948.687309071053, 5216.8695782605355, 5638.718064096779, 5737.8565535861935, 6040.969795150996, 6165.445109940127, 12453.688657783538, 14025.64935876601, 20251.493712657153, 26626.73373266262, 26697.332566685793, 1995.1819753900832, 2128.4040468668795, 2250.3670158501113, 2387.2957477544164, 2624.7925954061157, 10050.9497452513, 10262.897371026269, 10273.640422585248, 10323.174146068297, 10480.266381353309, 1493.3056128900005, 1736.305477808874, 1773.6807374672553, 1813.5755400320193, 1816.7819861616558, 123862.0, 128800.0, 129174.0, 131823.0, 132399.0, 10258.820235672843, 11268.661940141808, 11672.0, 11936.701582460508, 12123.939380303042, 14021.17724695783, 14956.878234132266, 15465.14941678196, 15611.843881561266, 15640.92179539095, 8766.0, 8907.73276801697, 8993.505357205286, 9001.279897608176, 9078.727638170865, 8754.824903501894, 8900.465972041698, 8929.0, 9013.143751343612, 9045.412048216846, 3807.2195199983935, 4650.813967441332, 4654.789754663811, 4692.202325604655, 4818.10864989978, 1967.8622496425112, 2019.1115909000287, 2023.9898800506198, 2027.2397850805949, 2072.8652637578507, 362.96733294003667, 390.3559127439747, 425.7525450430269, 839.7487743262495, 874.4796845876693, 11835.467403966843, 11884.286942783461, 11954.103442241867, 12019.87980120205, 12267.57063502773, 444.6242924728599, 445.25197667918206, 448.2379954078015, 451.4898165073446, 459.9701019433726, 6079.665618390943, 6090.446936031283, 6154.969225153935, 6221.96889015552, 6242.282137554149, 586786.0, 612909.0, 638595.0, 639801.0, 644247.0, 102390.0, 133997.0, 144413.27793361177, 145298.0, 147127.0, 800.2597597222546, 978.3591747405461, 998.7702828349375, 1003.5233264199593, 1025.1235193909101, 18848.717269240697, 19042.42872713821, 19158.0, 19200.903995479934, 19228.71156932675, 56273.718631406584, 57028.71485642545, 57137.0, 57217.713911430175, 57313.0, 71974.64012679903, 73746.26253737394, 78920.0, 81161.18838811536, 85832.57083714791, 5511.972440137773, 6482.481401487964, 7414.666340014604, 8071.434999549975, 8366.163383661586, 10487.580496780043, 12608.243505389706, 12623.242605443702, 12713.42789574453, 12911.741765164456, 10886.945565272123, 11068.225224234226, 11142.665720028412, 11287.661370158909, 11307.604233851776, 10704.732381690521, 10788.867168947147, 11101.944490277498, 11116.666500005012, 11230.101591872468, 10889.0, 11239.831402528805, 11579.478923448296, 11605.825912611148, 11608.878848008835, 8173.386995975206, 8287.171282871665, 8306.584670766559, 8323.292520135614, 8437.526410770164, 10044.949775251076, 10161.796764064826, 10346.017128372792, 10962.671119866416, 11328.546858125912], "value": 8953.189582176125, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.9056466047377, 1419.4889839657724, 1694.3476761446848, 1868.570228847366, 1926.0754837677923, 8186.304164146055, 8610.827783444336, 8699.260562852149, 9160.17558419742, 9165.220956218722, 8819.691310804123, 8859.379843410963, 8985.2811775058, 9032.774180645487, 9067.637294508211, 2449.1183174057282, 2491.252624212738, 2496.3010357100043, 2497.063601149569, 2500.099964012963, 2431.732509423962, 2479.3181874984434, 2489.0168383488513, 2491.7383674714165, 2495.6880389951257, 2447.0, 2453.472503411766, 2477.0, 2486.3038349262147, 2487.8507289562685, 2436.914707985221, 2442.645816356627, 2449.2284930247006, 2462.076721229539, 2468.9259322220364, 2416.5770990076667, 2431.294924471901, 2435.3789783605243, 2443.1937460637946, 2450.03223726627, 7340.192578816326, 8749.47503149813, 8803.77990550235, 8932.106789321017, 9016.143466370684, 5286.709230992294, 5387.757550910218, 5453.481919217668, 5455.481729235717, 5475.589330800166, 3582.1044738815294, 3610.9097272568133, 3643.811450697413, 3664.9554876859966, 3674.3753561894273, 937.7608709779031, 1045.148204213571, 1045.4406892312577, 1062.4793851012948, 1064.1486810551528, 11713.238639488405, 11854.585089521825, 11861.644150675493, 11873.465694043789, 11925.642230733005, 4775.21209000518, 4851.56335929768, 4941.678790878582, 4949.7772600233075, 4952.851414457572, 9998.25013124004, 10035.0, 10048.246381521416, 10051.798964020678, 10057.14514266281, 4561.2473941799235, 5425.728713564306, 5472.972635136799, 5495.120780675073, 5528.806491772769, 4126.628603425706, 4478.910421791546, 4836.153673107192, 5020.698758074527, 5022.096022715946, 4006.1186538961383, 4054.412110243998, 4133.338665813455, 4154.729942553724, 4155.252054630196, 1366.8974826888023, 1366.9726605467836, 1371.136184203965, 1376.105531404595, 1385.7436374270792, 907.9409838360485, 930.2557953637198, 939.178219058324, 948.9952550237205, 971.8104969531015, 5866.442687944638, 5990.9101363479585, 6026.638401695912, 6179.3820617937845, 6209.875802483925, 853.0, 959.7648576098761, 979.7312480337979, 1012.4431562640518, 1016.0398423489879, 3905.882823515299, 3981.462502562117, 4025.295573274665, 4046.2109888571445, 4105.404716316118, 10417.062464378243, 10871.728206794734, 10886.40124793144, 11119.72200694989, 11174.329540227613, 10992.505337259843, 11045.061169800658, 11094.1124710023, 11120.944395277971, 11136.554537818396, 7420.962895185489, 8376.329893608498, 8428.199321064612, 8508.064112947572, 8508.65965361391, 5803.332616749128, 6018.6689732063405, 6078.300995385587, 6151.815445536641, 6180.258368995749, 9733.318667693193, 10054.597816087275, 10234.692959211236, 10270.229732769923, 10333.328333658288, 10351.65428494291, 10534.947325263325, 10599.0, 10604.939506049333, 10632.787344252916, 6877.277885821916, 7530.962345188458, 7563.281488258606, 7647.502912310682, 7760.534367937942, 8438.409311348147, 8460.280876125598, 8491.15088491146, 8538.530380828866, 8552.059273480038, 7458.291462311072, 7559.508631938907, 7597.734079307198, 7626.923730762622, 7657.425693072931, 7337.669804858688, 7413.147488038729, 7462.067241594801, 7504.812379690551, 7576.24237576238, 7099.651066297387, 7140.892886606601, 7145.21402645719, 7178.533395329287, 7186.60473673932, 4022.939655905105, 4805.255185446262, 4882.536159064884, 5033.723145226902, 5132.923006154985, 4931.334269873521, 4984.277279794482, 4998.300237966615, 5017.37282839645, 5038.899222015612], "value": 4689.687616804838, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.59130925451535, 318.3219741949647, 319.7697657686465, 320.9679032096792, 326.9803811771295, 4472.709273897194, 5451.700156491396, 5466.590005749565, 5605.5515558755305, 5645.520130788888, 4614.792334344954, 5080.110980578403, 5317.032697901772, 5392.514673679368, 5422.9728851355685, 3439.4840773883866, 3892.2604705106082, 4028.899277518071, 4114.465119534456, 4211.38934854446, 4080.7143499955014, 4145.31602285624, 4147.315692910666, 4162.771047592381, 4166.979165104185, 4093.5906409359122, 4109.835606575733, 4112.157007813406, 4119.320312148492, 4127.236461254663, 3563.9109022274392, 3581.3374525712698, 4069.901126695805, 4100.384942258685, 4136.883041578757, 3510.5611798525183, 3519.9968009117533, 3520.876769313087, 3533.8233088345737, 3544.3442963051916, 4942.579880710144, 5211.374635043781, 5213.478652134756, 5250.422453530108, 5265.656782731686, 4103.425520427142, 4551.0, 4688.742119183477, 4696.154692155411, 4705.3647757552635, 5001.924971125436, 5618.848136132083, 5657.321121465409, 5680.403557626472, 5700.230468886689, 3748.7750734955716, 3881.165549406891, 3888.1336958260194, 3903.2974064668347, 3935.736951253041, 5671.986444812073, 5972.6715030673295, 5981.5513836462005, 6015.849603759941, 6041.969790151021, 3199.712025917632, 3281.917952051218, 3360.445526488102, 3883.3204189266767, 3959.9406008909314, 3275.819829909378, 3507.982460087734, 3861.227754449121, 3983.342748446532, 4010.839566417369, 4241.088166044316, 4310.159518893848, 4529.909401811945, 4695.694779839299, 4705.85882423521, 4815.903681926342, 5061.645684802029, 5418.83743487696, 5552.805651802249, 5896.646201227854, 1202.6590351757727, 1361.748076605831, 1363.3026881532555, 1386.646405166676, 1398.6083896508994, 722.8698834209788, 761.2958013837178, 803.8070862992956, 803.8304267291078, 806.5967016491754, 4740.3408806918, 5313.256144139824, 5318.946810531923, 5350.608841701186, 5408.0, 5056.595472362275, 5063.594912407072, 5898.734556944948, 5915.615484993462, 5938.970305148447, 3997.8001099944886, 4264.616184543344, 4678.345031695565, 4886.951130488721, 5265.789368425297, 2609.856457894796, 2750.844645249, 2784.5127102756937, 2904.6514418269944, 3034.286942568495, 5809.941900581025, 6261.467775239155, 6270.592411493239, 6354.872902541923, 6356.4279214870885, 4930.112579735682, 5559.27729395176, 5908.734106965197, 5973.402659733992, 6245.5628106033055, 5960.612560183574, 6001.399860013964, 6197.5351848611535, 6318.968405158037, 6321.462675672619, 4901.485344038824, 5327.547158491571, 5709.34342550612, 5966.552508561962, 6073.939260607514, 4942.950570494249, 5316.734163291897, 5441.932065814965, 5655.0, 5936.554758393224, 5274.868128296824, 6211.378862113753, 6269.655168965751, 6309.432151106423, 6309.716062777278, 3767.9058023549633, 3914.72596918213, 3925.2738243425056, 4106.630403263721, 4110.691698122593, 4925.842427029574, 5828.697664422444, 5839.8832023358445, 5891.406242192345, 5961.821145365646, 5187.884604809984, 5293.841184764463, 5373.435789242073, 5788.279508630937, 5882.558808089325, 4806.375171227718, 4824.855254342376, 4962.39474276008, 5571.721413929368, 5902.61632993854, 4839.322494850794, 4979.900401992012, 5513.696746678971, 5782.335031471434, 5794.348610646114, 4450.1989641864775, 4769.948921963589, 5108.514691104339, 5551.666899985852, 5874.794382196603, 5055.620828437955, 5607.635503692247, 5710.628809127394, 5723.570732195185, 5762.12730862463], "value": 4109.235333828891, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.936736010079, 687.1307795638518, 742.3244847189055, 742.3779883439723, 761.6648674583183, 14396.208208548525, 14672.92663536682, 15301.92349038256, 15413.537593872188, 15434.845651543495, 11463.484143213554, 14059.297035148253, 14112.71774564508, 14120.293985300745, 14163.29183540824, 913.8126684029775, 919.2278486071698, 931.1991687149047, 931.6413180925325, 935.0369119806576, 592.7925226170848, 808.3412019204359, 810.5096416667919, 898.4743924803964, 916.1525588830339, 965.7199412170462, 967.7048500207434, 968.5060619084283, 975.8145952269082, 980.3284749946309, 931.7624005878457, 965.9420434773936, 968.1286841842362, 968.2737946540095, 971.6113554578154, 688.7899190746821, 710.0839916507699, 720.6684924934536, 723.0780754538006, 724.6195747232692, 13144.60566183016, 13453.730925381535, 14542.27288635564, 14602.0, 14616.196109213988, 12259.325737084457, 12378.381080945917, 12402.317872517007, 12416.689582760413, 12661.366931653472, 8718.128187181232, 9412.905870941271, 9655.275854310954, 9804.509774511318, 9847.16299114576, 3422.6235114137426, 3471.6354782747953, 3478.565179352581, 3510.947335789965, 3574.535310409656, 81646.0, 83166.58416707878, 83215.0, 83438.0, 84396.57801711014, 9146.86279705805, 9171.35800493959, 9276.90723092774, 9392.483413412192, 9480.146786789084, 21190.258340958302, 21362.252321168686, 21372.0, 21400.0, 21511.892440537697, 7414.890192874254, 7465.141508726566, 7678.692852285958, 7736.883946740803, 7768.495047821873, 3982.7012974026484, 4055.4322394864744, 4067.6339129477897, 4091.5908409158847, 4095.3856921461424, 3449.9482507762405, 3475.6871881530783, 3565.8038807865814, 3566.8394922228563, 3569.625189355132, 601.3835818286292, 640.5772190354344, 644.1818890009662, 648.8183308673579, 667.3360006793272, 522.8732082362515, 578.7453520450988, 581.1457157977766, 597.0864577196809, 613.046712362272, 8052.919470805334, 8166.83666326682, 8176.264136227768, 8179.509229446252, 8271.1728827118, 513.2557791202771, 532.0715351711211, 600.3066458240739, 601.1853937914063, 615.3108518459264, 3542.628024057437, 3860.2279544091293, 3867.941980870289, 3894.90262743428, 3935.35066713989, 84429.0, 87780.68328975071, 89091.0, 89183.0, 91034.08278462011, 77234.0, 78047.60976195196, 78237.60881195671, 78478.0, 78857.4228515438, 52597.474025260024, 70522.64738676274, 70629.64685176438, 70896.0, 71054.57890842257, 8108.270255677017, 8184.918150818416, 8196.508209507449, 8276.462029968034, 8285.544295063588, 38334.0, 39115.6088439112, 40528.3920741195, 40672.38991415191, 42797.786011069744, 49306.0, 55583.72208138934, 62866.0, 62869.74260514855, 64089.03866441914, 10857.022867941923, 10967.616133435291, 11014.229003969645, 11015.118790496581, 11384.715382115513, 12002.579909703118, 12013.939930300292, 12049.638510844688, 12293.078019148421, 12387.19483233587, 11229.04553112962, 11488.425578720864, 11508.539658413905, 11544.769104617693, 11605.419729013684, 11172.273802202832, 11266.887331126913, 11539.769204615694, 11602.303861768321, 11642.6507204784, 9315.86026209594, 10351.585936562453, 11470.51394440088, 11541.884581154416, 12240.938795305967, 9156.705452523549, 9323.72028839136, 9487.288453365887, 9740.16909946566, 9903.851442228517, 8007.0, 8969.14793094655, 9342.439453632804, 9539.809203816023, 9718.979507151647], "value": 6764.407931610716, "unit": "score"}], "name": "dromaeo_css", "value": 7071.236675028995, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 123.0, 175.0, 118.0, 115.0, 108.0, 116.0, 116.0, 115.0], "value": 121.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 126.0, 146.0, 194.0, 156.0, 179.0, 144.0, 120.0, 203.0, 149.0], "value": 157.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 202.0, 229.0, 249.0, 283.0, 273.0, 194.0, 204.0, 200.0, 199.0], "value": 223.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 86.0, 71.0, 189.0, 82.0, 83.0, 93.0, 102.0, 129.0, 59.0], "value": 96.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 135.0, 166.0, 96.0, 116.0, 99.0, 108.0, 75.0, 118.0, 164.0], "value": 116.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 131.0, 88.0, 92.0, 104.0, 84.0, 90.0, 86.0, 89.0, 92.0], "value": 97.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 118.0, 150.0, 100.0, 107.0, 153.0, 118.0, 100.0, 151.0, 121.0], "value": 121.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 89.0, 125.0, 148.0, 92.0, 113.0, 116.0, 91.0, 91.0], "value": 104.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 51.0, 92.0, 90.0, 92.0, 49.0, 52.0, 161.0, 98.0, 93.0], "value": 83.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 79.0, 82.0, 73.0, 71.0, 69.0, 70.0, 59.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 89.0, 88.0, 88.0, 90.0, 93.0, 110.0, 74.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 154.0, 133.0, 137.0, 140.0, 141.0, 147.0, 136.0, 245.0, 165.0], "value": 155.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 167.0, 172.0, 169.0, 171.0, 170.0, 166.0, 172.0, 170.0, 170.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 70.0, 68.0, 66.0, 66.0, 67.0, 68.0, 66.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1675.5, "alertThreshold": 2.0}]} 20:23:07 INFO - Return code: 0 20:23:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:23:08 ERROR - # TBPL SUCCESS # 20:23:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:23:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:23:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:23:08 INFO - 2016-05-10 20:23:08.052 screenresolution[2280:45274] starting screenresolution argv=screenresolution get 20:23:08 INFO - 2016-05-10 20:23:08.054 screenresolution[2280:45274] Display 0: 1600x1200x32@60 20:23:08 INFO - 2016-05-10 20:23:08.060 screenresolution[2281:45277] starting screenresolution argv=screenresolution list 20:23:08 INFO - Available Modes on Display 0 20:23:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:23:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:23:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:23:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:23:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:23:08 INFO - Intel Iris: 20:23:08 INFO - Chipset Model: Intel Iris 20:23:08 INFO - Type: GPU 20:23:08 INFO - Bus: Built-In 20:23:08 INFO - VRAM (Dynamic, Max): 1536 MB 20:23:08 INFO - Vendor: Intel (0x8086) 20:23:08 INFO - Device ID: 0x0a2e 20:23:08 INFO - Revision ID: 0x0009 20:23:08 INFO - Displays: 20:23:08 INFO - Display: 20:23:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:23:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:23:08 INFO - Display Serial Number: 1600x1200 60 20:23:08 INFO - Main Display: Yes 20:23:08 INFO - Mirror: Off 20:23:08 INFO - Online: Yes 20:23:08 INFO - Rotation: Supported 20:23:08 INFO - Return code: 0 20:23:08 INFO - Running post-action listener: _resource_record_post_action 20:23:08 INFO - ##### 20:23:08 INFO - ##### Finished run-tests step (success) 20:23:08 INFO - ##### 20:23:08 INFO - Running post-run listener: _resource_record_post_run 20:23:09 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 389120; Write bytes: 694636032; Read time: 4; Write time: 2318 20:23:09 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 394164736; Write bytes: 387640320; Read time: 30439; Write time: 1044 20:23:09 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 389120; Write bytes: 295997952; Read time: 4; Write time: 1194 20:23:09 INFO - Running post-run listener: _upload_blobber_files 20:23:09 INFO - Blob upload gear active. 20:23:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:23:09 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 20:23:09 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'] 20:23:09 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 20:23:09 INFO - (blobuploader) - INFO - Open directory for files ... 20:23:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:23:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:23:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:23:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:23:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:23:10 INFO - (blobuploader) - INFO - Done attempting. 20:23:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:23:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:23:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:23:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:23:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:23:11 INFO - (blobuploader) - INFO - Done attempting. 20:23:11 INFO - (blobuploader) - INFO - Iteration through files over. 20:23:11 INFO - Return code: 0 20:23:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:23:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:23:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e3392df82aa5d209f47ac6d76360bd9123b6319dfb1368ac7d3860abc24e8cd7a83b89a57697b6abbc27dd6e1c621912c6533ad74c67d1152fc8222a7c179128", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0eddf066d2e0c1f71f16772bda1bb0e6de4ef782415f16f624592ac0f96a1cfd8ebdf5da4dc9c2e7ea6099ada2bc4c9f550a493cafa21ab59922bb9121fba22e"} 20:23:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:23:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:23:11 INFO - Contents: 20:23:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e3392df82aa5d209f47ac6d76360bd9123b6319dfb1368ac7d3860abc24e8cd7a83b89a57697b6abbc27dd6e1c621912c6533ad74c67d1152fc8222a7c179128", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0eddf066d2e0c1f71f16772bda1bb0e6de4ef782415f16f624592ac0f96a1cfd8ebdf5da4dc9c2e7ea6099ada2bc4c9f550a493cafa21ab59922bb9121fba22e"} 20:23:11 INFO - Running post-run listener: copy_logs_to_upload_dir 20:23:11 INFO - Copying logs to upload dir... 20:23:11 INFO - mkdir: /builds/slave/test/build/upload/logs 20:23:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.795456 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-10 20:23:11.712723) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 20:23:11.715907) ========= 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.g15MoE3Zib/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.I6k5RT37Kp/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e3392df82aa5d209f47ac6d76360bd9123b6319dfb1368ac7d3860abc24e8cd7a83b89a57697b6abbc27dd6e1c621912c6533ad74c67d1152fc8222a7c179128", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0eddf066d2e0c1f71f16772bda1bb0e6de4ef782415f16f624592ac0f96a1cfd8ebdf5da4dc9c2e7ea6099ada2bc4c9f550a493cafa21ab59922bb9121fba22e"} build_url:https://queue.taskcluster.net/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011063 build_url: 'https://queue.taskcluster.net/v1/task/FC3PhvY7Q5KLRCd6RB4G1g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e3392df82aa5d209f47ac6d76360bd9123b6319dfb1368ac7d3860abc24e8cd7a83b89a57697b6abbc27dd6e1c621912c6533ad74c67d1152fc8222a7c179128", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0eddf066d2e0c1f71f16772bda1bb0e6de4ef782415f16f624592ac0f96a1cfd8ebdf5da4dc9c2e7ea6099ada2bc4c9f550a493cafa21ab59922bb9121fba22e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 20:23:11.747571) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:23:11.747916) ========= 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.g15MoE3Zib/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.I6k5RT37Kp/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.004954 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:23:11.798265) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 20:23:11.798639) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 20:23:11.799036) ========= ========= Total master_lag: 0.17 =========