builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0354 starttime: 1469457115.55 results: success (0) buildid: 20160725055525 builduid: 1a746a1fe5174b1eafb83b4820e16575 revision: bc767d7a30ddb701a9b2bd62670fdbe14780f81f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 07:31:55.553307) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 07:31:55.553784) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-07-25 07:31:55.554102) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-07-25 07:31:58.856240) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 07:31:58.859402) ========= 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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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-07-25 07:31:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 148M=0s 2016-07-25 07:31:59 (148 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.182574 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 07:31:59.114517) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 07:31:59.115036) ========= 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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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.020591 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 07:31:59.177230) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-25 07:31:59.178475) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev bc767d7a30ddb701a9b2bd62670fdbe14780f81f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev bc767d7a30ddb701a9b2bd62670fdbe14780f81f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HgUBEGtKP3/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.QWRQNW4RdA/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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-07-25 07:31:59,257 truncating revision to first 12 chars 2016-07-25 07:31:59,257 Setting DEBUG logging. 2016-07-25 07:31:59,257 attempt 1/10 2016-07-25 07:31:59,257 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/bc767d7a30dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-25 07:32:00,379 unpacking tar archive at: autoland-bc767d7a30dd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.416183 ========= master_lag: 0.92 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-25 07:32:01.512285) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 07:32:01.512637) ========= script_repo_revision: bc767d7a30ddb701a9b2bd62670fdbe14780f81f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 07:32:01.513058) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-07-25 07:32:01.513329) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-07-25 07:32:02.827289) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 44 secs) (at 2016-07-25 07:32:02.827578) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HgUBEGtKP3/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.QWRQNW4RdA/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 07:32:03 INFO - MultiFileLogger online at 20160725 07:32:03 in /builds/slave/test 07:32:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 07:32:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:32:03 INFO - {'append_to_log': False, 07:32:03 INFO - 'base_work_dir': '/builds/slave/test', 07:32:03 INFO - 'blob_upload_branch': 'Autoland', 07:32:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:32:03 INFO - 'branch': 'Autoland', 07:32:03 INFO - 'buildbot_json_path': 'buildprops.json', 07:32:03 INFO - 'config_files': ('talos/mac_config.py',), 07:32:03 INFO - 'default_actions': ('clobber', 07:32:03 INFO - 'read-buildbot-config', 07:32:03 INFO - 'download-and-extract', 07:32:03 INFO - 'populate-webroot', 07:32:03 INFO - 'create-virtualenv', 07:32:03 INFO - 'install', 07:32:03 INFO - 'run-tests'), 07:32:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:32:03 INFO - 'download_minidump_stackwalk': True, 07:32:03 INFO - 'download_symbols': 'ondemand', 07:32:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:32:03 INFO - 'tooltool.py': '/tools/tooltool.py', 07:32:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:32:03 INFO - '/tools/misc-python/virtualenv.py')}, 07:32:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:32:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:32:03 INFO - 'installer_path': 'installer.exe', 07:32:03 INFO - 'log_level': 'info', 07:32:03 INFO - 'log_name': 'talos', 07:32:03 INFO - 'log_to_console': True, 07:32:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:32:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:32:03 INFO - 'opt_config_files': (), 07:32:03 INFO - 'pip_index': False, 07:32:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:32:03 INFO - 'cmd': ('bash', 07:32:03 INFO - '-c', 07:32:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:32:03 INFO - 'enabled': True, 07:32:03 INFO - 'halt_on_failure': False, 07:32:03 INFO - 'name': 'check_screen_resolution'},), 07:32:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:32:03 INFO - 'cmd': ('bash', 07:32:03 INFO - '-c', 07:32:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:32:03 INFO - 'enabled': True, 07:32:03 INFO - 'halt_on_failure': False, 07:32:03 INFO - 'name': 'check_screen_resolution'},), 07:32:03 INFO - 'run_cmd_checks_enabled': True, 07:32:03 INFO - 'sps_profile': False, 07:32:03 INFO - 'sps_profile_interval': 0, 07:32:03 INFO - 'suite': 'dromaeojs-e10s', 07:32:03 INFO - 'system_bits': '32', 07:32:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:32:03 INFO - 'title': 't-yosemite-r7-0354', 07:32:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:32:03 INFO - 'use_talos_json': True, 07:32:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:32:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:32:03 INFO - 'work_dir': 'build'} 07:32:03 INFO - [mozharness: 2016-07-25 14:32:03.035263Z] Running clobber step. 07:32:03 INFO - Running pre-action listener: _resource_record_pre_action 07:32:03 INFO - Running main action method: clobber 07:32:03 INFO - rmtree: /builds/slave/test/build 07:32:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:32:05 INFO - Running post-action listener: _resource_record_post_action 07:32:05 INFO - [mozharness: 2016-07-25 14:32:05.199315Z] Finished clobber step (success) 07:32:05 INFO - [mozharness: 2016-07-25 14:32:05.199476Z] Running read-buildbot-config step. 07:32:05 INFO - Running pre-action listener: _resource_record_pre_action 07:32:05 INFO - Running main action method: read_buildbot_config 07:32:05 INFO - Using buildbot properties: 07:32:05 INFO - { 07:32:05 INFO - "project": "", 07:32:05 INFO - "product": "firefox", 07:32:05 INFO - "script_repo_revision": "production", 07:32:05 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 07:32:05 INFO - "repository": "", 07:32:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 07:32:05 INFO - "buildid": "20160725055525", 07:32:05 INFO - "pgo_build": "False", 07:32:05 INFO - "basedir": "/builds/slave/test", 07:32:05 INFO - "buildnumber": 78, 07:32:05 INFO - "slavename": "t-yosemite-r7-0354", 07:32:05 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:32:05 INFO - "platform": "yosemite_r7", 07:32:05 INFO - "scheduler": "tests-autoland-macosx64-talos", 07:32:05 INFO - "branch": "autoland", 07:32:05 INFO - "repo_path": "integration/autoland", 07:32:05 INFO - "revision": "bc767d7a30ddb701a9b2bd62670fdbe14780f81f", 07:32:05 INFO - "stage_platform": "macosx64", 07:32:05 INFO - "builduid": "1a746a1fe5174b1eafb83b4820e16575", 07:32:05 INFO - "slavebuilddir": "test" 07:32:05 INFO - } 07:32:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 07:32:05 INFO - Running post-action listener: _resource_record_post_action 07:32:05 INFO - [mozharness: 2016-07-25 14:32:05.202791Z] Finished read-buildbot-config step (success) 07:32:05 INFO - [mozharness: 2016-07-25 14:32:05.202885Z] Running download-and-extract step. 07:32:05 INFO - Running pre-action listener: _resource_record_pre_action 07:32:05 INFO - Running main action method: download_and_extract 07:32:05 INFO - mkdir: /builds/slave/test/build/tests 07:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:05 INFO - https://queue.taskcluster.net/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:32:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 07:32:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 07:32:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 07:32:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 07:32:09 INFO - Downloaded 1199 bytes. 07:32:09 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 07:32:09 INFO - Using the following test package requirements: 07:32:09 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 07:32:09 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:32:09 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 07:32:09 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:32:09 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 07:32:09 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:32:09 INFO - u'jsshell-mac.zip'], 07:32:09 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:32:09 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 07:32:09 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 07:32:09 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:32:09 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 07:32:09 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:32:09 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 07:32:09 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:32:09 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 07:32:09 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:32:09 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 07:32:09 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:09 INFO - https://queue.taskcluster.net/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:32:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:32:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:32:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:32:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:32:27 INFO - Downloaded 18808541 bytes. 07:32:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:32:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:32:30 INFO - Return code: 0 07:32:30 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:32:30 INFO - https://queue.taskcluster.net/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:32:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:32:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:32:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:32:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:32:49 INFO - Downloaded 18808541 bytes. 07:32:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:32:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:32:52 INFO - Return code: 0 07:32:52 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')]} 07:32:52 INFO - https://queue.taskcluster.net/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:32:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 07:32:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 07:32:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 07:32:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:32:54 INFO - Downloaded 14413725 bytes. 07:32:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:32:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:32:55 INFO - Return code: 0 07:32:55 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')]} 07:32:55 INFO - https://queue.taskcluster.net/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:32:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:32:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:32:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 07:32:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 07:32:58 INFO - Downloaded 112936498 bytes. 07:32:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:32:58 INFO - mkdir: /builds/slave/test/properties 07:32:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:32:58 INFO - Writing to file /builds/slave/test/properties/build_url 07:32:58 INFO - Contents: 07:32:58 INFO - build_url:https://queue.taskcluster.net/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:32:59 INFO - Running post-action listener: _resource_record_post_action 07:32:59 INFO - Running post-action listener: set_extra_try_arguments 07:32:59 INFO - [mozharness: 2016-07-25 14:32:59.362081Z] Finished download-and-extract step (success) 07:32:59 INFO - [mozharness: 2016-07-25 14:32:59.362240Z] Running populate-webroot step. 07:32:59 INFO - Running pre-action listener: _resource_record_pre_action 07:32:59 INFO - Running main action method: populate_webroot 07:32:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:32:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:32:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:32:59 INFO - '--tppagecycles', 07:32:59 INFO - '7'], 07:32:59 INFO - 'tests': ['tsvgm']}}, 07:32:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:32:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:32:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:32:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:32:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:32:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:32:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:59 INFO - 'talos_options': ['--disable-e10s'], 07:32:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:59 INFO - 'talos_options': ['--disable-e10s'], 07:32:59 INFO - 'tests': ['damp', 'tps']}, 07:32:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:59 INFO - 'tests': ['damp', 'tps']}, 07:32:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:32:59 INFO - 'tests': ['dromaeo_dom']}, 07:32:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:32:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:32:59 INFO - 'tests': ['basic_compositor_video']}, 07:32:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:32:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:32:59 INFO - 'tests': ['a11yr', 07:32:59 INFO - 'ts_paint', 07:32:59 INFO - 'tpaint', 07:32:59 INFO - 'sessionrestore', 07:32:59 INFO - 'sessionrestore_no_auto_restore', 07:32:59 INFO - 'tabpaint']}, 07:32:59 INFO - 'other-e10s': {'tests': ['a11yr', 07:32:59 INFO - 'ts_paint', 07:32:59 INFO - 'tpaint', 07:32:59 INFO - 'sessionrestore', 07:32:59 INFO - 'sessionrestore_no_auto_restore', 07:32:59 INFO - 'tabpaint']}, 07:32:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:32:59 INFO - 'ts_paint', 07:32:59 INFO - 'tpaint', 07:32:59 INFO - 'sessionrestore', 07:32:59 INFO - 'sessionrestore_no_auto_restore', 07:32:59 INFO - 'tabpaint']}, 07:32:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:32:59 INFO - 'ts_paint', 07:32:59 INFO - 'tpaint', 07:32:59 INFO - 'sessionrestore', 07:32:59 INFO - 'sessionrestore_no_auto_restore', 07:32:59 INFO - 'tabpaint']}, 07:32:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:32:59 INFO - 'tests': ['a11yr', 07:32:59 INFO - 'ts_paint', 07:32:59 INFO - 'tpaint', 07:32:59 INFO - 'sessionrestore', 07:32:59 INFO - 'sessionrestore_no_auto_restore', 07:32:59 INFO - 'tabpaint']}, 07:32:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:32:59 INFO - 'tests': ['a11yr', 07:32:59 INFO - 'ts_paint', 07:32:59 INFO - 'tpaint', 07:32:59 INFO - 'sessionrestore', 07:32:59 INFO - 'sessionrestore_no_auto_restore', 07:32:59 INFO - 'tabpaint']}, 07:32:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:32:59 INFO - 'tests': ['tsvgx', 07:32:59 INFO - 'tsvgr_opacity', 07:32:59 INFO - 'tart', 07:32:59 INFO - 'tscrollx', 07:32:59 INFO - 'cart']}, 07:32:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:32:59 INFO - 'tsvgr_opacity', 07:32:59 INFO - 'tart', 07:32:59 INFO - 'tscrollx', 07:32:59 INFO - 'cart']}, 07:32:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:59 INFO - 'talos_options': ['--disable-e10s'], 07:32:59 INFO - 'tests': ['tp5o']}, 07:32:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:59 INFO - 'tests': ['tp5o']}, 07:32:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:59 INFO - 'talos_options': ['--disable-e10s', 07:32:59 INFO - '--xperf_path', 07:32:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:59 INFO - 'tests': ['tp5n']}, 07:32:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:59 INFO - 'talos_options': ['--xperf_path', 07:32:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:59 INFO - 'tests': ['tp5n']}}, 07:32:59 INFO - 'talos.zip': {'path': '', 07:32:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:32:59 INFO - Running post-action listener: _resource_record_post_action 07:32:59 INFO - [mozharness: 2016-07-25 14:32:59.381518Z] Finished populate-webroot step (success) 07:32:59 INFO - [mozharness: 2016-07-25 14:32:59.381649Z] Running create-virtualenv step. 07:32:59 INFO - Running pre-action listener: _resource_record_pre_action 07:32:59 INFO - Running main action method: create_virtualenv 07:32:59 INFO - Creating virtualenv /builds/slave/test/build/venv 07:32:59 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 07:32:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:32:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:32:59 INFO - Using real prefix '/tools/python27' 07:32:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:33:00 INFO - Installing distribute.............................................................................................................................................................................................done. 07:33:03 INFO - Installing pip.................done. 07:33:03 INFO - Return code: 0 07:33:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:33:03 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')]} 07:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:03 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')]} 07:33:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:03 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 0x10eb9aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99ad6fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eafed30>, '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 0x10e863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf6b20>, '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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 07:33:03 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 07:33:03 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 07:33:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HgUBEGtKP3/Render', 07:33:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:03 INFO - 'HOME': '/Users/cltbld', 07:33:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:03 INFO - 'LOGNAME': 'cltbld', 07:33:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:03 INFO - 'MOZ_NO_REMOTE': '1', 07:33:03 INFO - 'NO_EM_RESTART': '1', 07:33:03 INFO - 'PAGER': '/bin/cat', 07:33:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:03 INFO - 'PWD': '/builds/slave/test', 07:33:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:03 INFO - 'SHELL': '/bin/bash', 07:33:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QWRQNW4RdA/Listeners', 07:33:03 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 07:33:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:03 INFO - 'USER': 'cltbld', 07:33:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:03 INFO - 'XPC_FLAGS': '0x0', 07:33:03 INFO - 'XPC_SERVICE_NAME': '0', 07:33:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:03 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 07:33:03 INFO - Cleaning up... 07:33:03 INFO - Return code: 0 07:33:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:33:03 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')]} 07:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:03 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')]} 07:33:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:03 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99ad6fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eafed30>, '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 0x10e863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf6b20>, '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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 07:33:03 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>=3.1.1'] in /builds/slave/test/build 07:33:03 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>=3.1.1 07:33:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HgUBEGtKP3/Render', 07:33:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:03 INFO - 'HOME': '/Users/cltbld', 07:33:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:03 INFO - 'LOGNAME': 'cltbld', 07:33:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:03 INFO - 'MOZ_NO_REMOTE': '1', 07:33:03 INFO - 'NO_EM_RESTART': '1', 07:33:03 INFO - 'PAGER': '/bin/cat', 07:33:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:03 INFO - 'PWD': '/builds/slave/test', 07:33:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:03 INFO - 'SHELL': '/bin/bash', 07:33:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QWRQNW4RdA/Listeners', 07:33:03 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 07:33:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:03 INFO - 'USER': 'cltbld', 07:33:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:03 INFO - 'XPC_FLAGS': '0x0', 07:33:03 INFO - 'XPC_SERVICE_NAME': '0', 07:33:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:03 INFO - Downloading/unpacking psutil>=3.1.1 07:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:33:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:33:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:33:07 INFO - Installing collected packages: psutil 07:33:07 INFO - Running setup.py install for psutil 07:33:07 INFO - building 'psutil._psutil_osx' extension 07:33:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:33:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:33:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:33:07 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 07:33:07 INFO - building 'psutil._psutil_posix' extension 07:33:07 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 07:33:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:33:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:33:07 INFO - ^ 07:33:07 INFO - 1 warning generated. 07:33:07 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 07:33:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:33:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:33:07 INFO - Successfully installed psutil 07:33:07 INFO - Cleaning up... 07:33:07 INFO - Return code: 0 07:33:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:33:07 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')]} 07:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:07 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')]} 07:33:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:07 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99ad6fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eafed30>, '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 0x10e863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf6b20>, '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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 07:33:07 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.3'] in /builds/slave/test/build 07:33:07 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.3 07:33:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HgUBEGtKP3/Render', 07:33:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:07 INFO - 'HOME': '/Users/cltbld', 07:33:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:07 INFO - 'LOGNAME': 'cltbld', 07:33:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:07 INFO - 'MOZ_NO_REMOTE': '1', 07:33:07 INFO - 'NO_EM_RESTART': '1', 07:33:07 INFO - 'PAGER': '/bin/cat', 07:33:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:07 INFO - 'PWD': '/builds/slave/test', 07:33:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:07 INFO - 'SHELL': '/bin/bash', 07:33:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QWRQNW4RdA/Listeners', 07:33:07 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 07:33:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:07 INFO - 'USER': 'cltbld', 07:33:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:07 INFO - 'XPC_FLAGS': '0x0', 07:33:07 INFO - 'XPC_SERVICE_NAME': '0', 07:33:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:33:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:33:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:33:10 INFO - Installing collected packages: mozsystemmonitor 07:33:10 INFO - Running setup.py install for mozsystemmonitor 07:33:11 INFO - Successfully installed mozsystemmonitor 07:33:11 INFO - Cleaning up... 07:33:11 INFO - Return code: 0 07:33:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:33: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')]} 07:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33: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')]} 07:33:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99ad6fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eafed30>, '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 0x10e863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf6b20>, '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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 07:33:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:33:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:33:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HgUBEGtKP3/Render', 07:33:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:11 INFO - 'HOME': '/Users/cltbld', 07:33:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:11 INFO - 'LOGNAME': 'cltbld', 07:33:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:11 INFO - 'MOZ_NO_REMOTE': '1', 07:33:11 INFO - 'NO_EM_RESTART': '1', 07:33:11 INFO - 'PAGER': '/bin/cat', 07:33:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:11 INFO - 'PWD': '/builds/slave/test', 07:33:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:11 INFO - 'SHELL': '/bin/bash', 07:33:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QWRQNW4RdA/Listeners', 07:33:11 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 07:33:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:11 INFO - 'USER': 'cltbld', 07:33:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:11 INFO - 'XPC_FLAGS': '0x0', 07:33:11 INFO - 'XPC_SERVICE_NAME': '0', 07:33:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:11 INFO - Downloading/unpacking jsonschema==2.5.1 07:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:33:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:33:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:14 INFO - Downloading functools32-3.2.3-2.tar.gz 07:33:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:33:14 INFO - warning: no files found matching '*.txt' 07:33:14 INFO - no previously-included directories found matching 'build' 07:33:14 INFO - no previously-included directories found matching 'dist' 07:33:14 INFO - no previously-included directories found matching '.git*' 07:33:14 INFO - Installing collected packages: jsonschema, functools32 07:33:14 INFO - Running setup.py install for functools32 07:33:14 INFO - warning: no files found matching '*.txt' 07:33:14 INFO - no previously-included directories found matching 'build' 07:33:14 INFO - no previously-included directories found matching 'dist' 07:33:14 INFO - no previously-included directories found matching '.git*' 07:33:14 INFO - Successfully installed jsonschema functools32 07:33:14 INFO - Cleaning up... 07:33:14 INFO - Return code: 0 07:33:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99ad6fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eafed30>, '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 0x10e863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf6b20>, '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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 07:33:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:33:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HgUBEGtKP3/Render', 07:33:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:14 INFO - 'HOME': '/Users/cltbld', 07:33:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:14 INFO - 'LOGNAME': 'cltbld', 07:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:14 INFO - 'MOZ_NO_REMOTE': '1', 07:33:14 INFO - 'NO_EM_RESTART': '1', 07:33:14 INFO - 'PAGER': '/bin/cat', 07:33:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:14 INFO - 'PWD': '/builds/slave/test', 07:33:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:14 INFO - 'SHELL': '/bin/bash', 07:33:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QWRQNW4RdA/Listeners', 07:33:14 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 07:33:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:14 INFO - 'USER': 'cltbld', 07:33:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:14 INFO - 'XPC_FLAGS': '0x0', 07:33:14 INFO - 'XPC_SERVICE_NAME': '0', 07:33:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:33:14 INFO - Cleaning up... 07:33:14 INFO - Return code: 0 07:33:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99ad6fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eafed30>, '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 0x10e863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf6b20>, '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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 07:33:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:33:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HgUBEGtKP3/Render', 07:33:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:14 INFO - 'HOME': '/Users/cltbld', 07:33:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:14 INFO - 'LOGNAME': 'cltbld', 07:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:14 INFO - 'MOZ_NO_REMOTE': '1', 07:33:14 INFO - 'NO_EM_RESTART': '1', 07:33:14 INFO - 'PAGER': '/bin/cat', 07:33:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:14 INFO - 'PWD': '/builds/slave/test', 07:33:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:14 INFO - 'SHELL': '/bin/bash', 07:33:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QWRQNW4RdA/Listeners', 07:33:14 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 07:33:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:14 INFO - 'USER': 'cltbld', 07:33:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:14 INFO - 'XPC_FLAGS': '0x0', 07:33:14 INFO - 'XPC_SERVICE_NAME': '0', 07:33:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:15 INFO - Downloading/unpacking blobuploader==1.2.4 07:33:15 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 07:33:15 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 07:33:15 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 07:33:15 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 07:33:15 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 07:33:15 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 07:33:17 INFO - Downloading blobuploader-1.2.4.tar.gz 07:33:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:33:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:33:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:33:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:18 INFO - Downloading docopt-0.6.1.tar.gz 07:33:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:33:18 INFO - Installing collected packages: blobuploader, requests, docopt 07:33:18 INFO - Running setup.py install for blobuploader 07:33:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:33:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:33:19 INFO - Running setup.py install for requests 07:33:19 INFO - Running setup.py install for docopt 07:33:19 INFO - Successfully installed blobuploader requests docopt 07:33:19 INFO - Cleaning up... 07:33:19 INFO - Return code: 0 07:33:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:33:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:33:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99ad6fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eafed30>, '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 0x10e863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf6b20>, '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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 07:33:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:33:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:33:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HgUBEGtKP3/Render', 07:33:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:19 INFO - 'HOME': '/Users/cltbld', 07:33:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:19 INFO - 'LOGNAME': 'cltbld', 07:33:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:19 INFO - 'MOZ_NO_REMOTE': '1', 07:33:19 INFO - 'NO_EM_RESTART': '1', 07:33:19 INFO - 'PAGER': '/bin/cat', 07:33:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:19 INFO - 'PWD': '/builds/slave/test', 07:33:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:19 INFO - 'SHELL': '/bin/bash', 07:33:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QWRQNW4RdA/Listeners', 07:33:19 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 07:33:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:19 INFO - 'USER': 'cltbld', 07:33:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:19 INFO - 'XPC_FLAGS': '0x0', 07:33:19 INFO - 'XPC_SERVICE_NAME': '0', 07:33:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:33:20 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-0S7PIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:33:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:33:20 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-_Geka1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:33:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:33:20 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-7YgLrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:33:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:33:20 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-5MBMgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:33:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:33:20 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-eYf1GB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:33:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:33:20 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-ytzZOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:33:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:33:20 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-1R9yaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:33:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:33:20 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-jnJVq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:33:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:33:20 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-tik94X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:33:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:33:20 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-_ekL_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:33:21 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-S9DErp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:33:21 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-aAHsMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:33:21 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-2xaREa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:33:21 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-wkw5M5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:21 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-oHAnHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:33:21 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-JKgGT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:33:21 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-ZsH1Zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:33:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:33:21 INFO - Running setup.py install for manifestparser 07:33:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:33:21 INFO - Running setup.py install for mozcrash 07:33:22 INFO - Running setup.py install for mozdebug 07:33:22 INFO - Running setup.py install for mozdevice 07:33:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:33:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:33:22 INFO - Running setup.py install for mozfile 07:33:22 INFO - Running setup.py install for mozhttpd 07:33:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:33:22 INFO - Running setup.py install for mozinfo 07:33:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:33:22 INFO - Running setup.py install for mozInstall 07:33:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:33:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:33:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:33:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:33:22 INFO - Running setup.py install for mozleak 07:33:23 INFO - Running setup.py install for mozlog 07:33:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:33:23 INFO - Running setup.py install for moznetwork 07:33:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:33:23 INFO - Running setup.py install for mozprocess 07:33:23 INFO - Running setup.py install for mozprofile 07:33:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:33:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:33:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:33:23 INFO - Running setup.py install for mozrunner 07:33:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:33:23 INFO - Running setup.py install for mozscreenshot 07:33:24 INFO - Running setup.py install for moztest 07:33:24 INFO - Running setup.py install for mozversion 07:33:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:33:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:33:24 INFO - Cleaning up... 07:33:24 INFO - Return code: 0 07:33:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:33: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')]} 07:33:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33: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')]} 07:33:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99ad6fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eafed30>, '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 0x10e863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf6b20>, '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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 07:33:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:33:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:33:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HgUBEGtKP3/Render', 07:33:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:24 INFO - 'HOME': '/Users/cltbld', 07:33:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:24 INFO - 'LOGNAME': 'cltbld', 07:33:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:24 INFO - 'MOZ_NO_REMOTE': '1', 07:33:24 INFO - 'NO_EM_RESTART': '1', 07:33:24 INFO - 'PAGER': '/bin/cat', 07:33:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:24 INFO - 'PWD': '/builds/slave/test', 07:33:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:24 INFO - 'SHELL': '/bin/bash', 07:33:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QWRQNW4RdA/Listeners', 07:33:24 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 07:33:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:24 INFO - 'USER': 'cltbld', 07:33:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:24 INFO - 'XPC_FLAGS': '0x0', 07:33:24 INFO - 'XPC_SERVICE_NAME': '0', 07:33:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:33:24 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-8J2D3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:33:24 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-ex656b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:33:24 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-qgDZ5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:33:25 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-9BohS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:33:25 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)) 07:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:33:25 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-zsVINx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:33:25 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)) 07:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:33:25 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-vrC_j3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:33:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:33:25 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-NoyYKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:33:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:33:25 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-Kj1pgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:33:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:33:25 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-hrtoLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:33:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:33:25 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-jz7tNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:33:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 07:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:33:25 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-U0wqv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:33:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:33:25 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-Q4ND3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:33:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 07:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:33:25 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-JtSMV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:33:26 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)) 07:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:33:26 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-z1SD99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 07:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:26 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-sdC6AV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:26 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)) 07:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:33:26 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-DhOZf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:33:26 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-FgexvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:33:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:28 INFO - Downloading blessings-1.6.tar.gz 07:33:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:33:29 INFO - Installing collected packages: blessings 07:33:29 INFO - Running setup.py install for blessings 07:33:29 INFO - Successfully installed blessings 07:33:29 INFO - Cleaning up... 07:33:29 INFO - Return code: 0 07:33:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:33:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:33:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:33:29 INFO - Reading from file tmpfile_stdout 07:33:29 INFO - Current package versions: 07:33:29 INFO - blessings == 1.6 07:33:29 INFO - blobuploader == 1.2.4 07:33:29 INFO - docopt == 0.6.1 07:33:29 INFO - functools32 == 3.2.3-2 07:33:29 INFO - jsonschema == 2.5.1 07:33:29 INFO - manifestparser == 1.1 07:33:29 INFO - mozInstall == 1.12 07:33:29 INFO - mozcrash == 0.17 07:33:29 INFO - mozdebug == 0.1 07:33:29 INFO - mozdevice == 0.48 07:33:29 INFO - mozfile == 1.2 07:33:29 INFO - mozhttpd == 0.7 07:33:29 INFO - mozinfo == 0.9 07:33:29 INFO - mozleak == 0.1 07:33:29 INFO - mozlog == 3.3 07:33:29 INFO - moznetwork == 0.27 07:33:29 INFO - mozprocess == 0.23 07:33:29 INFO - mozprofile == 0.28 07:33:29 INFO - mozrunner == 6.12 07:33:29 INFO - mozscreenshot == 0.1 07:33:29 INFO - mozsystemmonitor == 0.3 07:33:29 INFO - moztest == 0.7 07:33:29 INFO - mozversion == 1.4 07:33:29 INFO - psutil == 3.1.1 07:33:29 INFO - requests == 1.2.3 07:33:29 INFO - wsgiref == 0.1.2 07:33:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:33: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')]} 07:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33: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')]} 07:33:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99ad6fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eafed30>, '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 0x10e863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf6b20>, '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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 07:33:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:33:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:33:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HgUBEGtKP3/Render', 07:33:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:29 INFO - 'HOME': '/Users/cltbld', 07:33:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:29 INFO - 'LOGNAME': 'cltbld', 07:33:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:29 INFO - 'MOZ_NO_REMOTE': '1', 07:33:29 INFO - 'NO_EM_RESTART': '1', 07:33:29 INFO - 'PAGER': '/bin/cat', 07:33:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:29 INFO - 'PWD': '/builds/slave/test', 07:33:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:29 INFO - 'SHELL': '/bin/bash', 07:33:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QWRQNW4RdA/Listeners', 07:33:29 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 07:33:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:29 INFO - 'USER': 'cltbld', 07:33:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:29 INFO - 'XPC_FLAGS': '0x0', 07:33:29 INFO - 'XPC_SERVICE_NAME': '0', 07:33:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:33:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:33:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:33:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:33:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:33:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:33:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:33:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:33:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:33:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:33:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:33:29 INFO - Cleaning up... 07:33:29 INFO - Return code: 0 07:33:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:33: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')]} 07:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33: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')]} 07:33:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb9aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99ad6fed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eafed30>, '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 0x10e863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf6b20>, '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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 07:33:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:33:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:33:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HgUBEGtKP3/Render', 07:33:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:29 INFO - 'HOME': '/Users/cltbld', 07:33:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:29 INFO - 'LOGNAME': 'cltbld', 07:33:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:29 INFO - 'MOZ_NO_REMOTE': '1', 07:33:29 INFO - 'NO_EM_RESTART': '1', 07:33:29 INFO - 'PAGER': '/bin/cat', 07:33:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:29 INFO - 'PWD': '/builds/slave/test', 07:33:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:29 INFO - 'SHELL': '/bin/bash', 07:33:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QWRQNW4RdA/Listeners', 07:33:29 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 07:33:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:29 INFO - 'USER': 'cltbld', 07:33:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:29 INFO - 'XPC_FLAGS': '0x0', 07:33:29 INFO - 'XPC_SERVICE_NAME': '0', 07:33:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:33:30 INFO - Cleaning up... 07:33:30 INFO - Return code: 0 07:33:30 INFO - Running post-action listener: _resource_record_post_action 07:33:30 INFO - Running post-action listener: _start_resource_monitoring 07:33:30 INFO - Starting resource monitoring. 07:33:30 INFO - [mozharness: 2016-07-25 14:33:30.170655Z] Finished create-virtualenv step (success) 07:33:30 INFO - [mozharness: 2016-07-25 14:33:30.171684Z] Running install step. 07:33:30 INFO - Running pre-action listener: _resource_record_pre_action 07:33:30 INFO - Running main action method: install 07:33:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:33:30 INFO - Reading from file tmpfile_stdout 07:33:30 INFO - Detecting whether we're running mozinstall >=1.0... 07:33:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:33:30 INFO - Reading from file tmpfile_stdout 07:33:30 INFO - Output received: 07:33:30 INFO - Usage: mozinstall [options] installer 07:33:30 INFO - Options: 07:33:30 INFO - -h, --help show this help message and exit 07:33:30 INFO - -d DEST, --destination=DEST 07:33:30 INFO - Directory to install application into. [default: 07:33:30 INFO - "/builds/slave/test"] 07:33:30 INFO - --app=APP Application being installed. [default: firefox] 07:33:30 INFO - mkdir: /builds/slave/test/build/application 07:33:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:34:05 INFO - Reading from file tmpfile_stdout 07:34:05 INFO - Output received: 07:34:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:34:05 INFO - Running post-action listener: _resource_record_post_action 07:34:05 INFO - [mozharness: 2016-07-25 14:34:05.047122Z] Finished install step (success) 07:34:05 INFO - [mozharness: 2016-07-25 14:34:05.047242Z] Running run-tests step. 07:34:05 INFO - Running pre-action listener: _resource_record_pre_action 07:34:05 INFO - Running main action method: run_tests 07:34:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:34:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:34:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:34:05 INFO - 2016-07-25 07:34:05.062 screenresolution[2222:23800] starting screenresolution argv=screenresolution get 07:34:05 INFO - 2016-07-25 07:34:05.064 screenresolution[2222:23800] Display 0: 1600x1200x32@60 07:34:05 INFO - 2016-07-25 07:34:05.070 screenresolution[2223:23802] starting screenresolution argv=screenresolution list 07:34:05 INFO - Available Modes on Display 0 07:34:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:34:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:34:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:34:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:34:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:34:05 INFO - Intel Iris: 07:34:05 INFO - Chipset Model: Intel Iris 07:34:05 INFO - Type: GPU 07:34:05 INFO - Bus: Built-In 07:34:05 INFO - VRAM (Dynamic, Max): 1536 MB 07:34:05 INFO - Vendor: Intel (0x8086) 07:34:05 INFO - Device ID: 0x0a2e 07:34:05 INFO - Revision ID: 0x0009 07:34:05 INFO - Displays: 07:34:05 INFO - Display: 07:34:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:34:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:34:05 INFO - Display Serial Number: 1600x1200 60 07:34:05 INFO - Main Display: Yes 07:34:05 INFO - Mirror: Off 07:34:05 INFO - Online: Yes 07:34:05 INFO - Rotation: Supported 07:34:05 INFO - Return code: 0 07:34:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:34:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:34:05 INFO - Python 2.7.3 07:34:05 INFO - Return code: 0 07:34:05 INFO - grabbing minidump binary from tooltool 07:34:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:34:05 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 0x10eafed30>, '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 0x10e863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaf6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:34:05 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 07:34:05 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 07:34:05 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 07:34:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:34:05 INFO - Return code: 0 07:34:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:34:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:34:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:34:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:34:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:34:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0354', '--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 07:34:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0354 --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 07:34:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HgUBEGtKP3/Render', 07:34:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:05 INFO - 'HOME': '/Users/cltbld', 07:34:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:05 INFO - 'LOGNAME': 'cltbld', 07:34:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:34:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:34:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:05 INFO - 'MOZ_NO_REMOTE': '1', 07:34:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:34:05 INFO - 'NO_EM_RESTART': '1', 07:34:05 INFO - 'PAGER': '/bin/cat', 07:34:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:05 INFO - 'PWD': '/builds/slave/test', 07:34:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:34:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:05 INFO - 'SHELL': '/bin/bash', 07:34:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QWRQNW4RdA/Listeners', 07:34:05 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 07:34:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:05 INFO - 'USER': 'cltbld', 07:34:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:05 INFO - 'XPC_FLAGS': '0x0', 07:34:05 INFO - 'XPC_SERVICE_NAME': '0', 07:34:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0354', '--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 07:34:05 INFO - mozversion application_buildid: 20160725055525 07:34:05 INFO - mozversion application_changeset: bc767d7a30ddb701a9b2bd62670fdbe14780f81f 07:34:05 INFO - mozversion application_display_name: Nightly 07:34:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:34:05 INFO - mozversion application_name: Firefox 07:34:05 INFO - mozversion application_remotingname: firefox 07:34:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 07:34:05 INFO - mozversion application_vendor: Mozilla 07:34:05 INFO - mozversion application_version: 50.0a1 07:34:05 INFO - mozversion platform_buildid: 20160725055525 07:34:05 INFO - mozversion platform_changeset: bc767d7a30ddb701a9b2bd62670fdbe14780f81f 07:34:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 07:34:05 INFO - mozversion platform_version: 50.0a1 07:34:05 INFO - using testdate: 1469457245 07:34:05 INFO - actual date: 1469457245 07:34:05 INFO - starting webserver on 'localhost:56520' 07:34:05 INFO - SUITE-START | Running 2 tests 07:34:05 INFO - TEST-START | dromaeo_css 07:34:05 INFO - operating with platform_type : mac_ 07:34:05 INFO - Initialising browser for dromaeo_css test... 07:34:05 INFO - TEST-INFO | started process 2233 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmpbQMqnX/profile http://localhost:56520/getInfo.html) 07:34:07 INFO - PROCESS | 2233 | JavaScript error: , line 0: uncaught exception: undefined 07:34:08 INFO - PROCESS | 2233 | __metrics Screen width/height:1600/1200 07:34:08 INFO - PROCESS | 2233 | colorDepth:24 07:34:08 INFO - PROCESS | 2233 | Browser inner width/height: 1024/689 07:34:08 INFO - PROCESS | 2233 | __metrics 07:34:08 ERROR - PROCESS | 2233 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 07:34:09 INFO - TEST-INFO | 2233: exit 0 07:34:09 INFO - Browser initialized. 07:34:09 INFO - Running cycle 1/1 for dromaeo_css test... 07:34:09 INFO - TEST-INFO | started process 2236 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmpbQMqnX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:34:21 INFO - PROCESS | 2236 | RSS: Main: 256569344 07:34:21 INFO - PROCESS | 2236 | 07:35:11 INFO - PROCESS | 2236 | 1469457311214 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] 07:37:22 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:56520/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56520/tests/dromaeo/cssquery-ext.html) 07:37:24 INFO - PROCESS | 2236 | RSS: Main: 269586432 07:37:24 INFO - PROCESS | 2236 | 07:40:26 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:56520/tests/dromaeo/cssquery-ext.html (next: http://localhost:56520/tests/dromaeo/cssquery-jquery.html) 07:40:27 INFO - PROCESS | 2236 | RSS: Main: 282009600 07:40:27 INFO - PROCESS | 2236 | 07:43:28 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:56520/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56520/tests/dromaeo/cssquery-mootools.html) 07:43:29 INFO - PROCESS | 2236 | RSS: Main: 289669120 07:43:29 INFO - PROCESS | 2236 | 07:46:31 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:56520/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56520/tests/dromaeo/cssquery-prototype.html) 07:46:32 INFO - PROCESS | 2236 | RSS: Main: 288489472 07:46:32 INFO - PROCESS | 2236 | 07:49:34 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:56520/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56520/tests/dromaeo/cssquery-yui.html) 07:49:35 INFO - PROCESS | 2236 | RSS: Main: 292732928 07:49:35 INFO - PROCESS | 2236 | 07:52:38 INFO - PROCESS | 2236 | Cycle 1(1): loaded http://localhost:56520/tests/dromaeo/cssquery-yui.html (next: http://localhost:56520/tests/dromaeo/cssquery-dojo.html) 07:52:38 INFO - PROCESS | 2236 | RSS: Main: 293892096 07:52:38 INFO - PROCESS | 2236 | 07:52:38 INFO - PROCESS | 2236 | __start_tp_report 07:52:38 INFO - PROCESS | 2236 | _x_x_mozilla_page_load 07:52:38 INFO - PROCESS | 2236 | _x_x_mozilla_page_load_details 07:52:38 INFO - PROCESS | 2236 | |i|pagename|runs| 07:52:38 INFO - PROCESS | 2236 | |0;dojo.html;3363.8822641207553;3396.575428071491;3400.7619466637343;3401.20071783131;3404.914877128073;5029.5221953914415;5425.728713564321;5656.830295091144;5747.827565173046;5857;5761.337446193689;5791.999999999989;5806.854828629276;5809.099589563614;5936.139259807325;5187.87030324243;5303.28405665236;5309.41596424393;5355.3305836770405;5447.972760136214;5455.427180146087;5473.67157970521;5490.039243132436;5526.977509160818;5528.75120619573;5175.534201921808;5197;5259.631825772198;5340.732963351817;5343.545798607123;5187.714675692835;5239.5546378557865;5278.10272253714;5287.709175995319;5335.306410166653;3860.478835357247;5015.122353588144;5258.316418865561;5261.210818377236;5297.12597421425;4808.63935204861;5509.862253443656;5593.776248950037;5768.221290125821;5783.739731712083;6192.864634855623;6194.566380353377;6262.749490020394;6289.119523266746;6289.276733175652;5602.215689803431;6221.782237621706;6240.594361366497;6249.250089989185;6300.684965751694;5755.942440575583;5845.269341332333;5926.140709597127;5941.524678025834;6044.063170208625;6329.525285603599;6375.362463753588;6394.71223794921;6397.680115994275;6402.871942561216;6270.372962703693;6280.717367718372;6290.370962903765;6297.4962003039645;6312.747490100344;6176.567640265138;6269.5611307208055;6271.372862713783;6298.0552917062805;6310.716017779211;5420.891582168335;6133.478654314344;6204.410580994799;6268.811935641938;6305.18032655761;6203.503719702413;6264.937350626436;6269.811905642838;6285.214348206474;6287.025511045796;4858.4898585648;4951.430585482643;5040.521150490697;5094.719790411489;5100.7959681613065;4458.799354029076;4493.303537951622;4543.909121817545;4614.676972611885;4621.630269578425;4838.516148385133;5897.32180799205;5986;5998.100284957204;6000.939990600125;5822.7670893163795;6214;6221.5333849961435;6288.402601752826;6323.336049714806;5845.590808643439;5974.880502389928;5981.102834574762;5996.2504686914135;6037.426444487766;5553.611247212737;5580.832575022756;5586.190002449622;5589.469000444951;5589.888202235932;6279.215098112736;6327.683615819098;6513.804585862337;6543.640099794557;6551.8362040948405;6235.719392627252;6288;6308.148399965946;6310.779122730866;6313.55805093639;6466.870662586816;6484.5460817742305;6515.706793194223;6534.444572211415;6547.836304092436;6119.441623354662;6193.690315484298;6300.369963003846;6308.621482711052;6314.179156709598;6248;6257.530685198537;6288.308286088618;6305.873882522415;6325.05124231369;6198.566100373111;6269.937300627118;6270.9372906270355;6284;6310.242770867525;6025.698715064317;6107.735039974475;6217.160683307877;6286.779962701284;6290.433860952537;4983.476734942778;5134.5635620972635;5999.340072591924;6046.7581296749395;6137;6255.343188965275;6290;6291.559590828598;6301.873962520633;6313.2424109107205;5448.645838020516;5931.822045338647;6064.48451881595;6082.635041897501;6165.815025549241 07:52:38 INFO - PROCESS | 2236 | |1;ext.html;486.4624589828242;506.96831946987885;508.9947353975896;523.5942144837751;563.2115038945474;38790.612093879055;41653.16693666125;41692;41693.9576510587;41713.374299385534;42690.57309426912;43256;43281.35077973833;43334.7833260834;43417;9538.666146684867;9588.664396746099;9757.902420975806;9760.17038551724;9762.463064531448;9523.619055237818;9627.951860240724;9638;9713.95143024284;9761.121499065082;9492.145706886378;9741.464219467927;9748.561314740818;9750.512474376288;9755.658551950683;9509.5720692569;9610.087041731025;9696.175825054843;9726.56230469627;9775.755606109904;9428.858567121499;9522.238220942309;9622.134007939248;9630.662926797599;9670.951645241728;40196.598034019575;40406.595934040575;40458.595414045776;40471;40533.39199912004;2659.9626145803154;2793.413383189533;3352.5306457096026;3600.72994525409;3679.944800827963;29607.851960740274;30288;30872.8456357719;31845.52231716526;31924.13631069308;3155.4477966355794;3160.3291347805007;3167.841607919595;3186.426443240216;3282.5896762904636;444658;446742;448163;451480;452683;34244.31511369759;34406;34423;34447.655523444446;34484.31031379358;34481.82759086188;35486.82256588752;35584.7545335912;35606.821965890005;35667.28665426677;16780.91609541969;16968.660626787394;17095;17102.657946841242;17140.40008599693;12641.620751377473;13021.609351719464;13090.541831036058;13140.934295328654;13183.868161318456;16094.75857862133;16132.75800862988;19714.507137321394;19829;19880.801191988183;6766.966165169143;8167.877481837777;8265.090840007595;8291.968467725324;8306.6677332906;806.6450761664848;876.8991565969867;879.560219890055;970.1947383671536;1167.1654766841748;29637.555436668466;30749.538756918664;30861;30988;31711.36577268488;1011.3022014809758;1051.75283808305;1067.2795862792584;1071.1056268016139;1084.9349039057681;15456.304466299043;15852.286647100907;15945.202739863193;16013.759793603105;16312.939658922132;498792.5181122286;517219;518868;584074.238886417;592430;46292.768536157775;58667.23998280059;60683.99999999912;60981.08528372078;61041;1205.8432403787451;1308.7109197440514;1418.5389748331834;1443.6896067345572;1448.891333150018;29631.555526667547;30069.94755183558;30439.782408703402;30491.39017219599;30659.386812264074;51807.22289165741;51820.22269665883;51977.480225197265;52060.73969630127;52073.21890171574;564012;573445;577679;579047;581356;26691.466170676867;26699.64396485546;27142.864285679236;27416.766245519397;27531.898724050814;45764.31353529766;49520.75239623779;50045;50270.245946310104;50363.24455133249;38046.809765950995;41504.96237594084;42169.578304216564;43055.569444305154;43506.56493435025;35393.646063540065;40740.38889416602;40979.59020409877;42013.159736805705;42316.576834232495;32337.838310808296;36927;38884.805575971935;39229.80385098056;40109;31770.6822931777;34466;36007.63992360043;36199;36588.81705591455;25388.74611253864;25421.61867572025;26468.602970955068;26627.33431664224;26779 07:52:38 INFO - PROCESS | 2236 | |2;jquery.html;321.8020917135961;348.39553374894535;370.98516059357627;381.3022169429943;394.1702715783278;20592.382228533148;20843.687344689824;20963.580728385423;21247.36257912265;21387.251446199385;21126.260580879672;21226.257081002168;21255.468613284676;21620;21666.674999875013;5356.437574054727;5917.615355001933;6165.32181460039;6231.844203894916;6247.7813276535335;6076.574639775217;6165.691715414234;6195.101710252034;6196.876062478747;6201.906971395432;5215.295935048777;5507.256520369759;5783.855403614923;5822.155787410823;6104.725287362079;5229.973850130725;5868.501177399926;6048.546359023069;6104.450599446027;6130.877382452349;4573.153966516205;5223.869403264911;5233.188855727369;5267.341582302212;5295.549878260353;26227;26299.868500657376;26469.470610587872;26763.19710408691;26796.59805102925;2383.0928551916063;2417.987910060456;3142.3872344892757;3229.531717900891;3249.528818321354;15758;16080.954737941996;16100.838991610168;17028.404005859735;17709.645807083787;2007.1569940624827;2045.5499790046167;2087.9791202087936;2097.3498215553077;2117.3224568138203;128895;130468;131176;148879.022419551;161575;20200;20208;21641;21709.782902171093;22119.889400552893;36552.45171322432;36684.81657591748;36688.63311366906;36707.81646091752;36787.81606092006;10896.61861834832;11039.503222355013;11144.832827507595;11176.944115279372;11286.322820630789;9086;9183.77040573991;9242.907570924339;9353.204977576846;9376.249900007982;8398.41211115216;8580.914190858137;10246.38521688701;10373.429461379546;10499.212559058102;3920.1375697347107;3935.4490371348033;3958.5051868516434;3965.345717956505;4032.850637568279;891.7369376033956;917.4220241247971;952.7760976170595;1036.3471013261596;1061.2412125330425;18607.16267767954;18806.87158770478;21062.47343816417;22296.66555001676;22326.88836555807;939.4410325856105;989.6091044037565;994.4033579852176;994.6021591363512;1017.765913839821;9424.528773561295;11337.886621133848;11638.6508404748;11679.941600292113;11804.940975295069;526788;533696;534249;626343;635565;83978.58010709907;146304.5369546291;149266;155244;159703.40296597118;965.753732798132;982.3221976835961;1015.279151802214;1020.6733845169623;1030.9329893556894;17183.31266749366;19491.51271218231;19692;19863.801361986196;19867.30464433738;40407.39388909227;55120.44879551153;57553.13670295032;57603;57744.845103096864;86423.56788216019;86697.56651216703;86836;89146;91552;7235.2764723527225;7399.556026638419;7617.6572054256585;8050.315723163597;8521.488710677379;9586.89750678653;13007.154534955198;13469;13970.860291396955;14084.859151408355;11343;11532.077433805443;11965.76068478608;12003.759924801281;12062.879371206527;10905.454727263446;11075.058620017067;11360.375179365214;11629.24409913353;12121.75756484883;9373.531323433665;9916.553755081182;10212.540435680263;11488.770224595293;11499.425028748361;8366.079731229682;8478;8638;8660.870086948575;8728.69449569262;11402;11701.2979221247;12017.879821201675;12072.75854482923;12107.818382724443 07:52:38 INFO - PROCESS | 2236 | |3;mootools.html;1445.385711072794;1567.3730507796884;1785.5714628489159;1912.4740696308513;1930.9131089100988;8042.678292868278;8285.337173026159;8592.957035214815;8647.783805404868;8662.52356120413;8678.826423471543;8864.202221800037;8870.955645221766;8889.733308000754;8890;2373.964390534143;2401.3070785096465;2406.783389494954;2417.697787776528;2418.056957786465;2351.8236132290062;2388.4984153327737;2414.915477958272;2418.9879050604723;2419.987900060506;2337.240396871015;2358.79950204232;2384.95230095398;2403.5433267679086;2404.879756012201;2332.976670233293;2350.15394457995;2367.1833217540093;2379.2624286471164;2384.2727967969763;2342.777436143581;2363.196513185525;2384.3800611840875;2387.331547166796;2388.3073908566494;8884.378093533454;8906.198442140238;8941.195292423714;8976.910230897673;8992.730218093468;5254.054270231357;5390.272313237702;5413.53984911283;5419.078756611386;5423.538999185074;3610.1155216971747;3619.8914032579064;3650.2699460108083;3671.559412870446;3671.7796932184156;1023.8993082436336;1088.450332582041;1102.7573933734566;1108.1688733449912;1111.3220935229504;10849.349039057683;11596.304221746637;11623.709407264801;11682.357470339128;11700.590479333268;4717.510842627051;4724.102420540086;4779.4264688236935;4862.756862156878;4881.8535443936735;9915.206783457306;9934.15559677421;10002.899971000343;10010.699679009642;10038.196944244442;5321.308229930084;5352.437994010573;5362.034833729888;5434.565234781208;5439.5104440600535;4474.530174331649;4815.085133824562;4927.408710954636;4950;4968.801247950114;3921.8823435296986;4037.9192416151513;4061.5532291447917;4084.509858816961;4169.1661667666585;1294.792833146692;1299.1166007115064;1308.9541866034642;1317.7101037771674;1319.1359659423092;950.254050570299;960.4813400763649;968.1770495079268;972.6887396033343;997.1324947295899;6063.696815159225;6166.445019948226;6207.472364848948;6216.7824126155365;6218.253809542884;447.7157005301657;448.5895405703744;452.5881447882411;453.1028563444333;460.7120549656465;4111.835526578963;4117.958820411817;4184.853530126492;4222.472190976128;4236.2374772540625;10424.843627345595;10795.514201860935;10813;10883.401412922207;10936.398498082683;10250.179985601135;10853.131749460024;10884.455777211107;10912.50893709785;10913.618023369145;8105.189481051965;8205.343572514184;8242.340612751086;8281.668733250723;8317.334613230927;5462.3445186577865;6105.175801266772;6135.294441139326;6284.622922624657;6383.329750376143;8612.569371531274;9225.446473211628;9609.174256891169;10133.341332813343;10193.439360835226;10232.539535720762;10250.487475626338;10279.84580231312;10289.43408112561;10318.48407579603;6353.714082866374;7010.298970102949;7407.518511296748;7494.362979146834;7682.961585192038;8473.830523389375;8506.61720222579;8531.701390451304;8547.230749232598;8551.315894728283;5870.26621672291;6424.550281480439;7297.014902988241;7474.551526908403;7528.661210245662;6404.839879003062;7303.379212766762;7452.105747310357;7478.738244161428;7545.471816972759;6582.17722784652;7016.649167541705;7145.39264162552;7146.3568278855155;7157.391621712213;5143.588512918883;5151.845444636667;5160.561352285098;5216.217567364925;5321.04221240165;5157.200633901752;5189.040027594907;5204.531592156724;5228.300802239211;5333.200019997032 07:52:38 INFO - PROCESS | 2236 | |4;prototype.html;306.2588535743499;313.91838122088257;318.3108569946067;318.85492101094;322.19612067890614;5364.91952620711;5397.514223719864;5642.633228840127;5706.400827913067;5724.280966540891;4894.804207831682;5505.807296744615;5517.420670829565;5562.7774889004395;5592.273004509419;3468.2890007548517;3995.60043995601;4229.471316085489;4235.639970602502;4246.3205887058075;3446.7070299024613;3933.5869733677982;4093.5087789465306;4263.360495925621;4279.657627389802;4147.21202971436;4179.310413781723;4183.707140500151;4193.412922190881;4217.641500472463;3325.866965321384;3646.9270614587826;4185.142045880602;4191.350340697197;4229.513605935326;3098.5352197170378;3588.4976103345552;3636.5454318210222;3658.8902332930056;3664.560252769658;4642.535746425364;5304.416514183436;5322.973385133088;5325.837562947581;5475;3984.820683069269;4757.429108507002;4820.493848145929;4821;4830.347903033081;5337.422393598698;5352.93911843939;5614.185943038277;5871.911921321183;5888.646681199141;3794.582595914447;3855.325318069327;3976.562578116434;3984.561698213194;4027.7986100694848;6101.51187904971;6198.535109866733;6264.812055638338;6283.706447146758;6285.968570157165;3385.8984230473125;3404.3701915145775;3605.008622628746;4044.1102957349335;4074.266632006208;3616.909577260537;4011.618896204856;4036.869676490587;4072.1448495815616;4099.852041428405;3932.488776459042;4225.894352641209;4612.169809434334;4723.83466578675;4823.684631376958;5875.29496460419;5934.317553481319;5956.940430595725;5974.372690867396;5999.400059993965;1327.6349004023778;1388.8402833674054;1413.413433425117;1423.487544483985;1424.593990712642;901.3104974694364;944.9196818270524;951.2437612098261;977.2816979520047;980.9411435313904;4314.935275970863;4999.625028122832;5027.567143364124;5480.7533660985355;5576.386597474275;5409.404965453797;5904.084866845646;5964.671943043174;5997.400259974055;6044;4641.86074417768;5315.73421328932;5335.67985920846;5384.78460861569;5497.350794761555;2432.878356082189;2436.90252389906;2462.741412151698;2677.7857771378244;2711.52548304046;4406.7576283304725;4533.818647254139;4807.61539076873;5196.662216955886;5299.761510732026;5104.417630534546;5298.602604804655;5659.405762394889;6221.657808820468;6342.082641984646;4857.611391088704;5257.63196576236;5802.912956305743;6250.531210159165;6366.7266546689925;5190.455002224712;5365.892682146413;5571.275734154696;5910;6172.210059082816;6005.309389420097;6046.9697651513225;6068.575199735976;6082.72627731762;6136.23297087864;5884;6383.36166383358;6393.360663933756;6400.71196796136;6424.453921416732;3731.832067556912;3981.562028176956;4007.91984160321;4010.6189911958318;4025.959740402676;5794.478496935296;6046.248956724592;6077;6173.536984726254;6187.226596675416;4685.672002959896;4898.338724272177;5348.224507446321;5687.516561092188;6007.879842403215;5713.257276554022;5969.9702101285775;6018.789342372996;6022.698865056817;6032.547558933185;4986.376702912136;5371.865703357447;5670.3086370524225;5809.24479817621;5898.9115163273445;5008.624353173599;5755.510781583444;6007.867207141402;6027.21646185993;6039.667818270037;5109.335786347751;5913.319968203712;5919.792807251725;5922.170896074467;5976 07:52:38 INFO - PROCESS | 2236 | |5;yui.html;683.87348340557;720.3876704800916;772.8802035684471;775.6354513378714;856.4861083349991;14854.960152789308;15198.620034499143;15651.295691693873;15845.762313565278;15876.761848572267;14311.427542898271;14314.713705725879;14350.641233969156;14464.493742719007;14468.855311446909;877.4822854515835;895.6372669069037;898.9460632362079;902.3412908576743;902.7111324376202;701.982450438738;855.0850589868843;877.2061284537504;880.4321212817729;884.2395539835736;942.9575669094872;946.4463288975958;947.5120313038791;948.4641177734593;949.5442187749884;907.2107266678032;919.1865199298602;943.5046600534705;946.7207173883721;949.2500924269818;688.277308825736;703.4829400390709;704.3132945378267;704.5279662626002;704.5526090932244;15170.24148792567;15434.922825385802;15445.382184712598;15469.303881325368;15495.380184792595;14849.480268190562;14902.70194596113;14920.403183872631;15072;15123.546293611209;9773.560189791477;11020.889791102147;11123;11172.385518796464;11185;3663.4504824276305;3803.391457366808;3829.8276577554075;3971.007248187953;3976.80115994202;106532.40201396986;109825.01749825034;113735;113837;115373;10812.621558245424;10868.782624347627;10879.728006799893;10995.285306455055;11024.897510249071;15716.607084822743;16637.41769038078;20605;20685;21063;7845.097813751491;7905.683772649029;7959.323457506177;7998;8080.9191908080165;4463.642908567307;4469.351943968171;4615.746133962597;4633.4671512775785;4815.446223684251;3592.2456284180093;3685.4103343465454;3686.2443199144113;3697.0572504011307;3701.3152566775234;595.2559300873908;681.8329509270161;688.3426327856885;693.3586432549871;705.1608585782902;574.0900672434155;594.7977687586155;645.8173160387925;652.0122015146961;740.7333359990399;9201.401908875901;9426.104520070583;9635.759106022406;9667.758306042264;9792.853107203398;477.8614201881485;655.3184687924505;658.9143411356589;700.8388070743656;711.3633298198084;3794.3928971365003;3980.7213495055066;4699.882502937454;4751.263554149113;4808.374911261513;88750.55624721965;109122;135849;142980.28509857383;148980;107360;132506.33746831203;132918.67081329064;133515;134432.3278383602;112251.43874280577;112453.43773281245;112833;115321.4233928825;116485;8575.95712021436;9047.728568142966;9092.499912504876;9103.590338434919;9417.387869788441;50104.49895501144;57812.39844206937;60320.69839650774;60434.697826510586;61206.693966529885;59716.70141649264;61481;72369;78898;79103.81344279725;12919.806202907152;13258.337083145998;13413.798793018308;13617.591472255848;13619.727605448034;15365.462208822637;15372.692546149237;15480.922595386952;15804.209789510249;16202.865799393929;13500.93249533746;14284.57146285613;14608.707825843636;14727.042742221722;14742.63143421423;13817.240051797247;13878.514252001132;14074.225917574222;14552.927235363755;14611.050281731654;13940;14050;14384.928075359556;14475.710485790436;14478.203698796668;10758.300710453796;10846.891531084588;11402.20184587071;11528;11552.59565915189;10948.34309941406;11202.43987800623;11512.769744604893;11750.94124529372;11849.289042657469 07:52:38 INFO - PROCESS | 2236 | __end_tp_report 07:52:38 INFO - PROCESS | 2236 | __start_cc_report 07:52:38 INFO - PROCESS | 2236 | _x_x_mozilla_cycle_collect,421 07:52:38 INFO - PROCESS | 2236 | __end_cc_report 07:52:38 INFO - PROCESS | 2236 | __startTimestamp1469458358726__endTimestamp 07:52:38 INFO - PROCESS | 2236 | 07:52:38 INFO - PROCESS | 2236 | ------- Summary: start ------- 07:52:38 INFO - PROCESS | 2236 | Number of tests: 6 07:52:38 INFO - PROCESS | 2236 | 07:52:38 INFO - PROCESS | 2236 | [#0] dojo.html Cycles:165 Average:5794.20 Median:6037.43 stddev:675.43 (11.2%) stddev-sans-first:650.04 07:52:38 INFO - PROCESS | 2236 | Values: 3363.9 3396.6 3400.8 3401.2 3404.9 5029.5 5425.7 5656.8 5747.8 5857.0 5761.3 5792.0 5806.9 5809.1 5936.1 5187.9 5303.3 5309.4 5355.3 5448.0 5455.4 5473.7 5490.0 5527.0 5528.8 5175.5 5197.0 5259.6 5340.7 5343.5 5187.7 5239.6 5278.1 5287.7 5335.3 3860.5 5015.1 5258.3 5261.2 5297.1 4808.6 5509.9 5593.8 5768.2 5783.7 6192.9 6194.6 6262.7 6289.1 6289.3 5602.2 6221.8 6240.6 6249.3 6300.7 5755.9 5845.3 5926.1 5941.5 6044.1 6329.5 6375.4 6394.7 6397.7 6402.9 6270.4 6280.7 6290.4 6297.5 6312.7 6176.6 6269.6 6271.4 6298.1 6310.7 5420.9 6133.5 6204.4 6268.8 6305.2 6203.5 6264.9 6269.8 6285.2 6287.0 4858.5 4951.4 5040.5 5094.7 5100.8 4458.8 4493.3 4543.9 4614.7 4621.6 4838.5 5897.3 5986.0 5998.1 6000.9 5822.8 6214.0 6221.5 6288.4 6323.3 5845.6 5974.9 5981.1 5996.3 6037.4 5553.6 5580.8 5586.2 5589.5 5589.9 6279.2 6327.7 6513.8 6543.6 6551.8 6235.7 6288.0 6308.1 6310.8 6313.6 6466.9 6484.5 6515.7 6534.4 6547.8 6119.4 6193.7 6300.4 6308.6 6314.2 6248.0 6257.5 6288.3 6305.9 6325.1 6198.6 6269.9 6270.9 6284.0 6310.2 6025.7 6107.7 6217.2 6286.8 6290.4 4983.5 5134.6 5999.3 6046.8 6137.0 6255.3 6290.0 6291.6 6301.9 6313.2 5448.6 5931.8 6064.5 6082.6 6165.8 07:52:38 INFO - PROCESS | 2236 | 07:52:38 INFO - PROCESS | 2236 | [#1] ext.html Cycles:180 Average:65270.13 Median:40432.60 stddev:140059.31 (346.4%) stddev-sans-first:140367.76 07:52:38 INFO - PROCESS | 2236 | Values: 486.5 507.0 509.0 523.6 563.2 38790.6 41653.2 41692.0 41694.0 41713.4 42690.6 43256.0 43281.4 43334.8 43417.0 9538.7 9588.7 9757.9 9760.2 9762.5 9523.6 9628.0 9638.0 9714.0 9761.1 9492.1 9741.5 9748.6 9750.5 9755.7 9509.6 9610.1 9696.2 9726.6 9775.8 9428.9 9522.2 9622.1 9630.7 9671.0 40196.6 40406.6 40458.6 40471.0 40533.4 2660.0 2793.4 3352.5 3600.7 3679.9 29607.9 30288.0 30872.8 31845.5 31924.1 3155.4 3160.3 3167.8 3186.4 3282.6 444658.0 446742.0 448163.0 451480.0 452683.0 34244.3 34406.0 34423.0 34447.7 34484.3 34481.8 35486.8 35584.8 35606.8 35667.3 16780.9 16968.7 17095.0 17102.7 17140.4 12641.6 13021.6 13090.5 13140.9 13183.9 16094.8 16132.8 19714.5 19829.0 19880.8 6767.0 8167.9 8265.1 8292.0 8306.7 806.6 876.9 879.6 970.2 1167.2 29637.6 30749.5 30861.0 30988.0 31711.4 1011.3 1051.8 1067.3 1071.1 1084.9 15456.3 15852.3 15945.2 16013.8 16312.9 498792.5 517219.0 518868.0 584074.2 592430.0 46292.8 58667.2 60684.0 60981.1 61041.0 1205.8 1308.7 1418.5 1443.7 1448.9 29631.6 30069.9 30439.8 30491.4 30659.4 51807.2 51820.2 51977.5 52060.7 52073.2 564012.0 573445.0 577679.0 579047.0 581356.0 26691.5 26699.6 27142.9 27416.8 27531.9 45764.3 49520.8 50045.0 50270.2 50363.2 38046.8 41505.0 42169.6 43055.6 43506.6 35393.6 40740.4 40979.6 42013.2 42316.6 32337.8 36927.0 38884.8 39229.8 40109.0 31770.7 34466.0 36007.6 36199.0 36588.8 25388.7 25421.6 26468.6 26627.3 26779.0 07:52:38 INFO - PROCESS | 2236 | 07:52:38 INFO - PROCESS | 2236 | [#2] jquery.html Cycles:180 Average:37009.08 Median:14969.49 stddev:96878.78 (647.2%) stddev-sans-first:97111.39 07:52:38 INFO - PROCESS | 2236 | Values: 321.8 348.4 371.0 381.3 394.2 20592.4 20843.7 20963.6 21247.4 21387.3 21126.3 21226.3 21255.5 21620.0 21666.7 5356.4 5917.6 6165.3 6231.8 6247.8 6076.6 6165.7 6195.1 6196.9 6201.9 5215.3 5507.3 5783.9 5822.2 6104.7 5230.0 5868.5 6048.5 6104.5 6130.9 4573.2 5223.9 5233.2 5267.3 5295.5 26227.0 26299.9 26469.5 26763.2 26796.6 2383.1 2418.0 3142.4 3229.5 3249.5 15758.0 16081.0 16100.8 17028.4 17709.6 2007.2 2045.5 2088.0 2097.3 2117.3 128895.0 130468.0 131176.0 148879.0 161575.0 20200.0 20208.0 21641.0 21709.8 22119.9 36552.5 36684.8 36688.6 36707.8 36787.8 10896.6 11039.5 11144.8 11176.9 11286.3 9086.0 9183.8 9242.9 9353.2 9376.2 8398.4 8580.9 10246.4 10373.4 10499.2 3920.1 3935.4 3958.5 3965.3 4032.9 891.7 917.4 952.8 1036.3 1061.2 18607.2 18806.9 21062.5 22296.7 22326.9 939.4 989.6 994.4 994.6 1017.8 9424.5 11337.9 11638.7 11679.9 11804.9 526788.0 533696.0 534249.0 626343.0 635565.0 83978.6 146304.5 149266.0 155244.0 159703.4 965.8 982.3 1015.3 1020.7 1030.9 17183.3 19491.5 19692.0 19863.8 19867.3 40407.4 55120.4 57553.1 57603.0 57744.8 86423.6 86697.6 86836.0 89146.0 91552.0 7235.3 7399.6 7617.7 8050.3 8521.5 9586.9 13007.2 13469.0 13970.9 14084.9 11343.0 11532.1 11965.8 12003.8 12062.9 10905.5 11075.1 11360.4 11629.2 12121.8 9373.5 9916.6 10212.5 11488.8 11499.4 8366.1 8478.0 8638.0 8660.9 8728.7 11402.0 11701.3 12017.9 12072.8 12107.8 07:52:38 INFO - PROCESS | 2236 | 07:52:38 INFO - PROCESS | 2236 | [#3] mootools.html Cycles:180 Average:5737.62 Median:4872.31 stddev:3187.97 (65.4%) stddev-sans-first:3180.59 07:52:38 INFO - PROCESS | 2236 | Values: 1445.4 1567.4 1785.6 1912.5 1930.9 8042.7 8285.3 8593.0 8647.8 8662.5 8678.8 8864.2 8871.0 8889.7 8890.0 2374.0 2401.3 2406.8 2417.7 2418.1 2351.8 2388.5 2414.9 2419.0 2420.0 2337.2 2358.8 2385.0 2403.5 2404.9 2333.0 2350.2 2367.2 2379.3 2384.3 2342.8 2363.2 2384.4 2387.3 2388.3 8884.4 8906.2 8941.2 8976.9 8992.7 5254.1 5390.3 5413.5 5419.1 5423.5 3610.1 3619.9 3650.3 3671.6 3671.8 1023.9 1088.5 1102.8 1108.2 1111.3 10849.3 11596.3 11623.7 11682.4 11700.6 4717.5 4724.1 4779.4 4862.8 4881.9 9915.2 9934.2 10002.9 10010.7 10038.2 5321.3 5352.4 5362.0 5434.6 5439.5 4474.5 4815.1 4927.4 4950.0 4968.8 3921.9 4037.9 4061.6 4084.5 4169.2 1294.8 1299.1 1309.0 1317.7 1319.1 950.3 960.5 968.2 972.7 997.1 6063.7 6166.4 6207.5 6216.8 6218.3 447.7 448.6 452.6 453.1 460.7 4111.8 4118.0 4184.9 4222.5 4236.2 10424.8 10795.5 10813.0 10883.4 10936.4 10250.2 10853.1 10884.5 10912.5 10913.6 8105.2 8205.3 8242.3 8281.7 8317.3 5462.3 6105.2 6135.3 6284.6 6383.3 8612.6 9225.4 9609.2 10133.3 10193.4 10232.5 10250.5 10279.8 10289.4 10318.5 6353.7 7010.3 7407.5 7494.4 7683.0 8473.8 8506.6 8531.7 8547.2 8551.3 5870.3 6424.6 7297.0 7474.6 7528.7 6404.8 7303.4 7452.1 7478.7 7545.5 6582.2 7016.6 7145.4 7146.4 7157.4 5143.6 5151.8 5160.6 5216.2 5321.0 5157.2 5189.0 5204.5 5228.3 5333.2 07:52:38 INFO - PROCESS | 2236 | 07:52:38 INFO - PROCESS | 2236 | [#4] prototype.html Cycles:180 Average:4677.72 Median:5302.09 stddev:1493.20 (28.2%) stddev-sans-first:1460.90 07:52:38 INFO - PROCESS | 2236 | Values: 306.3 313.9 318.3 318.9 322.2 5364.9 5397.5 5642.6 5706.4 5724.3 4894.8 5505.8 5517.4 5562.8 5592.3 3468.3 3995.6 4229.5 4235.6 4246.3 3446.7 3933.6 4093.5 4263.4 4279.7 4147.2 4179.3 4183.7 4193.4 4217.6 3325.9 3646.9 4185.1 4191.4 4229.5 3098.5 3588.5 3636.5 3658.9 3664.6 4642.5 5304.4 5323.0 5325.8 5475.0 3984.8 4757.4 4820.5 4821.0 4830.3 5337.4 5352.9 5614.2 5871.9 5888.6 3794.6 3855.3 3976.6 3984.6 4027.8 6101.5 6198.5 6264.8 6283.7 6286.0 3385.9 3404.4 3605.0 4044.1 4074.3 3616.9 4011.6 4036.9 4072.1 4099.9 3932.5 4225.9 4612.2 4723.8 4823.7 5875.3 5934.3 5956.9 5974.4 5999.4 1327.6 1388.8 1413.4 1423.5 1424.6 901.3 944.9 951.2 977.3 980.9 4314.9 4999.6 5027.6 5480.8 5576.4 5409.4 5904.1 5964.7 5997.4 6044.0 4641.9 5315.7 5335.7 5384.8 5497.4 2432.9 2436.9 2462.7 2677.8 2711.5 4406.8 4533.8 4807.6 5196.7 5299.8 5104.4 5298.6 5659.4 6221.7 6342.1 4857.6 5257.6 5802.9 6250.5 6366.7 5190.5 5365.9 5571.3 5910.0 6172.2 6005.3 6047.0 6068.6 6082.7 6136.2 5884.0 6383.4 6393.4 6400.7 6424.5 3731.8 3981.6 4007.9 4010.6 4026.0 5794.5 6046.2 6077.0 6173.5 6187.2 4685.7 4898.3 5348.2 5687.5 6007.9 5713.3 5970.0 6018.8 6022.7 6032.5 4986.4 5371.9 5670.3 5809.2 5898.9 5008.6 5755.5 6007.9 6027.2 6039.7 5109.3 5913.3 5919.8 5922.2 5976.0 07:52:38 INFO - PROCESS | 2236 | 07:52:38 INFO - PROCESS | 2236 | [#5] yui.html Cycles:180 Average:23691.01 Median:3685.83 stddev:37183.16 (1008.8%) stddev-sans-first:37247.34 07:52:38 INFO - PROCESS | 2236 | Values: 683.9 720.4 772.9 775.6 856.5 14855.0 15198.6 15651.3 15845.8 15876.8 14311.4 14314.7 14350.6 14464.5 14468.9 877.5 895.6 898.9 902.3 902.7 702.0 855.1 877.2 880.4 884.2 943.0 946.4 947.5 948.5 949.5 907.2 919.2 943.5 946.7 949.3 688.3 703.5 704.3 704.5 704.6 15170.2 15434.9 15445.4 15469.3 15495.4 14849.5 14902.7 14920.4 15072.0 15123.5 9773.6 11020.9 11123.0 11172.4 11185.0 3663.5 3803.4 3829.8 3971.0 3976.8 106532.4 109825.0 113735.0 113837.0 115373.0 10812.6 10868.8 10879.7 10995.3 11024.9 15716.6 16637.4 20605.0 20685.0 21063.0 7845.1 7905.7 7959.3 7998.0 8080.9 4463.6 4469.4 4615.7 4633.5 4815.4 3592.2 3685.4 3686.2 3697.1 3701.3 595.3 681.8 688.3 693.4 705.2 574.1 594.8 645.8 652.0 740.7 9201.4 9426.1 9635.8 9667.8 9792.9 477.9 655.3 658.9 700.8 711.4 3794.4 3980.7 4699.9 4751.3 4808.4 88750.6 109122.0 135849.0 142980.3 148980.0 107360.0 132506.3 132918.7 133515.0 134432.3 112251.4 112453.4 112833.0 115321.4 116485.0 8576.0 9047.7 9092.5 9103.6 9417.4 50104.5 57812.4 60320.7 60434.7 61206.7 59716.7 61481.0 72369.0 78898.0 79103.8 12919.8 13258.3 13413.8 13617.6 13619.7 15365.5 15372.7 15480.9 15804.2 16202.9 13500.9 14284.6 14608.7 14727.0 14742.6 13817.2 13878.5 14074.2 14552.9 14611.1 13940.0 14050.0 14384.9 14475.7 14478.2 10758.3 10846.9 11402.2 11528.0 11552.6 10948.3 11202.4 11512.8 11750.9 11849.3 07:52:38 INFO - PROCESS | 2236 | -------- Summary: end -------- 07:52:38 INFO - PROCESS | 2236 | 07:52:39 INFO - TEST-INFO | 2236: exit 0 07:52:39 INFO - TEST-OK | dromaeo_css | took 1113800ms 07:52:39 INFO - TEST-START | kraken 07:52:39 INFO - operating with platform_type : mac_ 07:52:39 INFO - Initialising browser for kraken test... 07:52:39 INFO - TEST-INFO | started process 2239 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmpcsKTAE/profile http://localhost:56520/getInfo.html) 07:52:41 INFO - PROCESS | 2239 | JavaScript error: , line 0: uncaught exception: undefined 07:52:42 INFO - PROCESS | 2239 | __metrics Screen width/height:1600/1200 07:52:42 INFO - PROCESS | 2239 | colorDepth:24 07:52:42 INFO - PROCESS | 2239 | Browser inner width/height: 1024/689 07:52:42 INFO - PROCESS | 2239 | __metrics 07:52:42 ERROR - PROCESS | 2239 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 07:52:42 INFO - TEST-INFO | 2239: exit 0 07:52:42 INFO - Browser initialized. 07:52:42 INFO - Running cycle 1/1 for kraken test... 07:52:42 INFO - TEST-INFO | started process 2241 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmpcsKTAE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:52:55 INFO - PROCESS | 2241 | RSS: Main: 256598016 07:52:55 INFO - PROCESS | 2241 | 07:52:57 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56520/tests/kraken/driver.html?testName=audio-beat-detection) 07:52:58 INFO - PROCESS | 2241 | RSS: Main: 269492224 07:52:58 INFO - PROCESS | 2241 | 07:53:01 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56520/tests/kraken/driver.html?testName=audio-dft) 07:53:02 INFO - PROCESS | 2241 | RSS: Main: 278245376 07:53:02 INFO - PROCESS | 2241 | 07:53:05 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56520/tests/kraken/driver.html?testName=audio-fft) 07:53:06 INFO - PROCESS | 2241 | RSS: Main: 281219072 07:53:06 INFO - PROCESS | 2241 | 07:53:08 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56520/tests/kraken/driver.html?testName=audio-oscillator) 07:53:08 INFO - PROCESS | 2241 | RSS: Main: 282501120 07:53:08 INFO - PROCESS | 2241 | 07:53:10 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56520/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:53:11 INFO - PROCESS | 2241 | RSS: Main: 284340224 07:53:11 INFO - PROCESS | 2241 | 07:53:14 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56520/tests/kraken/driver.html?testName=imaging-darkroom) 07:53:15 INFO - PROCESS | 2241 | RSS: Main: 285163520 07:53:15 INFO - PROCESS | 2241 | 07:53:18 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56520/tests/kraken/driver.html?testName=imaging-desaturate) 07:53:18 INFO - PROCESS | 2241 | RSS: Main: 285392896 07:53:18 INFO - PROCESS | 2241 | 07:53:21 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56520/tests/kraken/driver.html?testName=json-parse-financial) 07:53:22 INFO - PROCESS | 2241 | RSS: Main: 285540352 07:53:22 INFO - PROCESS | 2241 | 07:53:23 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56520/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:53:24 INFO - PROCESS | 2241 | RSS: Main: 285253632 07:53:24 INFO - PROCESS | 2241 | 07:53:26 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56520/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:53:27 INFO - PROCESS | 2241 | RSS: Main: 284741632 07:53:27 INFO - PROCESS | 2241 | 07:53:29 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56520/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:53:30 INFO - PROCESS | 2241 | RSS: Main: 285016064 07:53:30 INFO - PROCESS | 2241 | 07:53:32 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56520/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:53:33 INFO - PROCESS | 2241 | RSS: Main: 284721152 07:53:33 INFO - PROCESS | 2241 | 07:53:36 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56520/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:53:36 INFO - PROCESS | 2241 | RSS: Main: 285261824 07:53:36 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:56520/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56520/tests/kraken/driver.html?testName=ai-astar) 07:53:38 INFO - PROCESS | 2241 | RSS: Main: 286248960 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | __start_tp_report 07:53:38 INFO - PROCESS | 2241 | _x_x_mozilla_page_load 07:53:38 INFO - PROCESS | 2241 | _x_x_mozilla_page_load_details 07:53:38 INFO - PROCESS | 2241 | |i|pagename|runs| 07:53:38 INFO - PROCESS | 2241 | |0;ai-astar;106;115;107;119;115;116;114;117;118;117 07:53:38 INFO - PROCESS | 2241 | |1;audio-beat-detection;146;108;149;187;141;160;136;96;198;96 07:53:38 INFO - PROCESS | 2241 | |2;audio-dft;198;209;236;200;195;198;200;199;203;200 07:53:38 INFO - PROCESS | 2241 | |3;audio-fft;73;93;103;182;84;59;59;63;134;60 07:53:38 INFO - PROCESS | 2241 | |4;audio-oscillator;66;85;152;101;95;91;116;70;70;116 07:53:38 INFO - PROCESS | 2241 | |5;imaging-gaussian-blur;135;130;90;88;89;86;91;88;91;87 07:53:38 INFO - PROCESS | 2241 | |6;imaging-darkroom;98;95;100;157;94;153;129;98;96;99 07:53:38 INFO - PROCESS | 2241 | |7;imaging-desaturate;90;103;99;147;92;89;92;91;93;99 07:53:38 INFO - PROCESS | 2241 | |8;json-parse-financial;54;56;55;57;58;57;56;57;56;55 07:53:38 INFO - PROCESS | 2241 | |9;json-stringify-tinderbox;73;83;104;111;101;79;77;73;70;71 07:53:38 INFO - PROCESS | 2241 | |10;stanford-crypto-aes;84;89;92;83;86;84;80;87;80;86 07:53:38 INFO - PROCESS | 2241 | |11;stanford-crypto-ccm;135;139;143;134;135;137;135;136;168;137 07:53:38 INFO - PROCESS | 2241 | |12;stanford-crypto-pbkdf2;166;165;169;166;168;164;167;168;166;162 07:53:38 INFO - PROCESS | 2241 | |13;stanford-crypto-sha256-iterative;66;69;67;67;69;71;86;90;74;66 07:53:38 INFO - PROCESS | 2241 | __end_tp_report 07:53:38 INFO - PROCESS | 2241 | __start_cc_report 07:53:38 INFO - PROCESS | 2241 | _x_x_mozilla_cycle_collect,1075 07:53:38 INFO - PROCESS | 2241 | __end_cc_report 07:53:38 INFO - PROCESS | 2241 | __startTimestamp1469458418899__endTimestamp 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | ------- Summary: start ------- 07:53:38 INFO - PROCESS | 2241 | Number of tests: 14 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#0] ai-astar Cycles:10 Average:114.40 Median:116.50 stddev:4.43 (3.8%) stddev-sans-first:3.50 07:53:38 INFO - PROCESS | 2241 | Values: 106.0 115.0 107.0 119.0 115.0 116.0 114.0 117.0 118.0 117.0 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:173.50 stddev:34.92 (20.1%) stddev-sans-first:37.00 07:53:38 INFO - PROCESS | 2241 | Values: 146.0 108.0 149.0 187.0 141.0 160.0 136.0 96.0 198.0 96.0 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#2] audio-dft Cycles:10 Average:203.80 Median:200.00 stddev:11.91 (6.0%) stddev-sans-first:12.44 07:53:38 INFO - PROCESS | 2241 | Values: 198.0 209.0 236.0 200.0 195.0 198.0 200.0 199.0 203.0 200.0 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#3] audio-fft Cycles:10 Average:91.00 Median:61.50 stddev:40.06 (65.1%) stddev-sans-first:41.96 07:53:38 INFO - PROCESS | 2241 | Values: 73.0 93.0 103.0 182.0 84.0 59.0 59.0 63.0 134.0 60.0 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:70.00 stddev:26.58 (38.0%) stddev-sans-first:25.85 07:53:38 INFO - PROCESS | 2241 | Values: 66.0 85.0 152.0 101.0 95.0 91.0 116.0 70.0 70.0 116.0 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#5] imaging-gaussian-blur Cycles:10 Average:97.50 Median:88.50 stddev:18.55 (21.0%) stddev-sans-first:13.86 07:53:38 INFO - PROCESS | 2241 | Values: 135.0 130.0 90.0 88.0 89.0 86.0 91.0 88.0 91.0 87.0 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#6] imaging-darkroom Cycles:10 Average:111.90 Median:95.50 stddev:24.88 (26.0%) stddev-sans-first:25.87 07:53:38 INFO - PROCESS | 2241 | Values: 98.0 95.0 100.0 157.0 94.0 153.0 129.0 98.0 96.0 99.0 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#7] imaging-desaturate Cycles:10 Average:99.50 Median:92.00 stddev:17.31 (18.8%) stddev-sans-first:18.01 07:53:38 INFO - PROCESS | 2241 | Values: 90.0 103.0 99.0 147.0 92.0 89.0 92.0 91.0 93.0 99.0 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:56.50 stddev:1.20 (2.1%) stddev-sans-first:1.00 07:53:38 INFO - PROCESS | 2241 | Values: 54.0 56.0 55.0 57.0 58.0 57.0 56.0 57.0 56.0 55.0 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#9] json-stringify-tinderbox Cycles:10 Average:84.20 Median:73.00 stddev:15.27 (20.9%) stddev-sans-first:15.65 07:53:38 INFO - PROCESS | 2241 | Values: 73.0 83.0 104.0 111.0 101.0 79.0 77.0 73.0 70.0 71.0 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:86.00 stddev:3.75 (4.4%) stddev-sans-first:3.96 07:53:38 INFO - PROCESS | 2241 | Values: 84.0 89.0 92.0 83.0 86.0 84.0 80.0 87.0 80.0 86.0 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#11] stanford-crypto-ccm Cycles:10 Average:139.90 Median:137.00 stddev:10.21 (7.5%) stddev-sans-first:10.68 07:53:38 INFO - PROCESS | 2241 | Values: 135.0 139.0 143.0 134.0 135.0 137.0 135.0 136.0 168.0 137.0 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:166.50 stddev:2.08 (1.2%) stddev-sans-first:2.20 07:53:38 INFO - PROCESS | 2241 | Values: 166.0 165.0 169.0 166.0 168.0 164.0 167.0 168.0 166.0 162.0 07:53:38 INFO - PROCESS | 2241 | 07:53:38 INFO - PROCESS | 2241 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:70.00 stddev:8.58 (12.3%) stddev-sans-first:8.77 07:53:38 INFO - PROCESS | 2241 | Values: 66.0 69.0 67.0 67.0 69.0 71.0 86.0 90.0 74.0 66.0 07:53:38 INFO - PROCESS | 2241 | -------- Summary: end -------- 07:53:38 INFO - PROCESS | 2241 | 07:53:39 INFO - TEST-INFO | 2241: exit 0 07:53:39 INFO - TEST-OK | kraken | took 60262ms 07:53:39 INFO - SUITE-END | took 1174s 07:53:40 INFO - Completed test suite (00:19:34) 07:53:40 INFO - javascript benchmark 07:53:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7542.39632955271, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3363.8822641207553, 3396.575428071491, 3400.7619466637343, 3401.20071783131, 3404.914877128073, 5029.5221953914415, 5425.728713564321, 5656.830295091144, 5747.827565173046, 5857.0, 5761.337446193689, 5791.999999999989, 5806.854828629276, 5809.099589563614, 5936.139259807325, 5187.87030324243, 5303.28405665236, 5309.41596424393, 5355.3305836770405, 5447.972760136214, 5455.427180146087, 5473.67157970521, 5490.039243132436, 5526.977509160818, 5528.75120619573, 5175.534201921808, 5197.0, 5259.631825772198, 5340.732963351817, 5343.545798607123, 5187.714675692835, 5239.5546378557865, 5278.10272253714, 5287.709175995319, 5335.306410166653, 3860.478835357247, 5015.122353588144, 5258.316418865561, 5261.210818377236, 5297.12597421425, 4808.63935204861, 5509.862253443656, 5593.776248950037, 5768.221290125821, 5783.739731712083, 6192.864634855623, 6194.566380353377, 6262.749490020394, 6289.119523266746, 6289.276733175652, 5602.215689803431, 6221.782237621706, 6240.594361366497, 6249.250089989185, 6300.684965751694, 5755.942440575583, 5845.269341332333, 5926.140709597127, 5941.524678025834, 6044.063170208625, 6329.525285603599, 6375.362463753588, 6394.71223794921, 6397.680115994275, 6402.871942561216, 6270.372962703693, 6280.717367718372, 6290.370962903765, 6297.4962003039645, 6312.747490100344, 6176.567640265138, 6269.5611307208055, 6271.372862713783, 6298.0552917062805, 6310.716017779211, 5420.891582168335, 6133.478654314344, 6204.410580994799, 6268.811935641938, 6305.18032655761, 6203.503719702413, 6264.937350626436, 6269.811905642838, 6285.214348206474, 6287.025511045796, 4858.4898585648, 4951.430585482643, 5040.521150490697, 5094.719790411489, 5100.7959681613065, 4458.799354029076, 4493.303537951622, 4543.909121817545, 4614.676972611885, 4621.630269578425, 4838.516148385133, 5897.32180799205, 5986.0, 5998.100284957204, 6000.939990600125, 5822.7670893163795, 6214.0, 6221.5333849961435, 6288.402601752826, 6323.336049714806, 5845.590808643439, 5974.880502389928, 5981.102834574762, 5996.2504686914135, 6037.426444487766, 5553.611247212737, 5580.832575022756, 5586.190002449622, 5589.469000444951, 5589.888202235932, 6279.215098112736, 6327.683615819098, 6513.804585862337, 6543.640099794557, 6551.8362040948405, 6235.719392627252, 6288.0, 6308.148399965946, 6310.779122730866, 6313.55805093639, 6466.870662586816, 6484.5460817742305, 6515.706793194223, 6534.444572211415, 6547.836304092436, 6119.441623354662, 6193.690315484298, 6300.369963003846, 6308.621482711052, 6314.179156709598, 6248.0, 6257.530685198537, 6288.308286088618, 6305.873882522415, 6325.05124231369, 6198.566100373111, 6269.937300627118, 6270.9372906270355, 6284.0, 6310.242770867525, 6025.698715064317, 6107.735039974475, 6217.160683307877, 6286.779962701284, 6290.433860952537, 4983.476734942778, 5134.5635620972635, 5999.340072591924, 6046.7581296749395, 6137.0, 6255.343188965275, 6290.0, 6291.559590828598, 6301.873962520633, 6313.2424109107205, 5448.645838020516, 5931.822045338647, 6064.48451881595, 6082.635041897501, 6165.815025549241], "value": 5751.919717214123, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.4624589828242, 506.96831946987885, 508.9947353975896, 523.5942144837751, 563.2115038945474, 38790.612093879055, 41653.16693666125, 41692.0, 41693.9576510587, 41713.374299385534, 42690.57309426912, 43256.0, 43281.35077973833, 43334.7833260834, 43417.0, 9538.666146684867, 9588.664396746099, 9757.902420975806, 9760.17038551724, 9762.463064531448, 9523.619055237818, 9627.951860240724, 9638.0, 9713.95143024284, 9761.121499065082, 9492.145706886378, 9741.464219467927, 9748.561314740818, 9750.512474376288, 9755.658551950683, 9509.5720692569, 9610.087041731025, 9696.175825054843, 9726.56230469627, 9775.755606109904, 9428.858567121499, 9522.238220942309, 9622.134007939248, 9630.662926797599, 9670.951645241728, 40196.598034019575, 40406.595934040575, 40458.595414045776, 40471.0, 40533.39199912004, 2659.9626145803154, 2793.413383189533, 3352.5306457096026, 3600.72994525409, 3679.944800827963, 29607.851960740274, 30288.0, 30872.8456357719, 31845.52231716526, 31924.13631069308, 3155.4477966355794, 3160.3291347805007, 3167.841607919595, 3186.426443240216, 3282.5896762904636, 444658.0, 446742.0, 448163.0, 451480.0, 452683.0, 34244.31511369759, 34406.0, 34423.0, 34447.655523444446, 34484.31031379358, 34481.82759086188, 35486.82256588752, 35584.7545335912, 35606.821965890005, 35667.28665426677, 16780.91609541969, 16968.660626787394, 17095.0, 17102.657946841242, 17140.40008599693, 12641.620751377473, 13021.609351719464, 13090.541831036058, 13140.934295328654, 13183.868161318456, 16094.75857862133, 16132.75800862988, 19714.507137321394, 19829.0, 19880.801191988183, 6766.966165169143, 8167.877481837777, 8265.090840007595, 8291.968467725324, 8306.6677332906, 806.6450761664848, 876.8991565969867, 879.560219890055, 970.1947383671536, 1167.1654766841748, 29637.555436668466, 30749.538756918664, 30861.0, 30988.0, 31711.36577268488, 1011.3022014809758, 1051.75283808305, 1067.2795862792584, 1071.1056268016139, 1084.9349039057681, 15456.304466299043, 15852.286647100907, 15945.202739863193, 16013.759793603105, 16312.939658922132, 498792.5181122286, 517219.0, 518868.0, 584074.238886417, 592430.0, 46292.768536157775, 58667.23998280059, 60683.99999999912, 60981.08528372078, 61041.0, 1205.8432403787451, 1308.7109197440514, 1418.5389748331834, 1443.6896067345572, 1448.891333150018, 29631.555526667547, 30069.94755183558, 30439.782408703402, 30491.39017219599, 30659.386812264074, 51807.22289165741, 51820.22269665883, 51977.480225197265, 52060.73969630127, 52073.21890171574, 564012.0, 573445.0, 577679.0, 579047.0, 581356.0, 26691.466170676867, 26699.64396485546, 27142.864285679236, 27416.766245519397, 27531.898724050814, 45764.31353529766, 49520.75239623779, 50045.0, 50270.245946310104, 50363.24455133249, 38046.809765950995, 41504.96237594084, 42169.578304216564, 43055.569444305154, 43506.56493435025, 35393.646063540065, 40740.38889416602, 40979.59020409877, 42013.159736805705, 42316.576834232495, 32337.838310808296, 36927.0, 38884.805575971935, 39229.80385098056, 40109.0, 31770.6822931777, 34466.0, 36007.63992360043, 36199.0, 36588.81705591455, 25388.74611253864, 25421.61867572025, 26468.602970955068, 26627.33431664224, 26779.0], "value": 19451.00385268014, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.8020917135961, 348.39553374894535, 370.98516059357627, 381.3022169429943, 394.1702715783278, 20592.382228533148, 20843.687344689824, 20963.580728385423, 21247.36257912265, 21387.251446199385, 21126.260580879672, 21226.257081002168, 21255.468613284676, 21620.0, 21666.674999875013, 5356.437574054727, 5917.615355001933, 6165.32181460039, 6231.844203894916, 6247.7813276535335, 6076.574639775217, 6165.691715414234, 6195.101710252034, 6196.876062478747, 6201.906971395432, 5215.295935048777, 5507.256520369759, 5783.855403614923, 5822.155787410823, 6104.725287362079, 5229.973850130725, 5868.501177399926, 6048.546359023069, 6104.450599446027, 6130.877382452349, 4573.153966516205, 5223.869403264911, 5233.188855727369, 5267.341582302212, 5295.549878260353, 26227.0, 26299.868500657376, 26469.470610587872, 26763.19710408691, 26796.59805102925, 2383.0928551916063, 2417.987910060456, 3142.3872344892757, 3229.531717900891, 3249.528818321354, 15758.0, 16080.954737941996, 16100.838991610168, 17028.404005859735, 17709.645807083787, 2007.1569940624827, 2045.5499790046167, 2087.9791202087936, 2097.3498215553077, 2117.3224568138203, 128895.0, 130468.0, 131176.0, 148879.022419551, 161575.0, 20200.0, 20208.0, 21641.0, 21709.782902171093, 22119.889400552893, 36552.45171322432, 36684.81657591748, 36688.63311366906, 36707.81646091752, 36787.81606092006, 10896.61861834832, 11039.503222355013, 11144.832827507595, 11176.944115279372, 11286.322820630789, 9086.0, 9183.77040573991, 9242.907570924339, 9353.204977576846, 9376.249900007982, 8398.41211115216, 8580.914190858137, 10246.38521688701, 10373.429461379546, 10499.212559058102, 3920.1375697347107, 3935.4490371348033, 3958.5051868516434, 3965.345717956505, 4032.850637568279, 891.7369376033956, 917.4220241247971, 952.7760976170595, 1036.3471013261596, 1061.2412125330425, 18607.16267767954, 18806.87158770478, 21062.47343816417, 22296.66555001676, 22326.88836555807, 939.4410325856105, 989.6091044037565, 994.4033579852176, 994.6021591363512, 1017.765913839821, 9424.528773561295, 11337.886621133848, 11638.6508404748, 11679.941600292113, 11804.940975295069, 526788.0, 533696.0, 534249.0, 626343.0, 635565.0, 83978.58010709907, 146304.5369546291, 149266.0, 155244.0, 159703.40296597118, 965.753732798132, 982.3221976835961, 1015.279151802214, 1020.6733845169623, 1030.9329893556894, 17183.31266749366, 19491.51271218231, 19692.0, 19863.801361986196, 19867.30464433738, 40407.39388909227, 55120.44879551153, 57553.13670295032, 57603.0, 57744.845103096864, 86423.56788216019, 86697.56651216703, 86836.0, 89146.0, 91552.0, 7235.2764723527225, 7399.556026638419, 7617.6572054256585, 8050.315723163597, 8521.488710677379, 9586.89750678653, 13007.154534955198, 13469.0, 13970.860291396955, 14084.859151408355, 11343.0, 11532.077433805443, 11965.76068478608, 12003.759924801281, 12062.879371206527, 10905.454727263446, 11075.058620017067, 11360.375179365214, 11629.24409913353, 12121.75756484883, 9373.531323433665, 9916.553755081182, 10212.540435680263, 11488.770224595293, 11499.425028748361, 8366.079731229682, 8478.0, 8638.0, 8660.870086948575, 8728.69449569262, 11402.0, 11701.2979221247, 12017.879821201675, 12072.75854482923, 12107.818382724443], "value": 10952.917766513849, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.385711072794, 1567.3730507796884, 1785.5714628489159, 1912.4740696308513, 1930.9131089100988, 8042.678292868278, 8285.337173026159, 8592.957035214815, 8647.783805404868, 8662.52356120413, 8678.826423471543, 8864.202221800037, 8870.955645221766, 8889.733308000754, 8890.0, 2373.964390534143, 2401.3070785096465, 2406.783389494954, 2417.697787776528, 2418.056957786465, 2351.8236132290062, 2388.4984153327737, 2414.915477958272, 2418.9879050604723, 2419.987900060506, 2337.240396871015, 2358.79950204232, 2384.95230095398, 2403.5433267679086, 2404.879756012201, 2332.976670233293, 2350.15394457995, 2367.1833217540093, 2379.2624286471164, 2384.2727967969763, 2342.777436143581, 2363.196513185525, 2384.3800611840875, 2387.331547166796, 2388.3073908566494, 8884.378093533454, 8906.198442140238, 8941.195292423714, 8976.910230897673, 8992.730218093468, 5254.054270231357, 5390.272313237702, 5413.53984911283, 5419.078756611386, 5423.538999185074, 3610.1155216971747, 3619.8914032579064, 3650.2699460108083, 3671.559412870446, 3671.7796932184156, 1023.8993082436336, 1088.450332582041, 1102.7573933734566, 1108.1688733449912, 1111.3220935229504, 10849.349039057683, 11596.304221746637, 11623.709407264801, 11682.357470339128, 11700.590479333268, 4717.510842627051, 4724.102420540086, 4779.4264688236935, 4862.756862156878, 4881.8535443936735, 9915.206783457306, 9934.15559677421, 10002.899971000343, 10010.699679009642, 10038.196944244442, 5321.308229930084, 5352.437994010573, 5362.034833729888, 5434.565234781208, 5439.5104440600535, 4474.530174331649, 4815.085133824562, 4927.408710954636, 4950.0, 4968.801247950114, 3921.8823435296986, 4037.9192416151513, 4061.5532291447917, 4084.509858816961, 4169.1661667666585, 1294.792833146692, 1299.1166007115064, 1308.9541866034642, 1317.7101037771674, 1319.1359659423092, 950.254050570299, 960.4813400763649, 968.1770495079268, 972.6887396033343, 997.1324947295899, 6063.696815159225, 6166.445019948226, 6207.472364848948, 6216.7824126155365, 6218.253809542884, 447.7157005301657, 448.5895405703744, 452.5881447882411, 453.1028563444333, 460.7120549656465, 4111.835526578963, 4117.958820411817, 4184.853530126492, 4222.472190976128, 4236.2374772540625, 10424.843627345595, 10795.514201860935, 10813.0, 10883.401412922207, 10936.398498082683, 10250.179985601135, 10853.131749460024, 10884.455777211107, 10912.50893709785, 10913.618023369145, 8105.189481051965, 8205.343572514184, 8242.340612751086, 8281.668733250723, 8317.334613230927, 5462.3445186577865, 6105.175801266772, 6135.294441139326, 6284.622922624657, 6383.329750376143, 8612.569371531274, 9225.446473211628, 9609.174256891169, 10133.341332813343, 10193.439360835226, 10232.539535720762, 10250.487475626338, 10279.84580231312, 10289.43408112561, 10318.48407579603, 6353.714082866374, 7010.298970102949, 7407.518511296748, 7494.362979146834, 7682.961585192038, 8473.830523389375, 8506.61720222579, 8531.701390451304, 8547.230749232598, 8551.315894728283, 5870.26621672291, 6424.550281480439, 7297.014902988241, 7474.551526908403, 7528.661210245662, 6404.839879003062, 7303.379212766762, 7452.105747310357, 7478.738244161428, 7545.471816972759, 6582.17722784652, 7016.649167541705, 7145.39264162552, 7146.3568278855155, 7157.391621712213, 5143.588512918883, 5151.845444636667, 5160.561352285098, 5216.217567364925, 5321.04221240165, 5157.200633901752, 5189.040027594907, 5204.531592156724, 5228.300802239211, 5333.200019997032], "value": 4562.792069529489, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.2588535743499, 313.91838122088257, 318.3108569946067, 318.85492101094, 322.19612067890614, 5364.91952620711, 5397.514223719864, 5642.633228840127, 5706.400827913067, 5724.280966540891, 4894.804207831682, 5505.807296744615, 5517.420670829565, 5562.7774889004395, 5592.273004509419, 3468.2890007548517, 3995.60043995601, 4229.471316085489, 4235.639970602502, 4246.3205887058075, 3446.7070299024613, 3933.5869733677982, 4093.5087789465306, 4263.360495925621, 4279.657627389802, 4147.21202971436, 4179.310413781723, 4183.707140500151, 4193.412922190881, 4217.641500472463, 3325.866965321384, 3646.9270614587826, 4185.142045880602, 4191.350340697197, 4229.513605935326, 3098.5352197170378, 3588.4976103345552, 3636.5454318210222, 3658.8902332930056, 3664.560252769658, 4642.535746425364, 5304.416514183436, 5322.973385133088, 5325.837562947581, 5475.0, 3984.820683069269, 4757.429108507002, 4820.493848145929, 4821.0, 4830.347903033081, 5337.422393598698, 5352.93911843939, 5614.185943038277, 5871.911921321183, 5888.646681199141, 3794.582595914447, 3855.325318069327, 3976.562578116434, 3984.561698213194, 4027.7986100694848, 6101.51187904971, 6198.535109866733, 6264.812055638338, 6283.706447146758, 6285.968570157165, 3385.8984230473125, 3404.3701915145775, 3605.008622628746, 4044.1102957349335, 4074.266632006208, 3616.909577260537, 4011.618896204856, 4036.869676490587, 4072.1448495815616, 4099.852041428405, 3932.488776459042, 4225.894352641209, 4612.169809434334, 4723.83466578675, 4823.684631376958, 5875.29496460419, 5934.317553481319, 5956.940430595725, 5974.372690867396, 5999.400059993965, 1327.6349004023778, 1388.8402833674054, 1413.413433425117, 1423.487544483985, 1424.593990712642, 901.3104974694364, 944.9196818270524, 951.2437612098261, 977.2816979520047, 980.9411435313904, 4314.935275970863, 4999.625028122832, 5027.567143364124, 5480.7533660985355, 5576.386597474275, 5409.404965453797, 5904.084866845646, 5964.671943043174, 5997.400259974055, 6044.0, 4641.86074417768, 5315.73421328932, 5335.67985920846, 5384.78460861569, 5497.350794761555, 2432.878356082189, 2436.90252389906, 2462.741412151698, 2677.7857771378244, 2711.52548304046, 4406.7576283304725, 4533.818647254139, 4807.61539076873, 5196.662216955886, 5299.761510732026, 5104.417630534546, 5298.602604804655, 5659.405762394889, 6221.657808820468, 6342.082641984646, 4857.611391088704, 5257.63196576236, 5802.912956305743, 6250.531210159165, 6366.7266546689925, 5190.455002224712, 5365.892682146413, 5571.275734154696, 5910.0, 6172.210059082816, 6005.309389420097, 6046.9697651513225, 6068.575199735976, 6082.72627731762, 6136.23297087864, 5884.0, 6383.36166383358, 6393.360663933756, 6400.71196796136, 6424.453921416732, 3731.832067556912, 3981.562028176956, 4007.91984160321, 4010.6189911958318, 4025.959740402676, 5794.478496935296, 6046.248956724592, 6077.0, 6173.536984726254, 6187.226596675416, 4685.672002959896, 4898.338724272177, 5348.224507446321, 5687.516561092188, 6007.879842403215, 5713.257276554022, 5969.9702101285775, 6018.789342372996, 6022.698865056817, 6032.547558933185, 4986.376702912136, 5371.865703357447, 5670.3086370524225, 5809.24479817621, 5898.9115163273445, 5008.624353173599, 5755.510781583444, 6007.867207141402, 6027.21646185993, 6039.667818270037, 5109.335786347751, 5913.319968203712, 5919.792807251725, 5922.170896074467, 5976.0], "value": 4207.353019771164, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.87348340557, 720.3876704800916, 772.8802035684471, 775.6354513378714, 856.4861083349991, 14854.960152789308, 15198.620034499143, 15651.295691693873, 15845.762313565278, 15876.761848572267, 14311.427542898271, 14314.713705725879, 14350.641233969156, 14464.493742719007, 14468.855311446909, 877.4822854515835, 895.6372669069037, 898.9460632362079, 902.3412908576743, 902.7111324376202, 701.982450438738, 855.0850589868843, 877.2061284537504, 880.4321212817729, 884.2395539835736, 942.9575669094872, 946.4463288975958, 947.5120313038791, 948.4641177734593, 949.5442187749884, 907.2107266678032, 919.1865199298602, 943.5046600534705, 946.7207173883721, 949.2500924269818, 688.277308825736, 703.4829400390709, 704.3132945378267, 704.5279662626002, 704.5526090932244, 15170.24148792567, 15434.922825385802, 15445.382184712598, 15469.303881325368, 15495.380184792595, 14849.480268190562, 14902.70194596113, 14920.403183872631, 15072.0, 15123.546293611209, 9773.560189791477, 11020.889791102147, 11123.0, 11172.385518796464, 11185.0, 3663.4504824276305, 3803.391457366808, 3829.8276577554075, 3971.007248187953, 3976.80115994202, 106532.40201396986, 109825.01749825034, 113735.0, 113837.0, 115373.0, 10812.621558245424, 10868.782624347627, 10879.728006799893, 10995.285306455055, 11024.897510249071, 15716.607084822743, 16637.41769038078, 20605.0, 20685.0, 21063.0, 7845.097813751491, 7905.683772649029, 7959.323457506177, 7998.0, 8080.9191908080165, 4463.642908567307, 4469.351943968171, 4615.746133962597, 4633.4671512775785, 4815.446223684251, 3592.2456284180093, 3685.4103343465454, 3686.2443199144113, 3697.0572504011307, 3701.3152566775234, 595.2559300873908, 681.8329509270161, 688.3426327856885, 693.3586432549871, 705.1608585782902, 574.0900672434155, 594.7977687586155, 645.8173160387925, 652.0122015146961, 740.7333359990399, 9201.401908875901, 9426.104520070583, 9635.759106022406, 9667.758306042264, 9792.853107203398, 477.8614201881485, 655.3184687924505, 658.9143411356589, 700.8388070743656, 711.3633298198084, 3794.3928971365003, 3980.7213495055066, 4699.882502937454, 4751.263554149113, 4808.374911261513, 88750.55624721965, 109122.0, 135849.0, 142980.28509857383, 148980.0, 107360.0, 132506.33746831203, 132918.67081329064, 133515.0, 134432.3278383602, 112251.43874280577, 112453.43773281245, 112833.0, 115321.4233928825, 116485.0, 8575.95712021436, 9047.728568142966, 9092.499912504876, 9103.590338434919, 9417.387869788441, 50104.49895501144, 57812.39844206937, 60320.69839650774, 60434.697826510586, 61206.693966529885, 59716.70141649264, 61481.0, 72369.0, 78898.0, 79103.81344279725, 12919.806202907152, 13258.337083145998, 13413.798793018308, 13617.591472255848, 13619.727605448034, 15365.462208822637, 15372.692546149237, 15480.922595386952, 15804.209789510249, 16202.865799393929, 13500.93249533746, 14284.57146285613, 14608.707825843636, 14727.042742221722, 14742.63143421423, 13817.240051797247, 13878.514252001132, 14074.225917574222, 14552.927235363755, 14611.050281731654, 13940.0, 14050.0, 14384.928075359556, 14475.710485790436, 14478.203698796668, 10758.300710453796, 10846.891531084588, 11402.20184587071, 11528.0, 11552.59565915189, 10948.34309941406, 11202.43987800623, 11512.769744604893, 11750.94124529372, 11849.289042657469], "value": 7825.028174701805, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1559.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 107.0, 119.0, 115.0, 116.0, 114.0, 117.0, 118.0, 117.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 108.0, 149.0, 187.0, 141.0, 160.0, 136.0, 96.0, 198.0, 96.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 209.0, 236.0, 200.0, 195.0, 198.0, 200.0, 199.0, 203.0, 200.0], "value": 203.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 93.0, 103.0, 182.0, 84.0, 59.0, 59.0, 63.0, 134.0, 60.0], "value": 91.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 85.0, 152.0, 101.0, 95.0, 91.0, 116.0, 70.0, 70.0, 116.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 130.0, 90.0, 88.0, 89.0, 86.0, 91.0, 88.0, 91.0, 87.0], "value": 97.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 95.0, 100.0, 157.0, 94.0, 153.0, 129.0, 98.0, 96.0, 99.0], "value": 111.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 103.0, 99.0, 147.0, 92.0, 89.0, 92.0, 91.0, 93.0, 99.0], "value": 99.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 55.0, 57.0, 58.0, 57.0, 56.0, 57.0, 56.0, 55.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 83.0, 104.0, 111.0, 101.0, 79.0, 77.0, 73.0, 70.0, 71.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 92.0, 83.0, 86.0, 84.0, 80.0, 87.0, 80.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 139.0, 143.0, 134.0, 135.0, 137.0, 135.0, 136.0, 168.0, 137.0], "value": 139.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 169.0, 166.0, 168.0, 164.0, 167.0, 168.0, 166.0, 162.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 67.0, 67.0, 69.0, 71.0, 86.0, 90.0, 74.0, 66.0], "value": 72.5, "unit": "score"}]}]} 07:53:40 INFO - Return code: 0 07:53:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:53:40 ERROR - # TBPL SUCCESS # 07:53:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:53:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:53:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:53:40 INFO - 2016-07-25 07:53:40.248 screenresolution[2246:26500] starting screenresolution argv=screenresolution get 07:53:40 INFO - 2016-07-25 07:53:40.250 screenresolution[2246:26500] Display 0: 1600x1200x32@60 07:53:40 INFO - 2016-07-25 07:53:40.256 screenresolution[2247:26503] starting screenresolution argv=screenresolution list 07:53:40 INFO - Available Modes on Display 0 07:53:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:53:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:53:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:53:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:53:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:53:40 INFO - Intel Iris: 07:53:40 INFO - Chipset Model: Intel Iris 07:53:40 INFO - Type: GPU 07:53:40 INFO - Bus: Built-In 07:53:40 INFO - VRAM (Dynamic, Max): 1536 MB 07:53:40 INFO - Vendor: Intel (0x8086) 07:53:40 INFO - Device ID: 0x0a2e 07:53:40 INFO - Revision ID: 0x0009 07:53:40 INFO - Displays: 07:53:40 INFO - Display: 07:53:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:53:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:53:40 INFO - Display Serial Number: 1600x1200 60 07:53:40 INFO - Main Display: Yes 07:53:40 INFO - Mirror: Off 07:53:40 INFO - Online: Yes 07:53:40 INFO - Rotation: Supported 07:53:40 INFO - Return code: 0 07:53:40 INFO - Running post-action listener: _resource_record_post_action 07:53:40 INFO - [mozharness: 2016-07-25 14:53:40.501094Z] Finished run-tests step (success) 07:53:40 INFO - Running post-run listener: _resource_record_post_run 07:53:40 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 8192; Write bytes: 706668032; Read time: 2; Write time: 2023 07:53:40 INFO - TinderboxPrint: CPU usage
54.3% 07:53:40 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 2 07:53:40 INFO - TinderboxPrint: I/O write bytes / time
706,668,032 / 2,023 07:53:40 INFO - TinderboxPrint: CPU idle
2,212.5 (45.7%) 07:53:40 INFO - TinderboxPrint: CPU system
981.2 (20.3%) 07:53:40 INFO - TinderboxPrint: CPU user
1,643.9 (34.0%) 07:53:40 INFO - TinderboxPrint: Swap in / out
407,470,080 / 0 07:53:40 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391090688; Write bytes: 385845248; Read time: 29288; Write time: 818 07:53:40 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 8192; Write bytes: 295673344; Read time: 2; Write time: 1144 07:53:40 INFO - Running post-run listener: _upload_blobber_files 07:53:40 INFO - Blob upload gear active. 07:53:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:53:40 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 07:53:40 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:53:40 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:53:41 INFO - (blobuploader) - INFO - Open directory for files ... 07:53:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:53:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:53:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:42 INFO - (blobuploader) - INFO - Done attempting. 07:53:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:53:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:53:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:43 INFO - (blobuploader) - INFO - Done attempting. 07:53:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:53:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:53:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:47 INFO - (blobuploader) - INFO - Done attempting. 07:53:47 INFO - (blobuploader) - INFO - Iteration through files over. 07:53:47 INFO - Return code: 0 07:53:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:53:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:53:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d0f41f9f9f21c0404ea064a797e637bcf1e755a1420d4aa42b1d36f50b3e6e3082440f59bae553a9e67b18c4abc26805d2cde423a2cf2bef9c38089c790a7237", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01ff75865c32f272ce9ee8533049acf3aad1f43a8fbf6edde2c0e627ef012642214e313183738dd49b374be631ba0a68004a5533087f8b8d5e0cdcabb846f65f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b981562b56d3bc548c767e331d2f70dd6833241b06c655851e297b8119875f3a7a7ab859916e38e75bf9c2de397845d8430650612cfcf43a4f48e64efc2eef53"} 07:53:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:53:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:53:47 INFO - Contents: 07:53:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d0f41f9f9f21c0404ea064a797e637bcf1e755a1420d4aa42b1d36f50b3e6e3082440f59bae553a9e67b18c4abc26805d2cde423a2cf2bef9c38089c790a7237", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01ff75865c32f272ce9ee8533049acf3aad1f43a8fbf6edde2c0e627ef012642214e313183738dd49b374be631ba0a68004a5533087f8b8d5e0cdcabb846f65f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b981562b56d3bc548c767e331d2f70dd6833241b06c655851e297b8119875f3a7a7ab859916e38e75bf9c2de397845d8430650612cfcf43a4f48e64efc2eef53"} 07:53:47 INFO - Running post-run listener: copy_logs_to_upload_dir 07:53:47 INFO - Copying logs to upload dir... 07:53:47 INFO - mkdir: /builds/slave/test/build/upload/logs 07:53:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1304.672752 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 44 secs) (at 2016-07-25 07:53:47.565034) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 07:53:47.568583) ========= 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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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/Autoland/sha512/d0f41f9f9f21c0404ea064a797e637bcf1e755a1420d4aa42b1d36f50b3e6e3082440f59bae553a9e67b18c4abc26805d2cde423a2cf2bef9c38089c790a7237", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01ff75865c32f272ce9ee8533049acf3aad1f43a8fbf6edde2c0e627ef012642214e313183738dd49b374be631ba0a68004a5533087f8b8d5e0cdcabb846f65f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b981562b56d3bc548c767e331d2f70dd6833241b06c655851e297b8119875f3a7a7ab859916e38e75bf9c2de397845d8430650612cfcf43a4f48e64efc2eef53"} build_url:https://queue.taskcluster.net/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011122 build_url: 'https://queue.taskcluster.net/v1/task/FckujW0zSCaLuX2YEGDjDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d0f41f9f9f21c0404ea064a797e637bcf1e755a1420d4aa42b1d36f50b3e6e3082440f59bae553a9e67b18c4abc26805d2cde423a2cf2bef9c38089c790a7237", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01ff75865c32f272ce9ee8533049acf3aad1f43a8fbf6edde2c0e627ef012642214e313183738dd49b374be631ba0a68004a5533087f8b8d5e0cdcabb846f65f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b981562b56d3bc548c767e331d2f70dd6833241b06c655851e297b8119875f3a7a7ab859916e38e75bf9c2de397845d8430650612cfcf43a4f48e64efc2eef53"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 07:53:47.602010) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 07:53:47.602348) ========= 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.HgUBEGtKP3/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.QWRQNW4RdA/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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.005317 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 07:53:47.651200) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 07:53:47.651589) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 07:53:47.651942) ========= ========= Total master_lag: 1.16 =========